02/17/2005 11:29:17.375 CiscoTSP001.tsp|<--CTspTrace::Init() 02/17/2005 11:29:17.468 CiscoTSP001.tsp|-->TSPI_lineNegotiateTSPIVersion() 02/17/2005 11:29:17.468 CiscoTSP001.tsp| TSPI_lineNegotiateTSPIVersion() dwDeviceID =0xFFFFFFFF dwLowVersion =0x00010003 dwHighVersion =0x00030000 lpdwTSPIVersion =0x012148B8 02/17/2005 11:29:17.468 CiscoTSP001.tsp|-->InitGlobalTSP() 02/17/2005 11:29:17.593 CiscoTSP001.tsp|-->CQBEHelperBase::CQBEHelperBase() 02/17/2005 11:29:17.593 CiscoTSP001.tsp| CQBEHelperBase::CQBEHelperBase() trace mask=0x007F0601 02/17/2005 11:29:17.593 CiscoTSP001.tsp|<--CQBEHelperBase::CQBEHelperBase() 02/17/2005 11:29:17.687 CiscoTSP001.tsp|-->CSelsiusTSP::CSelsiusTSP() 02/17/2005 11:29:17.687 CiscoTSP001.tsp| CSelsiusTSP::CSelsiusTSP() TSP Product Version 3.3(0.24)ES02 02/17/2005 11:29:17.687 CiscoTSP001.tsp| CSelsiusTSP::CSelsiusTSP() trace mask=0x007F0601 02/17/2005 11:29:17.687 CiscoTSP001.tsp| CSelsiusTSP::CSelsiusTSP() m_EnumAutomatedVoiceOnly=0 02/17/2005 11:29:18.203 CiscoTSP001.tsp|-->CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() 02/17/2005 11:29:18.203 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() Synchronous message timeout 15000 02/17/2005 11:29:18.203 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() ProviderOpen Synchronous message timeout 30000 02/17/2005 11:29:18.203 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() Heartbeat interval 30 02/17/2005 11:29:18.203 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() Connect retry interval 30000 02/17/2005 11:29:18.203 CiscoTSP001.tsp|<--CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() 02/17/2005 11:29:18.203 CiscoTSP001.tsp|-->CSelsiusTSPUIMsgGetInfo::ValidateTimerSettingParameters() 02/17/2005 11:29:18.203 CiscoTSP001.tsp|<--CSelsiusTSPUIMsgGetInfo::ValidateTimerSettingParameters() 02/17/2005 11:29:18.203 CiscoTSP001.tsp| CSelsiusTSP::CSelsiusTSP() m_CMAssignedApplicationID=0 02/17/2005 11:29:18.265 CiscoTSP001.tsp|-->CSelsiusTSP::StartEventThread() 02/17/2005 11:29:18.281 CiscoTSP001.tsp| CSelsiusTSP::StartEventThread() Created event thread. 02/17/2005 11:29:18.281 CiscoTSP001.tsp|<--CSelsiusTSP::StartEventThread() 02/17/2005 11:29:18.281 CiscoTSP001.tsp|-->CSelsiusTSP::StartWorkerThread() 02/17/2005 11:29:18.281 CiscoTSP001.tsp|-->SelsiusTSP::EventThreadEntry() 02/17/2005 11:29:18.281 CiscoTSP001.tsp|-->SelsiusTSP::InitializeComObjects() 02/17/2005 11:29:18.281 CiscoTSP001.tsp| SelsiusTSP::InitializeComObjects() trace mask=0x007F0601 02/17/2005 11:29:18.281 CiscoTSP001.tsp| SelsiusTSP::InitializeComObjects() CoInitializeEx(0, COINIT_MULTITHREADED) OK 02/17/2005 11:29:18.281 CiscoTSP001.tsp| CSelsiusTSP::StartWorkerThread() Created worker thread. 02/17/2005 11:29:18.281 CiscoTSP001.tsp|<--CSelsiusTSP::StartWorkerThread() 02/17/2005 11:29:18.281 CiscoTSP001.tsp|-->CSelsiusTSP::SetApplicationID() 02/17/2005 11:29:18.281 CiscoTSP001.tsp|-->SelsiusTSP::WorkerThreadEntry() 02/17/2005 11:29:18.281 CiscoTSP001.tsp|-->SelsiusTSP::WorkerLoop() 02/17/2005 11:29:18.296 CiscoTSP001.tsp| SelsiusTSP::WorkerLoop() worker thread waiting for signal from main thread that there is a request 02/17/2005 11:29:18.296 CiscoTSP001.tsp| SelsiusTSP::InitializeComObjects() *ERROR* CoInitializeSecurity() return 0x80010119 02/17/2005 11:29:18.296 CiscoTSP001.tsp| SelsiusTSP::InitializeComObjects() GITInitialize() OK 02/17/2005 11:29:18.296 CiscoTSP001.tsp|-->CSelsiusTSPWaveList::CSelsiusTSPWaveList() 02/17/2005 11:29:19.031 CiscoTSP001.tsp| CSelsiusTSP::SetApplicationID() ApplicationID=CiscoTSP001-192.168.1.26 02/17/2005 11:29:19.031 CiscoTSP001.tsp|<--CSelsiusTSP::SetApplicationID() 02/17/2005 11:29:19.046 CiscoTSP001.tsp|-->CQBEHelperBase::CreateCtiInterface() 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CQBEHelperBase::CreateCtiInterface() m_SyncMsgTimeout=15000 ,m_ProviderOpenTimeout=30000,mHeartbeatInterval=30,mConnectRetryInterval=30000 02/17/2005 11:29:19.046 CiscoTSP001.tsp|<--CSelsiusTSPWaveList::CSelsiusTSPWaveList() 02/17/2005 11:29:19.046 CiscoTSP001.tsp|-->CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() user name riann 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() Primary CTI Manager Address Type: 2 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() Primary CTI Manager Address: 192.168.1.100 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() Backup CTI Manager Address Type: 0 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() Backup CTI Manager Address: 02/17/2005 11:29:19.046 CiscoTSP001.tsp|-->CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() Synchronous message timeout 15000 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() ProviderOpen Synchronous message timeout 30000 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() Heartbeat interval 30 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() Connect retry interval 30000 02/17/2005 11:29:19.046 CiscoTSP001.tsp|<--CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() TraceOn flag 1 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() TraceMask =0xC07F0601 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() TraceMaxLines =0x00002710 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() TraceNumFiles =10 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() Call TraceFileName c:\Temp 02/17/2005 11:29:19.046 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() LangId = 0x00000009 02/17/2005 11:29:19.390 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() ResourceDirPath C:\Program Files\Cisco 02/17/2005 11:29:19.390 CiscoTSP001.tsp|-->CSelsiusTSPUIMsgGetInfo::ValidateTSPParameters() 02/17/2005 11:29:19.390 CiscoTSP001.tsp|-->CSelsiusTSPUIMsgGetInfo::ValidateTimerSettingParameters() 02/17/2005 11:29:19.390 CiscoTSP001.tsp|<--CSelsiusTSPUIMsgGetInfo::ValidateTimerSettingParameters() 02/17/2005 11:29:19.390 CiscoTSP001.tsp|<--CSelsiusTSPUIMsgGetInfo::ValidateTSPParameters() 02/17/2005 11:29:19.484 CiscoTSP001.tsp|<--CSelsiusTSPUIMsgGetInfo::ProcessTSPUIMsgGetInfo() 02/17/2005 11:29:19.484 CiscoTSP001.tsp|-->CSelsiusTSPWaveList::Init() 02/17/2005 11:29:19.500 CiscoTSP001.tsp|-->CCtiInterface::CCtiInterface() 02/17/2005 11:29:19.500 CiscoTSP001.tsp|<--CCtiInterface::CCtiInterface() 02/17/2005 11:29:19.500 CiscoTSP001.tsp|-->CCtiInterface::Init() 02/17/2005 11:29:19.500 CiscoTSP001.tsp|-->CSelsiusTCPLink() 02/17/2005 11:29:19.500 CiscoTSP001.tsp|<--CSelsiusTCPLink() 02/17/2005 11:29:19.500 CiscoTSP001.tsp|<--CCtiInterface::Init() 02/17/2005 11:29:19.500 CiscoTSP001.tsp|<--CQBEHelperBase::CreateCtiInterface() 02/17/2005 11:29:19.500 CiscoTSP001.tsp|-->CQBEHelperBase::StartConnectThread() 02/17/2005 11:29:19.500 CiscoTSP001.tsp|-->CQBEHelperBase::SetTspState() 02/17/2005 11:29:19.500 CiscoTSP001.tsp| CQBEHelperBase::SetTspState() TSPState = 1 02/17/2005 11:29:19.500 CiscoTSP001.tsp|<--CQBEHelperBase::SetTspState() 02/17/2005 11:29:19.500 CiscoTSP001.tsp| CQBEHelperBase::StartConnectThread() Created m_hTerminateConnectThreadEvent = 1640 02/17/2005 11:29:19.500 CiscoTSP001.tsp|-->CQBEHelperBase::ConnectToCtiManager() 02/17/2005 11:29:19.500 CiscoTSP001.tsp|-->CSelsiusTSP::ReadCtiMgrLocation() 02/17/2005 11:29:19.500 CiscoTSP001.tsp| CSelsiusTSP::ReadCtiMgrLocation() Primary CTI Manager is IP address [192.168.1.100] 02/17/2005 11:29:19.500 CiscoTSP001.tsp| CSelsiusTSP::ReadCtiMgrLocation() Primary CTI Manager address: 0x6401A8C0 02/17/2005 11:29:19.500 CiscoTSP001.tsp| CSelsiusTSP::ReadCtiMgrLocation() Backup CTI Manager is not configured 02/17/2005 11:29:19.500 CiscoTSP001.tsp|-->CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() 02/17/2005 11:29:19.500 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() Synchronous message timeout 15000 02/17/2005 11:29:19.500 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() ProviderOpen Synchronous message timeout 30000 02/17/2005 11:29:19.500 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() Heartbeat interval 30 02/17/2005 11:29:19.500 CiscoTSP001.tsp| CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() Connect retry interval 30000 02/17/2005 11:29:19.500 CiscoTSP001.tsp|<--CSelsiusTSPUIMsgGetInfo::ReadTimerSettingValuesFromRegistry() 02/17/2005 11:29:19.500 CiscoTSP001.tsp|-->CSelsiusTSPUIMsgGetInfo::ValidateTimerSettingParameters() 02/17/2005 11:29:19.500 CiscoTSP001.tsp|<--CSelsiusTSPUIMsgGetInfo::ValidateTimerSettingParameters() 02/17/2005 11:29:19.500 CiscoTSP001.tsp|<--CSelsiusTSP::ReadCtiMgrLocation() 02/17/2005 11:29:19.500 CiscoTSP001.tsp| CQBEHelperBase::ConnectToCtiManager() ReadCtiMgrLocation() returned 0 02/17/2005 11:29:19.500 CiscoTSP001.tsp|-->CCtiInterface::ConnectLink() 02/17/2005 11:29:19.500 CiscoTSP001.tsp|-->CSelsiusTSPLink::Socket() 02/17/2005 11:29:22.406 CiscoTSP001.tsp|<--CSelsiusTSPLink::Socket() 02/17/2005 11:29:22.406 CiscoTSP001.tsp|-->CSelsiusTSPLink::Connect() 02/17/2005 11:29:22.421 CiscoTSP001.tsp|-->Connect() 02/17/2005 11:29:22.421 CiscoTSP001.tsp| Connect() Connected to Call Manager 02/17/2005 11:29:22.421 CiscoTSP001.tsp|<--Connect() 02/17/2005 11:29:22.421 CiscoTSP001.tsp|<--CSelsiusTSPLink::Connect() 02/17/2005 11:29:22.421 CiscoTSP001.tsp| CCtiInterface::ConnectLink() Connected to CTI Manager 02/17/2005 11:29:22.421 CiscoTSP001.tsp|<--CCtiInterface::ConnectLink() 02/17/2005 11:29:22.421 CiscoTSP001.tsp| CQBEHelperBase::ConnectToCtiManager() Connected to CTI Manager: [0] address: 0x6401A8C0, port: 0xabc 02/17/2005 11:29:22.421 CiscoTSP001.tsp|<--CQBEHelperBase::ConnectToCtiManager() 02/17/2005 11:29:22.421 CiscoTSP001.tsp|-->CQBEHelperBase::InitializeTSP() 02/17/2005 11:29:22.421 CiscoTSP001.tsp|-->CQBEHelperBase::SetTspState() 02/17/2005 11:29:22.421 CiscoTSP001.tsp| CQBEHelperBase::SetTspState() TSPState = 1 02/17/2005 11:29:22.421 CiscoTSP001.tsp|<--CQBEHelperBase::SetTspState() 02/17/2005 11:29:22.437 CiscoTSP001.tsp|-->CCtiInterface::ReceiveThreadEntry() 02/17/2005 11:29:22.437 CiscoTSP001.tsp|-->CCtiInterface::LoadTSPLib() 02/17/2005 11:29:22.437 CiscoTSP001.tsp|-->CSelsiusTSP::LoadTSPLib() 02/17/2005 11:29:22.437 CiscoTSP001.tsp|<--CSelsiusTSP::LoadTSPLib() 02/17/2005 11:29:22.437 CiscoTSP001.tsp|<--CCtiInterface::LoadTSPLib() 02/17/2005 11:29:22.437 CiscoTSP001.tsp|-->CCtiInterface::ReceiveLoop() 02/17/2005 11:29:22.437 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:29:22.437 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:22.437 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:29:22.562 CiscoTSP001.tsp| CQBEHelperBase::InitializeTSP() TSPConnectState = TspConnectState_LinkConnected 02/17/2005 11:29:22.562 CiscoTSP001.tsp|-->CSelsiusTSP::ReadUserNamePassword() 02/17/2005 11:29:22.562 CiscoTSP001.tsp| CSelsiusTSP::ReadUserNamePassword() user name=riann 02/17/2005 11:29:22.562 CiscoTSP001.tsp|<--CSelsiusTSP::ReadUserNamePassword() 02/17/2005 11:29:22.671 CiscoTSP001.tsp| CQBEHelperBase::InitializeTSP() TSPConnectState = TspConnectState_OpenProvider 02/17/2005 11:29:22.671 CiscoTSP001.tsp|-->CQBEHelperBase::ProviderOpen() 02/17/2005 11:29:22.671 CiscoTSP001.tsp|-->CCtiInterface::SendProviderOpenRequest() 02/17/2005 11:29:22.703 CiscoTSP001.tsp|-->CCtiInterface::SendAndProcessProviderOpenRequest() 02/17/2005 11:29:22.703 CiscoTSP001.tsp|-->CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:22.703 CiscoTSP001.tsp| CCtiInterface::AddToSequenceNumberMap() Adding map entry for sequenceNumber(0x00000000) to responseObject(0x0048C1F8) 02/17/2005 11:29:22.703 CiscoTSP001.tsp|<--CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:22.703 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:29:22.828 CiscoTSP001.tsp|-->ProviderOpenRequest::Trace 02/17/2005 11:29:22.828 CiscoTSP001.tsp| ProviderOpenRequest::Trace :dwSequenceNumber = 0x00000000 02/17/2005 11:29:22.890 CiscoTSP001.tsp| ProviderOpenRequest::Trace dummyHeader.dwLen =190 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =3 dwHeaderSize =32 dwFixedSize =64 dwVariableSize =102 02/17/2005 11:29:22.890 CiscoTSP001.tsp| ProviderOpenRequest::Trace dwProviderNameOffset =0x00000060 dwProviderNameSize =23 ProviderName =CiscoTSP 3.3(0.24)ES02 dwLoginOffset =0x0000008E dwLoginSize =6 Login =riann dwPasswordOffset =0x00000094 dwPasswordSize =13 dwApplicationIDOffset =0x000000AD dwApplicationIDSize =25 ApplicationID = CiscoTSP001-192.168.1.26 dwQBEClientVersionOffset = 0x00000077 dwQBEClientVersionSize = 23 QBEClientVersion = CiscoTSP 3.3(0.24)ES02 02/17/2005 11:29:22.890 CiscoTSP001.tsp| ProviderOpenRequest::Trace dwProviderEventFilterOffset =0x000000A1 dwProviderEventFilterSize =12 dwProviderEventFilterFixedSize =12 ProviderEventFilter_t.bDeviceRegistered =0xFFFFFFFF ProviderEventFilter_t.bDeviceUnRegistered =0xFFFFFFFF ProviderEventFilter_t.bDirectoryChangeNotify =0xFFFFFFFF dwWantServerHeartbeat =0x0000001E dwCMAssignedApplicationID =0x00000000 02/17/2005 11:29:22.890 CiscoTSP001.tsp|<--ProviderOpenRequest::Trace 02/17/2005 11:29:22.890 CiscoTSP001.tsp| SendMessage() sent message number 3, sequence number 0, 198 bytes 02/17/2005 11:29:22.890 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:29:22.890 CiscoTSP001.tsp|-->CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:22.890 CiscoTSP001.tsp| CCtiInterface::WaitForSynchronousResponse() Waiting to receive synchronous response or timeout 15000 02/17/2005 11:29:22.906 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:29:22.906 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=4, length=31 02/17/2005 11:29:22.906 CiscoTSP001.tsp|-->ProviderOpenResponse::Trace 02/17/2005 11:29:22.906 CiscoTSP001.tsp| ProviderOpenResponse::Trace :dwSequenceNumber = 0x00000000 02/17/2005 11:29:22.906 CiscoTSP001.tsp| ProviderOpenResponse::Trace dummyHeader.dwLen =55 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =4 dwHeaderSize =32 dwFixedSize =20 dwVariableSize =11 02/17/2005 11:29:22.906 CiscoTSP001.tsp| ProviderOpenResponse::Trace dwProviderInfoOffset =0x00000034 dwProviderInfoSize =11 ProviderInfo =3.3(3)sr4a dwClientHeartbeat =0x0000001E dwServerHeartbeat =0x0000001E 02/17/2005 11:29:22.906 CiscoTSP001.tsp|<--ProviderOpenResponse::Trace 02/17/2005 11:29:22.921 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 63 bytes, message number is 4 02/17/2005 11:29:22.921 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:22.921 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 4 02/17/2005 11:29:23.250 CiscoTSP001.tsp|-->CCtiInterface::ProcessResponse() 02/17/2005 11:29:23.250 CiscoTSP001.tsp|-->CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::FindAndRemoveSequenceNumber() Found mapping for sequenceNumber(0x00000000) to responseObject(0x0048C1F8) 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::ProcessResponse() wait till 15000 sec for the processing of ProviderOpenResponse 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::SendAndProcessProviderOpenRequest() EnteredCriticalSection m_SequenceNumberMapCriticalSection 02/17/2005 11:29:23.250 CiscoTSP001.tsp|-->CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() Removing map entry for sequenceNumber(0x00000000) 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() *ERROR* Map entry does not exists for sequenceNumber(0x00000000) 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:23.250 CiscoTSP001.tsp|-->CCtiInterface::ProcessProviderOpenResponse() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::ProcessProviderOpenResponse() CallManager Version 3.3(3)sr4a 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::ProcessProviderOpenResponse() ServerHeartbeat=30, ClientHeartbeat=30 02/17/2005 11:29:23.250 CiscoTSP001.tsp|-->CCtiInterface::StartHeartbeatThread() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::StartHeartbeatThread() Created heartbeat thread. 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CCtiInterface::StartHeartbeatThread() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::ProcessProviderOpenResponse() Result of provider open request is 0 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CCtiInterface::ProcessProviderOpenResponse() 02/17/2005 11:29:23.250 CiscoTSP001.tsp|-->CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::AddToSequenceNumberMap() Adding map entry for sequenceNumber(0x00000000) to responseObject(0x0048C1F8) 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::SendAndProcessProviderOpenRequest() Added ResponseObj in map for ProviderOpenCompletedEvent - LeavingCriticalSection m_SequenceNumberMapCriticalSection 02/17/2005 11:29:23.250 CiscoTSP001.tsp|-->CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::WaitForSynchronousResponse() Waiting to receive synchronous response or timeout 30000 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::ProcessResponse() WaitForSingleObject (m_hProviderOpenResponseProcessingDone) Successful 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CCtiInterface::ProcessResponse() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:29:23.250 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=126, length=47 02/17/2005 11:29:23.250 CiscoTSP001.tsp|-->ProviderOpenCompletedEvent::Trace 02/17/2005 11:29:23.250 CiscoTSP001.tsp| ProviderOpenCompletedEvent::Trace dummyHeader.dwLen =71 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =126 dwHeaderSize =32 dwFixedSize =36 dwVariableSize =11 02/17/2005 11:29:23.250 CiscoTSP001.tsp| ProviderOpenCompletedEvent::Trace dwReason =0x00000000 dwSequnceNumber =0 dwProviderInfoOffset =68 dwProviderInfoSize =11 dwClientHeartbeat = 79 dwServerHeartbeat = 0 dwDescriptionOffset = 0x00000000 dwDescriptionSize = 0x004897F0 02/17/2005 11:29:23.250 CiscoTSP001.tsp| ProviderOpenCompletedEvent::Trace ProviderInfo = 3.3(3)sr4a Description = bMonitorCallParkDNs = 0 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--ProviderOpenCompletedEvent::Trace 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 79 bytes, message number is 126 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 126 02/17/2005 11:29:23.250 CiscoTSP001.tsp|-->CCtiInterface::ProcessResponse() 02/17/2005 11:29:23.250 CiscoTSP001.tsp|-->CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::FindAndRemoveSequenceNumber() Found mapping for sequenceNumber(0x00000000) to responseObject(0x0048C1F8) 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CCtiInterface::ProcessResponse() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:29:23.250 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:23.250 CiscoTSP001.tsp|-->CCtiInterface::ProcessProviderOpenCompletedEvent() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::ProcessProviderOpenCompletedEvent() CallManager Version 3.3(3)sr4a 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::ProcessProviderOpenCompletedEvent() ServerHeartbeat=30, ClientHeartbeat=30 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::ProcessProviderOpenCompletedEvent() Result of provider open request is 0 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CCtiInterface::ProcessProviderOpenCompletedEvent() 02/17/2005 11:29:23.250 CiscoTSP001.tsp|-->CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() Removing map entry for sequenceNumber(0x00000000) 02/17/2005 11:29:23.250 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() *ERROR* Map entry does not exists for sequenceNumber(0x00000000) 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CCtiInterface::SendAndProcessProviderOpenRequest() 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CCtiInterface::SendProviderOpenRequest() 02/17/2005 11:29:23.250 CiscoTSP001.tsp|<--CQBEHelperBase::ProviderOpen() 02/17/2005 11:29:23.375 CiscoTSP001.tsp| CQBEHelperBase::InitializeTSP() TSPConnectState = TspConnectState_QueryProviderCapabilities 02/17/2005 11:29:23.421 CiscoTSP001.tsp|-->CCtiInterface::HeartbeatThreadEntry() 02/17/2005 11:29:23.421 CiscoTSP001.tsp|-->CCtiInterface::LoadTSPLib() 02/17/2005 11:29:23.421 CiscoTSP001.tsp|-->CSelsiusTSP::LoadTSPLib() 02/17/2005 11:29:23.421 CiscoTSP001.tsp|<--CSelsiusTSP::LoadTSPLib() 02/17/2005 11:29:23.421 CiscoTSP001.tsp|<--CCtiInterface::LoadTSPLib() 02/17/2005 11:29:23.421 CiscoTSP001.tsp|-->CCtiInterface::HeartbeatLoop() 02/17/2005 11:29:23.421 CiscoTSP001.tsp|-->CQBEHelperBase::CheckIsSuperProvider() 02/17/2005 11:29:23.531 CiscoTSP001.tsp|-->CCtiInterface::SendProviderGetCapabilitiesRequest() 02/17/2005 11:29:23.531 CiscoTSP001.tsp|-->CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:23.531 CiscoTSP001.tsp|-->CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:23.531 CiscoTSP001.tsp| CCtiInterface::AddToSequenceNumberMap() Adding map entry for sequenceNumber(0x00000001) to responseObject(0x0048C318) 02/17/2005 11:29:23.531 CiscoTSP001.tsp|<--CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:23.531 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:29:23.531 CiscoTSP001.tsp|-->ProviderGetCapabilitiesRequest::Trace 02/17/2005 11:29:23.531 CiscoTSP001.tsp| ProviderGetCapabilitiesRequest::Trace :dwSequenceNumber = 0x00000001 02/17/2005 11:29:23.531 CiscoTSP001.tsp| ProviderGetCapabilitiesRequest::Trace dummyHeader.dwLen =28 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =127 dwHeaderSize =32 dwFixedSize =4 dwVariableSize =0 02/17/2005 11:29:23.531 CiscoTSP001.tsp|<--ProviderGetCapabilitiesRequest::Trace 02/17/2005 11:29:23.531 CiscoTSP001.tsp| SendMessage() sent message number 127, sequence number 1, 36 bytes 02/17/2005 11:29:23.531 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:29:23.531 CiscoTSP001.tsp|-->CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:23.531 CiscoTSP001.tsp| CCtiInterface::WaitForSynchronousResponse() Waiting to receive synchronous response or timeout 15000 02/17/2005 11:29:23.531 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:29:23.531 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=128, length=24 02/17/2005 11:29:23.531 CiscoTSP001.tsp|-->ProviderGetCapabilitiesResponse::Trace 02/17/2005 11:29:23.531 CiscoTSP001.tsp| ProviderGetCapabilitiesResponse::Trace :dwSequenceNumber = 0x00000001 02/17/2005 11:29:23.531 CiscoTSP001.tsp| ProviderGetCapabilitiesResponse::Trace dummyHeader.dwLen =48 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =128 dwHeaderSize =32 dwFixedSize =16 dwVariableSize =8 02/17/2005 11:29:23.531 CiscoTSP001.tsp| ProviderGetCapabilitiesResponse::Trace dwCapabilitiesInfoOffset = 48 dwCapabilitiesInfoSize = 8 dwCapabilitiesInfoFixedSize = 8 bControlAnyDevice = 0 dwMaximumNumberDevicesOpened = 0 02/17/2005 11:29:23.531 CiscoTSP001.tsp|<--ProviderGetCapabilitiesResponse::Trace 02/17/2005 11:29:23.531 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 56 bytes, message number is 128 02/17/2005 11:29:23.531 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:23.531 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 128 02/17/2005 11:29:23.531 CiscoTSP001.tsp|-->CCtiInterface::ProcessResponse() 02/17/2005 11:29:23.531 CiscoTSP001.tsp|-->CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:23.531 CiscoTSP001.tsp| CCtiInterface::FindAndRemoveSequenceNumber() Found mapping for sequenceNumber(0x00000001) to responseObject(0x0048C318) 02/17/2005 11:29:23.531 CiscoTSP001.tsp|<--CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:23.531 CiscoTSP001.tsp|<--CCtiInterface::ProcessResponse() 02/17/2005 11:29:23.531 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:29:23.531 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:23.531 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:29:23.531 CiscoTSP001.tsp|<--CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:23.531 CiscoTSP001.tsp|-->CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:23.531 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() Removing map entry for sequenceNumber(0x00000001) 02/17/2005 11:29:23.531 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() *ERROR* Map entry does not exists for sequenceNumber(0x00000001) 02/17/2005 11:29:23.531 CiscoTSP001.tsp|<--CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:23.531 CiscoTSP001.tsp|<--CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:23.531 CiscoTSP001.tsp|-->CCtiInterface::ProcessProviderGetCapabilitiesResponse() 02/17/2005 11:29:23.531 CiscoTSP001.tsp| CCtiInterface::ProcessProviderGetCapabilitiesResponse() IsSuperProvider = 0, canOpenMaxDevices = 0, Result = 0 02/17/2005 11:29:23.531 CiscoTSP001.tsp|<--CCtiInterface::ProcessProviderGetCapabilitiesResponse() 02/17/2005 11:29:23.531 CiscoTSP001.tsp|<--CCtiInterface::SendProviderGetCapabilitiesRequest() 02/17/2005 11:29:23.531 CiscoTSP001.tsp| CQBEHelperBase::CheckIsSuperProvider() IsSuperProviderUser = 0, canOpenMaxDevices = 0 02/17/2005 11:29:23.531 CiscoTSP001.tsp|<--CQBEHelperBase::CheckIsSuperProvider() 02/17/2005 11:29:23.625 CiscoTSP001.tsp| CQBEHelperBase::InitializeTSP() TSPConnectState = TspConnectState_InitializeDevicesAndLines 02/17/2005 11:29:23.625 CiscoTSP001.tsp|-->CSelsiusTSP::InitializeLineList() 02/17/2005 11:29:24.359 CiscoTSP001.tsp|-->CSelsiusTSPLineList::CSelsiusTSPLineList() 02/17/2005 11:29:24.359 CiscoTSP001.tsp|<--CSelsiusTSPLineList::CSelsiusTSPLineList() 02/17/2005 11:29:24.359 CiscoTSP001.tsp| CSelsiusTSP::InitializeLineList() Return Value = 0x00000000 02/17/2005 11:29:24.359 CiscoTSP001.tsp|<--CSelsiusTSP::InitializeLineList() 02/17/2005 11:29:24.359 CiscoTSP001.tsp|-->CSelsiusTSP::InitializeDeviceList() 02/17/2005 11:29:25.031 CiscoTSP001.tsp| CSelsiusTSPWaveList::Init() ISelsiusNTWaveCtl Created 02/17/2005 11:29:25.031 CiscoTSP001.tsp| CSelsiusTSPWaveList::Init() GetNumDevice() returned 0 02/17/2005 11:29:25.031 CiscoTSP001.tsp| CSelsiusTSPWaveList::Init() pWaveCtl->AddTSPSinkToMap done 02/17/2005 11:29:25.031 CiscoTSP001.tsp|-->CSelsiusTSPWaveList::SetSilenceDetectionParameters() 02/17/2005 11:29:25.031 CiscoTSP001.tsp|<--CSelsiusTSPWaveList::SetSilenceDetectionParameters() 02/17/2005 11:29:25.031 CiscoTSP001.tsp|<--CSelsiusTSPWaveList::Init() 02/17/2005 11:29:25.031 CiscoTSP001.tsp|<--SelsiusTSP::InitializeComObjects() 02/17/2005 11:29:25.031 CiscoTSP001.tsp|-->SelsiusTSP::SignalingLoop() 02/17/2005 11:29:25.031 CiscoTSP001.tsp| SelsiusTSP::SignalingLoop() event thread waiting for signal from main thread that there is a request 02/17/2005 11:29:25.046 CiscoTSP001.tsp|-->CSelsiusTSPDeviceList::CSelsiusTSPDeviceList() 02/17/2005 11:29:25.046 CiscoTSP001.tsp|<--CSelsiusTSPDeviceList::CSelsiusTSPDeviceList() 02/17/2005 11:29:25.046 CiscoTSP001.tsp|-->CSelsiusTSPDeviceList::Init() 02/17/2005 11:29:25.046 CiscoTSP001.tsp| CSelsiusTSPDeviceList::Init() DeviceList::Init state = DeviceInitState_GetControllableDeviceInfo 02/17/2005 11:29:25.046 CiscoTSP001.tsp|-->CSelsiusTSPDeviceList::AddDeviceType() 02/17/2005 11:29:25.125 CiscoTSP001.tsp|-->CCtiInterface::SendGetDeviceInfoRequest() 02/17/2005 11:29:25.125 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoRequest() Tracing PROVIDER_GET_DEVICE_INFO_REQUEST (13) 02/17/2005 11:29:25.125 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoRequest() dwSequenceNumber = 4718592 02/17/2005 11:29:25.125 CiscoTSP001.tsp|-->CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:25.125 CiscoTSP001.tsp|-->CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:25.125 CiscoTSP001.tsp| CCtiInterface::AddToSequenceNumberMap() Adding map entry for sequenceNumber(0x00000002) to responseObject(0x0048C318) 02/17/2005 11:29:25.125 CiscoTSP001.tsp|<--CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:25.125 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:29:25.125 CiscoTSP001.tsp|-->ProviderGetDeviceInfoRequest::Trace 02/17/2005 11:29:25.125 CiscoTSP001.tsp| ProviderGetDeviceInfoRequest::Trace :dwSequenceNumber = 0x00000002 02/17/2005 11:29:25.125 CiscoTSP001.tsp| ProviderGetDeviceInfoRequest::Trace dummyHeader.dwLen =36 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =13 dwHeaderSize =32 dwFixedSize =12 dwVariableSize =0 02/17/2005 11:29:25.125 CiscoTSP001.tsp| ProviderGetDeviceInfoRequest::Trace dwDeviceGroup =0x00000001 bEnumerateRegisterableDevices =0x00000030 02/17/2005 11:29:25.125 CiscoTSP001.tsp|<--ProviderGetDeviceInfoRequest::Trace 02/17/2005 11:29:25.125 CiscoTSP001.tsp| SendMessage() sent message number 13, sequence number 2, 44 bytes 02/17/2005 11:29:25.125 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:29:25.125 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=14, length=8 02/17/2005 11:29:25.125 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:29:25.125 CiscoTSP001.tsp|-->CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:25.125 CiscoTSP001.tsp| CCtiInterface::WaitForSynchronousResponse() Waiting to receive synchronous response or timeout 15000 02/17/2005 11:29:25.156 CiscoTSP001.tsp|-->ProviderGetDeviceInfoResponse::Trace 02/17/2005 11:29:25.156 CiscoTSP001.tsp| ProviderGetDeviceInfoResponse::Trace :dwSequenceNumber = 0x00000002 02/17/2005 11:29:25.156 CiscoTSP001.tsp| ProviderGetDeviceInfoResponse::Trace dummyHeader.dwLen =32 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =14 dwHeaderSize =32 dwFixedSize =8 dwVariableSize =0 02/17/2005 11:29:25.156 CiscoTSP001.tsp| ProviderGetDeviceInfoResponse::Trace dwDeviceEnumerationHandle =0x00000003 02/17/2005 11:29:25.156 CiscoTSP001.tsp|<--ProviderGetDeviceInfoResponse::Trace 02/17/2005 11:29:25.156 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 40 bytes, message number is 14 02/17/2005 11:29:25.156 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:25.156 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 14 02/17/2005 11:29:25.203 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Tracing PROVIDER_GET_DEVICE_INFO_RESPONSE (14) 02/17/2005 11:29:25.203 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwSequenceNumber = 2 02/17/2005 11:29:25.203 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwDeviceEnumerationHandle = 3 02/17/2005 11:29:25.203 CiscoTSP001.tsp|-->CCtiInterface::ProcessResponse() 02/17/2005 11:29:25.203 CiscoTSP001.tsp|-->CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:25.203 CiscoTSP001.tsp| CCtiInterface::FindAndRemoveSequenceNumber() Found mapping for sequenceNumber(0x00000002) to responseObject(0x0048C318) 02/17/2005 11:29:25.203 CiscoTSP001.tsp|<--CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:25.203 CiscoTSP001.tsp|<--CCtiInterface::ProcessResponse() 02/17/2005 11:29:25.203 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:29:25.203 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:25.203 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:29:25.218 CiscoTSP001.tsp|<--CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:25.218 CiscoTSP001.tsp|-->CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:25.218 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() Removing map entry for sequenceNumber(0x00000002) 02/17/2005 11:29:25.218 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() *ERROR* Map entry does not exists for sequenceNumber(0x00000002) 02/17/2005 11:29:25.218 CiscoTSP001.tsp|<--CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:25.218 CiscoTSP001.tsp|<--CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:25.218 CiscoTSP001.tsp|-->CCtiInterface::ProcessProviderGetDeviceInfoResponse() 02/17/2005 11:29:25.218 CiscoTSP001.tsp| CCtiInterface::ProcessProviderGetDeviceInfoResponse() Result of get device info request is 0 02/17/2005 11:29:25.218 CiscoTSP001.tsp|<--CCtiInterface::ProcessProviderGetDeviceInfoResponse() 02/17/2005 11:29:25.218 CiscoTSP001.tsp|<--CCtiInterface::SendGetDeviceInfoRequest() 02/17/2005 11:29:25.218 CiscoTSP001.tsp|-->CCtiInterface::SendGetDeviceInfoFetchRequest() 02/17/2005 11:29:25.218 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoFetchRequest() Tracing GET_DEVICE_INFO_FETCH_REQUEST (94) 02/17/2005 11:29:25.218 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoFetchRequest() dwSequenceNumber = 1231647811 02/17/2005 11:29:25.218 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoFetchRequest() dwCount = 100 02/17/2005 11:29:25.218 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoFetchRequest() dwEnumerationHandle = 3 02/17/2005 11:29:25.218 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoFetchRequest() dwType = 72 02/17/2005 11:29:25.218 CiscoTSP001.tsp|-->CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:25.218 CiscoTSP001.tsp|-->CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:25.218 CiscoTSP001.tsp| CCtiInterface::AddToSequenceNumberMap() Adding map entry for sequenceNumber(0x00000003) to responseObject(0x0048C318) 02/17/2005 11:29:25.218 CiscoTSP001.tsp|<--CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:25.218 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:29:25.218 CiscoTSP001.tsp|-->GetDeviceInfoFetchRequest::Trace 02/17/2005 11:29:25.218 CiscoTSP001.tsp| GetDeviceInfoFetchRequest::Trace :dwSequenceNumber = 0x00000003 02/17/2005 11:29:25.218 CiscoTSP001.tsp| GetDeviceInfoFetchRequest::Trace dummyHeader.dwLen =40 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =94 dwHeaderSize =32 dwFixedSize =16 dwVariableSize =0 02/17/2005 11:29:25.218 CiscoTSP001.tsp| GetDeviceInfoFetchRequest::Trace dwEnumerationHandle = 0x00000003 dwCount =100 dwType =72 02/17/2005 11:29:25.218 CiscoTSP001.tsp|<--GetDeviceInfoFetchRequest::Trace 02/17/2005 11:29:25.218 CiscoTSP001.tsp| SendMessage() sent message number 94, sequence number 3, 48 bytes 02/17/2005 11:29:25.218 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:29:25.218 CiscoTSP001.tsp|-->CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:25.218 CiscoTSP001.tsp| CCtiInterface::WaitForSynchronousResponse() Waiting to receive synchronous response or timeout 15000 02/17/2005 11:29:25.281 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=95, length=56 02/17/2005 11:29:25.375 CiscoTSP001.tsp|-->GetDeviceInfoFetchResponse::Trace 02/17/2005 11:29:25.375 CiscoTSP001.tsp| GetDeviceInfoFetchResponse::Trace :dwSequenceNumber = 0x00000003 02/17/2005 11:29:25.375 CiscoTSP001.tsp| GetDeviceInfoFetchResponse::Trace dummyHeader.dwLen =80 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =95 dwHeaderSize =32 dwFixedSize =24 dwVariableSize =32 02/17/2005 11:29:25.375 CiscoTSP001.tsp| GetDeviceInfoFetchResponse::Trace dwInfoOffset =0x00000038 dwInfoSize =20 dwInfoCount =1 dwInfoElementFixedSize =20 bMore =0x00000000 02/17/2005 11:29:25.375 CiscoTSP001.tsp| GetDeviceInfoFetchResponse::Trace DeviceInfo[0] dwNameOffset = 0x0000004C dwNameSize = 12 DeviceName =CIT90_Riann dwType = 0x00000048 bAllowsRegistration = 0x00000001 dwDeviceId = 0x4BC1641E 02/17/2005 11:29:25.375 CiscoTSP001.tsp|<--GetDeviceInfoFetchResponse::Trace 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 88 bytes, message number is 95 02/17/2005 11:29:25.375 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 95 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Tracing GET_DEVICE_INFO_FETCH_RESPONSE (95) 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwSequenceNumber = 3 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() bMore = 0 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwInfoCount = 1 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwInfoElementFixedSize = 20 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwInfoOffset = 56 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwInfoSize = 20 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received 1 devices 02/17/2005 11:29:25.375 CiscoTSP001.tsp|-->CCtiInterface::ProcessResponse() 02/17/2005 11:29:25.375 CiscoTSP001.tsp|-->CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CCtiInterface::FindAndRemoveSequenceNumber() Found mapping for sequenceNumber(0x00000003) to responseObject(0x0048C318) 02/17/2005 11:29:25.375 CiscoTSP001.tsp|<--CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:25.375 CiscoTSP001.tsp|<--CCtiInterface::ProcessResponse() 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:29:25.375 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:29:25.375 CiscoTSP001.tsp|<--CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:25.375 CiscoTSP001.tsp|-->CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() Removing map entry for sequenceNumber(0x00000003) 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() *ERROR* Map entry does not exists for sequenceNumber(0x00000003) 02/17/2005 11:29:25.375 CiscoTSP001.tsp|<--CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:25.375 CiscoTSP001.tsp|<--CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:25.375 CiscoTSP001.tsp|-->CCtiInterface::ProcessGetDeviceInfoFetchResponse() 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CCtiInterface::ProcessGetDeviceInfoFetchResponse() Result of get device info fetch request is 0 02/17/2005 11:29:25.375 CiscoTSP001.tsp|<--CCtiInterface::ProcessGetDeviceInfoFetchResponse() 02/17/2005 11:29:25.375 CiscoTSP001.tsp|<--CCtiInterface::SendGetDeviceInfoFetchRequest() 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CSelsiusTSPDeviceList::AddDeviceType() number of devices=1, moreDevices=0 02/17/2005 11:29:25.375 CiscoTSP001.tsp|-->CSelsiusTSPDeviceList::AddDevice() 02/17/2005 11:29:25.375 CiscoTSP001.tsp| CSelsiusTSPDeviceList::AddDevice() deviceType=72 deviceName=[CIT90_Riann] permDeviceID=ox4BC1641E sendCreateMessages=0 02/17/2005 11:29:25.828 CiscoTSP001.tsp|-->CSelsiusTSPDevice::Initialize() 02/17/2005 11:29:25.828 CiscoTSP001.tsp| CSelsiusTSPDevice::Initialize() device name CIT90_Riann, device type 72 02/17/2005 11:29:25.828 CiscoTSP001.tsp|-->CSelsiusTSPDevice::ClearLines() 02/17/2005 11:29:25.828 CiscoTSP001.tsp|<--CSelsiusTSPDevice::ClearLines() 02/17/2005 11:29:25.828 CiscoTSP001.tsp|-->CSelsiusTSPDeviceList::GetLineList() 02/17/2005 11:29:25.828 CiscoTSP001.tsp|-->CCtiInterface::SendDeviceGetLineInfoRequest() [CIT90_Riann] 02/17/2005 11:29:25.828 CiscoTSP001.tsp| CCtiInterface::SendDeviceGetLineInfoRequest() [CIT90_Riann] Sending DEVICE_GET_LINE_INFO_REQUEST 02/17/2005 11:29:25.828 CiscoTSP001.tsp|-->CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:25.828 CiscoTSP001.tsp|-->CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:25.828 CiscoTSP001.tsp| CCtiInterface::AddToSequenceNumberMap() Adding map entry for sequenceNumber(0x00000004) to responseObject(0x0048CBF0) 02/17/2005 11:29:25.828 CiscoTSP001.tsp|<--CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:25.828 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:29:25.828 CiscoTSP001.tsp|-->DeviceGetLineInfoRequest::Trace 02/17/2005 11:29:25.828 CiscoTSP001.tsp| DeviceGetLineInfoRequest::Trace :dwSequenceNumber = 0x00000004 02/17/2005 11:29:25.828 CiscoTSP001.tsp| DeviceGetLineInfoRequest::Trace dummyHeader.dwLen =48 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =24 dwHeaderSize =32 dwFixedSize =12 dwVariableSize =12 02/17/2005 11:29:25.828 CiscoTSP001.tsp| DeviceGetLineInfoRequest::Trace dwDeviceNameOffset =0x0000002C dwDeviceNameSize =12 DeviceName =CIT90_Riann 02/17/2005 11:29:25.828 CiscoTSP001.tsp|<--DeviceGetLineInfoRequest::Trace 02/17/2005 11:29:25.828 CiscoTSP001.tsp| SendMessage() sent message number 24, sequence number 4, 56 bytes 02/17/2005 11:29:25.828 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:29:25.828 CiscoTSP001.tsp|-->CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:25.828 CiscoTSP001.tsp| CCtiInterface::WaitForSynchronousResponse() Waiting to receive synchronous response or timeout 15000 02/17/2005 11:29:25.828 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:29:25.828 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=25, length=8 02/17/2005 11:29:25.828 CiscoTSP001.tsp|-->DeviceGetLineInfoResponse::Trace 02/17/2005 11:29:25.828 CiscoTSP001.tsp| DeviceGetLineInfoResponse::Trace :dwSequenceNumber = 0x00000004 02/17/2005 11:29:25.828 CiscoTSP001.tsp| DeviceGetLineInfoResponse::Trace dummyHeader.dwLen =32 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =25 dwHeaderSize =32 dwFixedSize =8 dwVariableSize =0 02/17/2005 11:29:25.828 CiscoTSP001.tsp| DeviceGetLineInfoResponse::Trace dwLineEnumerationHandle =0x00000001 02/17/2005 11:29:25.828 CiscoTSP001.tsp|<--DeviceGetLineInfoResponse::Trace 02/17/2005 11:29:25.828 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 40 bytes, message number is 25 02/17/2005 11:29:25.828 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:25.828 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 25 02/17/2005 11:29:25.859 CiscoTSP001.tsp|-->CCtiInterface::ProcessResponse() 02/17/2005 11:29:25.859 CiscoTSP001.tsp|-->CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:25.859 CiscoTSP001.tsp| CCtiInterface::FindAndRemoveSequenceNumber() Found mapping for sequenceNumber(0x00000004) to responseObject(0x0048CBF0) 02/17/2005 11:29:25.859 CiscoTSP001.tsp|<--CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:25.859 CiscoTSP001.tsp|<--CCtiInterface::ProcessResponse() 02/17/2005 11:29:25.859 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:29:25.859 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:25.859 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:29:25.859 CiscoTSP001.tsp|<--CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:25.859 CiscoTSP001.tsp|-->CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:25.859 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() Removing map entry for sequenceNumber(0x00000004) 02/17/2005 11:29:25.859 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() *ERROR* Map entry does not exists for sequenceNumber(0x00000004) 02/17/2005 11:29:25.859 CiscoTSP001.tsp|<--CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:25.859 CiscoTSP001.tsp|<--CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:25.859 CiscoTSP001.tsp|-->CCtiInterface::ProcessDeviceGetLineInfoResponse() 02/17/2005 11:29:25.859 CiscoTSP001.tsp| CCtiInterface::ProcessDeviceGetLineInfoResponse() Result of device get line info request is 0 02/17/2005 11:29:25.859 CiscoTSP001.tsp|<--CCtiInterface::ProcessDeviceGetLineInfoResponse() 02/17/2005 11:29:25.859 CiscoTSP001.tsp|<--CCtiInterface::SendDeviceGetLineInfoRequest() [CIT90_Riann] 02/17/2005 11:29:25.859 CiscoTSP001.tsp|-->CSelsiusTSPDeviceList::LineAssignments() 02/17/2005 11:29:25.859 CiscoTSP001.tsp|-->CCtiInterface::SendGetLineInfoFetchRequest() 02/17/2005 11:29:25.859 CiscoTSP001.tsp| CCtiInterface::SendGetLineInfoFetchRequest() Sending GET_LINE_INFO_FETCH_REQUEST 02/17/2005 11:29:25.859 CiscoTSP001.tsp|-->CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:25.859 CiscoTSP001.tsp|-->CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:25.859 CiscoTSP001.tsp| CCtiInterface::AddToSequenceNumberMap() Adding map entry for sequenceNumber(0x00000005) to responseObject(0x0048CC28) 02/17/2005 11:29:25.859 CiscoTSP001.tsp|<--CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:25.859 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:29:25.859 CiscoTSP001.tsp|-->GetLineInfoFetchRequest::Trace 02/17/2005 11:29:25.859 CiscoTSP001.tsp| GetLineInfoFetchRequest::Trace :dwSequenceNumber = 0x00000005 02/17/2005 11:29:25.859 CiscoTSP001.tsp| GetLineInfoFetchRequest::Trace dummyHeader.dwLen =36 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =98 dwHeaderSize =32 dwFixedSize =12 dwVariableSize =0 02/17/2005 11:29:25.859 CiscoTSP001.tsp| GetLineInfoFetchRequest::Trace dwEnumerationHandle = 0x00000001 dwCount =100 02/17/2005 11:29:25.859 CiscoTSP001.tsp|<--GetLineInfoFetchRequest::Trace 02/17/2005 11:29:25.859 CiscoTSP001.tsp| SendMessage() sent message number 98, sequence number 5, 44 bytes 02/17/2005 11:29:25.859 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:29:25.859 CiscoTSP001.tsp|-->CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:25.859 CiscoTSP001.tsp| CCtiInterface::WaitForSynchronousResponse() Waiting to receive synchronous response or timeout 15000 02/17/2005 11:29:25.875 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:29:25.875 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=99, length=41 02/17/2005 11:29:25.875 CiscoTSP001.tsp|-->GetLineInfoFetchResponse::Trace 02/17/2005 11:29:25.875 CiscoTSP001.tsp| GetLineInfoFetchResponse::Trace :dwSequenceNumber = 0x00000005 02/17/2005 11:29:25.875 CiscoTSP001.tsp| GetLineInfoFetchResponse::Trace dummyHeader.dwLen =65 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =99 dwHeaderSize =32 dwFixedSize =24 dwVariableSize =17 02/17/2005 11:29:25.875 CiscoTSP001.tsp| GetLineInfoFetchResponse::Trace dwLineInfoOffset =0x00000038 dwLineInfoSize =12 dwLineInfoCount =1 dwLineInfoElementFixedSize =12 bMore =0x00000000 02/17/2005 11:29:25.875 CiscoTSP001.tsp| GetLineInfoFetchResponse::Trace LineInfo[0] dwNameOffset = 0x00000044 dwNameSize = 5 LineName =3190 dwPermanentLineID = 0x24691520 02/17/2005 11:29:25.875 CiscoTSP001.tsp|<--GetLineInfoFetchResponse::Trace 02/17/2005 11:29:25.875 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 73 bytes, message number is 99 02/17/2005 11:29:25.875 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:25.875 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 99 02/17/2005 11:29:25.875 CiscoTSP001.tsp|-->CCtiInterface::ProcessResponse() 02/17/2005 11:29:25.875 CiscoTSP001.tsp|-->CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:25.875 CiscoTSP001.tsp| CCtiInterface::FindAndRemoveSequenceNumber() Found mapping for sequenceNumber(0x00000005) to responseObject(0x0048CC28) 02/17/2005 11:29:25.875 CiscoTSP001.tsp|<--CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:25.875 CiscoTSP001.tsp|<--CCtiInterface::ProcessResponse() 02/17/2005 11:29:25.875 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:29:25.875 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:25.875 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() Removing map entry for sequenceNumber(0x00000005) 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() *ERROR* Map entry does not exists for sequenceNumber(0x00000005) 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->CCtiInterface::ProcessGetLineInfoFetchResponse() 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CCtiInterface::ProcessGetLineInfoFetchResponse() Received 1 line(s) (more=0) 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CCtiInterface::ProcessGetLineInfoFetchResponse() Result of get line info fetch request is 0 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CCtiInterface::ProcessGetLineInfoFetchResponse() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CCtiInterface::SendGetLineInfoFetchRequest() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CSelsiusTSPDeviceList::LineAssignments() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->CCtiInterface::SendGetLineInfoCloseRequest() 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CCtiInterface::SendGetLineInfoCloseRequest() Sending GET_LINE_INFO_CLOSE_REQUEST 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CCtiInterface::AddToSequenceNumberMap() Adding map entry for sequenceNumber(0x00000006) to responseObject(0x0048CC28) 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->GetLineInfoCloseRequest::Trace 02/17/2005 11:29:25.906 CiscoTSP001.tsp| GetLineInfoCloseRequest::Trace :dwSequenceNumber = 0x00000006 02/17/2005 11:29:25.906 CiscoTSP001.tsp| GetLineInfoCloseRequest::Trace dummyHeader.dwLen =32 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =100 dwHeaderSize =32 dwFixedSize =8 dwVariableSize =0 02/17/2005 11:29:25.906 CiscoTSP001.tsp| GetLineInfoCloseRequest::Trace dwEnumerationHandle = 0x00000001 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--GetLineInfoCloseRequest::Trace 02/17/2005 11:29:25.906 CiscoTSP001.tsp| SendMessage() sent message number 100, sequence number 6, 40 bytes 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CCtiInterface::WaitForSynchronousResponse() Waiting to receive synchronous response or timeout 15000 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=101, length=4 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->GetLineInfoCloseResponse::Trace 02/17/2005 11:29:25.906 CiscoTSP001.tsp| GetLineInfoCloseResponse::Trace :dwSequenceNumber = 0x00000006 02/17/2005 11:29:25.906 CiscoTSP001.tsp| GetLineInfoCloseResponse::Trace dummyHeader.dwLen =28 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =101 dwHeaderSize =32 dwFixedSize =4 dwVariableSize =0 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--GetLineInfoCloseResponse::Trace 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 36 bytes, message number is 101 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 101 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->CCtiInterface::ProcessResponse() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CCtiInterface::FindAndRemoveSequenceNumber() Found mapping for sequenceNumber(0x00000006) to responseObject(0x0048CC28) 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CCtiInterface::ProcessResponse() 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() Removing map entry for sequenceNumber(0x00000006) 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() *ERROR* Map entry does not exists for sequenceNumber(0x00000006) 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->CCtiInterface::ProcessGetLineInfoCloseResponse() 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CCtiInterface::ProcessGetLineInfoCloseResponse() Result of get line info close request is 0 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CCtiInterface::ProcessGetLineInfoCloseResponse() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CCtiInterface::SendGetLineInfoCloseRequest() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CSelsiusTSPDeviceList::GetLineList() 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CSelsiusTSPDevice::Initialize() Found 1 lines on device CIT90_Riann. 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->CSelsiusTSPLineList::AddLine() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|-->CSelsiusTSPLineList::FindLineByPermLineId() 02/17/2005 11:29:25.906 CiscoTSP001.tsp|<--CSelsiusTSPLineList::FindLineByPermLineId() 02/17/2005 11:29:25.906 CiscoTSP001.tsp| CSelsiusTSPLineList::AddLine() 3190 is a new line 02/17/2005 11:29:25.937 CiscoTSP001.tsp|-->CSelsiusTSPLineList::AddToPermLineIdMap() 02/17/2005 11:29:25.937 CiscoTSP001.tsp| CSelsiusTSPLineList::AddToPermLineIdMap() Adding map entry for permLineId(0x24691520) to line(0x0048B970) 02/17/2005 11:29:25.937 CiscoTSP001.tsp|<--CSelsiusTSPLineList::AddToPermLineIdMap() 02/17/2005 11:29:25.937 CiscoTSP001.tsp| CSelsiusTSPLineList::AddLine() dirn=[3190], permLineID=0x24691520, line=0x0048B970 02/17/2005 11:29:25.937 CiscoTSP001.tsp| CSelsiusTSPLineList::AddLine() Firing LINE_CREATE for 3190 02/17/2005 11:29:26.015 CiscoTSP001.tsp|-->CSelsiusTSPLine::FireLineCreateEvent() m_TAPIPermanentLineID=[0x24691520] 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CSelsiusTSPLine::FireLineCreateEvent() m_TAPIPermanentLineID=[0x24691520] Not firing TAPI message. 02/17/2005 11:29:26.015 CiscoTSP001.tsp|<--CSelsiusTSPLine::FireLineCreateEvent() m_TAPIPermanentLineID=[0x24691520] 02/17/2005 11:29:26.015 CiscoTSP001.tsp|<--CSelsiusTSPLineList::AddLine() 02/17/2005 11:29:26.015 CiscoTSP001.tsp|-->CSelsiusTSPDevice::AddToLines() 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CSelsiusTSPDevice::AddToLines() Adding map entry for permLineId(0x24691520) to line(0x0048B970) 02/17/2005 11:29:26.015 CiscoTSP001.tsp|<--CSelsiusTSPDevice::AddToLines() 02/17/2005 11:29:26.015 CiscoTSP001.tsp|<--CSelsiusTSPDevice::Initialize() 02/17/2005 11:29:26.015 CiscoTSP001.tsp|-->CSelsiusTSPDeviceList::AddToPermDeviceID2DeviceMap() 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CSelsiusTSPDeviceList::AddToPermDeviceID2DeviceMap() Adding map entry for permDeviceID(0x4BC1641E) to device(0x01017260) 02/17/2005 11:29:26.015 CiscoTSP001.tsp|<--CSelsiusTSPDeviceList::AddToPermDeviceID2DeviceMap() 02/17/2005 11:29:26.015 CiscoTSP001.tsp|<--CSelsiusTSPDeviceList::AddDevice() 02/17/2005 11:29:26.015 CiscoTSP001.tsp|-->CQBEHelperBase::IsCTIError() 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CQBEHelperBase::IsCTIError() Error = 0x00000000 is NOT a CTI ERROR 02/17/2005 11:29:26.015 CiscoTSP001.tsp|<--CQBEHelperBase::IsCTIError() 02/17/2005 11:29:26.015 CiscoTSP001.tsp|-->CCtiInterface::SendGetDeviceInfoCloseRequest() 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoCloseRequest() Tracing GET_DEVICE_INFO_CLOSE_REQUEST (96) 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoCloseRequest() dwSequenceNumber = 0 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoCloseRequest() dwEnumerationHandle = 3 02/17/2005 11:29:26.015 CiscoTSP001.tsp|-->CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:26.015 CiscoTSP001.tsp|-->CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CCtiInterface::AddToSequenceNumberMap() Adding map entry for sequenceNumber(0x00000007) to responseObject(0x0048CBF0) 02/17/2005 11:29:26.015 CiscoTSP001.tsp|<--CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:26.015 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:29:26.015 CiscoTSP001.tsp|-->GetDeviceInfoCloseRequest::Trace 02/17/2005 11:29:26.015 CiscoTSP001.tsp| GetDeviceInfoCloseRequest::Trace :dwSequenceNumber = 0x00000007 02/17/2005 11:29:26.015 CiscoTSP001.tsp| GetDeviceInfoCloseRequest::Trace dummyHeader.dwLen =32 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =96 dwHeaderSize =32 dwFixedSize =8 dwVariableSize =0 02/17/2005 11:29:26.015 CiscoTSP001.tsp| GetDeviceInfoCloseRequest::Trace dwEnumerationHandle = 0x00000003 02/17/2005 11:29:26.015 CiscoTSP001.tsp|<--GetDeviceInfoCloseRequest::Trace 02/17/2005 11:29:26.015 CiscoTSP001.tsp| SendMessage() sent message number 96, sequence number 7, 40 bytes 02/17/2005 11:29:26.015 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:29:26.015 CiscoTSP001.tsp|-->CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CCtiInterface::WaitForSynchronousResponse() Waiting to receive synchronous response or timeout 15000 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=97, length=4 02/17/2005 11:29:26.015 CiscoTSP001.tsp|-->GetDeviceInfoCloseResponse::Trace 02/17/2005 11:29:26.015 CiscoTSP001.tsp| GetDeviceInfoCloseResponse::Trace :dwSequenceNumber = 0x00000007 02/17/2005 11:29:26.015 CiscoTSP001.tsp| GetDeviceInfoCloseResponse::Trace dummyHeader.dwLen =28 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =97 dwHeaderSize =32 dwFixedSize =4 dwVariableSize =0 02/17/2005 11:29:26.015 CiscoTSP001.tsp|<--GetDeviceInfoCloseResponse::Trace 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 36 bytes, message number is 97 02/17/2005 11:29:26.015 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 97 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Tracing GET_DEVICE_INFO_CLOSE_RESPONSE (97) 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwSequenceNumber = 7 02/17/2005 11:29:26.015 CiscoTSP001.tsp|-->CCtiInterface::ProcessResponse() 02/17/2005 11:29:26.015 CiscoTSP001.tsp|-->CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CCtiInterface::FindAndRemoveSequenceNumber() Found mapping for sequenceNumber(0x00000007) to responseObject(0x0048CBF0) 02/17/2005 11:29:26.015 CiscoTSP001.tsp|<--CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:26.015 CiscoTSP001.tsp|<--CCtiInterface::ProcessResponse() 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:29:26.015 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:26.015 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() Removing map entry for sequenceNumber(0x00000007) 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() *ERROR* Map entry does not exists for sequenceNumber(0x00000007) 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CCtiInterface::ProcessGetDeviceInfoCloseResponse() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::ProcessGetDeviceInfoCloseResponse() Result of get device info request is 0 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CCtiInterface::ProcessGetDeviceInfoCloseResponse() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CCtiInterface::SendGetDeviceInfoCloseRequest() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CSelsiusTSPDeviceList::AddDeviceType() Return Value = 0x00000000 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CSelsiusTSPDeviceList::AddDeviceType() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CSelsiusTSPDeviceList::Init() DeviceList::Init state = DeviceInitState_GetLoggedInDeviceInfo 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CSelsiusTSPDeviceList::AddDeviceType() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CCtiInterface::SendGetDeviceInfoRequest() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoRequest() Tracing PROVIDER_GET_DEVICE_INFO_REQUEST (13) 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoRequest() dwSequenceNumber = 4718592 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::AddToSequenceNumberMap() Adding map entry for sequenceNumber(0x00000008) to responseObject(0x0048C318) 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->ProviderGetDeviceInfoRequest::Trace 02/17/2005 11:29:26.046 CiscoTSP001.tsp| ProviderGetDeviceInfoRequest::Trace :dwSequenceNumber = 0x00000008 02/17/2005 11:29:26.046 CiscoTSP001.tsp| ProviderGetDeviceInfoRequest::Trace dummyHeader.dwLen =36 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =13 dwHeaderSize =32 dwFixedSize =12 dwVariableSize =0 02/17/2005 11:29:26.046 CiscoTSP001.tsp| ProviderGetDeviceInfoRequest::Trace dwDeviceGroup =0x00000003 bEnumerateRegisterableDevices =0x00000030 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--ProviderGetDeviceInfoRequest::Trace 02/17/2005 11:29:26.046 CiscoTSP001.tsp| SendMessage() sent message number 13, sequence number 8, 44 bytes 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::WaitForSynchronousResponse() Waiting to receive synchronous response or timeout 15000 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=14, length=8 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->ProviderGetDeviceInfoResponse::Trace 02/17/2005 11:29:26.046 CiscoTSP001.tsp| ProviderGetDeviceInfoResponse::Trace :dwSequenceNumber = 0x00000008 02/17/2005 11:29:26.046 CiscoTSP001.tsp| ProviderGetDeviceInfoResponse::Trace dummyHeader.dwLen =32 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =14 dwHeaderSize =32 dwFixedSize =8 dwVariableSize =0 02/17/2005 11:29:26.046 CiscoTSP001.tsp| ProviderGetDeviceInfoResponse::Trace dwDeviceEnumerationHandle =0x00000004 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--ProviderGetDeviceInfoResponse::Trace 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 40 bytes, message number is 14 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 14 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Tracing PROVIDER_GET_DEVICE_INFO_RESPONSE (14) 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwSequenceNumber = 8 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwDeviceEnumerationHandle = 4 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CCtiInterface::ProcessResponse() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::FindAndRemoveSequenceNumber() Found mapping for sequenceNumber(0x00000008) to responseObject(0x0048C318) 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CCtiInterface::ProcessResponse() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() Removing map entry for sequenceNumber(0x00000008) 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() *ERROR* Map entry does not exists for sequenceNumber(0x00000008) 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CCtiInterface::ProcessProviderGetDeviceInfoResponse() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::ProcessProviderGetDeviceInfoResponse() Result of get device info request is 0 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CCtiInterface::ProcessProviderGetDeviceInfoResponse() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CCtiInterface::SendGetDeviceInfoRequest() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CCtiInterface::SendGetDeviceInfoFetchRequest() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoFetchRequest() Tracing GET_DEVICE_INFO_FETCH_REQUEST (94) 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoFetchRequest() dwSequenceNumber = 1231647811 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoFetchRequest() dwCount = 100 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoFetchRequest() dwEnumerationHandle = 4 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoFetchRequest() dwType = 72 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::AddToSequenceNumberMap() Adding map entry for sequenceNumber(0x00000009) to responseObject(0x0048C318) 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->GetDeviceInfoFetchRequest::Trace 02/17/2005 11:29:26.046 CiscoTSP001.tsp| GetDeviceInfoFetchRequest::Trace :dwSequenceNumber = 0x00000009 02/17/2005 11:29:26.046 CiscoTSP001.tsp| GetDeviceInfoFetchRequest::Trace dummyHeader.dwLen =40 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =94 dwHeaderSize =32 dwFixedSize =16 dwVariableSize =0 02/17/2005 11:29:26.046 CiscoTSP001.tsp| GetDeviceInfoFetchRequest::Trace dwEnumerationHandle = 0x00000004 dwCount =100 dwType =72 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--GetDeviceInfoFetchRequest::Trace 02/17/2005 11:29:26.046 CiscoTSP001.tsp| SendMessage() sent message number 94, sequence number 9, 48 bytes 02/17/2005 11:29:26.046 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:29:26.046 CiscoTSP001.tsp|-->CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:26.046 CiscoTSP001.tsp| CCtiInterface::WaitForSynchronousResponse() Waiting to receive synchronous response or timeout 15000 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=95, length=24 02/17/2005 11:29:26.093 CiscoTSP001.tsp|-->GetDeviceInfoFetchResponse::Trace 02/17/2005 11:29:26.093 CiscoTSP001.tsp| GetDeviceInfoFetchResponse::Trace :dwSequenceNumber = 0x00000009 02/17/2005 11:29:26.093 CiscoTSP001.tsp| GetDeviceInfoFetchResponse::Trace dummyHeader.dwLen =48 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =95 dwHeaderSize =32 dwFixedSize =24 dwVariableSize =0 02/17/2005 11:29:26.093 CiscoTSP001.tsp| GetDeviceInfoFetchResponse::Trace dwInfoOffset =0x00000038 dwInfoSize =0 dwInfoCount =0 dwInfoElementFixedSize =20 bMore =0x00000000 02/17/2005 11:29:26.093 CiscoTSP001.tsp|<--GetDeviceInfoFetchResponse::Trace 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 56 bytes, message number is 95 02/17/2005 11:29:26.093 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 95 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Tracing GET_DEVICE_INFO_FETCH_RESPONSE (95) 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwSequenceNumber = 9 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() bMore = 0 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwInfoCount = 0 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwInfoElementFixedSize = 20 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwInfoOffset = 56 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwInfoSize = 0 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received 0 devices 02/17/2005 11:29:26.093 CiscoTSP001.tsp|-->CCtiInterface::ProcessResponse() 02/17/2005 11:29:26.093 CiscoTSP001.tsp|-->CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::FindAndRemoveSequenceNumber() Found mapping for sequenceNumber(0x00000009) to responseObject(0x0048C318) 02/17/2005 11:29:26.093 CiscoTSP001.tsp|<--CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:26.093 CiscoTSP001.tsp|<--CCtiInterface::ProcessResponse() 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:29:26.093 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:29:26.093 CiscoTSP001.tsp|<--CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:26.093 CiscoTSP001.tsp|-->CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() Removing map entry for sequenceNumber(0x00000009) 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() *ERROR* Map entry does not exists for sequenceNumber(0x00000009) 02/17/2005 11:29:26.093 CiscoTSP001.tsp|<--CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:26.093 CiscoTSP001.tsp|<--CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:26.093 CiscoTSP001.tsp|-->CCtiInterface::ProcessGetDeviceInfoFetchResponse() 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::ProcessGetDeviceInfoFetchResponse() Result of get device info fetch request is 0 02/17/2005 11:29:26.093 CiscoTSP001.tsp|<--CCtiInterface::ProcessGetDeviceInfoFetchResponse() 02/17/2005 11:29:26.093 CiscoTSP001.tsp|<--CCtiInterface::SendGetDeviceInfoFetchRequest() 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CSelsiusTSPDeviceList::AddDeviceType() number of devices=0, moreDevices=0 02/17/2005 11:29:26.093 CiscoTSP001.tsp|-->CCtiInterface::SendGetDeviceInfoCloseRequest() 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoCloseRequest() Tracing GET_DEVICE_INFO_CLOSE_REQUEST (96) 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoCloseRequest() dwSequenceNumber = 9 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::SendGetDeviceInfoCloseRequest() dwEnumerationHandle = 4 02/17/2005 11:29:26.093 CiscoTSP001.tsp|-->CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:26.093 CiscoTSP001.tsp|-->CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:26.093 CiscoTSP001.tsp| CCtiInterface::AddToSequenceNumberMap() Adding map entry for sequenceNumber(0x0000000A) to responseObject(0x0048CC28) 02/17/2005 11:29:26.093 CiscoTSP001.tsp|<--CCtiInterface::AddToSequenceNumberMap() 02/17/2005 11:29:26.093 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:29:26.093 CiscoTSP001.tsp|-->GetDeviceInfoCloseRequest::Trace 02/17/2005 11:29:26.093 CiscoTSP001.tsp| GetDeviceInfoCloseRequest::Trace :dwSequenceNumber = 0x0000000A 02/17/2005 11:29:26.093 CiscoTSP001.tsp| GetDeviceInfoCloseRequest::Trace dummyHeader.dwLen =32 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =96 dwHeaderSize =32 dwFixedSize =8 dwVariableSize =0 02/17/2005 11:29:26.093 CiscoTSP001.tsp| GetDeviceInfoCloseRequest::Trace dwEnumerationHandle = 0x00000004 02/17/2005 11:29:26.093 CiscoTSP001.tsp|<--GetDeviceInfoCloseRequest::Trace 02/17/2005 11:29:26.093 CiscoTSP001.tsp| SendMessage() sent message number 96, sequence number 10, 40 bytes 02/17/2005 11:29:26.093 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:29:26.109 CiscoTSP001.tsp|-->CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CCtiInterface::WaitForSynchronousResponse() Waiting to receive synchronous response or timeout 15000 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=97, length=4 02/17/2005 11:29:26.109 CiscoTSP001.tsp|-->GetDeviceInfoCloseResponse::Trace 02/17/2005 11:29:26.109 CiscoTSP001.tsp| GetDeviceInfoCloseResponse::Trace :dwSequenceNumber = 0x0000000A 02/17/2005 11:29:26.109 CiscoTSP001.tsp| GetDeviceInfoCloseResponse::Trace dummyHeader.dwLen =28 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =97 dwHeaderSize =32 dwFixedSize =4 dwVariableSize =0 02/17/2005 11:29:26.109 CiscoTSP001.tsp|<--GetDeviceInfoCloseResponse::Trace 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 36 bytes, message number is 97 02/17/2005 11:29:26.109 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 97 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Tracing GET_DEVICE_INFO_CLOSE_RESPONSE (97) 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() dwSequenceNumber = 10 02/17/2005 11:29:26.109 CiscoTSP001.tsp|-->CCtiInterface::ProcessResponse() 02/17/2005 11:29:26.109 CiscoTSP001.tsp|-->CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CCtiInterface::FindAndRemoveSequenceNumber() Found mapping for sequenceNumber(0x0000000A) to responseObject(0x0048CC28) 02/17/2005 11:29:26.109 CiscoTSP001.tsp|<--CCtiInterface::FindAndRemoveSequenceNumber() 02/17/2005 11:29:26.109 CiscoTSP001.tsp|<--CCtiInterface::ProcessResponse() 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:29:26.109 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:29:26.109 CiscoTSP001.tsp|<--CCtiInterface::WaitForSynchronousResponse() 02/17/2005 11:29:26.109 CiscoTSP001.tsp|-->CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() Removing map entry for sequenceNumber(0x0000000A) 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CCtiInterface::RemoveFromSequenceNumberMap() *ERROR* Map entry does not exists for sequenceNumber(0x0000000A) 02/17/2005 11:29:26.109 CiscoTSP001.tsp|<--CCtiInterface::RemoveFromSequenceNumberMap() 02/17/2005 11:29:26.109 CiscoTSP001.tsp|<--CCtiInterface::SendSynchronousRequest() 02/17/2005 11:29:26.109 CiscoTSP001.tsp|-->CCtiInterface::ProcessGetDeviceInfoCloseResponse() 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CCtiInterface::ProcessGetDeviceInfoCloseResponse() Result of get device info request is 0 02/17/2005 11:29:26.109 CiscoTSP001.tsp|<--CCtiInterface::ProcessGetDeviceInfoCloseResponse() 02/17/2005 11:29:26.109 CiscoTSP001.tsp|<--CCtiInterface::SendGetDeviceInfoCloseRequest() 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CSelsiusTSPDeviceList::AddDeviceType() Return Value = 0x00000000 02/17/2005 11:29:26.109 CiscoTSP001.tsp|<--CSelsiusTSPDeviceList::AddDeviceType() 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CSelsiusTSPDeviceList::Init() DeviceList::Init state = DeviceInitState_Initialized 02/17/2005 11:29:26.109 CiscoTSP001.tsp|<--CSelsiusTSPDeviceList::Init() 02/17/2005 11:29:26.109 CiscoTSP001.tsp| CSelsiusTSP::InitializeDeviceList() Return Value = 0x00000000 02/17/2005 11:29:26.109 CiscoTSP001.tsp|<--CSelsiusTSP::InitializeDeviceList() 02/17/2005 11:29:26.218 CiscoTSP001.tsp| CQBEHelperBase::InitializeTSP() TSPConnectState = TspConnectState_TspInitialized 02/17/2005 11:29:26.218 CiscoTSP001.tsp|-->CQBEHelperBase::SetTspState() 02/17/2005 11:29:26.218 CiscoTSP001.tsp| CQBEHelperBase::SetTspState() TSPState = 2 02/17/2005 11:29:26.218 CiscoTSP001.tsp|<--CQBEHelperBase::SetTspState() 02/17/2005 11:29:26.218 CiscoTSP001.tsp|-->CQBEHelperBase::FireLinkInServiceEvent() 02/17/2005 11:29:26.218 CiscoTSP001.tsp|-->CCtiInterface::ReportLinkInServiceEvent() 02/17/2005 11:29:26.218 CiscoTSP001.tsp|-->CCtiInterface::ProcessEvent() 02/17/2005 11:29:26.218 CiscoTSP001.tsp|-->CSelsiusTSP::ProcessEvent() 02/17/2005 11:29:26.218 CiscoTSP001.tsp|-->CTapiApiQueue::AddTail() 02/17/2005 11:29:26.218 CiscoTSP001.tsp| CTapiApiQueue::AddTail() ApiID=319, num in request queue=1 02/17/2005 11:29:26.218 CiscoTSP001.tsp| CTapiApiQueue::AddTail() signaling thread that message Unknown PDU Number is in queue. 02/17/2005 11:29:26.218 CiscoTSP001.tsp|-->SelsiusTSP::ProcessEventQueueUntilEmpty() 02/17/2005 11:29:26.218 CiscoTSP001.tsp| SelsiusTSP::ProcessEventQueueUntilEmpty() num in queue=[0] 02/17/2005 11:29:26.218 CiscoTSP001.tsp|<--CTapiApiQueue::AddTail() 02/17/2005 11:29:26.218 CiscoTSP001.tsp|<--CSelsiusTSP::ProcessEvent() 02/17/2005 11:29:26.218 CiscoTSP001.tsp|<--CCtiInterface::ProcessEvent() 02/17/2005 11:29:26.218 CiscoTSP001.tsp|<--CCtiInterface::ReportLinkInServiceEvent() 02/17/2005 11:29:26.218 CiscoTSP001.tsp|<--CQBEHelperBase::FireLinkInServiceEvent() 02/17/2005 11:29:26.218 CiscoTSP001.tsp|<--CQBEHelperBase::InitializeTSP() 02/17/2005 11:29:26.218 CiscoTSP001.tsp| CQBEHelperBase::StartConnectThread() InitializeTSP() returned 1, TSP state: 2 02/17/2005 11:29:26.218 CiscoTSP001.tsp| CQBEHelperBase::StartConnectThread() Created connect thread. 02/17/2005 11:29:26.218 CiscoTSP001.tsp|<--CQBEHelperBase::StartConnectThread() 02/17/2005 11:29:26.218 CiscoTSP001.tsp| CSelsiusTSP::CSelsiusTSP() this=0x0101F8A8 02/17/2005 11:29:26.218 CiscoTSP001.tsp|<--CSelsiusTSP::CSelsiusTSP() 02/17/2005 11:29:26.218 CiscoTSP001.tsp|<--InitGlobalTSP() 02/17/2005 11:29:26.250 CiscoTSP001.tsp|-->CQBEHelperBase::ConnectThreadEntry() 02/17/2005 11:29:26.250 CiscoTSP001.tsp|-->CSelsiusTSP::LoadTSPLib() 02/17/2005 11:29:26.250 CiscoTSP001.tsp|<--CSelsiusTSP::LoadTSPLib() 02/17/2005 11:29:26.250 CiscoTSP001.tsp|-->CQBEHelperBase::ConnectLoop() 02/17/2005 11:29:26.250 CiscoTSP001.tsp| CQBEHelperBase::ConnectLoop() Waiting for signal to start connecting process or to exit 02/17/2005 11:29:26.343 CiscoTSP001.tsp| SelsiusTSP::ProcessEventQueueUntilEmpty() *ERROR* !!!!!!!!!!!!!!Unknown event ApiID=319 02/17/2005 11:29:26.343 CiscoTSP001.tsp|<--SelsiusTSP::ProcessEventQueueUntilEmpty() 02/17/2005 11:29:26.343 CiscoTSP001.tsp| SelsiusTSP::SignalingLoop() event thread waiting for signal from main thread that there is a request 02/17/2005 11:29:26.343 CiscoTSP001.tsp|-->SelsiusTSP::TSPI_lineNegotiateTSPIVersion(-1) 02/17/2005 11:29:26.343 CiscoTSP001.tsp| SelsiusTSP::TSPI_lineNegotiateTSPIVersion(-1) TSPI Version is 0x00020001 02/17/2005 11:29:26.343 CiscoTSP001.tsp|<--SelsiusTSP::TSPI_lineNegotiateTSPIVersion(-1) 02/17/2005 11:29:26.343 CiscoTSP001.tsp| TSPI_lineNegotiateTSPIVersion() TSPI_lineNegotiateTSPIVersion returns = 0x00000000 TSPI return version = 0x00020001 02/17/2005 11:29:26.343 CiscoTSP001.tsp|<--TSPI_lineNegotiateTSPIVersion() 02/17/2005 11:29:26.578 CiscoTSP001.tsp|-->TSPI_providerEnumDevices() 02/17/2005 11:29:26.578 CiscoTSP001.tsp| TSPI_providerEnumDevices() dwPermanentProviderID = 0x0000000A hProvider = 0x012148A8 lpfnLineCreateProc = 0x66DFA723 lpfnPhoneCreateProc = 0x66E079A3 02/17/2005 11:29:26.578 CiscoTSP001.tsp|-->SelsiusTSP::TSPI_providerEnumDevices() 02/17/2005 11:29:26.578 CiscoTSP001.tsp| SelsiusTSP::TSPI_providerEnumDevices() Returning 1 lines 1 phones. 02/17/2005 11:29:26.578 CiscoTSP001.tsp|<--SelsiusTSP::TSPI_providerEnumDevices() 02/17/2005 11:29:26.578 CiscoTSP001.tsp| TSPI_providerEnumDevices() TSPI_providerEnumDevices returns = 0x00000000 dwNumLines = 1 dwNumPhones = 1 02/17/2005 11:29:26.578 CiscoTSP001.tsp|<--TSPI_providerEnumDevices() 02/17/2005 11:29:26.578 CiscoTSP001.tsp|-->TSPI_providerInit() 02/17/2005 11:29:26.578 CiscoTSP001.tsp| TSPI_providerInit() dwTSPIVersion = 0x00020001 dwPermanentProviderID = 0x0000000A lpfnCompletionProc = 0x66E0C6DB 02/17/2005 11:29:26.578 CiscoTSP001.tsp|-->SelsiusTSP::TSPI_providerInit() 02/17/2005 11:29:26.578 CiscoTSP001.tsp| SelsiusTSP::TSPI_providerInit() m_pfnCompletionProc=0x66E0C6DB 02/17/2005 11:29:26.578 CiscoTSP001.tsp|-->CSelsiusTSPLineList::SetTapiID() 02/17/2005 11:29:26.656 CiscoTSP001.tsp| CSelsiusTSPLineList::SetTapiID() Adding map entry for line(3190) on device(CIT90_Riann) 02/17/2005 11:29:26.750 CiscoTSP001.tsp| CSelsiusTSPLineList::SetTapiID() sort string = 3190/CIT90_Riann 02/17/2005 11:29:26.750 CiscoTSP001.tsp|-->CSelsiusTSPLine::SetTAPIDeviceID() linePermanentId = [0x24691520] 02/17/2005 11:29:26.750 CiscoTSP001.tsp|<--CSelsiusTSPLine::SetTAPIDeviceID() linePermanentId = [0x24691520] 02/17/2005 11:29:26.750 CiscoTSP001.tsp|-->CSelsiusTSPLineList::AddToTapiLineDeviceIdMap() 02/17/2005 11:29:26.750 CiscoTSP001.tsp| CSelsiusTSPLineList::AddToTapiLineDeviceIdMap() Adding map entry for deviceId(0x00000005) to line(0x0048B970) 02/17/2005 11:29:26.750 CiscoTSP001.tsp|<--CSelsiusTSPLineList::AddToTapiLineDeviceIdMap() 02/17/2005 11:29:26.750 CiscoTSP001.tsp|<--CSelsiusTSPLineList::SetTapiID() 02/17/2005 11:29:26.750 CiscoTSP001.tsp|-->CSelsiusTSPDeviceList::SetTapiID() 02/17/2005 11:29:26.750 CiscoTSP001.tsp| CSelsiusTSPDeviceList::SetTapiID() Adding map entry for device(CIT90_Riann) 02/17/2005 11:29:26.750 CiscoTSP001.tsp|-->CSelsiusTSPDeviceList::AddToTapiPhoneDeviceIdMap() 02/17/2005 11:29:26.750 CiscoTSP001.tsp| CSelsiusTSPDeviceList::AddToTapiPhoneDeviceIdMap() Adding map entry for deviceId(0x00000000) to device(0x01017260) 02/17/2005 11:29:26.750 CiscoTSP001.tsp|<--CSelsiusTSPDeviceList::AddToTapiPhoneDeviceIdMap() 02/17/2005 11:29:26.750 CiscoTSP001.tsp|<--CSelsiusTSPDeviceList::SetTapiID() 02/17/2005 11:29:26.750 CiscoTSP001.tsp|<--SelsiusTSP::TSPI_providerInit() 02/17/2005 11:29:26.750 CiscoTSP001.tsp| TSPI_providerInit() TSPI_providerInit returns = 0x00000000 dwLineDeviceIDBase = 0x00000005 dwPhoneDeviceIDBase = 0x00000000 dwNumLines = 1 dwNumPhones = 1 lpdwTSPIOptions = 0x012148B4 02/17/2005 11:29:26.750 CiscoTSP001.tsp|<--TSPI_providerInit() 02/17/2005 11:29:26.750 CiscoTSP001.tsp|-->TSPI_lineNegotiateTSPIVersion() 02/17/2005 11:29:26.750 CiscoTSP001.tsp| TSPI_lineNegotiateTSPIVersion() dwDeviceID =0x00000005 dwLowVersion =0x00010003 dwHighVersion =0x00030000 lpdwTSPIVersion =0x0129F704 02/17/2005 11:29:26.750 CiscoTSP001.tsp|-->SelsiusTSP::TSPI_lineNegotiateTSPIVersion(5) 02/17/2005 11:29:26.750 CiscoTSP001.tsp| SelsiusTSP::TSPI_lineNegotiateTSPIVersion(5) TSPI Version is 0x00020001 02/17/2005 11:29:26.750 CiscoTSP001.tsp|<--SelsiusTSP::TSPI_lineNegotiateTSPIVersion(5) 02/17/2005 11:29:26.750 CiscoTSP001.tsp| TSPI_lineNegotiateTSPIVersion() TSPI_lineNegotiateTSPIVersion returns = 0x00000000 TSPI return version = 0x00020001 02/17/2005 11:29:26.750 CiscoTSP001.tsp|<--TSPI_lineNegotiateTSPIVersion() 02/17/2005 11:29:26.750 CiscoTSP001.tsp|-->TSPI_phoneNegotiateTSPIVersion() 02/17/2005 11:29:26.750 CiscoTSP001.tsp| TSPI_phoneNegotiateTSPIVersion() dwDeviceID = 0x00000000 dwLowVersion = 0x00010003 dwHighVersion = 0x00030000 lpdwTSPIVersion = 0x0129F708 02/17/2005 11:29:26.750 CiscoTSP001.tsp|-->TSPI_lineNegotiateTSPIVersion() 02/17/2005 11:29:26.750 CiscoTSP001.tsp| TSPI_lineNegotiateTSPIVersion() dwDeviceID =0x00000000 dwLowVersion =0x00010003 dwHighVersion =0x00030000 lpdwTSPIVersion =0x0129F708 02/17/2005 11:29:26.750 CiscoTSP001.tsp|-->SelsiusTSP::TSPI_lineNegotiateTSPIVersion(0) 02/17/2005 11:29:26.750 CiscoTSP001.tsp| SelsiusTSP::TSPI_lineNegotiateTSPIVersion(0) TSPI Version is 0x00020001 02/17/2005 11:29:26.750 CiscoTSP001.tsp|<--SelsiusTSP::TSPI_lineNegotiateTSPIVersion(0) 02/17/2005 11:29:26.750 CiscoTSP001.tsp| TSPI_lineNegotiateTSPIVersion() TSPI_lineNegotiateTSPIVersion returns = 0x00000000 TSPI return version = 0x00020001 02/17/2005 11:29:26.750 CiscoTSP001.tsp|<--TSPI_lineNegotiateTSPIVersion() 02/17/2005 11:29:26.750 CiscoTSP001.tsp| TSPI_phoneNegotiateTSPIVersion() TSPI_phoneNegotiateTSPIVersion returns = 0x00000000 02/17/2005 11:29:26.750 CiscoTSP001.tsp|<--TSPI_phoneNegotiateTSPIVersion() 02/17/2005 11:29:27.328 CiscoTSP001.tsp|-->TSPI_lineGetExtensionID() 02/17/2005 11:29:27.328 CiscoTSP001.tsp| TSPI_lineGetExtensionID() dwDeviceID = 0x00000005 dwTSPIVersion = 0x00020001 lpExtensionID = 0x01214E64 02/17/2005 11:29:27.328 CiscoTSP001.tsp|-->SelsiusTSP::TSPI_lineGetExtensionID(5) 02/17/2005 11:29:27.328 CiscoTSP001.tsp|-->CSelsiusTSPLineList::LineFromTapiDeviceID() 02/17/2005 11:29:27.328 CiscoTSP001.tsp| CSelsiusTSPLineList::LineFromTapiDeviceID() Found mapping for dwDeviceID(0x00000005) to line(0x0048B970) 02/17/2005 11:29:27.328 CiscoTSP001.tsp|<--CSelsiusTSPLineList::LineFromTapiDeviceID() 02/17/2005 11:29:27.328 CiscoTSP001.tsp| SelsiusTSP::TSPI_lineGetExtensionID(5) Version=0x20001, Extension IDs:0x8ebd6a50, 0x138011d2, 0x905b0060, 0xb03dd275 02/17/2005 11:29:27.328 CiscoTSP001.tsp|<--SelsiusTSP::TSPI_lineGetExtensionID(5) 02/17/2005 11:29:27.328 CiscoTSP001.tsp| TSPI_lineGetExtensionID() TSPI_lineGetExtensionID returns = 0x00000000 lpExtensionID = 0x01214E64 02/17/2005 11:29:27.328 CiscoTSP001.tsp|<--TSPI_lineGetExtensionID() 02/17/2005 11:29:27.328 CiscoTSP001.tsp|-->TSPI_lineNegotiateExtVersion() 02/17/2005 11:29:27.328 CiscoTSP001.tsp| TSPI_lineNegotiateExtVersion() dwDeviceID = 0x00000005 dwTSPIVersion = 0x00020001 dwLowVersion = 0x00000000 dwHighVersion = 0x00000000 lpdwExtVersion = 0x01214EAC 02/17/2005 11:29:27.328 CiscoTSP001.tsp|-->CSelsiusTSP::TSPI_lineNegotiateExtVersion(5) 02/17/2005 11:29:27.328 CiscoTSP001.tsp|-->CSelsiusTSPLineList::LineFromTapiDeviceID() 02/17/2005 11:29:27.328 CiscoTSP001.tsp| CSelsiusTSPLineList::LineFromTapiDeviceID() Found mapping for dwDeviceID(0x00000005) to line(0x0048B970) 02/17/2005 11:29:27.328 CiscoTSP001.tsp|<--CSelsiusTSPLineList::LineFromTapiDeviceID() 02/17/2005 11:29:27.328 CiscoTSP001.tsp|-->CSelsiusTSPLine::NegotiateExtVersion() [0x00000000] 02/17/2005 11:29:27.328 CiscoTSP001.tsp| CSelsiusTSPLine::NegotiateExtVersion() [0x00000000] highVer=0x00000000 lowVer=0x00000000 02/17/2005 11:29:27.328 CiscoTSP001.tsp| CSelsiusTSPLine::NegotiateExtVersion() [0x00000000] Negotiated version=0x00000000 02/17/2005 11:29:27.328 CiscoTSP001.tsp| CSelsiusTSPLine::NegotiateExtVersion() [0x00000000] Want RTP Control = false 02/17/2005 11:29:27.328 CiscoTSP001.tsp|<--CSelsiusTSPLine::NegotiateExtVersion() [0x00000000] 02/17/2005 11:29:27.328 CiscoTSP001.tsp|<--CSelsiusTSP::TSPI_lineNegotiateExtVersion(5) 02/17/2005 11:29:27.328 CiscoTSP001.tsp| TSPI_lineNegotiateExtVersion() TSPI_lineNegotiateExtVersion returns = 0x00000000 02/17/2005 11:29:27.328 CiscoTSP001.tsp|<--TSPI_lineNegotiateExtVersion() 02/17/2005 11:29:27.328 CiscoTSP001.tsp|-->TSPI_lineGetDevCaps() 02/17/2005 11:29:27.328 CiscoTSP001.tsp| TSPI_lineGetDevCaps() dwDeviceID =0x00000005 dwTSPIVersion =0x00020001 dwExtVersion =0x00000000 lpLineDevCaps =0x01214F24 02/17/2005 11:29:27.328 CiscoTSP001.tsp|-->SelsiusTSP::TSPI_lineGetDevCaps(5) 02/17/2005 11:29:27.328 CiscoTSP001.tsp|-->CSelsiusTSPLineList::LineFromTapiDeviceID() 02/17/2005 11:29:27.328 CiscoTSP001.tsp| CSelsiusTSPLineList::LineFromTapiDeviceID() Found mapping for dwDeviceID(0x00000005) to line(0x0048B970) 02/17/2005 11:29:27.328 CiscoTSP001.tsp|<--CSelsiusTSPLineList::LineFromTapiDeviceID() 02/17/2005 11:29:27.328 CiscoTSP001.tsp|-->CSelsiusTSPLine::GetDevCaps() line id=0x00000000 02/17/2005 11:29:27.328 CiscoTSP001.tsp| CSelsiusTSPLine::GetDevCaps() line id=0x00000000 Cisco Line: [CIT90_Riann] (3190) device id=0x00000005 02/17/2005 11:29:27.328 CiscoTSP001.tsp|<--CSelsiusTSPLine::GetDevCaps() line id=0x00000000 02/17/2005 11:29:27.328 CiscoTSP001.tsp|<--SelsiusTSP::TSPI_lineGetDevCaps(5) 02/17/2005 11:29:27.328 CiscoTSP001.tsp| TSPI_lineGetDevCaps() TSPI_lineGetDevCaps returns = 0x00000000 lpLineDevCaps->dwPermanentLineID = 0x24691520 02/17/2005 11:29:27.328 CiscoTSP001.tsp|<--TSPI_lineGetDevCaps() 02/17/2005 11:29:27.875 CiscoTSP001.tsp|-->TSPI_lineOpen() 02/17/2005 11:29:27.875 CiscoTSP001.tsp| TSPI_lineOpen() dwDeviceID =0x00000005 htLine =0x00010322 lphdLine =0x012152C4 dwTSPIVersion =0x00020001 lpfnEventProc = 0x66DFA723 02/17/2005 11:29:27.875 CiscoTSP001.tsp|-->SelsiusTSP::TSPI_lineOpen(5) 02/17/2005 11:29:27.875 CiscoTSP001.tsp|-->CSelsiusTSPLineList::LineFromTapiDeviceID() 02/17/2005 11:29:27.875 CiscoTSP001.tsp| CSelsiusTSPLineList::LineFromTapiDeviceID() Found mapping for dwDeviceID(0x00000005) to line(0x0048B970) 02/17/2005 11:29:27.875 CiscoTSP001.tsp|<--CSelsiusTSPLineList::LineFromTapiDeviceID() 02/17/2005 11:29:27.875 CiscoTSP001.tsp|-->CSelsiusTSPLine::Open() 02/17/2005 11:29:27.875 CiscoTSP001.tsp|-->CSelsiusTSPDevice::OpenDevice() [CIT90_Riann] 02/17/2005 11:29:27.875 CiscoTSP001.tsp|-->CSelsiusTSPWaveList::GetAvailWave() 02/17/2005 11:29:27.875 CiscoTSP001.tsp| CSelsiusTSPWaveList::GetAvailWave() *ERROR* No wave available 02/17/2005 11:29:27.875 CiscoTSP001.tsp|<--CSelsiusTSPWaveList::GetAvailWave() 02/17/2005 11:29:27.875 CiscoTSP001.tsp| CSelsiusTSPDevice::OpenDevice() [CIT90_Riann] *ERROR* GetAvailWave() returned WAVELIST_NOT_ASSIGNED 02/17/2005 11:29:27.875 CiscoTSP001.tsp|<--CSelsiusTSPDevice::OpenDevice() [CIT90_Riann] 02/17/2005 11:29:27.875 CiscoTSP001.tsp|<--CSelsiusTSPLine::Open() 02/17/2005 11:29:27.875 CiscoTSP001.tsp|<--SelsiusTSP::TSPI_lineOpen(5) 02/17/2005 11:29:27.875 CiscoTSP001.tsp| TSPI_lineOpen() TSPI_lineOpen returns = 0x8000004B 02/17/2005 11:29:27.875 CiscoTSP001.tsp|<--TSPI_lineOpen() 02/17/2005 11:29:51.703 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:29:51.781 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=120, length=0 02/17/2005 11:29:51.781 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:29:51.781 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:29:51.781 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:29:51.781 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 32 bytes, message number is 120 02/17/2005 11:29:51.781 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:51.781 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 120 02/17/2005 11:29:51.781 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:29:51.781 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:29:51.781 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:29:56.421 CiscoTSP001.tsp|-->CCtiInterface::SendHeartbeat() 02/17/2005 11:29:56.421 CiscoTSP001.tsp|-->CCtiInterface::SendMessage() 02/17/2005 11:29:56.421 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:29:56.421 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:29:56.421 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:29:56.421 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:29:56.421 CiscoTSP001.tsp| SendMessage() sent message number 120, sequence number 71503532, 32 bytes 02/17/2005 11:29:56.421 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:29:56.421 CiscoTSP001.tsp|<--CCtiInterface::SendMessage() 02/17/2005 11:29:56.421 CiscoTSP001.tsp|<--CCtiInterface::SendHeartbeat() 02/17/2005 11:30:22.953 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:30:22.953 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=120, length=0 02/17/2005 11:30:22.953 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:30:22.953 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:30:22.953 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:30:22.953 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 32 bytes, message number is 120 02/17/2005 11:30:22.953 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:30:22.953 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 120 02/17/2005 11:30:22.953 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:30:22.953 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:30:22.953 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:30:26.421 CiscoTSP001.tsp|-->CCtiInterface::SendHeartbeat() 02/17/2005 11:30:26.421 CiscoTSP001.tsp|-->CCtiInterface::SendMessage() 02/17/2005 11:30:26.421 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:30:26.421 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:30:26.421 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:30:26.421 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:30:26.421 CiscoTSP001.tsp| SendMessage() sent message number 120, sequence number 71503532, 32 bytes 02/17/2005 11:30:26.421 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:30:26.421 CiscoTSP001.tsp|<--CCtiInterface::SendMessage() 02/17/2005 11:30:26.421 CiscoTSP001.tsp|<--CCtiInterface::SendHeartbeat() 02/17/2005 11:30:53.015 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:30:53.015 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=120, length=0 02/17/2005 11:30:53.015 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:30:53.015 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:30:53.015 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:30:53.015 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 32 bytes, message number is 120 02/17/2005 11:30:53.015 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:30:53.015 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 120 02/17/2005 11:30:53.015 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:30:53.015 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:30:53.015 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:30:56.421 CiscoTSP001.tsp|-->CCtiInterface::SendHeartbeat() 02/17/2005 11:30:56.421 CiscoTSP001.tsp|-->CCtiInterface::SendMessage() 02/17/2005 11:30:56.421 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:30:56.421 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:30:56.421 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:30:56.421 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:30:56.421 CiscoTSP001.tsp| SendMessage() sent message number 120, sequence number 71503532, 32 bytes 02/17/2005 11:30:56.421 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:30:56.421 CiscoTSP001.tsp|<--CCtiInterface::SendMessage() 02/17/2005 11:30:56.421 CiscoTSP001.tsp|<--CCtiInterface::SendHeartbeat() 02/17/2005 11:31:23.203 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:31:23.203 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=120, length=0 02/17/2005 11:31:23.203 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:31:23.203 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:31:23.203 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:31:23.203 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 32 bytes, message number is 120 02/17/2005 11:31:23.203 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:31:23.203 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 120 02/17/2005 11:31:23.203 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:31:23.203 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:31:23.203 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:31:26.421 CiscoTSP001.tsp|-->CCtiInterface::SendHeartbeat() 02/17/2005 11:31:26.421 CiscoTSP001.tsp|-->CCtiInterface::SendMessage() 02/17/2005 11:31:26.421 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:31:26.421 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:31:26.421 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:31:26.421 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:31:26.421 CiscoTSP001.tsp| SendMessage() sent message number 120, sequence number 71503532, 32 bytes 02/17/2005 11:31:26.421 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:31:26.421 CiscoTSP001.tsp|<--CCtiInterface::SendMessage() 02/17/2005 11:31:26.421 CiscoTSP001.tsp|<--CCtiInterface::SendHeartbeat() 02/17/2005 11:31:53.062 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:31:53.078 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=120, length=0 02/17/2005 11:31:53.078 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:31:53.078 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:31:53.078 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:31:53.078 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 32 bytes, message number is 120 02/17/2005 11:31:53.078 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:31:53.078 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 120 02/17/2005 11:31:53.078 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:31:53.078 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:31:53.078 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:31:56.453 CiscoTSP001.tsp|-->CCtiInterface::SendHeartbeat() 02/17/2005 11:31:56.453 CiscoTSP001.tsp|-->CCtiInterface::SendMessage() 02/17/2005 11:31:56.453 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:31:56.453 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:31:56.453 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:31:56.453 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:31:56.453 CiscoTSP001.tsp| SendMessage() sent message number 120, sequence number 71503532, 32 bytes 02/17/2005 11:31:56.453 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:31:56.453 CiscoTSP001.tsp|<--CCtiInterface::SendMessage() 02/17/2005 11:31:56.453 CiscoTSP001.tsp|<--CCtiInterface::SendHeartbeat() 02/17/2005 11:32:23.093 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:32:23.093 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=120, length=0 02/17/2005 11:32:23.093 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:32:23.093 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:32:23.093 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:32:23.093 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 32 bytes, message number is 120 02/17/2005 11:32:23.093 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:32:23.093 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 120 02/17/2005 11:32:23.093 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:32:23.093 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:32:23.093 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:32:26.453 CiscoTSP001.tsp|-->CCtiInterface::SendHeartbeat() 02/17/2005 11:32:26.453 CiscoTSP001.tsp|-->CCtiInterface::SendMessage() 02/17/2005 11:32:26.453 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:32:26.453 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:32:26.453 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:32:26.453 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:32:26.453 CiscoTSP001.tsp| SendMessage() sent message number 120, sequence number 71503532, 32 bytes 02/17/2005 11:32:26.453 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:32:26.453 CiscoTSP001.tsp|<--CCtiInterface::SendMessage() 02/17/2005 11:32:26.453 CiscoTSP001.tsp|<--CCtiInterface::SendHeartbeat() 02/17/2005 11:32:50.953 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:32:50.953 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=120, length=0 02/17/2005 11:32:50.953 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:32:50.953 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:32:50.953 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:32:50.953 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 32 bytes, message number is 120 02/17/2005 11:32:50.953 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:32:50.953 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 120 02/17/2005 11:32:50.953 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:32:50.953 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:32:50.953 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:32:56.453 CiscoTSP001.tsp|-->CCtiInterface::SendHeartbeat() 02/17/2005 11:32:56.453 CiscoTSP001.tsp|-->CCtiInterface::SendMessage() 02/17/2005 11:32:56.453 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:32:56.453 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:32:56.453 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:32:56.453 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:32:56.453 CiscoTSP001.tsp| SendMessage() sent message number 120, sequence number 71503532, 32 bytes 02/17/2005 11:32:56.453 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:32:56.453 CiscoTSP001.tsp|<--CCtiInterface::SendMessage() 02/17/2005 11:32:56.453 CiscoTSP001.tsp|<--CCtiInterface::SendHeartbeat() 02/17/2005 11:33:23.015 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:33:23.015 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=120, length=0 02/17/2005 11:33:23.015 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:33:23.015 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:33:23.015 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:33:23.015 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 32 bytes, message number is 120 02/17/2005 11:33:23.015 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:33:23.015 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 120 02/17/2005 11:33:23.015 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:33:23.015 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:33:23.015 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select() 02/17/2005 11:33:26.453 CiscoTSP001.tsp|-->CCtiInterface::SendHeartbeat() 02/17/2005 11:33:26.453 CiscoTSP001.tsp|-->CCtiInterface::SendMessage() 02/17/2005 11:33:26.453 CiscoTSP001.tsp|-->SendMessage() 02/17/2005 11:33:26.453 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:33:26.453 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:33:26.453 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:33:26.453 CiscoTSP001.tsp| SendMessage() sent message number 120, sequence number 71503532, 32 bytes 02/17/2005 11:33:26.453 CiscoTSP001.tsp|<--SendMessage() 02/17/2005 11:33:26.453 CiscoTSP001.tsp|<--CCtiInterface::SendMessage() 02/17/2005 11:33:26.453 CiscoTSP001.tsp|<--CCtiInterface::SendHeartbeat() 02/17/2005 11:33:53.093 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() select() returned 1. 02/17/2005 11:33:53.093 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Message number=120, length=0 02/17/2005 11:33:53.093 CiscoTSP001.tsp|-->Heartbeat::Trace 02/17/2005 11:33:53.093 CiscoTSP001.tsp| Heartbeat::Trace dummyHeader.dwLen =24 dummyHeader.dwType =0 dwMagicCookie =0xFFFFDDDD dwProtocolVersion =0x00040000 dwPDUNumber =120 dwHeaderSize =32 dwFixedSize =0 dwVariableSize =0 02/17/2005 11:33:53.093 CiscoTSP001.tsp|<--Heartbeat::Trace 02/17/2005 11:33:53.093 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Read 32 bytes, message number is 120 02/17/2005 11:33:53.093 CiscoTSP001.tsp|<--CSelsiusTSPLink::GetMessage() 02/17/2005 11:33:53.093 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() Received message number 120 02/17/2005 11:33:53.093 CiscoTSP001.tsp| CCtiInterface::ReceiveLoop() receive thread: Waiting for messages 02/17/2005 11:33:53.093 CiscoTSP001.tsp|-->CSelsiusTSPLink::GetMessage() 02/17/2005 11:33:53.093 CiscoTSP001.tsp| CSelsiusTSPLink::GetMessage() Receive thread waiting on select()