locked
Live Meeting Connection Failure RRS feed

  • Question

  • I can have remote users (with AD credentials) access Communicator and Live Meeting from many computers outside of the LAN with no problems.

     

    Below is a trace of a Test Connection failing.  The user can successfully connect using OCS Communicator.  The same user can connect from another machine (outside the LAN) using OCS Communicator and Live Meeting Console.

     

    Both the computer that can connect and the one that fails have the same certificates and in theory the same connectivity.

     

    On the computer that fails to connect using Live Meeting with credentials can connect as an anonymous user.

     

    Any help is appreciated.  I have removed the actual IP addresses and the domain names.

     

     

    04/08/2007|20:10:22.562 A54:118 INFO  :: CUccMediaDeviceManager::FireDeviceChanged - Default device changed to <Microsoft RDP Audio Driver (emulated)> for category=2 media=1 direction=2 reason=2
    04/08/2007|20:10:22.562 A54:118 INFO  :: CUccMediaDeviceManager::FireDeviceChanged - Selected device changed to <Microsoft RDP Audio Driver (emulated)> for category=2 media=1 direction=2 reason=2
    04/08/2007|20:10:22.562 A54:118 INFO  :: CUccMediaDeviceManager::FireDeviceChanged - Default device changed to <Microsoft RDP Audio Driver (emulated)> for category=3 media=1 direction=2 reason=2
    04/08/2007|20:10:22.562 A54:118 INFO  :: CUccMediaDeviceManager::FireDeviceChanged - Selected device changed to <Microsoft RDP Audio Driver (emulated)> for category=3 media=1 direction=2 reason=2
    04/08/2007|20:10:22.562 A54:118 TRACE :: client[001184C0] new sipStack[00153F08]
    04/08/2007|20:10:22.562 A54:118 INFO  :: No registry setting, using default: "Mode" = 2
    04/08/2007|20:10:22.562 A54:118 INFO  :: No registry setting, using default: "NegotiateTimeout" = 5000
    04/08/2007|20:10:22.562 A54:118 INFO  :: No registry setting, using default: "Threshold" = 128
    04/08/2007|20:10:22.562 A54:118 TRACE :: CUccServerEndpoint::UpdateEndpointState - Update state from 1 to 2. Status 0. Status text (null).
    04/08/2007|20:10:22.562 A54:118 TRACE :: New mpss created: 0245F420, stack=00153F08, 0
    04/08/2007|20:10:22.562 A54:118 INFO  :: MSP.SetMultipartySsnRole[0245F420] 0->0
    04/08/2007|20:10:22.578 A54:118 TRACE :: MULTIPARTY_SESSION:Tongue TiedetConnectParams[0245F420] n=(null), uri=sipBig Smileiane@rtc.XXXXXX.com
    04/08/2007|20:10:22.578 A54:118 TRACE :: MULTIPARTY_SESSION::AddParty - Enter participant: sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793, this=sipBig Smileiane@rtc.XXXXXX.com, RM=(null)
    04/08/2007|20:10:22.578 A54:118 TRACE :: MULTIPARTY_SESSION::AddParty SessionState 0, m_fAddedFirstParty 0, participant: sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793, this 0245F420
    04/08/2007|20:10:22.578 A54:118 TRACE :: New SipCall:001566A8, dialog=001566FC, first=1, typ=0, mpss=0245F420, redir=00000000
    04/08/2007|20:10:22.578 A54:118 INFO  :: call.SetRemote[001566A8] sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793Sadnull);(null),0,0
    04/08/2007|20:10:22.578 A54:118 TRACE :: SIP_CALL:Tongue TiedetLocalAndRemoteForConnect - enter LocalDisplayName: 
    04/08/2007|20:10:22.578 A54:118 INFO  :: SIP_CALL:Tongue TiedetLocalAndRemoteForConnect - Call.Invite[001566A8]0Tongue TiedipBig Smileiane@rtc.XXXXXX.com==>sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793:, RM=(null)
    04/08/2007|20:10:22.578 A54:118 INFO  :: Outgoing 001566FC-<sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793>, local=sipBig Smileiane@rtc.XXXXXX.com
    04/08/2007|20:10:22.578 A54:118 INFO  :: call.SetRemote[001566A8] sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793Sadnull);(null)
    04/08/2007|20:10:22.578 A54:118 TRACE :: SIP_CALL::CreateOutgoingInviteTransaction[001566A8], first=1, headers=0, cookie=0
    04/08/2007|20:10:22.578 A54:118 TRACE :: call.SetState[001566A8]  SIP_CALL_STATE_IDLE-->SIP_CALL_STATE_CONNECTING for sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793, stack=00153F08
    04/08/2007|20:10:22.578 A54:118 TRACE :: SIP_CALL::NotifyCallStateChange[001566A8] : CallState : 3 StatusCode: 0, s=3
    04/08/2007|20:10:22.578 A54:118 TRACE :: MULTIPARTY_SESSION::NotifyCallChange[0245F420]- call-leg:001566A8, Callstate:3 StatusCode:0 SessionState:0
    04/08/2007|20:10:22.578 A54:118 INFO  :: MSP.SetState[0245F420] SIP_CALL_STATE_IDLE->SIP_CALL_STATE_CONNECTING, local=sipBig Smileiane@rtc.XXXXXX.com
    04/08/2007|20:10:22.578 A54:118 INFO  :: CUccConfSession::Enter - successfully sent INVITE
    04/08/2007|20:10:22.578 A54:118 TRACE :: SIP_MSG_PROCESSOR:SurprisenDnsResolutionComplete[001566FC] Entered host XXXXXXvrtcls.rtc.XXXXXX.com
    04/08/2007|20:10:22.578 A54:118 ERROR :: SIP_STACK::MapDestAddressToNatInternalAddress m_pDirectPlayNATHelp is NULL.  Setting *pIsDestExternalToNat to FALSE
    Some duplicate removed
    04/08/2007|20:10:22.703 A54:118 TRACE :: Async work item posted for TLS negotiation: this 00129C38
    04/08/2007|20:10:22.703 A54:118 TRACE :: ASYNC_SOCKET:Tongue TiedtartTlsNegotiationWorkitem TLS negotiation is in progress. Do not start another workitem, this 00129C38
    04/08/2007|20:10:22.703 A54:118 TRACE :: Async work item posted for TLS negotiation: this 00129C38
    Some duplicates removed
    04/08/2007|20:10:22.750 A54:118 TRACE :: Async work item posted for TLS negotiation: this 00129C38
    04/08/2007|20:10:22.750 A54:118 TRACE :: Async work item posted for TLS negotiation: this 00129C38
    04/08/2007|20:10:22.750 A54:118 TRACE :: ASYNC_SOCKET:Tongue TiedtartTlsNegotiationWorkitem TLS negotiation is in progress. Do not start another workitem, this 00129C38
    04/08/2007|20:10:22.750 A54:118 TRACE :: Async work item posted for TLS negotiation: this 00129C38
    04/08/2007|20:10:22.750 A54:118 TRACE :: Async work item posted for TLS negotiation: this 00129C38
    04/08/2007|20:10:22.750 A54:118 TRACE :: Async work item posted for TLS negotiation: this 00129C38
    04/08/2007|20:10:22.750 A54:118 TRACE :: Async work item posted for TLS negotiation: this 00129C38
    04/08/2007|20:10:22.765 A54:118 TRACE :: Async work item posted for TLS negotiation: this 00129C38
    04/08/2007|20:10:22.781 A54:118 TRACE :: Async work item posted for TLS negotiation: this 00129C38
    04/08/2007|20:10:22.781 A54:118 TRACE :: ASYNC_SOCKET:Tongue TiedtartTlsNegotiationWorkitem TLS negotiation is in progress. Do not start another workitem, this 00129C38
    04/08/2007|20:10:22.781 A54:118 TRACE :: Async work item posted for TLS negotiation: this 00129C38
    04/08/2007|20:10:22.828 A54:118 TRACE :: Async work item posted for TLS negotiation: this 00129C38
    04/08/2007|20:10:23.890 A54:FF8 TRACE :: SECURE_SOCKET: stream sizes: header 5 trailer 16 max message 16384 buffers 4 block size 1
    04/08/2007|20:10:23.890 A54:118 INFO  :: CSIPCompressor:Tongue TiedtartCompressionNegotiation - Link test not yet performed, checking registry for compression settings
    04/08/2007|20:10:23.890 A54:118 INFO  :: CSIPCompressor:Tongue TiedtartLinkSpeedDetectionUsingWindows - Testing link bandwidth using adapter info.
    04/08/2007|20:10:23.890 A54:118 INFO  :: Connection speed detected=1000000000, Threshold=128000, returning fUseCompression=FALSE
    04/08/2007|20:10:23.890 A54:118 TRACE :: SIP_MSG_PROCESSOR:SurprisenRequestSocketConnectComplete - Enter this: 001566FC, callid=(null), ErrorCode: 0x0
    04/08/2007|20:10:23.890 A54:118 INFO  :: Sending Packet - 99.999.99206:5061 (From Local Address: 192.168.1.103:1681) 1431 bytes:
    04/08/2007|20:10:23.890 A54:118 INFO  :: INVITE sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793 SIP/2.0Via: SIP/2.0/TLS 192.168.1.103:1681Max-Forwards: 70From: <sipBig Smileiane@rtc.XXXXXX.com>;tag=188cfba445;epid=cd3094d4d5To: <sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793>Call-ID: b357b0c96a1c457a91340b1eabbaefb2CSeq: 1 INVITEContact: <sipBig Smileiane@rtc.XXXXXX.com:1681;maddr=192.168.1.103;transport=tls>;proxy=replace;+sip.instance="<urn:uuid:B57B686B-6EE4-5D81-80D2-440668061AC2>"User-Agent: UCCP/2.0.6090.0 LMC/8.0.6090.0Supported: timerSupported: ms-senderms-keep-alive: UAC;hop-hop=yesContent-Type: application/cccp+xmlContent-Length: 726<?xml version="1.0"?><request xmlns="urn:ietfStick out tonguearams:xml:ns:cccp" xmlns:mscp="http://schemas.microsoft.com/rtc/2005/08/cccpextensions" C3PVersion="1" to="sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793" from="sipBig Smileiane@rtc.XXXXXX.com" requestId="0"><addUser><conferenceKeys confEntity="sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793"/><ci:user xmlns:ci="urn:ietfStick out tonguearams:xml:ns:conference-info" entity="sipBig Smileiane@rtc.XXXXXX.com"><ci:roles><ci:entry>attendee</ci:entry></ci:roles><ci:endpoint entity="{6A6E53A0-29CC-4FC8-BED9-7D592EEF5C5E}" xmlns:msci="http://schemas.microsoft.com/rtc/2005/08/confinfoextensions"/></ci:user></addUser></request>
    04/08/2007|20:10:23.890 A54:118 INFO  :: End of Sending Packet - 99.999.99206:5061 (From Local Address: 192.168.1.103:1681) 1431 bytes
    04/08/2007|20:10:23.890 A54:118 TRACE :: - encrypted buffer length: 1452 bytes.  First 8 bytes:
    04/08/2007|20:10:23.890 A54:118 TRACE ::  17 03 01 05 A7 DC AA 70  :....§Üªp
    04/08/2007|20:10:23.968 A54:118 TRACE :: SECURE_SOCKET: decrypting buffer size: 698 (first 8):
    04/08/2007|20:10:23.968 A54:118 TRACE ::  17 03 01 02 B5 9A AF 8F  :....µš¯
    04/08/2007|20:10:23.968 A54:118 INFO  :: Data Received - 99.999.99206:5061 (To Local Address: 192.168.1.103:1681) 677 bytes:
    04/08/2007|20:10:23.968 A54:118 INFO  :: SIP/2.0 401 UnauthorizedDate: Mon, 09 Apr 2007 03:13:20 GMTWWW-Authenticate: NTLM realm="SIP Communications Service", targetname="XXXXXXvrtcls.RTC.XXXXXX.com", version=3WWW-Authenticate: Kerberos realm="SIP Communications Service", targetname="sip/XXXXXXvrtcls.RTC.XXXXXX.com", version=3Via: SIP/2.0/TLS 192.168.1.103:1681;received=76.167.237.170;ms-received-port=1681;ms-received-cid=5C9B00From: <sipBig Smileiane@rtc.XXXXXX.com>;tag=188cfba445;epid=cd3094d4d5To: <sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793>;tag=B4EF4F9600638B78DF3E763D6C0A72DCCall-ID: b357b0c96a1c457a91340b1eabbaefb2CSeq: 1 INVITEContent-Length: 0
    04/08/2007|20:10:23.968 A54:118 INFO  :: End of Data Received - 99.999.99206:5061 (To Local Address: 192.168.1.103:1681) 677 bytes
    04/08/2007|20:10:23.968 A54:118 INFO  :: Sending Packet - 99.999.99206:5061 (From Local Address: 192.168.1.103:1681) 472 bytes:
    04/08/2007|20:10:23.968 A54:118 INFO  :: ACK sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793 SIP/2.0Via: SIP/2.0/TLS 192.168.1.103:1681Max-Forwards: 70From: <sipBig Smileiane@rtc.XXXXXX.com>;tag=188cfba445;epid=cd3094d4d5To: <sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793>;tag=B4EF4F9600638B78DF3E763D6C0A72DCCall-ID: b357b0c96a1c457a91340b1eabbaefb2CSeq: 1 ACKUser-Agent: UCCP/2.0.6090.0 LMC/8.0.6090.0Content-Length: 0
    04/08/2007|20:10:23.968 A54:118 INFO  :: End of Sending Packet - 99.999.99206:5061 (From Local Address: 192.168.1.103:1681) 472 bytes
    04/08/2007|20:10:23.968 A54:118 TRACE :: - encrypted buffer length: 493 bytes.  First 8 bytes:
    04/08/2007|20:10:23.968 A54:118 TRACE ::  17 03 01 01 E8 D0 A4 0B  :....èФ.
    04/08/2007|20:10:23.968 A54:118 INFO  :: SIP_MSG_PROCESSOR::GetChallengeListForPlatform ProtocolsFromPlatform=0xf
    04/08/2007|20:10:23.968 A54:118 TRACE :: SIP_STACK::UpdateProviderSAContextAndSetAuthProtocol adding SA with TargetName: sip/XXXXXXvrtcls.RTC.XXXXXX.com Auth: 8 to provider at index 0
    04/08/2007|20:10:23.968 A54:118 TRACE :: SIP_STACK::AddSAToProvider SA addedTongue Tiedip/XXXXXXvrtcls.RTC.XXXXXX.com-0246E568 list entry 00133698, this 00153F08
    04/08/2007|20:10:23.968 A54:118 TRACE :: SIP_STACK::FindProviderSAContext SA_CONTEXT foundTongue Tiedip/XXXXXXvrtcls.RTC.XXXXXX.com- 0246E568, SA list entry 00133698, this 00153F08
    04/08/2007|20:10:23.968 A54:118 TRACE :: SIP_MSG_PROCESSOR::GetSAListEntry- SA not found for TargetName: sip/XXXXXXvrtcls.RTC.XXXXXX.com Auth: 8, this 001566FC
    04/08/2007|20:10:23.968 A54:118 WARN  :: SIP_MSG_PROCESSOR::AddSAToList cannot find SA [0246E568] in list, inserting list entry [001336B0] into list, TargetName sip/XXXXXXvrtcls.RTC.XXXXXX.com, Auth: 8, this 001566FC
    04/08/2007|20:10:23.968 A54:118 TRACE :: SIP_MSG_PROCESSOR::GetSAListEntry SA [0246E568] targetname sip/XXXXXXvrtcls.RTC.XXXXXX.com, auth 8, this 001566FC
    04/08/2007|20:10:23.968 A54:118 TRACE :: SIP_STACK::FindProviderSAContext SA_CONTEXT foundTongue Tiedip/XXXXXXvrtcls.RTC.XXXXXX.com- 0246E568, SA list entry 00133698, this 00153F08
    04/08/2007|20:10:23.968 A54:118 TRACE :: Async work item posted for Init-SA: 0
    04/08/2007|20:10:23.968 A54:118 ERROR :: SIP_MSG_PROCESSOR::CompleteSAProcessingAndGetAuthHeader InitializeSecurityContext failed: 0x80090311

    04/08/2007|20:10:23.968 A54:118 ERROR :: SIP_MSG_PROCESSOR:SurprisenSAInitComplete - CompleteSAProcessingAndGetAuthHeader failed 80ee00a6
    04/08/2007|20:10:23.968 A54:118 TRACE :: call.SetState[001566A8]  SIP_CALL_STATE_CONNECTING-->SIP_CALL_STATE_DISCONNECTED for sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793, stack=00153F08
    04/08/2007|20:10:23.968 A54:118 TRACE :: SIP_CALL::NotifyCallStateChange[001566A8] : CallState : 2 StatusCode: 80ee00a6, s=2
    04/08/2007|20:10:23.968 A54:118 TRACE :: MULTIPARTY_SESSION::NotifyCallChange[0245F420]- call-leg:001566A8, Callstate:2 StatusCode:80ee00a6 SessionState:3
    04/08/2007|20:10:23.968 A54:118 INFO  :: Function: CSipCallDiagnosticData::Initialize
    04/08/2007|20:10:23.968 A54:118 ERROR :: Condition failed with 80070057: '(szRequestUri != 0 && szFromTag != 0 && szToTag != 0 && pResponseRecords != 0 )'
    04/08/2007|20:10:23.968 A54:118 INFO  :: Function: SIP_CALL::get_CallDiagnosticData
    04/08/2007|20:10:23.968 A54:118 ERROR :: HRESULT API failed: 80070057 = hr
    04/08/2007|20:10:23.968 A54:118 INFO  :: Function: CUccSession::ReportCallLegFailure
    04/08/2007|20:10:23.968 A54:118 ERROR :: HRESULT API failed: 80070057 = hr
    04/08/2007|20:10:23.968 A54:118 INFO  :: Function: CUccConfSession::NotifyPartyChange
    04/08/2007|20:10:23.968 A54:118 ERROR :: HRESULT API failed: 80070057 = hr. Reporting call leg failure
    04/08/2007|20:10:23.968 A54:118 TRACE :: MULTIPARTY_SESSION::HandleCallLegNotifyDisconnect number of participants remaining 0, this 0245F420
    04/08/2007|20:10:23.968 A54:118 INFO  :: MSP.SetState[0245F420] SIP_CALL_STATE_CONNECTING->SIP_CALL_STATE_DISCONNECTED, local=sipBig Smileiane@rtc.XXXXXX.com
    04/08/2007|20:10:23.968 A54:118 INFO  :: CUccConfSession::Cleanup - entering [02447C88], hr=0x80ee00a6, text=(null), retryAfter=0
    04/08/2007|20:10:23.968 A54:118 INFO  :: CUccConfSession:Big Smileisconnect - really disconnecting ...
    04/08/2007|20:10:23.968 A54:118 TRACE :: MULTIPARTY_SESSION:Big Smileisconnect - enter [0x0245F420]
    04/08/2007|20:10:23.968 A54:118 TRACE :: MULTIPARTY_SESSION::InternalDisconnect[0245F420] state 2, sc=0, t=(null)
    04/08/2007|20:10:23.968 A54:118 INFO  :: CUccSessionParticipant::InternalSetState [024785D8] - state: 0x1->0x5
    04/08/2007|20:10:23.968 A54:118 TRACE :: CUccSession::InternalRemoveParticipant - Removing participant sipBig Smileiane@rtc.XXXXXX.com
    04/08/2007|20:10:23.968 A54:118 INFO  :: CUccConfSession::Cleanup - exiting [02447C88]
    04/08/2007|20:10:45.140 A54:118 TRACE :: CUccServerEndpoint::UpdateEndpointState - Update state from 1 to 2. Status 0. Status text (null).
    04/08/2007|20:10:45.140 A54:118 TRACE :: New mpss created: 0245F628, stack=00153F08, 0
    04/08/2007|20:10:45.140 A54:118 INFO  :: MSP.SetMultipartySsnRole[0245F628] 0->0
    04/08/2007|20:10:45.140 A54:118 TRACE :: MULTIPARTY_SESSION:Tongue TiedetConnectParams[0245F628] n=(null), uri=sipBig Smileiane@rtc.XXXXXX.com
    04/08/2007|20:10:45.140 A54:118 TRACE :: MULTIPARTY_SESSION::AddParty - Enter participant: sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793, this=sipBig Smileiane@rtc.XXXXXX.com, RM=(null)
    04/08/2007|20:10:45.140 A54:118 TRACE :: MULTIPARTY_SESSION::AddParty SessionState 0, m_fAddedFirstParty 0, participant: sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793, this 0245F628
    04/08/2007|20:10:45.140 A54:118 TRACE :: New SipCall:001564F0, dialog=00156544, first=1, typ=0, mpss=0245F628, redir=00000000
    04/08/2007|20:10:45.140 A54:118 INFO  :: call.SetRemote[001564F0] sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793Sadnull);(null),0,0
    04/08/2007|20:10:45.140 A54:118 TRACE :: SIP_CALL:Tongue TiedetLocalAndRemoteForConnect - enter LocalDisplayName: 
    04/08/2007|20:10:45.140 A54:118 INFO  :: SIP_CALL:Tongue TiedetLocalAndRemoteForConnect - Call.Invite[001564F0]0Tongue TiedipBig Smileiane@rtc.XXXXXX.com==>sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793:, RM=(null)
    04/08/2007|20:10:45.140 A54:118 INFO  :: Outgoing 00156544-<sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793>, local=sipBig Smileiane@rtc.XXXXXX.com
    04/08/2007|20:10:45.140 A54:118 INFO  :: call.SetRemote[001564F0] sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793Sadnull);(null)
    04/08/2007|20:10:45.140 A54:118 TRACE :: SIP_CALL::CreateOutgoingInviteTransaction[001564F0], first=1, headers=0, cookie=0
    04/08/2007|20:10:45.140 A54:118 TRACE :: call.SetState[001564F0]  SIP_CALL_STATE_IDLE-->SIP_CALL_STATE_CONNECTING for sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793, stack=00153F08
    04/08/2007|20:10:45.140 A54:118 TRACE :: SIP_CALL::NotifyCallStateChange[001564F0] : CallState : 3 StatusCode: 0, s=3
    04/08/2007|20:10:45.140 A54:118 TRACE :: MULTIPARTY_SESSION::NotifyCallChange[0245F628]- call-leg:001564F0, Callstate:3 StatusCode:0 SessionState:0
    04/08/2007|20:10:45.140 A54:118 INFO  :: MSP.SetState[0245F628] SIP_CALL_STATE_IDLE->SIP_CALL_STATE_CONNECTING, local=sipBig Smileiane@rtc.XXXXXX.com
    04/08/2007|20:10:45.140 A54:118 INFO  :: CUccConfSession::Enter - successfully sent INVITE
    04/08/2007|20:10:45.171 A54:118 TRACE :: SIP_MSG_PROCESSOR:SurprisenDnsResolutionComplete[00156544] Entered host XXXXXXvrtcls.rtc.XXXXXX.com
    04/08/2007|20:10:45.171 A54:118 ERROR :: SIP_STACK::MapDestAddressToNatInternalAddress m_pDirectPlayNATHelp is NULL.  Setting *pIsDestExternalToNat to FALSE
    04/08/2007|20:10:45.203 A54:118 TRACE :: Async work item posted for TLS negotiation: this 00129DD0
    04/08/2007|20:10:45.453 A54:118 TRACE :: Async work item posted for TLS negotiation: this 00129DD0
    04/08/2007|20:10:46.531 A54:FF8 TRACE :: SECURE_SOCKET: stream sizes: header 5 trailer 16 max message 16384 buffers 4 block size 1
    04/08/2007|20:10:46.531 A54:118 INFO  :: CSIPCompressor:Tongue TiedtartCompressionNegotiation - Link test not yet performed, checking registry for compression settings
    04/08/2007|20:10:46.531 A54:118 INFO  :: CSIPCompressor:Tongue TiedtartLinkSpeedDetectionUsingWindows - Testing link bandwidth using adapter info.
    04/08/2007|20:10:46.531 A54:118 INFO  :: Connection speed detected=1000000000, Threshold=128000, returning fUseCompression=FALSE
    04/08/2007|20:10:46.531 A54:118 TRACE :: SIP_MSG_PROCESSOR:SurprisenRequestSocketConnectComplete - Enter this: 00156544, callid=(null), ErrorCode: 0x0
    04/08/2007|20:10:46.531 A54:118 INFO  :: Sending Packet - 99.999.99206:5061 (From Local Address: 192.168.1.103:1683) 1431 bytes:
    04/08/2007|20:10:46.531 A54:118 INFO  :: INVITE sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793 SIP/2.0Via: SIP/2.0/TLS 192.168.1.103:1683Max-Forwards: 70From: <sipBig Smileiane@rtc.XXXXXX.com>;tag=c0f2260479;epid=cd3094d4d5To: <sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793>Call-ID: 91bfdfe8a9d94b258ae164d86f6b84bbCSeq: 1 INVITEContact: <sipBig Smileiane@rtc.XXXXXX.com:1683;maddr=192.168.1.103;transport=tls>;proxy=replace;+sip.instance="<urn:uuid:B57B686B-6EE4-5D81-80D2-440668061AC2>"User-Agent: UCCP/2.0.6090.0 LMC/8.0.6090.0Supported: timerSupported: ms-senderms-keep-alive: UAC;hop-hop=yesContent-Type: application/cccp+xmlContent-Length: 726<?xml version="1.0"?><request xmlns="urn:ietfStick out tonguearams:xml:ns:cccp" xmlns:mscp="http://schemas.microsoft.com/rtc/2005/08/cccpextensions" C3PVersion="1" to="sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793" from="sipBig Smileiane@rtc.XXXXXX.com" requestId="0"><addUser><conferenceKeys confEntity="sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793"/><ci:user xmlns:ci="urn:ietfStick out tonguearams:xml:ns:conference-info" entity="sipBig Smileiane@rtc.XXXXXX.com"><ci:roles><ci:entry>attendee</ci:entry></ci:roles><ci:endpoint entity="{F57AA5E6-13FE-4EF7-9CF7-A093B8D1A1CF}" xmlns:msci="http://schemas.microsoft.com/rtc/2005/08/confinfoextensions"/></ci:user></addUser></request>
    04/08/2007|20:10:46.531 A54:118 INFO  :: End of Sending Packet - 99.999.99206:5061 (From Local Address: 192.168.1.103:1683) 1431 bytes
    04/08/2007|20:10:46.531 A54:118 TRACE :: - encrypted buffer length: 1452 bytes.  First 8 bytes:
    04/08/2007|20:10:46.531 A54:118 TRACE ::  17 03 01 05 A7 0D 34 96  :....§.4–
    04/08/2007|20:10:46.593 A54:118 TRACE :: SECURE_SOCKET: decrypting buffer size: 698 (first 8):
    04/08/2007|20:10:46.593 A54:118 TRACE ::  17 03 01 02 B5 EC 5A A5  :....µìZ¥
    04/08/2007|20:10:46.593 A54:118 INFO  :: Data Received - 99.999.99206:5061 (To Local Address: 192.168.1.103:1683) 677 bytes:
    04/08/2007|20:10:46.593 A54:118 INFO  :: SIP/2.0 401 UnauthorizedDate: Mon, 09 Apr 2007 03:13:43 GMTWWW-Authenticate: NTLM realm="SIP Communications Service", targetname="XXXXXXvrtcls.RTC.XXXXXX.com", version=3WWW-Authenticate: Kerberos realm="SIP Communications Service", targetname="sip/XXXXXXvrtcls.RTC.XXXXXX.com", version=3Via: SIP/2.0/TLS 192.168.1.103:1683;received=76.167.237.170;ms-received-port=1683;ms-received-cid=5C9C00From: <sipBig Smileiane@rtc.XXXXXX.com>;tag=c0f2260479;epid=cd3094d4d5To: <sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793>;tag=B4EF4F9600638B78DF3E763D6C0A72DCCall-ID: 91bfdfe8a9d94b258ae164d86f6b84bbCSeq: 1 INVITEContent-Length: 0
    04/08/2007|20:10:46.593 A54:118 INFO  :: End of Data Received - 99.999.99206:5061 (To Local Address: 192.168.1.103:1683) 677 bytes
    04/08/2007|20:10:46.593 A54:118 INFO  :: Sending Packet - 99.999.99206:5061 (From Local Address: 192.168.1.103:1683) 472 bytes:
    04/08/2007|20:10:46.593 A54:118 INFO  :: ACK sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793 SIP/2.0Via: SIP/2.0/TLS 192.168.1.103:1683Max-Forwards: 70From: <sipBig Smileiane@rtc.XXXXXX.com>;tag=c0f2260479;epid=cd3094d4d5To: <sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793>;tag=B4EF4F9600638B78DF3E763D6C0A72DCCall-ID: 91bfdfe8a9d94b258ae164d86f6b84bbCSeq: 1 ACKUser-Agent: UCCP/2.0.6090.0 LMC/8.0.6090.0Content-Length: 0
    04/08/2007|20:10:46.593 A54:118 INFO  :: End of Sending Packet - 99.999.99206:5061 (From Local Address: 192.168.1.103:1683) 472 bytes
    04/08/2007|20:10:46.593 A54:118 TRACE :: - encrypted buffer length: 493 bytes.  First 8 bytes:
    04/08/2007|20:10:46.593 A54:118 TRACE ::  17 03 01 01 E8 FE 45 DA  :....èþEÚ
    04/08/2007|20:10:46.593 A54:118 INFO  :: SIP_MSG_PROCESSOR::GetChallengeListForPlatform ProtocolsFromPlatform=0xf
    04/08/2007|20:10:46.593 A54:118 TRACE :: SIP_STACK::UpdateProviderSAContextAndSetAuthProtocol adding SA with TargetName: sip/XXXXXXvrtcls.RTC.XXXXXX.com Auth: 8 to provider at index 1
    04/08/2007|20:10:46.593 A54:118 TRACE :: SIP_STACK::AddSAToProvider SA addedTongue Tiedip/XXXXXXvrtcls.RTC.XXXXXX.com-0246E670 list entry 001574C8, this 00153F08
    04/08/2007|20:10:46.593 A54:118 TRACE :: SIP_STACK::FindProviderSAContext SA_CONTEXT foundTongue Tiedip/XXXXXXvrtcls.RTC.XXXXXX.com- 0246E670, SA list entry 001574C8, this 00153F08
    04/08/2007|20:10:46.593 A54:118 TRACE :: SIP_MSG_PROCESSOR::GetSAListEntry- SA not found for TargetName: sip/XXXXXXvrtcls.RTC.XXXXXX.com Auth: 8, this 00156544
    04/08/2007|20:10:46.593 A54:118 WARN  :: SIP_MSG_PROCESSOR::AddSAToList cannot find SA [0246E670] in list, inserting list entry [001574E0] into list, TargetName sip/XXXXXXvrtcls.RTC.XXXXXX.com, Auth: 8, this 00156544
    04/08/2007|20:10:46.593 A54:118 TRACE :: SIP_MSG_PROCESSOR::GetSAListEntry SA [0246E670] targetname sip/XXXXXXvrtcls.RTC.XXXXXX.com, auth 8, this 00156544
    04/08/2007|20:10:46.593 A54:118 TRACE :: SIP_STACK::FindProviderSAContext SA_CONTEXT foundTongue Tiedip/XXXXXXvrtcls.RTC.XXXXXX.com- 0246E670, SA list entry 001574C8, this 00153F08
    04/08/2007|20:10:46.593 A54:118 TRACE :: Async work item posted for Init-SA: 0
    04/08/2007|20:10:46.593 A54:118 ERROR :: SIP_MSG_PROCESSOR::CompleteSAProcessingAndGetAuthHeader InitializeSecurityContext failed: 0x80090311

    04/08/2007|20:10:46.593 A54:118 ERROR :: SIP_MSG_PROCESSOR:SurprisenSAInitComplete - CompleteSAProcessingAndGetAuthHeader failed 80ee00a6
    04/08/2007|20:10:46.593 A54:118 TRACE :: call.SetState[001564F0]  SIP_CALL_STATE_CONNECTING-->SIP_CALL_STATE_DISCONNECTED for sipBig Smileoug@rtc.XXXXXX.com;gruu;opaque=app:conf:focus:id:b8d188ef7004437fa01d056c62545793, stack=00153F08
    04/08/2007|20:10:46.593 A54:118 TRACE :: SIP_CALL::NotifyCallStateChange[001564F0] : CallState : 2 StatusCode: 80ee00a6, s=2
    04/08/2007|20:10:46.593 A54:118 TRACE :: MULTIPARTY_SESSION::NotifyCallChange[0245F628]- call-leg:001564F0, Callstate:2 StatusCode:80ee00a6 SessionState:3
    04/08/2007|20:10:46.593 A54:118 INFO  :: Function: CSipCallDiagnosticData::Initialize
    04/08/2007|20:10:46.593 A54:118 ERROR :: Condition failed with 80070057: '(szRequestUri != 0 && szFromTag != 0 && szToTag != 0 && pResponseRecords != 0 )'
    04/08/2007|20:10:46.593 A54:118 INFO  :: Function: SIP_CALL::get_CallDiagnosticData
    04/08/2007|20:10:46.593 A54:118 ERROR :: HRESULT API failed: 80070057 = hr
    04/08/2007|20:10:46.593 A54:118 INFO  :: Function: CUccSession::ReportCallLegFailure
    04/08/2007|20:10:46.593 A54:118 ERROR :: HRESULT API failed: 80070057 = hr
    04/08/2007|20:10:46.593 A54:118 INFO  :: Function: CUccConfSession::NotifyPartyChange
    04/08/2007|20:10:46.593 A54:118 ERROR :: HRESULT API failed: 80070057 = hr. Reporting call leg failure
    04/08/2007|20:10:46.593 A54:118 TRACE :: MULTIPARTY_SESSION::HandleCallLegNotifyDisconnect number of participants remaining 0, this 0245F628
    04/08/2007|20:10:46.593 A54:118 INFO  :: MSP.SetState[0245F628] SIP_CALL_STATE_CONNECTING->SIP_CALL_STATE_DISCONNECTED, local=sipBig Smileiane@rtc.XXXXXX.com
    04/08/2007|20:10:46.593 A54:118 INFO  :: CUccConfSession::Cleanup - entering [02447F30], hr=0x80ee00a6, text=(null), retryAfter=0
    04/08/2007|20:10:46.593 A54:118 INFO  :: CUccConfSession:Big Smileisconnect - really disconnecting ...
    04/08/2007|20:10:46.593 A54:118 TRACE :: MULTIPARTY_SESSION:Big Smileisconnect - enter [0x0245F628]
    04/08/2007|20:10:46.593 A54:118 TRACE :: MULTIPARTY_SESSION::InternalDisconnect[0245F628] state 2, sc=0, t=(null)
    04/08/2007|20:10:46.593 A54:118 INFO  :: CUccSessionParticipant::InternalSetState [024785D8] - state: 0x1->0x5
    04/08/2007|20:10:46.593 A54:118 TRACE :: CUccSession::InternalRemoveParticipant - Removing participant sipBig Smileiane@rtc.XXXXXX.com
    04/08/2007|20:10:46.593 A54:118 INFO  :: CUccConfSession::Cleanup - exiting [02447F30]
    04/08/2007|20:10:48.000 A54:118 TRACE :: CUccServerEndpoint::UpdateEndpointState - Update state from 3 to 4. Status 0. Status text (null).
    04/08/2007|20:10:48.000 A54:118 TRACE :: CUccServerEndpoint::UpdateEndpointState - Update state from 3 to 4. Status 0. Status text (null).
    04/08/2007|20:10:48.000 A54:118 TRACE :: SIP_STACK::FreeProviderSAList deleting SA list entry [00133698] with SA [0246E568] from provider
    04/08/2007|20:10:48.000 A54:118 TRACE :: SIP_STACK:Big SmileeleteProviderProfile freed profile at index 0
    04/08/2007|20:10:48.000 A54:118 TRACE :: SIP_STACK::FreeProviderSAList deleting SA list entry [001574C8] with SA [0246E670] from provider
    04/08/2007|20:10:48.000 A54:118 TRACE :: SIP_STACK:Big SmileeleteAllProviderProfiles freed profile at index 0

    Doug

    Monday, April 9, 2007 3:47 PM

