none
Clientanmeldung am Lync 2013 nicht möglich RRS feed

  • Frage

  • Hallo zusammen,

    wir sind gerade beim Aufbau einer Lync 2013 Umgebung in unserem Unternehmen.

    Dabei haben wir folgendes Problem. Wir Betreiben Lync in einer separaten DMZ. Eine Clientanmeldung eines Rechners der sich in dieser DMZ befindet klappt ohne Probleme.

    Wenn man aber versucht sich außerhalb dieser DMZ sich anzumelden scheitert es. DNS-Einträge, Zertifikat und FW-Ports sind eingerichtet. Es wird auf der FW auch nichts geblockt. Auch Wiresharck und Fiddler zeigen nichts.

    Anbei noch das log einer erfolglosen Anmeldung:

     

    10/01/2015|20:29:37.880 E64:38C0 INFO  :: ********************************************************************************
    ********************************************************************************
    #######  module=UccApi flavor=fre version=15.0.4745.1000 ######
    #######  branch=UNKNOWN architecture=X86 ######
    ************************************************************************************************************************
    ****************************************

    10/01/2015|20:29:37.880 E64:38C0 INFO  :: Initialization flags (100)
    10/01/2015|20:29:38.225 E64:38C0 TRACE :: CUccMediaManagerEventAdapter::Initialize Start Initialize message handler(...)
    10/01/2015|20:29:38.225 E64:38C0 TRACE :: CUccMediaManagerEventAdapter::Initialize End Initialize message handler(...)
    10/01/2015|20:29:38.234 E64:38C0 INFO  :: CHidManager::AddHidDevice - HID device <\\?\hid#bthavrcpdevice&col01#9&1ce63f30&0&0000#{4d1e55b2-f16f-11cf-88cb-001111000030}> is not a telephony device
    10/01/2015|20:29:38.234 E64:38C0 INFO  :: CHidManager::AddHidDevice - HID device <\\?\hid#bthavrcpdevice&col02#9&1ce63f30&0&0001#{4d1e55b2-f16f-11cf-88cb-001111000030}> is not a telephony device
    10/01/2015|20:29:38.234 E64:38C0 INFO  :: CHidManager::AddHidDevice - HID device <\\?\hid#bthavrcpdevice&col03#9&1ce63f30&0&0002#{4d1e55b2-f16f-11cf-88cb-001111000030}> is not a telephony device
    10/01/2015|20:29:38.234 E64:38C0 ERROR :: CHidManager::AddHidDevice - CreateFile(\\?\hid#vid_03f0&pid_0024#6&7437d88&0&0000#{4d1e55b2-f16f-11cf-88cb-001111000030}) failed with 0x80070005
    10/01/2015|20:29:38.234 E64:38C0 ERROR :: CHidManager::EnumerateHidDevices - AddHidDevice failed 0x80070005
    10/01/2015|20:29:38.234 E64:38C0 ERROR :: CHidManager::AddHidDevice - CreateFile(\\?\hid#vid_0461&pid_4e22#7&f2f0db7&0&0000#{4d1e55b2-f16f-11cf-88cb-001111000030}) failed with 0x80070005
    10/01/2015|20:29:38.234 E64:38C0 ERROR :: CHidManager::EnumerateHidDevices - AddHidDevice failed 0x80070005
    10/01/2015|20:29:38.234 E64:38C0 INFO  :: MediaManager found 0 devices
    10/01/2015|20:29:38.234 E64:38C0 ERROR :: CUccMediaDeviceManager::LoadCustomTelephonyDeviceCollection: HRESULT failed: 80070002 = hr. Audio capture
    10/01/2015|20:29:38.235 E64:38C0 ERROR :: CUccMediaDeviceManager::LoadCustomTelephonyDeviceCollection: HRESULT failed: 80070002 = hr. Audio capture
    10/01/2015|20:29:38.235 E64:38C0 WARN  :: We did not find device based on Name and Moniker match. Let's try to find device based on only name match
    10/01/2015|20:29:38.235 E64:38C0 INFO  :: Function: CUccMediaDeviceManager::GetBoolProperty
    10/01/2015|20:29:38.235 E64:38C0 TRACE :: Condition failed with 80ee0058: 'spProperty.IsValid()'
    10/01/2015|20:29:38.235 E64:38C0 INFO  :: Function: CUccMediaDeviceManager::GetBoolProperty
    10/01/2015|20:29:38.235 E64:38C0 TRACE :: Condition failed with 80ee0058: 'spProperty.IsValid()'
    10/01/2015|20:29:38.235 E64:38C0 INFO  :: Function: CUccMediaDeviceManager::GetBoolProperty
    10/01/2015|20:29:38.235 E64:38C0 TRACE :: Condition failed with 80ee0058: 'spProperty.IsValid()'
    10/01/2015|20:29:38.235 E64:38C0 INFO  :: Function: CUccMediaDeviceManager::GetBoolProperty
    10/01/2015|20:29:38.235 E64:38C0 TRACE :: Condition failed with 80ee0058: 'spProperty.IsValid()'
    10/01/2015|20:29:38.235 E64:38C0 INFO  :: Function: CUccMediaDeviceManager::GetBoolProperty
    10/01/2015|20:29:38.235 E64:38C0 TRACE :: Condition failed with 80ee0058: 'spProperty.IsValid()'
    10/01/2015|20:29:38.263 E64:38C0 TRACE :: SipStackGlobalInit - Doing WSAStatusup(1,1)
    10/01/2015|20:29:38.263 E64:38C0 TRACE :: SipStackGlobalInit - WsaData fields: wsaVersion=1.1(0x101), wVersion=1.1(0x101), wHighVersion=2.2(0x202)
    10/01/2015|20:29:38.263 E64:38C0 INFO  :: ProxyMessageHandler::Initialize: ProxyMessageHandler Initialized
    10/01/2015|20:29:38.263 E64:38C0 TRACE :: client[09426688] new sipStack[0959BC00]
    10/01/2015|20:29:38.274 E64:38C0 INFO  :: SIP_STACK::GetLocalIPAddresses - Skipping Adapter, Friendly Name = Bluetooth-Netzwerkverbindung 3, OperStatus = 2
    10/01/2015|20:29:38.274 E64:38C0 INFO  :: SIP_STACK::QueryPlatformAuthCaps ProtocolsFromPlatform=0x2f
    10/01/2015|20:29:38.275 E64:38C0 WARN  :: CUccPlatform::EnableTracing: tracing is already enabled
    10/01/2015|20:29:38.404 E64:374C INFO  :: IMediaPlatformNotify::DevicePnpEvent(), DeviceAdded: 1, MediaDevice: 094222D8, MediaType: 65536, MediaDirection 2, Description: Remoteaudio
    10/01/2015|20:29:38.711 E64:38C0 INFO  :: Crop=2
    10/01/2015|20:29:38.791 E64:38C0 INFO  :: Function: CUccAudioMediaDevice::InternalInitialize
    10/01/2015|20:29:38.791 E64:38C0 ERROR :: HRESULT API failed: 80004005 = hr. ParsePidVidMi
    10/01/2015|20:29:38.791 E64:38C0 INFO  :: CUccAudioMediaDevice::InternalInitialize - DisplayName set to  
    10/01/2015|20:29:38.803 E64:38C0 INFO  :: CUccMediaDeviceManager::DeviceAdded - Device was added: id=157032440 name=[Remoteaudio]
    10/01/2015|20:29:38.803 E64:38C0 INFO  :: CUccMediaDeviceManager::UpdateTelDeviceAdded - Checking if PnpName \\?\UnknownPnpName#{3.0.0.00000001}.{6C26BA7D-F0B2-4225-B422-8168C5261E45}# has EndpointID or not
    10/01/2015|20:29:38.803 E64:38C0 INFO  :: CUccMediaDeviceManager::UpdateTelDeviceAdded - Found endpointID. PnpName without EndpointID is \\?\UnknownPnpName#
    10/01/2015|20:29:38.803 E64:38C0 WARN  :: We did not find device based on Name and Moniker match. Let's try to find device based on only name match
    10/01/2015|20:29:38.803 E64:38C0 INFO  :: CUccMediaDeviceManager::InitializeVolumeForDevice: Volume was not found for the new deviceid = Remoteaudio in registry, initialized default volume to 26214
    10/01/2015|20:29:38.803 E64:38C0 INFO  :: CUccMediaDeviceManager::InitializeVolumeForDevice: Volume was not found for the new deviceid = Remoteaudio in registry, initialized default volume to 26214
    10/01/2015|20:29:38.803 E64:38C0 INFO  :: Function: CUccMediaDeviceManager::GetBoolProperty
    10/01/2015|20:29:38.803 E64:38C0 TRACE :: Condition failed with 80ee0058: 'spProperty.IsValid()'
    10/01/2015|20:29:38.803 E64:38C0 INFO  :: Function: CUccMediaDeviceManager::GetBoolProperty
    10/01/2015|20:29:38.803 E64:38C0 TRACE :: Condition failed with 80ee0058: 'spProperty.IsValid()'
    10/01/2015|20:29:38.803 E64:38C0 INFO  :: Function: CUccMediaDeviceManager::GetBoolProperty
    10/01/2015|20:29:38.803 E64:38C0 TRACE :: Condition failed with 80ee0058: 'spProperty.IsValid()'
    10/01/2015|20:29:38.803 E64:38C0 INFO  :: Function: CUccMediaDeviceManager::GetBoolProperty
    10/01/2015|20:29:38.803 E64:38C0 TRACE :: Condition failed with 80ee0058: 'spProperty.IsValid()'
    10/01/2015|20:29:38.803 E64:38C0 INFO  :: CUccMediaDeviceManager::FireDeviceChanged - Default device changed to <Remoteaudio> for category=3 media=1 direction=2 reason=2
    10/01/2015|20:29:38.803 E64:38C0 INFO  :: CUccMediaDeviceManager::FireDeviceChanged - Selected device changed to <Remoteaudio> for category=3 media=1 direction=2 reason=2
    10/01/2015|20:29:38.803 E64:38C0 TRACE :: CUccMediaManagerEventAdapter::OnMediaPlatformEvent - Processed MPEMT_DevicePnpEvent for 155347028l.
    10/01/2015|20:29:38.803 E64:38C0 TRACE :: CUccMediaManagerEventAdapter::OnMessage - Processed WM_MMGR_PLATFORM_EVENT.
    10/01/2015|20:29:40.339 E64:38C0 INFO  :: CUccServerEndpoint::Initialize - This endpoint is  sharing presentity data.
    10/01/2015|20:29:40.339 E64:38C0 INFO  :: CUccEndpoint::put_TelephonyTypewrite - Set TTY enabled to false
    10/01/2015|20:29:40.607 E64:38C0 INFO  :: UCCP:ClientAllowedAuthProts0x1000c
    10/01/2015|20:29:40.607 E64:38C0 INFO  :: CUccServerSignalingSettings::put_AllowedHttpProxyAuthenticationModes set auth modes 0x3f
    10/01/2015|20:29:40.607 E64:38C0 INFO  :: CUccServerSignalingSettings::put_AllowedHttpProxyAuthenticationModes Basic Authentication enabled on MediaPlatform
    10/01/2015|20:29:40.607 E64:38C0 INFO  :: CUccDnsResolverManager::Start MR-INFO - [0E641BD0] DNS resolver manager started
    10/01/2015|20:29:40.607 E64:38C0 INFO  :: UCCP:NegotiatedAuthProt0x0
    10/01/2015|20:29:40.607 E64:38C0 INFO  :: UCCP:ServerAuthProts0x0
    10/01/2015|20:29:40.607 E64:38C0 INFO  :: CUccPrincipalServerEndpoint::GetSipProviderProfile - Found no http proxy creds
    10/01/2015|20:29:40.607 E64:38C0 INFO  :: SIP_ENDPOINT::SetSipAuthBroker (0E6425D8) _IUccSipAuthBroker(0x00000000)
    10/01/2015|20:29:40.607 E64:38C0 INFO  :: UCCP:ServerAuthProts0x0
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: SIP_AUTH(e642c2c) PrepareAuth at state: 0
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: SIP_REGISTER:State (0) => (1)
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: Outgoing 0E729E78-<sip:vorname.name@xxx.de>, local=(null)
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: Function: StringToSockAddress
    10/01/2015|20:29:40.608 E64:38C0 ERROR :: HRESULT failed: 80072726 = HRESULT_FROM_WIN32(::ShimWSAGetLastError()) . Failed to convert string IP to SOCKADDR
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: Created Async workitem 0E7F075C, txn timeout 0, time sensitive 0, txn (00000000)
    10/01/2015|20:29:40.608 E64:348C INFO  :: Function: StringToSockAddress
    10/01/2015|20:29:40.608 E64:348C ERROR :: HRESULT failed: 80072726 = HRESULT_FROM_WIN32(::ShimWSAGetLastError()) . Failed to convert string IP to SOCKADDR
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: Out trxn corr-id (0E4627F8)
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: SIP_MSG_PROCESSOR::GetLocalConnectionAddrSubnet get loca IP address returned NULL
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: SIP_MSG_PROCESSOR::GetSubnetHeader Invalid subnet -1
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: REGISTER_CONTEXT(e729e78) SetAndNotify Recv(4) at State (0)
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: REGISTER_CONTEXT:State (0) => (4)
    10/01/2015|20:29:40.608 E64:38C0 TRACE :: SIP_MSG_PROCESSOR::OnDnsResolutionComplete[0E729E78] Entered host lyncfe.xxx.de
    10/01/2015|20:29:40.608 E64:38C0 TRACE :: SIP_MSG_PROCESSOR::OnDnsResolutionComplete get DNS result server: lyncfe.xxx.de IP: 192.168.154.129:5061
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: Use proxy server cache:1
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: Remote Addr: 192.168.154.129
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: Verification Flags: 1
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: Interface Index: 11
    10/01/2015|20:29:40.608 E64:38C0 INFO  :: Interface Type: 0
    10/01/2015|20:29:40.609 E64:38C0 INFO  :: GetAdaptersAddresses Succeeded.
    10/01/2015|20:29:40.609 E64:38C0 INFO  :: Identified local Ipv4 address: 192.168.125.127
    10/01/2015|20:29:40.609 E64:38C0 INFO  :: Found local routable Ipv4 address for SIP proxy server from proxy address list
    10/01/2015|20:29:40.609 E64:38C0 INFO  :: SockMgr: Create New Connection:DestName:(lyncfe.xxx.de)DestPort:(5061)Transport:(2)httpTunnel:(0)TLS RemotePrincipalName:(lyncfe.xxx.de)
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: DestAddr :192.168.154.129:5061

    10/01/2015|20:29:40.609 E64:38C0 TRACE :: CSIPTransportLayer::CSIPTransportLayer - [0x0E5FAD88]
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: CSIPTransportLayerNotify::CSIPTransportLayerNotify - [0x0E5FAD90]
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: SSIPTransportContext::SSIPTransportContext - [0x0E5FADC0]
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: SSIPTransportContext::SSIPTransportContext - [0x0028E918]
    10/01/2015|20:29:40.609 E64:38C0 ERROR :: CSIPClientConnection::AttachLyntrix no instance of CLyntrixClient
    10/01/2015|20:29:40.609 E64:38C0 ERROR :: CSIPClientConnection::Initialize AttachLyntrix failed hr=80004005
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: CSIPTransportLayer::CSIPTransportLayer - [0x0E8AD2C8]
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: CSIPTransportLayerNotify::CSIPTransportLayerNotify - [0x0E8AD2D0]
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: CSIPAsyncSocket::CSIPAsyncSocket - [0x0E8AD2C8]
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: CSIPAsyncSocket::Initialize - [0x0E8AD2C8]
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: CSIPTransportLayer::CSIPTransportLayer - [0x0E6B4360]
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: CSIPTransportLayerNotify::CSIPTransportLayerNotify - [0x0E6B4368]
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: CSIPTransportLayerSecurity::CSIPTransportLayerSecurity - [0x0E6B4360]
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: CSIPTransportLayer::CSIPTransportLayer - [0x005FDC38]
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: CSIPTransportLayerNotify::CSIPTransportLayerNotify - [0x005FDC40]
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: CSIPCompression::CSIPCompression - [0x005FDC38]
    10/01/2015|20:29:40.609 E64:38C0 INFO  :: CSIPCompressor::Initialize - Compression setting 1, threshold 128000, timeout 5000
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: CSIPTransportLayerNotify::CSIPTransportLayerNotify - [0x0E977F88]
    10/01/2015|20:29:40.609 E64:38C0 TRACE :: CSIPMessageCollator::CSIPMessageCollator - [0x0E977F88]
    10/01/2015|20:29:40.617 E64:38C0 INFO  :: CAsyncSocketWin32::Connect 0E78BAA0 connecting(async) window 0031104C socket:1b34 DestAddr:192.168.154.129:5061
    10/01/2015|20:29:40.617 E64:38C0 ERROR :: CSIPAsyncSocket::Connect: HRESULT API failed: 80072733 = hr
    10/01/2015|20:29:40.617 E64:38C0 TRACE :: SSIPTransportContext::~SSIPTransportContext - [0x0028E918]
    10/01/2015|20:29:40.617 E64:38C0 INFO  :: deleting Async workitem 0E7F075C
    10/01/2015|20:29:40.618 E64:38C0 TRACE :: CSIPAsyncSocket::SetLocalAddr - [0x0E8AD2C8]
    10/01/2015|20:29:40.618 E64:38C0 TRACE :: CSIPAsyncSocket::SetRemoteAddr - [0x0E8AD2C8]
    10/01/2015|20:29:40.618 E64:38C0 TRACE :: CSIPAsyncSocket::SetNetworkId - [0x0E8AD2C8]
    10/01/2015|20:29:40.618 E64:38C0 INFO  :: Created Async workitem 0E8419B0, txn timeout 0, time sensitive 0, txn (00000000)
    10/01/2015|20:29:40.618 E64:38C0 TRACE :: Async work item posted for TLS negotiation: this 0E6B4360
    10/01/2015|20:29:40.618 E64:38C0 TRACE :: CSIPAsyncSocket::Send this 0E8AD2C8, sending pbSendBuf 0E9136E0, dwSendBufSize = 185
    10/01/2015|20:29:40.618 E64:38C0 TRACE :: CSIPAsyncSocket::SendHelper - [0x0E8AD2C8]
    10/01/2015|20:29:40.618 E64:38C0 TRACE :: CAsyncSocketWin32::Send this 0E78BAA0, pbSendBuf 0E9136E0, dwSendBufSize = 185, dwBytesSent = 185
    10/01/2015|20:29:40.618 E64:38C0 INFO  :: deleting Async workitem 0E8419B0
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerNotify::OnRecv - [0x0E8AD2D0]
    10/01/2015|20:29:40.621 E64:38C0 INFO  :: Created Async workitem 0E8419B0, txn timeout 0, time sensitive 0, txn (00000000)
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: Async work item posted for TLS negotiation: this 0E6B4360
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerNotify::OnRecv - [0x0E8AD2D0]
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerSecurity::StartTlsNegotiationWorkitem TLS negotiation is in progress. Do not start another workitem, this 0E6B4360
    10/01/2015|20:29:40.621 E64:38C0 INFO  :: Created Async workitem 0E841B90, txn timeout 0, time sensitive 0, txn (00000000)
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: Async work item posted for TLS negotiation: this 0E6B4360
    10/01/2015|20:29:40.621 E64:38C0 INFO  :: deleting Async workitem 0E8419B0
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerNotify::OnRecv - [0x0E8AD2D0]
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerSecurity::StartTlsNegotiationWorkitem TLS negotiation is in progress. Do not start another workitem, this 0E6B4360
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerNotify::OnRecv - [0x0E8AD2D0]
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerSecurity::StartTlsNegotiationWorkitem TLS negotiation is in progress. Do not start another workitem, this 0E6B4360
    10/01/2015|20:29:40.621 E64:38C0 INFO  :: Created Async workitem 0E841C08, txn timeout 0, time sensitive 0, txn (00000000)
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: Async work item posted for TLS negotiation: this 0E6B4360
    10/01/2015|20:29:40.621 E64:38C0 INFO  :: deleting Async workitem 0E841B90
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerNotify::OnRecv - [0x0E8AD2D0]
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerSecurity::StartTlsNegotiationWorkitem TLS negotiation is in progress. Do not start another workitem, this 0E6B4360
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerNotify::OnRecv - [0x0E8AD2D0]
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerSecurity::StartTlsNegotiationWorkitem TLS negotiation is in progress. Do not start another workitem, this 0E6B4360
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerNotify::OnRecv - [0x0E8AD2D0]
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerSecurity::StartTlsNegotiationWorkitem TLS negotiation is in progress. Do not start another workitem, this 0E6B4360
    10/01/2015|20:29:40.621 E64:38C0 INFO  :: Created Async workitem 0E8419B0, txn timeout 0, time sensitive 0, txn (00000000)
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: Async work item posted for TLS negotiation: this 0E6B4360
    10/01/2015|20:29:40.621 E64:38C0 INFO  :: deleting Async workitem 0E841C08
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerNotify::OnRecv - [0x0E8AD2D0]
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerSecurity::StartTlsNegotiationWorkitem TLS negotiation is in progress. Do not start another workitem, this 0E6B4360
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerNotify::OnRecv - [0x0E8AD2D0]
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: CSIPTransportLayerSecurity::StartTlsNegotiationWorkitem TLS negotiation is in progress. Do not start another workitem, this 0E6B4360
    10/01/2015|20:29:40.621 E64:38C0 INFO  :: Created Async workitem 0E841B90, txn timeout 0, time sensitive 0, txn (00000000)
    10/01/2015|20:29:40.621 E64:38C0 TRACE :: Async work item posted for TLS negotiation: this 0E6B4360
    10/01/2015|20:29:40.621 E64:38C0 INFO  :: deleting Async workitem 0E8419B0
    10/01/2015|20:29:40.622 E64:38C0 TRACE :: CSIPAsyncSocket::Send this 0E8AD2C8, sending pbSendBuf 0E4B8030, dwSendBufSize = 333
    10/01/2015|20:29:40.622 E64:38C0 TRACE :: CSIPAsyncSocket::SendHelper - [0x0E8AD2C8]
    10/01/2015|20:29:40.622 E64:38C0 TRACE :: CAsyncSocketWin32::Send this 0E78BAA0, pbSendBuf 0E4B8030, dwSendBufSize = 333, dwBytesSent = 333
    10/01/2015|20:29:40.622 E64:38C0 INFO  :: deleting Async workitem 0E841B90
    10/01/2015|20:29:40.626 E64:38C0 TRACE :: CSIPTransportLayerNotify::OnRecv - [0x0E8AD2D0]
    10/01/2015|20:29:40.626 E64:38C0 INFO  :: Created Async workitem 0E841B90, txn timeout 0, time sensitive 0, txn (00000000)
    10/01/2015|20:29:40.626 E64:38C0 TRACE :: Async work item posted for TLS negotiation: this 0E6B4360
    10/01/2015|20:29:40.626 E64:348C TRACE :: SECURE_SOCKET: security negotiation has completed successfully
    10/01/2015|20:29:40.626 E64:348C TRACE :: SECURE_SOCKET: stream sizes: header 5 trailer 36 max message 16384 buffers 4 block size 16
    10/01/2015|20:29:40.626 E64:38C0 INFO  :: CSIPTransportLayerSecurity::OnTlsNegotiationComplete (0E6B4360) successful. Raising OnConnect with S_OK
    10/01/2015|20:29:40.626 E64:38C0 INFO  :: CSIPCompressor::StartCompressionNegotiation - Link test not yet performed, checking compression settings configured by core
    10/01/2015|20:29:40.626 E64:38C0 INFO  :: CSIPCompressor::StartCompressionNegotiation - Compression requested - beginning negotiation.
    10/01/2015|20:29:40.626 E64:38C0 TRACE :: - encrypted buffer length: 341 bytes.  First 8 bytes:
    10/01/2015|20:29:40.626 E64:38C0 TRACE ::     17 03 01 01 50 D9 F9 0E  :....PÙù.
    10/01/2015|20:29:40.626 E64:38C0 TRACE :: CSIPTransportLayerSecurity::Send after encryption, BytesLeft = 306, BytesProcessed = 306, cbEncryptedBufSize = 341, spEncryptedBuff = 094EAC10
    10/01/2015|20:29:40.626 E64:38C0 TRACE :: CSIPAsyncSocket::Send this 0E8AD2C8, sending pbSendBuf 094EAC10, dwSendBufSize = 341
    10/01/2015|20:29:40.626 E64:38C0 TRACE :: CSIPAsyncSocket::SendHelper - [0x0E8AD2C8]
    10/01/2015|20:29:40.626 E64:38C0 TRACE :: CAsyncSocketWin32::Send this 0E78BAA0, pbSendBuf 094EAC10, dwSendBufSize = 341, dwBytesSent = 341
    10/01/2015|20:29:40.626 E64:38C0 INFO  :: deleting Async workitem 0E841B90
    10/01/2015|20:29:50.627 E64:38C0 INFO  :: No response to compression NEGOTIATE request received, assume no compression
    10/01/2015|20:31:50.008 E64:38C0 INFO  :: CUccDnsResolverManager::Stop MR-INFO - [0E641BD0] DNS resolver manager stopped
    10/01/2015|20:31:50.008 E64:38C0 INFO  :: Function: CUccSubscriptionManager::DisableSubscriptionManager
    10/01/2015|20:31:50.008 E64:38C0 TRACE :: Condition failed with 80ee0061: 'm_fSubMgrEnabled'
    10/01/2015|20:31:50.008 E64:38C0 INFO  :: Function: CUccServerEndpoint::InternalDisable
    10/01/2015|20:31:50.008 E64:38C0 ERROR :: HRESULT API failed: 80ee0061 = hr. DisableSubscriptionManager
    10/01/2015|20:31:50.008 E64:38C0 INFO  :: Function: CUccPublicationManager::DisablePublicationManager
    10/01/2015|20:31:50.008 E64:38C0 TRACE :: Condition failed with 80ee0061: 'm_fIsEnabled'
    10/01/2015|20:31:50.008 E64:38C0 INFO  :: Function: CUccServerEndpoint::InternalDisable
    10/01/2015|20:31:50.008 E64:38C0 ERROR :: HRESULT API failed: 80ee0061 = hr. DisablePublicationManager
    10/01/2015|20:31:50.008 E64:38C0 INFO  :: Function: CUccUserSearchManager::DisableUserSearchManager
    10/01/2015|20:31:50.008 E64:38C0 TRACE :: Condition failed with 80ee0061: '1 == m_fEnabled'
    10/01/2015|20:31:50.008 E64:38C0 INFO  :: Function: CUccServerEndpoint::InternalDisable
    10/01/2015|20:31:50.008 E64:38C0 ERROR :: HRESULT API failed: 80ee0061 = hr. DisableUserSearchManager
    10/01/2015|20:31:50.008 E64:38C0 INFO  :: Function: CUccPrincipalServerEndpoint::InternalDisable
    10/01/2015|20:31:50.008 E64:38C0 ERROR :: HRESULT API failed: 80ee0061 = hr. failed to disable the endpoint
    10/01/2015|20:31:50.008 E64:38C0 INFO  :: CUccPrincipalServerEndpoint::GetSipProviderProfile - Found no http proxy creds
    10/01/2015|20:31:50.008 E64:38C0 INFO  :: SIP_ENDPOINT::SetSipAuthBroker (0E6425D8) _IUccSipAuthBroker(0x00000000)
    10/01/2015|20:31:50.008 E64:38C0 INFO  :: UCCP:ServerAuthProts0x0
    10/01/2015|20:31:50.008 E64:38C0 INFO  :: SIP_REGISTER:State (1) => (3)
    10/01/2015|20:31:50.008 E64:38C0 INFO  :: Out trxn corr-id (0E463B90)
    10/01/2015|20:31:50.011 E64:38C0 INFO  :: REGISTER_CONTEXT(e729e78) SetAndNotify Recv(2) at State (4)
    10/01/2015|20:31:50.011 E64:38C0 INFO  :: REGISTER_CONTEXT:State (4) => (2)

    Vieleicht hat ja von euch einern einen Hinweis!

    Danke

    madmax21

    Donnerstag, 1. Oktober 2015 18:43

Alle Antworten

  • Hallo,

    Was ich im log sehen kann ist, das der Client sich nicht verbinden kann.

    10/01/2015|20:29:40.617 E64:38C0 INFO  :: CAsyncSocketWin32::Connect 0E78BAA0 connecting(async) window 0031104C socket:1b34 DestAddr:192.168.154.129:5061
    10/01/2015|20:29:40.617 E64:38C0 ERROR :: CSIPAsyncSocket::Connect: HRESULT API failed: 80072733 = hr

    Hier müsste man mal genau schauen wo der Fehler liegt. Meistens liegt es an den Firewall Einstellungen. Die Server IP 192.168.154.129:5061 und Port werden ja richtig erkannt. Eventuell hilft auch ein Trace über den OCSLogger auf dem Frontendserver weiter.

    Ich gehe davon aus, die Zertifikate passen auf dem server und den entsprechenden Clients.

    Eventuell kann man auch noch mal mit dem RUCT Tool prüfen ob erstmal die generellen Einstellungen passen.

    Hier kann man auch den Lync Connectivity Analyzer zum Testen herunter laden.


    regards Holger Technical Specialist UC

    Freitag, 2. Oktober 2015 21:04
    Moderator
  • <meta content="text/html; charset=utf-8" http-equiv="Content-Type" /><title></title>

    Hallo Holger,

    vielen Dank für deine Antwort.

    Die beiden o.g. Tools (RUCT und LCA) zeigen keine Fehler an. Alles wird ordentlich aufgelöst. Auch bringen die Aufrufe:

    https://lync.xxx.de/Autodiscover/AutodiscoverService.svc/root?originalDomain=xxx.de

    https://lyncdiscoverinternal.xxx.de/?sipuri=vorname.nachname@xxx.de

    jeweils eine json Datei zurück (Zertifikate wird dabei auch richtig ohne Fehler angezeigt).

    Habe am Lync FE eine OCS LOG vom SIPStack erstellt:

    https://onedrive.live.com/redir?resid=fc6394ad4b7440b6!224&authkey=!ALTjc1iD9LqXJIY&ithint=file%2ctxt

    vielleicht kannst Du da etwas erkennen?

    Vielen Dank

    Marcel 

     



    • Bearbeitet madmax21 Montag, 5. Oktober 2015 08:59
    Montag, 5. Oktober 2015 07:00