none
Lync Incomming Calls Not work, error 408

    Question

  • I insalled a standard edition lync pool, with a separate mediation server. i have a cisco sip gateway (mc 3810). outgoing calls go through the gateway sucssefully. but i have a problem with incoming calls. error : Start-Line: Terminating Proxy side call with session state: Idle, sip-response: 408, ms-diag: 10001, ms-diag reason: Gateway did not respond in a timely manner (timeout). how can i solv ?
    Ahmed Mostafa
    Tuesday, March 15, 2011 7:38 AM

All replies

  • You should use Lync logging tool, activate SIPStack and S4, both with Level All, All Flags. After doing a trace click "Analyze" (Lync Reskit tools must be installed) and you will see full details of SIP conversation. Then check incoming INVITE and all related conversation (right click offers filtering option). If you can't find the reason this way please post those SIP messages here.
    Johann Deutinger | MCTS Exchange 2007/2010 / OCS 2007 | ucblog.deutinger.de
    Tuesday, March 15, 2011 7:20 PM
  • please find the below log

     

    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.219.00c9a782 (MediationServer,GatewayListener.GatewaySessionReceivedEventHandler:gatewaylistener.cs(566))(0000000003A88A3C)$$START-MEDIATIONSERVER
    MediationCall: 3aae50e6-3390-4c6b-ac03-60504758855b
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Received new Gateway incoming session with requestUri: sip:1111@192.168.1.5:5060
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.219.00c9a783 (MediationServer,GatewayListener.GatewaySessionReceivedEventHandler:gatewaylistener.cs(568))[1][0]->[60613391]$$START-MEDIATIONSERVER
    MediationCall: 3aae50e6-3390-4c6b-ac03-60504758855b
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Mapping GatewayListener to incoming session
    $$END-MEDIATIONSERVER
    TL_VERBOSE(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.219.00c9a784 (MediationServer,SessionCommon.MatchSessionToNextHopPeerForInbound:sessioncommon.cs(404))This connection has been matched to the next hop peer 195.4.191.13
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.219.00c9a785 (MediationServer,MediationCall.constructor:mediationcall.cs(64))$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Mediation Call constructor.
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.219.00c9a786 (MediationServer,MediationCall.constructor:mediationcall.cs(66))[1][0]->[60613391]$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Mapping MediationCall to incoming session
    $$END-MEDIATIONSERVER
    TL_INFO(TF_PROTOCOL) [0]151C.12CC::03/17/2011-16:47:52.219.00c9a787 (MediationServer,MediationCall.constructor:mediationcall.cs(85))$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Incoming signalling headers: ALLOW:INVITE
    ALLOW:OPTIONS
    ALLOW:BYE
    ALLOW:CANCEL
    ALLOW:ACK
    ALLOW:PRACK
    ALLOW:COMET
    ALLOW:REFER
    ALLOW:SUBSCRIBE
    ALLOW:NOTIFY
    ALLOW:INFO
    ALLOW-EVENTS:telephone-event
    CALL-ID:9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    CONTACT:<sip:6004@195.4.191.13:5060;transport=tcp>
    CONTENT-LENGTH:274
    CONTENT-TYPE:application/sdp
    CSEQ:101 INVITE
    DATE:Tue, 01 Jun 1993 04:16:26 GMT
    EXPIRES:180
    FROM:"Mans_BPX_FXS"<sip:6004@195.4.191.13>;tag=DAB3DAB3-5C
    MAX-FORWARDS:6
    SUPPORTED:timer
    SUPPORTED:100rel
    TIMESTAMP:738908186
    TO:<sip:1111@192.168.1.5>
    USER-AGENT:Cisco-SIPGateway/IOS-12.x
    VIA:SIP/2.0/TCP 195.4.191.13;branch=z9hG4bK3328878
    Min-SE:1800
    Cisco-Guid:2523217516-1566380492-2359417551-1710919112
    Remote-Party-ID:<sip:6004@195.4.191.13>;party=calling;screen=no;privacy=off

    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.220.00c9a788 (MediationServer,MsMimeHelper.ParseSdpMimeBody:msmimehelper.cs(281))Number of application/gw-sdp Mime Content: 0
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.220.00c9a789 (MediationServer,SessionCommon.GetOutgoingFromHeaderInfoInbound:sessioncommon.cs(1116))$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line:  Inbound call's from header user part has valid phone number .
    $$END-MEDIATIONSERVER
    TL_INFO(TF_PROTOCOL) [0]151C.12CC::03/17/2011-16:47:52.220.00c9a78a (MediationServer,GatewaySDP.ParseSdpOffer:gatewaysdp.cs(969))(00000000014325B3)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5
    Direction: Inbound
    Start-Line: Receive invite from Gateway, SDP is: v=0
    o=CiscoSystemsSIP-GW-UserAgent 4304 1084 IN IP4 195.4.191.13
    s=SIP Call
    c=IN IP4 195.4.191.13
    t=0 0
    m=audio 16856 RTP/AVP 0 101 13
    c=IN IP4 195.4.191.13
    a=rtpmap:0 PCMU/8000
    a=rtpmap:101 telephone-event/8000
    a=fmtp:101 0-16
    a=rtpmap:13 CN/8000
    a=ptime:20

    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.220.00c9a78b (MediationServer,SessionCommon.ApplyOutgoingRequestURIRules:sessioncommon.cs(1179))$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Valid phone number for inbound call.
    $$END-MEDIATIONSERVER
    TL_INFO(TF_PROTOCOL) [0]151C.12CC::03/17/2011-16:47:52.220.00c9a78c (MediationServer,MediationCall.CreateOutgoingSession:mediationcall.cs(1296))(00000000028217C0)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Creating outgoing Session. Local URI: sip:6004;phone-context=Global@sipdomain.com;user=phone, Local DisplayName: Mans_BPX_FXS, Remote Uri: sip:1111;phone-context=Global@sipdomain.com;user=phone
    $$END-MEDIATIONSERVER
    TL_INFO(TF_PROTOCOL) [0]151C.12CC::03/17/2011-16:47:52.221.00c9a78d (MediationServer,MediationCall.CreateOutgoingSession:mediationcall.cs(1307))(00000000028217C0)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Created outgoing Session. Local URI: sip:6004;phone-context=Global@sipdomain.com;user=phone, Local DisplayName: Mans_BPX_FXS, Remote Uri: sip:1111;phone-context=Global@sipdomain.com;user=phone, Call Id: df3d59a8-46eb-481b-ad55-dbc645405726
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.221.00c9a78e (MediationServer,MediationCall.CreateOutgoingSession:mediationcall.cs(1336))[1][0]->[11268815]$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Mapping inbound ProxyCall to outgoing session
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.221.00c9a78f (MediationServer,MediationMedia.CreateConference:mediationmedia.cs(81))(00000000021AEB04)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Conference Created.
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.222.00c9a790 (MediationServer,ProxyCall.CreateMediaSessionAgent:proxycall.cs(1419))(0000000000354758)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: df3d59a8-46eb-481b-ad55-dbc645405726
    From: sip:6004;phone-context=Global@sipdomain.com;user=phone
    To: sip:1111;phone-context=Global@sipdomain.com;user=phone
    Direction: Inbound
    Start-Line: ProxyCall creating MediaSessionAgent with the following settings. Flags=ApplicationIsB2BUA. EnableBandwidthPolicy=False. SipCallId=df3d59a8-46eb-481b-ad55-dbc645405726. LocalLocation=Intranet. RemoteLocation=Unknown. Federation=NonFederated. EncyptionMode=Supported. SessionIPAddress=null.
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.222.00c9a791 (MediationServer,MediaSessionAgent.constructor:mediasessionagent.cs(849))$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: df3d59a8-46eb-481b-ad55-dbc645405726
    From: sip:6004;phone-context=Global@sipdomain.com;user=phone
    To: sip:1111;phone-context=Global@sipdomain.com;user=phone
    Direction: Inbound
    Start-Line:  MediaSessionAgent component initialized. Encryption policy: Supported
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.222.00c9a792 (MediationServer,MediaAsyncResult<TEx>.constructor:asyncresult.cs(368))<StartFinishedAsyncResult_5EDA3D> Owner: <null>, Microsoft.RTC.MediationServerCore.StartFinishedAsyncResult created. External callback:<AsyncCallback_F8CFB578>, OperationId: NULL
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.222.00c9a793 (MediationServer,MediationCall.EnablePsm:mediationcall.cs(777))(00000000028217C0)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: PSM metrics are enabled on Gateway leg for this call
    $$END-MEDIATIONSERVER
    TL_VERBOSE(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.222.00c9a794 (MediationServer,GatewayMedia.BeginApplyMediaLines:gatewaymedia.cs(114))(0000000003C7E714)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5
    Direction: Inbound
    Start-Line: GatewayMedia's BeginApplyMediaLines was called while m_shutdown = False with psmEnabled = True, dtmfLeakRemoval = False, bwManagementEnabled = False, retargeting = NotRetargeting.
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.222.00c9a795 (MediationServer,MediaAsyncResult<TEx>.constructor:asyncresult.cs(368))<ApplyMediaLineWorkitemAsyncResult_365F4AB> Owner: <null>, Microsoft.RTC.MediationServerCore.ApplyMediaLineWorkitemAsyncResult created. External callback:<AsyncCallback_F8CFB578>, OperationId: NULL
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.222.00c9a796 (MediationServer,MediaAsyncResult<TEx>.constructor:asyncresult.cs(368))<GetConnectivityServersAsyncResult_35D1219> Owner: <null>, Microsoft.Rtc.Collaboration.AudioVideo.MediaSessionAgent+GetConnectivityServersAsyncResult created. External callback:<AsyncCallback_F8CFB578>, OperationId: NULL
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.222.00c9a79e (MediationServer,CountingAsyncResult.IncrementOperationCount:asyncresult.cs(1052))(00000000035D1219)<GetConnectivityServersAsyncResult_35D1219> Owner: <null> Adding operations 1 (0/1)
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.223.00c9a79f (MediationServer,CountingAsyncResult.WrappedOperationComplete:asyncresult.cs(1023))(00000000035D1219)<GetConnectivityServersAsyncResult_35D1219> Owner: <null> WrappedOperationComplete: 1/1 Async = <null>
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.223.00c9a7a0 (MediationServer,MediaAsyncResult<TEx>.Complete:asyncresult.cs(617))(00000000035D1219)<GetConnectivityServersAsyncResult_35D1219> Owner: <null> Completing operation: Microsoft.Rtc.Collaboration.AudioVideo.MediaSessionAgent+GetConnectivityServersAsyncResult
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.223.00c9a7a1 (MediationServer,MediaAsyncResult<TEx>.MakeCallback:asyncresult.cs(695))(00000000035D1219)<GetConnectivityServersAsyncResult_35D1219> Owner: <null> External callback=<AsyncCallback_F8CFB578>
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.223.00c9a7a2 (MediationServer,MediationMedia.CreateParticipant:mediationmedia.cs(131))(00000000021AEB04)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Creating Participant.
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.223.00c9a7a3 (MediationServer,GatewayMedia.ProcessApplyMediaLineWorkItem:gatewaymedia.cs(211))(0000000003C7E714)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5
    Direction: Inbound
    Start-Line: Adding audio stream.
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.223.00c9a7a4 (MediationServer,MediationMedia.CreateAudioChannel:mediationmedia.cs(115))(00000000021AEB04)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Creating Audio Channel.
    $$END-MEDIATIONSERVER
    TL_VERBOSE(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.225.00c9a7a5 (MediationServer,GatewayStream.CreateAndConfigureRtpAudioChannel:gatewaystream.cs(193))(0000000002A27C1C)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5
    Direction: Inbound
    Media: audio
    Start-Line: ReceiveStream EnableLeakedToneRemoval set to False.
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [0]151C.12CC::03/17/2011-16:47:52.225.00c9a7a6 (MediationServer,MediationMedia.CreateEndpoint:mediationmedia.cs(147))(00000000021AEB04)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Creating Endpoint.
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [2]151C.1328::03/17/2011-16:47:52.467.00c9a7a7 (MediationServer,MediationMedia.CreateMediaSender:mediationmedia.cs(179))(00000000021AEB04)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Creating MediaSender.
    $$END-MEDIATIONSERVER
    TL_VERBOSE(TF_COMPONENT) [2]151C.1328::03/17/2011-16:47:52.467.00c9a7a8 (MediationServer,ApplyMediaLineWorkitemAsyncResult.FinishStreamInitialize:gatewaymedia.cs(1190))(000000000365F4AB)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5
    Direction: Inbound
    Start-Line: Media Stack returned a reason code of: None
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [2]151C.1328::03/17/2011-16:47:52.467.00c9a7a9 (MediationServer,MediaAsyncResult<TEx>.Complete:asyncresult.cs(617))(000000000365F4AB)<ApplyMediaLineWorkitemAsyncResult_365F4AB> Owner: <null> Completing operation: Microsoft.RTC.MediationServerCore.ApplyMediaLineWorkitemAsyncResult
    TL_INFO(TF_COMPONENT) [2]151C.1328::03/17/2011-16:47:52.467.00c9a7aa (MediationServer,MediaAsyncResult<TEx>.MakeCallback:asyncresult.cs(695))(000000000365F4AB)<ApplyMediaLineWorkitemAsyncResult_365F4AB> Owner: <null> External callback=<AsyncCallback_F8CFB578>
    TL_VERBOSE(TF_COMPONENT) [2]151C.1328::03/17/2011-16:47:52.468.00c9a7ab (MediationServer,GatewayCall.FinishApplyMediaLineDescriptions:gatewaycall.cs(879))(000000000221DA43)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5
    Direction: Inbound
    Start-Line: Complete applying media line descriptions, result: CompletedSuccessfully.
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [2]151C.1328::03/17/2011-16:47:52.468.00c9a7ac (MediationServer,GatewaySDP.SetAvailableLocalCodecs:gatewaysdp.cs(281))(00000000014325B3)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5
    Direction: Inbound
    Start-Line: Stack returned 2 enabled codecs (8 0 ) on the gateway side.
    $$END-MEDIATIONSERVER
    TL_INFO(TF_PROTOCOL) [2]151C.1328::03/17/2011-16:47:52.468.00c9a7ad (MediationServer,GatewaySDP.GetAnswer:gatewaysdp.cs(476))(00000000014325B3)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5
    Direction: Inbound
    Start-Line: Send SDP Answer to Gateway, SDP is: v=0
    o=- 4 1 IN IP4 192.168.1.5
    s=session
    c=IN IP4 192.168.1.5
    b=CT:1000
    t=0 0
    m=audio 52478 RTP/AVP 0 101 13
    c=IN IP4 192.168.1.5
    a=rtcp:52479
    a=label:Audio
    a=sendrecv
    a=rtpmap:0 PCMU/8000
    a=rtpmap:101 telephone-event/8000
    a=fmtp:101 0-16
    a=rtpmap:13 CN/8000

    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [2]151C.1328::03/17/2011-16:47:52.469.00c9a7ce (MediationServer,MediaAsyncResult<TEx>.Complete:asyncresult.cs(617))(00000000005EDA3D)<StartFinishedAsyncResult_5EDA3D> Owner: <null> Completing operation: Microsoft.RTC.MediationServerCore.StartFinishedAsyncResult
    TL_INFO(TF_COMPONENT) [2]151C.1328::03/17/2011-16:47:52.469.00c9a7cf (MediationServer,MediaAsyncResult<TEx>.MakeCallback:asyncresult.cs(695))(00000000005EDA3D)<StartFinishedAsyncResult_5EDA3D> Owner: <null> External callback=<AsyncCallback_F8CFB578>
    TL_INFO(TF_PROTOCOL) [1]151C.1328::03/17/2011-16:48:25.110.00cc3586 (MediationServer,GatewayCall.FinishSendReliableProvisionalResponse:gatewaycall.cs(1498))(000000000221DA43)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5
    Direction: Inbound
    Start-Line: OperationTimeoutException: Microsoft.Rtc.Signaling.OperationTimeoutException:This operation has timed out.
       at Microsoft.Rtc.Signaling.SipAsyncResult2`1.ThrowIfFailed()
       at Microsoft.Rtc.Signaling.SipAsyncResultBase2.EndAsyncOperation[TResult](Object owner, IAsyncResult result)
       at Microsoft.Rtc.Signaling.SignalingSession.EndSendProvisionalResponse(IAsyncResult result)
       at Microsoft.RTC.MediationServerCore.GatewayCall.FinishSendReliableProvisionalResponse(IAsyncResult ar)
    Detected at System.Environment.get_StackTrace()
       at Microsoft.Rtc.Signaling.Helper.get_StackTrace()
       at Microsoft.Rtc.Signaling.OperationTimeoutException..ctor(String message)
       at Microsoft.Rtc.Signaling.SipProvisionalResponseAsyncResult.TimerItem_Expired(Object sender, EventArgs e)
       at Microsoft.Rtc.Signaling.TimerItem.OnExpired(Object state)
       at Microsoft.Rtc.Signaling.QueueWorkItemState.ExecuteWrappedMethod(WaitCallback method, Object state)
       at System.Threading.ExecutionContext.Run(ExecutionContext executionContext, ContextCallback callback, Object state)
       at System.Threading._ThreadPoolWaitCallback.PerformWaitCallbackInternal(_ThreadPoolWaitCallback tpWaitCallBack)
       at System.Threading._ThreadPoolWaitCallback.PerformWaitCallback(Object state)
    $$END-MEDIATIONSERVER
    TL_VERBOSE(TF_COMPONENT) [1]151C.1328::03/17/2011-16:48:25.110.00cc3587 (MediationServer,GatewayMedia.InnerDispose:gatewaymedia.cs(62))(0000000003C7E714)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5
    Direction: Inbound
    Start-Line: GatewayMedia's InnerDispose was called with shutdown = True.
    $$END-MEDIATIONSERVER
    TL_VERBOSE(TF_COMPONENT) [1]151C.1328::03/17/2011-16:48:25.110.00cc3588 (MediationServer,GatewayMedia.DisposeGatewayStream:gatewaymedia.cs(774))(0000000003C7E714)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5
    Direction: Inbound
    Start-Line: Disposing Gateway Stream audio, Initialized = True.
    $$END-MEDIATIONSERVER
    TL_VERBOSE(TF_COMPONENT) [1]151C.1328::03/17/2011-16:48:25.110.00cc3589 (MediationServer,GatewayStream.InternalDispose:gatewaystream.cs(685))(0000000002A27C1C)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5
    Direction: Inbound
    Media: audio
    Start-Line:  Disposing Gateway stream media resources.
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [1]151C.1328::03/17/2011-16:48:25.171.00cc358a (MediationServer,MediaAsyncResult<TEx>.constructor:asyncresult.cs(368))<SendReportAsyncResult_264996C> Owner: <null>, Microsoft.Rtc.Internal.Qoe.QoeReportSender+SendReportAsyncResult created. External callback:<AsyncCallback_F8CFB578>, OperationId: NULL
    TL_INFO(TF_COMPONENT) [1]151C.1328::03/17/2011-16:48:25.171.00cc358b (MediationServer,QoeReportSender.BeginSendReportInternal:qoeagent.cs(236))<OBJ><T>static QoeReportSender, hash of QoeAgent</T><ADR>02F7C705</ADR><MSG>QoE report not sent. DialogStart not set. NULL </MSG></OBJ>
    TL_INFO(TF_COMPONENT) [1]151C.1328::03/17/2011-16:48:25.171.00cc358c (MediationServer,MediaAsyncResult<TEx>.Complete:asyncresult.cs(617))(000000000264996C)<SendReportAsyncResult_264996C> Owner: <null> Completing operation: Microsoft.Rtc.Internal.Qoe.QoeReportSender+SendReportAsyncResult
    TL_INFO(TF_COMPONENT) [1]151C.1328::03/17/2011-16:48:25.171.00cc358d (MediationServer,MediaAsyncResult<TEx>.MakeCallback:asyncresult.cs(695))(000000000264996C)<SendReportAsyncResult_264996C> Owner: <null> External callback=<AsyncCallback_F8CFB578>
    TL_INFO(TF_COMPONENT) [1]151C.1328::03/17/2011-16:48:25.172.00cc35a8 (MediationServer,ProxyCall.TerminateSession:proxycall.cs(3303))(0000000000354758)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: df3d59a8-46eb-481b-ad55-dbc645405726
    From: sip:6004;phone-context=Global@sipdomain.com;user=phone
    To: sip:1111;phone-context=Global@sipdomain.com;user=phone
    Direction: Inbound
    Start-Line: Send back P-Asserted-Identity header to Proxy with displayname: "Mans_BPX_FXS" value: <sip:6004;phone-context=Global@sipdomain.com;user=phone>
    $$END-MEDIATIONSERVER
    TL_INFO(TF_PROTOCOL) [1]151C.1328::03/17/2011-16:48:25.172.00cc35a9 (MediationServer,ProxyCall.TerminateSession:proxycall.cs(3311))(0000000000354758)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: df3d59a8-46eb-481b-ad55-dbc645405726
    From: sip:6004;phone-context=Global@sipdomain.com;user=phone
    To: sip:1111;phone-context=Global@sipdomain.com;user=phone
    Direction: Inbound
    Start-Line: Terminating Proxy side call with session state: Idle, sip-response: 408, ms-diag: 10001, ms-diag reason: Gateway did not respond in a timely manner (timeout).
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [1]151C.1328::03/17/2011-16:48:25.172.00cc35aa (MediationServer,MediaAsyncResult<TEx>.constructor:asyncresult.cs(368))<TerminateMediaSessionWorkitemAsyncResult_59D77> Owner: <MediaSessionAgent_B94C71>, Microsoft.Rtc.Collaboration.AudioVideo.MediaSessionAgent+TerminateMediaSessionWorkitemAsyncResult created. External callback:<AsyncCallback_F8CFB578>, OperationId: NULL
    TL_INFO(TF_COMPONENT) [1]151C.1328::03/17/2011-16:48:25.172.00cc35ab (MediationServer,MediationCall.Terminate:mediationcall.cs(271))(00000000028217C0)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: 9905D948-5D5D11CC-8CA4DACF-65FA8DC8@195.4.191.13
    From: sip:6004@195.4.191.13
    To: sip:1111@192.168.1.5:5060
    Direction: Inbound
    Start-Line: Mediation Call Terminate successfully.
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [1]151C.1328::03/17/2011-16:48:25.173.00cc35cf (MediationServer,MediaSessionAgent.TerminateMediaSession:mediasessionagent.cs(5315))(0000000000B94C71)$$START-MEDIATIONSERVER
    MediationCall: 3658b638443342da81d656bac69bb9a5
    CallId: df3d59a8-46eb-481b-ad55-dbc645405726
    From: sip:6004;phone-context=Global@sipdomain.com;user=phone
    To: sip:1111;phone-context=Global@sipdomain.com;user=phone
    Direction: Inbound
    Start-Line:  Terminating media session, reason: MediaSessionTerminated
    $$END-MEDIATIONSERVER


    Ahmed Mostafa
    Thursday, March 17, 2011 5:11 PM
  • Looks like the initial INVITE came in from your Gateway, but there was no subsequent audio mstream delivered by your gateway. The Mediation server is timing out due to no incoming audio.
    Richard McGiboney, Support Escalation Engineer, Microsoft
    Friday, March 18, 2011 5:49 PM
  • Also , i installed ocs 2007 R1 in a standard edition topology, and installed medation server. I connected that mediation with the same sip gateway (Cisco 3810), and the incoming calls which went thrugh gateway to mediatiom to ocs 2007 enabled client done successfully. 

    are there changes between lync mediation & ocs mediation in sip signaling, media protocols & media used ports ? 


    Ahmed Mostafa
    Saturday, March 19, 2011 4:58 PM
  • Have you disabled Media Bypass on the Lync Trunk configuration?

     

    Also take a look at this blog entry:

    http://voipnorm.blogspot.com/2011/02/issue-alert-cisco-gateways-with-lync.html  


    Richard McGiboney, Support Escalation Engineer, Microsoft

    Tuesday, March 22, 2011 8:10 PM
  • SIP messages are not logged - did you check SIPStack and S4, both with Level All, All Flags? Since you are not using TLS you can do a trace using Wireshark on your mediation server. Wireshark can help you analyze your VoIP calls. If you need help on that upload the pcap log somwhere and post a link here.
    Johann Deutinger | MCTS Exchange 2007/2010 / OCS 2007 | ucblog.deutinger.de
    Wednesday, March 23, 2011 8:32 AM
  • There is no Wireshark pcap trace; and files size can be reduced dramatically if you zip it.
    Johann Deutinger | MCTS Exchange 2007/2010 / OCS 2007 | ucblog.deutinger.de
    Monday, March 28, 2011 9:19 AM
  • Analyzing s4.txt I see an incoming call from Cisco, Medation server answers with Trying and then "183 Session Progress" which contains REQUIRE: 100rel. That means it expects Cisco to acknowledge this provisional message with PRACK. Since Cisco does not send PRACK, Mediation server later sends 408 Request Timeout.

    So you have to find out how to enable provisional acknowledge (PRACK) in your Cisco gateway.


    Johann Deutinger | MCTS Exchange 2007/2010 / OCS 2007 | ucblog.deutinger.de
    • Proposed as answer by jwdberlin Monday, March 28, 2011 10:15 AM
    Monday, March 28, 2011 10:15 AM
  • Hi Johann ,

    It worked with TrixBox 2.8

    How can i enable provisional acknowledge (PRACK) in my cisco gateway (Cisco mc 3810).


    Ahmed Mostafa
    Tuesday, March 29, 2011 8:56 AM
  • Router(config)# voice service voip
    
    Router(config-voi-srv)# sip
    
    Router(conf-serv-sip)# rel1xx supported 100rel
    

    Johann Deutinger | MCTS Exchange 2007/2010 / OCS 2007 | ucblog.deutinger.de
    • Proposed as answer by jwdberlin Tuesday, March 29, 2011 10:38 PM
    Tuesday, March 29, 2011 9:06 AM