Answers

  • Due to lack of response, this post has been closed. If you have a similar issue please start another post.
    Tuesday, May 15, 2007 5:17 AM

All replies

  • Hi Dougb123456,

    We need to collect the following logs.

    On the client:

    • Pwconsole.log located in the %temp% directory.  
    • Confapi.log located in the %userprofile%\tracing directory. 

    The pwconsole is enabled by default, but the confapi is enabled in registry here:

    HKEY_CURRENT_USER\Software\Microsoft\Tracing\uccp\ConfAPI change the value of EnableFileTracing  to 1

    On your Edge Server: 

    In Computer Management click Service and Applications and then Office Communications Server. Right-click, click Logging Tool  and then click New Debug Session.

    • In Components check DataProxy, SipStack
    • Under Level click All
    • Under Flags click All Flags
    • Then click  Start Logging

    On your Front End Server:

    Right-click the Pool, click Logging Tool, click New Debug Session

    • In Components check SIPStack, UserServices, DataMCu, 
    • Under Level click All
      Flags All Flags.

    Can you post these logs here?

    Monday, April 30, 2007 7:03 PM
  • Hi,

    Can you let us know the status of your issue? Did you find a solution? If so, can you post it for the rest of the forum to see? If you still have the issue please let us know ASAP and provide the requested informaiton?

    Monday, May 7, 2007 7:22 PM
  • Due to lack of response, this post has been closed. If you have a similar issue please start another post.
    Tuesday, May 15, 2007 5:17 AM