locked
Lync No Incoming Call - Mediation Server was not able to find a next hop peer with a Fqdn of NULL RRS feed

  • Question

  • Hi Guys,

     

    I've just configured Lync 2010 Standard Edition Server with Integrated Mediation Server Server and AudioCodes Mediant 1000. This is then connected to an E1 Line which currently only has a single test number associated with it. I have configured my user account with the Line URI of the test number in E164

    I am able to place an external call to my mobile no problem, when I dial the test number the call fails. using the lync logging tool set to log all events on mediation server i see the below log.

    Please note i am in the uk (+44) and have configured the translation rules on the mediant 1000 to ensure both the From and To numbers are passed over in E.164. i have edited the log file to hide the telephone numbers and server names:

     TL_INFO(TF_COMPONENT) [1]08B8.08E4::12/07/2010-12:01:38.636.00001780 (MediationServer,GatewayListener.GatewaySessionReceivedEventHandler:gatewaylistener.cs(566))(000000000167483D)$$START-MEDIATIONSERVER
    MediationCall: bb97c95b-4d93-4839-a342-77a1bfcb893c
    CallId:
    660791333712201012138@10.64.60.215
    From: sip:+447875555555@sipgateway.fakedomain.net
    To: sip:+442070315555@mhlucs01.fakedomain.net;user=phone
    Direction: Inbound
    Start-Line: Received new Gateway incoming session with requestUri: sip:+442070315555@mhlucs01.fakedomain.net;user=phone
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [1]08B8.08E4::12/07/2010-12:01:38.636.00001781 (MediationServer,GatewayListener.GatewaySessionReceivedEventHandler:gatewaylistener.cs(568))[1][0]->[37216]$$START-MEDIATIONSERVER
    MediationCall: bb97c95b-4d93-4839-a342-77a1bfcb893c
    CallId:
    660791333712201012138@10.64.60.215
    From: sip:+447875555555@sipgateway.fakedomain.net
    To: sip:+442070315555@mhlucs01.fakedomain.net;user=phone
    Direction: Inbound
    Start-Line: Mapping GatewayListener to incoming session
    $$END-MEDIATIONSERVER
    TL_ERROR(TF_COMPONENT) [1]08B8.08E4::12/07/2010-12:01:38.636.00001782 (MediationServer,SessionCommon.MatchSessionToNextHopPeerForInbound:sessioncommon.cs(351))Mediation Server was not able to find a next hop peer with an Fqdn of NULL
    TL_INFO(TF_COMPONENT) [1]08B8.08E4::12/07/2010-12:01:38.636.00001783 (MediationServer,SessionCommon.SendInboundErrorCerReport:sessioncommon.cs(1301))$$START-MEDIATIONSERVER
    MediationCall: 392b7d91-f8a8-49e8-aa7d-586a7a3b4b8b
    CallId:
    660791333712201012138@10.64.60.215
    From: sip:+447875555555@sipgateway.fakedomain.net
    To: sip:+442070315555@mhlucs01.fakedomain.net;user=phone
    Direction: Inbound
    Start-Line: There was an inbound call error, sending a CER
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [1]08B8.08E4::12/07/2010-12:01:38.636.00001784 (MediationServer,SessionCommon.ApplyOutgoingRequestURIRules:sessioncommon.cs(1169))$$START-MEDIATIONSERVER
    MediationCall: 392b7d91-f8a8-49e8-aa7d-586a7a3b4b8b
    CallId:
    660791333712201012138@10.64.60.215
    From: sip:+447875555555@sipgateway.fakedomain.net
    To: sip:+442070315555@mhlucs01.fakedomain.net;user=phone
    Direction: Inbound
    Start-Line:  Inbound call's from header user part is E164 .
    $$END-MEDIATIONSERVER
    TL_ERROR(TF_COMPONENT) [1]08B8.08F8::12/07/2010-12:01:38.646.00001785 (MediationServer,SendCerResultHandler.OnMsDiagnosticsReporterStoreUnavailable:sessioncommon.cs(2089))(000000000015A468)$$START-MEDIATIONSERVER
    MediationCall: 392b7d91-f8a8-49e8-aa7d-586a7a3b4b8b
    CallId:
    660791333712201012138@10.64.60.215
    From: sip:+447875555555@sipgateway.fakedomain.net
    To: sip:+442070315555@mhlucs01.fakedomain.net;user=phone
    Direction: Inbound
    Start-Line: Send CER Report Failed with Service Unavailable.
    $$END-MEDIATIONSERVER

     

     

    there is also an entry i see in the TRACES section of snooper between "Start-Line: Mapping GatewayListener to incoming session" and "Start-Line: There was an inbound call error, sending a CER" this line says:

    Mediation Server was not able to find a next hop peer with an Fqdn of NULL

     

    Tuesday, December 7, 2010 12:19 PM

Answers

All replies

  • You should enable full logging for SIP and S4 (all flags, all levels) to see complete SIP messages. Did you configure gateway in topology using fqdn? Is it resolved by DNS (including reverse lookup)?

     


    Johann Deutinger | MCTS Exchange 2007/2010 / OCS 2007
    Tuesday, December 7, 2010 12:58 PM
  • yes it is defined by FQDN and i have both forward and reverse lookups, it is also the device's hostname as defined in it's own config.

    I bumped up the logging and think this is the list of SIP messages. does it shed any light? Do i have to configure inbound routes? I cannot see any sort of inbound options for the mediation server like there was in OCS.

    <<<<<<<<<<<<Incoming SipMessage c=[<SipTlsConnection_36EBF12>], 10.64.60.49:5067<-10.64.60.215:64425
    INVITE sip:+442070355555@mhlucs01.fakedomina.net;user=phone SIP/2.0
    FROM: <sip:+447875555555@sipgateway.fakedomina.net>;tag=1c1508349167
    TO: <sip:+442070355555@mhlucs01.fakedomina.net;user=phone>
    CSEQ: 1 INVITE
    CALL-ID: 15083482147122010132244@10.64.60.215
    MAX-FORWARDS: 70
    VIA: SIP/2.0/TLS 10.64.60.215;branch=z9hG4bKac1508359895;alias
    CONTACT: <sip:+447875555555@10.64.60.215:5060;transport=tcp>
    CONTENT-LENGTH: 260
    SUPPORTED: em,100rel,timer,replaces,path,resource-priority,sdp-anat
    USER-AGENT: Audiocodes-Sip-Gateway-/v.5.80A.023.006
    CONTENT-TYPE: application/sdp
    ALLOW: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE
    v=0
    o=AudiocodesGW 1508342807 1508342488 IN IP4 10.64.60.215
    s=Phone-Call
    c=IN IP4 10.64.60.215
    t=0 0
    m=audio 6760 RTP/AVP 8 0 13 101
    a=rtpmap:8 PCMA/8000
    a=rtpmap:0 PCMU/8000
    a=rtpmap:101 telephone-event/8000
    a=fmtp:101 0-15
    a=ptime:20
    a=sendrecv
    ------------EndOfIncoming SipMessage

    >>>>>>>>>>>>Outgoing SipMessage c=[<SipTlsConnection_36EBF12>], 10.64.60.49:5067->10.64.60.215:64425
    SIP/2.0 100 Trying
    FROM: <sip:+447875535555@sipgateway.EDITED.net>;tag=1c1508349167
    TO: <sip:+442070355553@EDITED.net;user=phone>
    CSEQ: 1 INVITE
    CALL-ID: 15083482147122010132244@10.64.60.215
    VIA: SIP/2.0/TLS 10.64.60.215;branch=z9hG4bKac1508359895;alias
    CONTENT-LENGTH: 0
    ------------EndOfOutgoing SipMessage

    >>>>>>>>>>>>Outgoing SipMessage c=[<SipTlsConnection_36EBF12>], 10.64.60.49:5067->10.64.60.215:64425
    SIP/2.0 488 Not Acceptable Here
    FROM: <sip:+447875535555@sipgateway.EDITED.net>;tag=1c1508349167
    TO: <sip:+442070315555@mhlucs01.EDITED.net;user=phone>;epid=28A62EF385;tag=756f2ce9f4
    CSEQ: 1 INVITE
    CALL-ID: 15083482147122010132244@10.64.60.215
    VIA: SIP/2.0/TLS 10.64.60.215;branch=z9hG4bKac1508359895;alias
    CONTENT-LENGTH: 0
    SERVER: RTCC/4.0.0.0 MediationServer
    ------------EndOfOutgoing SipMessage

     

    Tuesday, December 7, 2010 1:30 PM
  • I remember getting this message when gateway FQDN in topology does not match or topology is not updated. Otherwise you have to look at the "inner" part of the conversation; the messages posted here are only those between mediation server and gateway. You might also have to activate "collaboration" in logging tool.
    Johann Deutinger | MCTS Exchange 2007/2010 / OCS 2007 | ucblog.deutinger.de
    Tuesday, December 7, 2010 1:34 PM
  • I've double checked for typos etc. and cant find any.

    the gateway is defined in the topology as "sipgateway.mydomain.net" and associated with the SE server (inc. med)

    the A and PTR record are both there and resolvable from everything inc. the SE server i'm also able to make outbound calls no problem. the mediant is using DNS and is routing to the FQDN of the SE server (same as pool name) mhlucs01.mydomain.net

    the Mediant 1000 has "sipgateway.mydomain.com" set as it's gateway name (and if i blank this out it shows up as it's IP in the sip "from" )

    I turned on logging for collaboration and that didnt hep much, it just gives me:

    (0000000002E9AEB1)<SignalingSession_48869041> SS(53): Terminate with rejection called. Response Code = 488. [sip:mhlucs01.EDITED.net;epid=28A62EF385]

    as soon as it receives the packet from S4

    doesnt seem like much point posting up any of the log as it doesnt seem to contain any info

     

    ps thanks for your help sofar

    Tuesday, December 7, 2010 2:35 PM
  • actually, log attached as it MAY offer some more insight due to the ordering, ie the first error is that "mediation server was unable to find next hop" one.

    TL_INFO(TF_COMPONENT) [1]08B8.1A00::12/07/2010-15:50:49.884.00006a5b (S4,NegotiateLogic.constructor:negotiatelogic.cs(260))constructed
    TL_INFO(TF_NETWORK) [1]08B8.1A00::12/07/2010-15:50:49.906.00006a5c (S4,TcpTransport.TraceNetworkPacket:tcptransport.cs(569))(00000000028BFF64)Packet size=115
    >>> Incoming TCP packet BEGIN
    \0x80q\0x01\0x03\0x01\0x00H\0x00\0x00\0x00 \0x00\0x00f\0x00\0x00... the rest is skipped (non-printable data)
    <<< Incoming TCP packet END
    TL_INFO(TF_NETWORK) [1]08B8.1A00::12/07/2010-15:50:49.907.00006a5d (S4,TcpTransport.TraceNetworkPacket:tcptransport.cs(569))(00000000028BFF64)Packet size=2512
    >>> Outgoing TCP packet BEGIN
    \0x16\0x03\0x01\t\0xcb\0x02\0x00\0x00F\0x03\0x01L\0xfeW\0xd9\0x8c\0x1b\0xe9\0xa2KR\0x956\0xf0P/\0x1a\0x03\0x8fm\0xc6\0xd9p\0xbc!\0xbf\0xd0\0x9cklJw! \0xb4;\0x00\0x00\0x9a\0xc4j\0xe1\0xa4e\0xa6\0x12@R\0x0c\0xaf\0xd0\0xfa\0xf0\0x94\0x1a\0xc9\0xa2\0x8b\0x89\0x03\0xb0n\0xe3\0xd3\0xbe\0x9d\0x00/\0x00\0x0b\0x00\0x06*\0x00\0x06'\0x00\0x06$0\0x82\0x06 0\0x82\0x05\0x08\0xa0\0x03\0x02\0x01\0x02\0x02\n
    2m\0x15\0x86\0x00\0x00\0x00\0x00\0x0020\r\0x06\t*\0x86H\0x86\0xf7\r\0x01\0x01\0x05\0x05\0x000?1\0x130\0x11\0x06\n
    \t\0x92&\0x89\0x93\0xf2,d\0x01\0x19\0x16\0x03net1\0x150\0x13\0x06\n
    \t\0x92&\0x89\0x93\0xf2,d\0x01\0x19\0x16\0x05mfuse1\0x110\0x0f\0x06\0x03U\0x04\0x03\0x13\0x08mhloas020\0x1e\0x17\r101203140654Z\0x17\r111203141654Z0v1\0x0b0\t\0x06\0x03U\0x04\0x06\0x13\0x02GB1\0x0f0\r\0x06\0x03U\0x04\0x08\0x13\0x06London1\0x0f0\r\0x06\0x03U\0x04\0x07\0x13\0x06London1\0x130\0x11\0x06\0x03U\0x04\n
    \0x13\n
    Mfuse Ltd.1\0x130\0x11\0x06\0x03U\0x04\0x0b\0x13\n
    Mfuse Ltd.1\0x1b0\0x19\0x06\0x03U\0x04\0x03\0x13\0x12mhlucs01.EDITED.net0\0x82\0x01"0\r\0x06\t*\0x86H\0x86\0xf7\r\0x01\0x01\0x01\0x05\0x00\0x03\0x82\0x01\0x0f\0x000\0x82\0x01\n
    \0x02\0x82\0x01\0x01\0x00\0xdc\0x86\r\0x05\0xe4\0xd7\0x00\0xbf\0x1b)\0x99.\0xcb\0x03\0xda|V\0x8e\0x10\0x10\0x98-\0xae,\0xca9Qf\0x89\0xab@\0x14\0x92K\0x8f\0x8e\0xc1\0xde\0xee\0x15\0xa9\0xfe\0xba\0x15\0x91/7!\0x0e\r\0x85\0xa9\0xf2f\0xf7\0xed\0x1f\0xbe\0xdf'\0xfe\0x08\0x1cK\0xfd\0xde\0x92\0xde\0x0f\0x15\r\0x08\0xfa\0x9d\0xd0\0xbc\0x00Nj\0xc9\0xf59\0xfb\0x1b\0x05\0xd3\0xaa\0xc8\0x0b... the rest is skipped (non-printable data)
    <<< Outgoing TCP packet END
    TL_INFO(TF_NETWORK) [1]08B8.1A00::12/07/2010-15:50:49.959.00006a5e (S4,TcpTransport.TraceNetworkPacket:tcptransport.cs(569))(00000000028BFF64)Packet size=338
    >>> Incoming TCP packet BEGIN
    \0x16\0x03\0x01\0x00\0x07\0x0b\0x00\0x00\0x03\0x00\0x00\0x00\0x16\0x03\0x01\0x01\0x06\0x10\0x00\0x01\0x02\0x01\0x00\0x9f\0x1d\0xe3\0x1e\0xa7u\0x00>\0xb0A\0xea9m\0xe4\n
    B\0xfe\0xd4... the rest is skipped (non-printable data)
    <<< Incoming TCP packet END
    TL_INFO(TF_NETWORK) [1]08B8.1A00::12/07/2010-15:50:49.965.00006a5f (S4,TlsTransport.EnterConnectedState:tlstransport.cs(598))(00000000000BEC01)Advance to connected state
    TL_INFO(TF_NETWORK) [1]08B8.1A00::12/07/2010-15:50:49.965.00006a60 (S4,TcpTransport.TraceNetworkPacket:tcptransport.cs(569))(00000000028BFF64)Packet size=59
    >>> Outgoing TCP packet BEGIN
    \0x14\0x03\0x01\0x00\0x01\0x01\0x16\0x03\0x01\0x000\0x91... the rest is skipped (non-printable data)
    <<< Outgoing TCP packet END
    TL_INFO(TF_CONNECTION) [1]08B8.08E4::12/07/2010-15:50:49.965.00006a61 (Collaboration,RealTimeServerConnectionManager.GetWrappedConnection:connectionmanager.cs(6324))(0000000003C3F1A4)<RealTimeServerTlsConnectionManager_63173028> Taking CreateConnectionToDestinationLock <Object_42268884>
    TL_INFO(TF_CONNECTION) [1]08B8.08E4::12/07/2010-15:50:49.965.00006a62 (Collaboration,RealTimeServerConnectionManager.GetWrappedConnection:connectionmanager.cs(6330))(0000000003C3F1A4)<RealTimeServerTlsConnectionManager_63173028> Releasing CreateConnectionToDestinationLock <Object_42268884>
    TL_INFO(TF_CONNECTION) [1]08B8.08E4::12/07/2010-15:50:49.965.00006a63 (Collaboration,RealTimeConnection.constructor:connectionmanager.cs(287))<RealTimeConnection_25538660_m_SipTlsConnection_66549813> New connection created. Direction = Incoming
    TL_INFO(TF_NETWORK) [1]08B8.1A00::12/07/2010-15:50:50.006.00006a64 (S4,TcpTransport.TraceNetworkPacket:tcptransport.cs(569))(00000000028BFF64)Packet size=1029
    >>> Incoming TCP packet BEGIN
    \0x17\0x03\0x01\0x04\0x00\0xb9d\0x1e\0xa3\0x1b\0x8c\0x12\0x9aBTp\0xba\0x12\0xed\0xfc1\0xa4\0x03\0x05p\0x12\0x88\0x93<\0xae'^\0xffP\0xc0\0xf5|\0x08S\0xbd`\0xb0\0xfdO>\0xd9\0xb5\0xfcc\0x84;\0x83\0x1dG(]\0xd8\0x1eS\0x1e\0x16\0xd4=Z3`\0x95\0x1ce\0xb8\0x8a\0xf6\0xbf\0x93!\0xdf\0xf2;\0xa2\0x04lJiF\0xb2\0x06 \0xf9S4EL"\0xa5\0xe7\0x8f6:\0x8b\0xf3?BVX\0xca\0x96\0x9e\0xf1\tp\0x91l\0xcc{\0x12#E\0x9d^\0x9b/\0xea\0x0f\0x8a\0xfa\0xf6\0xcb5\0xff\0x96\0x93\0xe8\0xd5\0xfe\0xbahy\0xdf\0x1d\0xaf\0x99\0x87L}\0xaf\0xba\0xba\0xacB\0x97=\0xae\0x81\0xd8\0x01\0xa6[S\0x01\0x80\0x83F\0xc7\0xcd\0x08... the rest is skipped (non-printable data)
    <<< Incoming TCP packet END
    TL_INFO(TF_PROTOCOL) [1]08B8.1A00::12/07/2010-15:50:50.006.00006a65 (S4,SipMessage.DataLoggingHelper:sipmessage.cs(686))[3789119773]
    <<<<<<<<<<<<Incoming SipMessage c=[<SipTlsConnection_3F77835>], 10.64.60.49:5067<-10.64.60.215:62772
    INVITE sips:+442070315555@mhlucs01.EDITED.net;user=phone SIP/2.0
    FROM: "+447875555555" <sips:+447875555555@sipgateway.EDITED.net;user=phone>;tag=1c2074891151
    TO: <sips:+442070315555@mhlucs01.EDITED.net;user=phone>
    CSEQ: 1 INVITE
    CALL-ID:
    20748901727122010155049@10.64.60.215
    MAX-FORWARDS: 70
    VIA: SIP/2.0/TLS 10.64.60.215;branch=z9hG4bKac2074901910;alias
    CONTACT: <sips:+447875555555@10.64.60.215:5061;user=phone;transport=tls>
    CONTENT-LENGTH: 260
    SUPPORTED: em,100rel,timer,replaces,path,early-session,resource-priority,sdp-anat
    USER-AGENT: Audiocodes-Sip-Gateway-/v.5.80A.023.006
    CONTENT-TYPE: application/sdp
    ALLOW: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE
    CONTENT-DISPOSITION: session

    v=0
    o=AudiocodesGW 2074884779 2074884458 IN IP4 10.64.60.215
    s=Phone-Call
    c=IN IP4 10.64.60.215
    t=0 0
    m=audio 6970 RTP/AVP 8 0 13 101
    a=rtpmap:8 PCMA/8000
    a=rtpmap:0 PCMU/8000
    a=rtpmap:101 telephone-event/8000
    a=fmtp:101 0-15
    a=ptime:20
    a=sendrecv
    ------------EndOfIncoming SipMessage

    TL_INFO(TF_PROTOCOL) [1]08B8.08F8::12/07/2010-15:50:50.006.00006a66 (S4,SipMessage.DataLoggingHelper:sipmessage.cs(613))[3789119773]
    >>>>>>>>>>>>Outgoing SipMessage c=[<SipTlsConnection_3F77835>], 10.64.60.49:5067->10.64.60.215:62772
    SIP/2.0 100 Trying
    FROM: "+447875555555"<sips:+447875555555@sipgateway.EDITED.net;user=phone>;tag=1c2074891151
    TO: <sips:+442070315555@mhlucs01.EDITED.net;user=phone>
    CSEQ: 1 INVITE
    CALL-ID:
    20748901727122010155049@10.64.60.215
    VIA: SIP/2.0/TLS 10.64.60.215;branch=z9hG4bKac2074901910;alias
    CONTENT-LENGTH: 0

    ------------EndOfOutgoing SipMessage

    TL_INFO(TF_PROTOCOL) [1]08B8.08F8::12/07/2010-15:50:50.006.00006a67 (S4,SipMessage.DataLoggingHelper:sipmessage.cs(617))
    >>>>>>>>>>>>Outgoing RawDataBuffer 10.64.60.49:5067->10.64.60.215:62772
    SIP/2.0 100 Trying
    FROM: "+447875555555"<sips:+447875555555@sipgateway.EDITED.net;user=phone>;tag=1c2074891151
    TO: <sips:+442070315555@mhlucs01.EDITED.net;user=phone>
    CSEQ: 1 INVITE
    CALL-ID:
    20748901727122010155049@10.64.60.215
    VIA: SIP/2.0/TLS 10.64.60.215;branch=z9hG4bKac2074901910;alias
    CONTENT-LENGTH: 0

    ------------EndOfOutgoing RawDataBuffer

    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.006.00006a68 (Collaboration,RealTimeServerConnectionManager.RetrieveSpecialComponentHandlers:connectionmanager.cs(6074))(0000000003C3F1A4)<RealTimeServerTlsConnectionManager_63173028> StrictDispatching: Found component handlers = True
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a69 (Collaboration,SignalingSession.Initialize:sipsignalingsession.cs(3240))(0000000001223BF9)<SipPeerToPeerEndpoint_16866778||SignalingSession_19020793> Initialzing session with endpoint
    TL_INFO(TF_PROTOCOL) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a6a (Collaboration,SignalingSession.Initialize:sipsignalingsession.cs(3267))(0000000001223BF9)Correlation Tracing. SignalingSession object created. 19020793 <SignalingSession_19020793>
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a6b (Collaboration,SignalingSession.IsUpdateSupportedByRemote:sipsignalingsession.cs(3032))(0000000001223BF9)<SignalingSession_19020793> SS(63): Remote supports Update method. [sip:mhlucs01.EDITED.net;epid=28A62EF385]
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a6c (Collaboration,SignalingParticipant.UpdateRemoteFeatures:signalingparticipant.cs(279))(FFFFFFFF8510C58D)<SignalingParticipant_-2062498419> SS(63): Remote supports 100rel.
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a6d (Collaboration,SignalingParticipant.UpdateRemoteFeatures:signalingparticipant.cs(286))(FFFFFFFF8510C58D)<SignalingParticipant_-2062498419> SS(63): Remote supports Session Timer.
    TL_INFO(TF_CONNECTION) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a6e (Collaboration,RealTimeServerConnectionManager.GetWrappedConnection:connectionmanager.cs(6324))(0000000003C3F1A4)<RealTimeServerTlsConnectionManager_63173028> Taking CreateConnectionToDestinationLock <Object_42268884>
    TL_INFO(TF_CONNECTION) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a6f (Collaboration,RealTimeServerConnectionManager.GetWrappedConnection:connectionmanager.cs(6330))(0000000003C3F1A4)<RealTimeServerTlsConnectionManager_63173028> Releasing CreateConnectionToDestinationLock <Object_42268884>
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a70 (Collaboration,SipSignalingSessionManager.CoreManagerIncomingInviteTransactionCreated:sipsignalingsessionmanager.cs(554))(000000000160F356)<SipSignalingSessionManager_23130966> Incoming Signaling Session with local id 63 is added
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a71 (Collaboration,SipSignalingSessionManager.AddSession:sipsignalingsessionmanager.cs(81))(000000000160F356)<SipSignalingSessionManager_23130966> Signaling Session with local id 63 is Connected. Adding to mgr list. [sip:mhlucs01.EDITED.net;epid=28A62EF385]
    TL_INFO(TF_NETWORK) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a72 (S4,TcpTransport.TraceNetworkPacket:tcptransport.cs(569))(00000000028BFF64)Packet size=357
    >>> Outgoing TCP packet BEGIN
    \0x17\0x03\0x01\0x01`;\0xf9\0xad;T\0xb6\0xbe\0x984^}\0xb6\0xaf\0x1b\0xeb\0x18\0x98\0x03\0x9d\0xf5\0x8eRd\0x833\0xac\0xb9\0x91s\0x8e\0x8e\0xdf\0xd4\0xf2oe\0xdb\0xa7\0xe82QY\0xdbY/\0xfa\0x8c\0xbbF=\0xa9... the rest is skipped (non-printable data)
    <<< Outgoing TCP packet END
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a73 (MediationServer,GatewayListener.GatewaySessionReceivedEventHandler:gatewaylistener.cs(566))(000000000167483D)$$START-MEDIATIONSERVER
    MediationCall: c308435b-49ad-4b64-9580-531742c9ce82
    CallId:
    20748901727122010155049@10.64.60.215
    From: sips:+447875555555@sipgateway.EDITED.net;user=phone
    To: sips:+442070315555@mhlucs01.EDITED.net;user=phone
    Direction: Inbound
    Start-Line: Received new Gateway incoming session with requestUri: sips:+442070315555@mhlucs01.EDITED.net;user=phone
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a74 (MediationServer,GatewayListener.GatewaySessionReceivedEventHandler:gatewaylistener.cs(568))[1][0]->[19020793]$$START-MEDIATIONSERVER
    MediationCall: c308435b-49ad-4b64-9580-531742c9ce82
    CallId:
    20748901727122010155049@10.64.60.215
    From: sips:+447875555555@sipgateway.EDITED.net;user=phone
    To: sips:+442070315555@mhlucs01.EDITED.net;user=phone
    Direction: Inbound
    Start-Line: Mapping GatewayListener to incoming session
    $$END-MEDIATIONSERVER
    TL_ERROR(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a75 (MediationServer,SessionCommon.MatchSessionToNextHopPeerForInbound:sessioncommon.cs(351))Mediation Server was not able to find a next hop peer with an Fqdn of NULL
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a76 (MediationServer,SessionCommon.SendInboundErrorCerReport:sessioncommon.cs(1301))$$START-MEDIATIONSERVER
    MediationCall: 94d77a50-3ec8-4ac6-8f65-21cb3ff01811
    CallId:
    20748901727122010155049@10.64.60.215
    From: sips:+447875555555@sipgateway.EDITED.net;user=phone
    To: sips:+442070315555@mhlucs01.EDITED.net;user=phone
    Direction: Inbound
    Start-Line: There was an inbound call error, sending a CER
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a77 (MediationServer,SessionCommon.ApplyOutgoingRequestURIRules:sessioncommon.cs(1169))$$START-MEDIATIONSERVER
    MediationCall: 94d77a50-3ec8-4ac6-8f65-21cb3ff01811
    CallId:
    20748901727122010155049@10.64.60.215
    From: sips:+447875555555@sipgateway.EDITED.net;user=phone
    To: sips:+442070315555@mhlucs01.EDITED.net;user=phone
    Direction: Inbound
    Start-Line:  Inbound call's from header user part is E164 .
    $$END-MEDIATIONSERVER
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.007.00006a78 (Collaboration,SignalingSession.TerminateWithRejection:sipsignalingsessioninvite.cs(795))(0000000001223BF9)<SignalingSession_19020793> SS(63): Terminate with rejection called. Response Code = 488. [sip:mhlucs01.EDITED.net;epid=28A62EF385]
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a79 (Collaboration,SignalingSession.ProcessStateTransition:sipsignalingsession.cs(3213))(0000000001223BF9)<SignalingSession_19020793> SS(63): Transition from state Incoming To Disconnected [sip:mhlucs01.EDITED.net;epid=28A62EF385]
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a7a (Collaboration,SignalingSession.SetState:sipsignalingsession.cs(3422))(0000000001223BF9)<SignalingSession_19020793> SS(63): Session state change: Incoming-->Disconnected, r:sips:+447875555555@sipgateway.EDITED.net;user=phone, l:sip:mhlucs01.EDITED.net:28A62EF385
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a7b (Collaboration,SipSignalingSessionManager.RemoveSession:sipsignalingsessionmanager.cs(123))(000000000160F356)<SipSignalingSessionManager_23130966> Signaling Session with local id 63 is in state Disconnected. Removed from mgr list. [sip:mhlucs01.EDITED.net;epid=28A62EF385]
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a7c (Collaboration,SignalingSession.StopExpirationTimer:signalingsessionrefreshtimer.cs(572))(0000000001223BF9)<SignalingSession_19020793> Stopping Session Expiration Timer. [sip:mhlucs01.EDITED.net;epid=28A62EF385]
    TL_INFO(TF_CONNECTION) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a7d (Collaboration,ConnectionPool.GetConnection:connectionmanager.cs(1592))(000000000059A7F6)<ConnectionPool_5875702> A new connection is created (mhlucs01.EDITED.net, 5061, Tls). TlsTarget = null
    TL_INFO(TF_CONNECTION) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a7e (Collaboration,ConnectionPool.GetConnection:connectionmanager.cs(1602))(000000000059A7F6)<ConnectionPool_5875702> Taking CreateConnectionToDestinationLock <Object_56280897>
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a7f (S4,NegotiateLogic.constructor:negotiatelogic.cs(260))constructed
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a80 (S4,NegotiateLogic.constructor:negotiatelogic.cs(279))constructed
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a81 (S4,SipTlsConnection.set_DontSendNegotiateRequest:sipconnection.cs(2963))(00000000027E9CA9)Negotiate Request = False
    TL_INFO(TF_NETWORK) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a82 (S4,TlsTransport.set_DontSendNegotiateRequest:tlstransport.cs(88))(00000000025D4C91)Negotiate = False
    TL_INFO(TF_NETWORK) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a83 (S4,NegotiateLogic.set_DontSendNegotiateRequest:negotiatelogic.cs(150))(00000000031C7A29)Negotiate = False
    TL_INFO(TF_CONNECTION) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a84 (Collaboration,RealTimeConnection.constructor:connectionmanager.cs(287))<RealTimeConnection_29879828_m_SipTlsConnection_41852073> New connection created. Direction = Outgoing
    TL_INFO(TF_CONNECTION) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a85 (Collaboration,ConnectionPool.GetConnection:connectionmanager.cs(1610))(000000000059A7F6)<ConnectionPool_5875702> Released CreateConnectionToDestinationLock <Object_56280897>
    TL_INFO(TF_PROTOCOL) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a86 (S4,SipMessage.DataLoggingHelper:sipmessage.cs(613))[3789119773]
    >>>>>>>>>>>>Outgoing SipMessage c=[<SipTlsConnection_3F77835>], 10.64.60.49:5067->10.64.60.215:62772
    SIP/2.0 488 Not Acceptable Here
    FROM: "+447875555555"<sips:+447875555555@sipgateway.EDITED.net;user=phone>;tag=1c2074891151
    TO: <sips:+442070315555@mhlucs01.EDITED.net;user=phone>;epid=28A62EF385;tag=e21a1f3995
    CSEQ: 1 INVITE
    CALL-ID:
    20748901727122010155049@10.64.60.215
    VIA: SIP/2.0/TLS 10.64.60.215;branch=z9hG4bKac2074901910;alias
    CONTENT-LENGTH: 0
    SERVER: RTCC/4.0.0.0 MediationServer

    ------------EndOfOutgoing SipMessage

    TL_INFO(TF_PROTOCOL) [1]08B8.08F8::12/07/2010-15:50:50.008.00006a87 (S4,SipMessage.DataLoggingHelper:sipmessage.cs(617))
    >>>>>>>>>>>>Outgoing RawDataBuffer 10.64.60.49:5067->10.64.60.215:62772
    SIP/2.0 488 Not Acceptable Here
    FROM: "+447875555555"<sips:+447875555555@sipgateway.EDITED.net;user=phone>;tag=1c2074891151
    TO: <sips:+442070315555@mhlucs01.EDITED.net;user=phone>;epid=28A62EF385;tag=e21a1f3995
    CSEQ: 1 INVITE
    CALL-ID:
    20748901727122010155049@10.64.60.215
    VIA: SIP/2.0/TLS 10.64.60.215;branch=z9hG4bKac2074901910;alias
    CONTENT-LENGTH: 0
    SERVER: RTCC/4.0.0.0 MediationServer

    ------------EndOfOutgoing RawDataBuffer

    TL_INFO(TF_NETWORK) [1]08B8.08E4::12/07/2010-15:50:50.009.00006a88 (S4,TcpTransport.TraceNetworkPacket:tcptransport.cs(569))(00000000028BFF64)Packet size=437
    >>> Outgoing TCP packet BEGIN
    \0x17\0x03\0x01\0x01\0xb0 }\0xb6\0xa7yQ#$O\0xec\0xeb\0x05Q#e\t\0x04\n
    \0x98\0xee\0xba\0xb0\0x11\0x0c\0xbc.\0xdf\0xca&R\0xaa\0x98\0x92\0x04\0xfeMZ\0x85\0x99\0x15x\0xa7+\0x9e\0xfe\0x9f\0x84\0xf0\0x93\0x97\0x03\0xd1\0xdf\0xd0\0xc1\0xbb\0xd5CIJ\0xc3... the rest is skipped (non-printable data)
    <<< Outgoing TCP packet END
    TL_INFO(TF_NETWORK) [1]08B8.08F8::12/07/2010-15:50:50.009.00006a89 (S4,TcpTransport.TraceNetworkPacket:tcptransport.cs(569))(00000000011C411E)Packet size=163
    >>> Outgoing TCP packet BEGIN
    \0x16\0x03\0x01\0x00\0x9e\0x01\0x00\0x00\0x9a\0x03\0x01L\0xfeW\0xda\r\0x98\0x98\0x81.\0x12... the rest is skipped (non-printable data)
    <<< Outgoing TCP packet END
    TL_INFO(TF_NETWORK) [1]08B8.1A00::12/07/2010-15:50:50.010.00006a8a (S4,TcpTransport.TraceNetworkPacket:tcptransport.cs(569))(00000000011C411E)Packet size=145
    >>> Incoming TCP packet BEGIN
    \0x16\0x03\0x01\0x00Q\0x02\0x00\0x00M\0x03\0x01L\0xfeW\0xda\0xc66&\0x00\0x01{\0xd5... the rest is skipped (non-printable data)
    <<< Incoming TCP packet END
    TL_INFO(TF_NETWORK) [1]08B8.1A00::12/07/2010-15:50:50.010.00006a8b (S4,Microsoft::Rtc::Internal::Sip::TlsTransportHelper::GetRemotePeerIdentity:TlsTransportHelper.cpp(1575))( 0000000002B6F42D ) Exit - trusted root, wildCard=0
    TL_INFO(TF_NETWORK) [1]08B8.1A00::12/07/2010-15:50:50.011.00006a8c (S4,NegotiateLogic.ProcessPeerIdentity:negotiatelogic.cs(974))(00000000031C7A29)Exit - peer identity with FQDN [mhlucs01.EDITED.net] is now defined and authorized
    TL_INFO(TF_NETWORK) [1]08B8.1A00::12/07/2010-15:50:50.011.00006a8d (S4,NegotiateLogic.AdvanceOutboundNegotiation:negotiatelogic.cs(782))(00000000031C7A29)Outbound negotiation sequence is complete
    TL_INFO(TF_NETWORK) [1]08B8.1A00::12/07/2010-15:50:50.011.00006a8e (S4,TlsTransport.EnterConnectedState:tlstransport.cs(598))(00000000025D4C91)Advance to connected state
    TL_INFO(TF_NETWORK) [1]08B8.1A00::12/07/2010-15:50:50.011.00006a8f (S4,TcpTransport.TraceNetworkPacket:tcptransport.cs(569))(00000000011C411E)Packet size=59
    >>> Outgoing TCP packet BEGIN
    \0x14\0x03\0x01\0x00\0x01\0x01\0x16\0x03\0x01\0x000\0xd9... the rest is skipped (non-printable data)
    <<< Outgoing TCP packet END
    TL_INFO(TF_CONNECTION) [1]08B8.08F8::12/07/2010-15:50:50.011.00006a90 (Collaboration,RealTimeConnectionManager.HandleOutgoingConnectionConnected:connectionmanager.cs(2601))(0000000000F81430)<RealTimeServerTlsConnectionManager_16258096> Taking CreateConnectionToDestinationLock <Object_56280897>
    TL_INFO(TF_CONNECTION) [1]08B8.08F8::12/07/2010-15:50:50.011.00006a91 (Collaboration,RealTimeConnectionManager.HandleOutgoingConnectionConnected:connectionmanager.cs(2620))(0000000000F81430)<RealTimeServerTlsConnectionManager_16258096> Releasing CreateConnectionToDestinationLock <Object_56280897>
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.011.00006a92 (Collaboration,SipAsyncResult2<TEx>.Complete:asyncresult2.cs(582))(000000000086400B)<RealTimeConnection_29879828||DoNothingAsyncResult`1_8798219> Completing operation: Microsoft.Rtc.Signaling.DoNothingAsyncResult`1
    TL_INFO(TF_COMPONENT) [1]08B8.08F8::12/07/2010-15:50:50.013.00006a93 (Collaboration,SipTransactionAsyncResult<T>.ConnectionConnectCompleted:siptransactionasyncresult.cs(892))(0000000000946CAA)<SipPeerToPeerEndpoint_45768388||SipMessageAsyncResult_9727146> Sent request to S4. [sip:mhlucs01.EDITED.net;epid=DDB957FC7D]
    TL_INFO(TF_PROTOCOL) [1]08B8.08F8::12/07/2010-15:50:50.013.00006a94 (S4,SipMessage.DataLoggingHelper:sipmessage.cs(613))[850413941]
    >>>>>>>>>>>>Outgoing SipMessage c=[<SipTlsConnection_27E9CA9>], 10.64.60.49:53536->10.64.60.49:5061
    SERVICE sip:+442070315555@mfuse.com;user=phone SIP/2.0
    FROM: <sip:+442070315555@mfuse.com;user=phone>;epid=DDB957FC7D;tag=af74d63466
    TO: <sip:+442070315555@mfuse.com;user=phone>
    CSEQ: 1289 SERVICE
    CALL-ID: 3ee9fc9376cd4de9acb6ff6cbd076f81
    MAX-FORWARDS: 70
    VIA: SIP/2.0/TLS 10.64.60.49:53536;branch=z9hG4bK2eb18ed8
    CONTACT: <sip:mhlucs01.EDITED.net@mfuse.com;gruu;opaque=srvr:MediationServer:ovoV_qBobVKWtHM8G3JytQAA>;isGateway
    CONTENT-LENGTH: 529
    SUPPORTED: gruu-10
    USER-AGENT: RTCC/4.0.0.0 MediationServer
    CONTENT-TYPE: application/msrtc-reporterror+xml

    <?xml version="1.0" encoding="us-ascii"?><reportError xmlns="http://schemas.microsoft.com/2006/09/sip/error-reporting"><error callId="20748901727122010155049@10.64.60.215" fromUri="sip:+447875555555@mfuse.com;user=phone" toUri="sip:+442070315555@mfuse.com;user=phone" fromTag="1c2074891151" toTag="" requestType="INVITE" contentType="application/sdp;call-type=audio" responseCode="488"><diagHeader>10013;reason="Gateway peer in inbound call is not found in topology document"</diagHeader><progressReports /></error></reportError>------------EndOfOutgoing SipMessage

    TL_INFO(TF_PROTOCOL) [1]08B8.08F8::12/07/2010-15:50:50.013.00006a95 (S4,SipMessage.DataLoggingHelper:sipmessage.cs(617))
    >>>>>>>>>>>>Outgoing RawDataBuffer 10.64.60.49:53536->10.64.60.49:5061
    SERVICE sip:+442070315555@mfuse.com;user=phone SIP/2.0
    FROM: <sip:+442070315555@mfuse.com;user=phone>;epid=DDB957FC7D;tag=af74d63466
    TO: <sip:+442070315555@mfuse.com;user=phone>
    CSEQ: 1289 SERVICE
    CALL-ID: 3ee9fc9376cd4de9acb6ff6cbd076f81
    MAX-FORWARDS: 70
    VIA: SIP/2.0/TLS 10.64.60.49:53536;branch=z9hG4bK2eb18ed8
    CONTACT: <sip:mhlucs01.EDITED.net@mfuse.com;gruu;opaque=srvr:MediationServer:ovoV_qBobVKWtHM8G3JytQAA>;isGateway
    CONTENT-LENGTH: 529
    SUPPORTED: gruu-10
    USER-AGENT: RTCC/4.0.0.0 MediationServer
    CONTENT-TYPE: application/msrtc-reporterror+xml

    <?xml version="1.0" encoding="us-ascii"?><reportError xmlns="http://schemas.microsoft.com/2006/09/sip/error-reporting"><error callId="20748901727122010155049@10.64.60.215" fromUri="sip:+447875555555@mfuse.com;user=phone" toUri="sip:+442070315555@mfuse.com;user=phone" fromTag="1c2074891151" toTag="" requestType="INVITE" contentType="application/sdp;call-type=audio" responseCode="488"><diagHeader>10013;reason="Gateway peer in inbound call is not found in topology document"</diagHeader><progressReports /></error></reportError>------------EndOfOutgoing RawDataBuffer

    TL_INFO(TF_NETWORK) [1]08B8.08F8::12/07/2010-15:50:50.014.00006a96 (S4,TcpTransport.TraceNetworkPacket:tcptransport.cs(569))(00000000011C411E)Packet size=1125
    >>> Outgoing TCP packet BEGIN
    \0x17\0x03\0x01\0x04`\Z\0xf9-C\0x86@M\0xd6Q,\0xa2\0xce\0xd7\0xc0\0xaa"\0xea\0x82\0x91\0xf6\0xa0\0xb7H\0xa5n\0xe2n\0x0c\0xf9\0x04\0x16\0x8e\0xf4Y\0xea\0x8a\0xea\0xdd\0xb8\0xf0\0x84U\0x8a\0xc5\0xbfPa\0xe1\0x10\0x86$\0x18\0x1f*\0xd5\0xe4\0xeer\0xf2\0x82\0xbd\0xdf\0x9f\0x85(o\0xcf\0x16\0x9b\0x0e<8(\0xa0[\0xca[ \0x18\0xee\0xdb\0x9f\0x9c\0x00\0xee\0x8e\0x99\0xdb"e\0x0b\0x19\0xcb\0xa5-\0xeb\0x8dz+:\0xe1`\0x1e\0x1cM\0xc6k\0x0b\0xb4\0x11\0xf3C_\0xc0#m\0x12\0xd6$'\0x11\0x90\0x1f\0xf1~ (h&\0xc9=+\0x17\0xe1w\0xe3\0xe6L\0xf6_n9\0xc4r\0x9b%@\0x1c\0xcdgjd\0xa4p\0x96t=)\0xd2[1\0xc2\0x02\0xee\0x80^5\0x16i\0xd7\0x96A\0xe8\0xffg\0xe3wH\0x16\0xdc\0xf2... the rest is skipped (non-printable data)
    <<< Outgoing TCP packet END
    TL_INFO(TF_PROTOCOL) [1]08B8.08F8::12/07/2010-15:50:50.014.00006a97 (Collaboration,SipTransactionAsyncResult<T>.Transaction_MessageSent:siptransactionasyncresult.cs(2359))[1][45768388]->[850413941]Correlation Tracing <SipMessageAsyncResult_9727146>
    TL_INFO(TF_NETWORK) [1]08B8.1A00::12/07/2010-15:50:50.018.00006a98 (S4,TcpTransport.TraceNetworkPacket:tcptransport.cs(569))(00000000011C411E)Packet size=533
    >>> Incoming TCP packet BEGIN
    \0x17\0x03\0x01\0x02\0x10\0xc9\0xa5\0xb7\0xbd\0xfe\0xf0b\0xb7\0xab\0xc4\0xb6m'\0xc4\0xd1\0xaf\0xa8\0x9a\0x16\0x88sO\0xb3\0xc0\0xdd\0x8d0n\0xc4\0xfd\\0x8aM\0xab/\0xa2\0x08\0xca\0xd9\0x97\0xc4\0xd0\0x06"\0xba\0xcc\0xae\0xb9,*\0xcf\0x11\0x9d\0xe2?\0x04b\0xd2a\0xb7\0x04\0x92z\0xe3\0x92\0x17... the rest is skipped (non-printable data)
    <<< Incoming TCP packet END
    TL_INFO(TF_PROTOCOL) [1]08B8.1A00::12/07/2010-15:50:50.018.00006a99 (S4,SipMessage.DataLoggingHelper:sipmessage.cs(686))[850413941]
    <<<<<<<<<<<<Incoming SipMessage c=[<SipTlsConnection_27E9CA9>], 10.64.60.49:53536<-10.64.60.49:5061
    SIP/2.0 503 Service unavailable
    FROM: <sip:+442070315555@mfuse.com;user=phone>;epid=DDB957FC7D;tag=af74d63466
    TO: <sip:+442070315555@mfuse.com;user=phone>;tag=B4A272D1A091B49BA9DDC7D46E3E9113
    CSEQ: 1289 SERVICE
    CALL-ID: 3ee9fc9376cd4de9acb6ff6cbd076f81
    VIA: SIP/2.0/TLS 10.64.60.49:53536;branch=z9hG4bK2eb18ed8;ms-received-port=53536;ms-received-cid=9D800
    CONTENT-LENGTH: 0
    SERVER: RTC/4.0
    ms-diagnostics: 2019;reason="Report error service is not available";source="MHLUCS01.EDITED.net"

    ------------EndOfIncoming SipMessage

    TL_INFO(TF_COMPONENT) [1]08B8.08E4::12/07/2010-15:50:50.018.00006a9a (Collaboration,SipTransactionAsyncResult<T>.Transaction_ResponseReceived:siptransactionasyncresult.cs(2379))(0000000000946CAA)<SipPeerToPeerEndpoint_45768388||SipMessageAsyncResult_9727146> Transaction response received: class ServerFailure code 503 text Service unavailable [sip:mhlucs01.EDITED.net;epid=DDB957FC7D] isBadResp=No
    TL_INFO(TF_COMPONENT) [1]08B8.08E4::12/07/2010-15:50:50.019.00006a9b (Collaboration,SipTransactionAsyncResult<T>.CompleteCore:siptransactionasyncresult.cs(1174))(0000000000946CAA)<SipPeerToPeerEndpoint_45768388||SipMessageAsyncResult_9727146> Completing transaction [sip:mhlucs01.EDITED.net;epid=DDB957FC7D]
    TL_INFO(TF_CONNECTION) [1]08B8.08E4::12/07/2010-15:50:50.019.00006a9c (Collaboration,RealTimeConnection.ReleaseConnection:connectionmanager.cs(947))(0000000001C7EE14)<RealTimeConnection_29879828> RefCount = 0. Starting timer for recycling this connection.
    TL_INFO(TF_COMPONENT) [1]08B8.08E4::12/07/2010-15:50:50.019.00006a9d (Collaboration,SipTransactionAsyncResult<T>.Transaction_ResponseReceived:siptransactionasyncresult.cs(2499))(0000000000946CAA)<SipPeerToPeerEndpoint_45768388||SipMessageAsyncResult_9727146> Transaction processed successfully. [sip:mhlucs01.EDITED.net;epid=DDB957FC7D]
    TL_INFO(TF_COMPONENT) [1]08B8.08E4::12/07/2010-15:50:50.019.00006a9e (Collaboration,SipAsyncResult2<TEx>.Complete:asyncresult2.cs(582))(00000000014E13DE)<SessionErrorReporter_38109242||DoNothingAsyncResult`1_21894110> Completing operation: Microsoft.Rtc.Signaling.DoNothingAsyncResult`1
    TL_INFO(TF_COMPONENT) [1]08B8.08E4::12/07/2010-15:50:50.020.00006a9f (Collaboration,SipAsyncResult2<TEx>.ThrowIfFailed:asyncresult2.cs(688))(0000000000946CAA)<SipPeerToPeerEndpoint_45768388||SipMessageAsyncResult_9727146> Throwing exception: Exception: Microsoft.Rtc.Signaling.FailureResponseException
    > ResponseData.ResponseCode: 503
    > ResponseData.ResponseText: Service unavailable
    > ResponseData.SignalingHeaders: System.Collections.Generic.List`1[Microsoft.Rtc.Signaling.SignalingHeader]
    > ResponseData.FromHeader.Uri: sip:+442070315555@mfuse.com;user=phone
    > ResponseData.FromHeader.Epid: DDB957FC7D
    > ResponseData.FromHeader.HeaderValue: <sip:+442070315555@mfuse.com;user=phone>;epid=DDB957FC7D;tag=af74d63466
    > ResponseData.FromHeader.DisplayName:
    > ResponseData.FromHeader.Tag: af74d63466
    > ResponseData.ToHeader.Uri: sip:+442070315555@mfuse.com;user=phone
    > ResponseData.ToHeader.Epid:
    > ResponseData.ToHeader.HeaderValue: <sip:+442070315555@mfuse.com;user=phone>;tag=B4A272D1A091B49BA9DDC7D46E3E9113
    > ResponseData.ToHeader.DisplayName:
    > ResponseData.ToHeader.Tag: B4A272D1A091B49BA9DDC7D46E3E9113
    > ResponseData.CSeq: 1289
    > ResponseData.RequestUri:
    > ResponseData.UserAgent: RTC/4.0
    > ResponseData.CallId: 3ee9fc9376cd4de9acb6ff6cbd076f81
    > DiagnosticInformation: ErrorCode=2019,Source=MHLUCS01.EDITED.net,Reason=Report error service is not available
    Microsoft.Rtc.Signaling.DiagnosticHeader

    > WarningInformation: System.Collections.ObjectModel.Collection`1[Microsoft.Rtc.Signaling.WarningHeader]
    > DetectionStackTrace:    at System.Environment.get_StackTrace()
       at Microsoft.Rtc.Signaling.FailureResponseException..ctor(String message, Exception innerException, SipResponseData responseData)
       at Microsoft.Rtc.Signaling.RealTimeException.GetWrappedRealTimeException(String message, SipResponseData responseData, Exception exceptionToWrap)
       at Microsoft.Rtc.Signaling.SipTransactionAsyncResult`1.ProcessFailureResponse(SipResponse response)
       at Microsoft.Rtc.Signaling.SipMessageAsyncResult.ProcessFailureResponse(SipResponse response)
       at Microsoft.Rtc.Signaling.SipTransactionAsyncResult`1.Transaction_ResponseReceived(Object sender, ResponseReceivedEventArgs e)
       at Microsoft.Rtc.Internal.Sip.OutgoingNonInviteTransaction.OnResponseReceived(EventArgs e)
       at Microsoft.Rtc.Internal.Sip.SingleThreadedDispatcherQueue.DispatcherCallback(Object queue)
       at System.Threading._ThreadPoolWaitCallback.PerformWaitCallbackInternal(_ThreadPoolWaitCallback tpWaitCallBack)
       at System.Threading._ThreadPoolWaitCallback.PerformWaitCallback(Object state)
    > Message: A 503 (Service unavailable) response was received from the network and the operation failed. See the exception details for more information.
    > Source: Microsoft.Rtc.Collaboration

    TL_ERROR(TF_COMPONENT) [1]08B8.08E4::12/07/2010-15:50:50.020.00006aa0 (MediationServer,SendCerResultHandler.OnMsDiagnosticsReporterStoreUnavailable:sessioncommon.cs(2089))(000000000367A137)$$START-MEDIATIONSERVER
    MediationCall: 94d77a50-3ec8-4ac6-8f65-21cb3ff01811
    CallId:
    20748901727122010155049@10.64.60.215
    From: sips:+447875555555@sipgateway.EDITED.net;user=phone
    To: sips:+442070315555@mhlucs01.EDITED.net;user=phone
    Direction: Inbound
    Start-Line: Send CER Report Failed with Service Unavailable.
    $$END-MEDIATIONSERVER
    TL_INFO(TF_NETWORK) [1]08B8.1A00::12/07/2010-15:50:50.035.00006aa1 (S4,TcpTransport.TraceNetworkPacket:tcptransport.cs(569))(00000000028BFF64)Packet size=725
    >>> Incoming TCP packet BEGIN
    \0x17\0x03\0x01\0x02\0xd0u\0x9f\0xeb\r\r[R,\r\0xae\0xae\0xb1\0x97N]\0xc3t\0x7f7\0xe5r\0xb0z'\0xb8@V$\0xe8P\0x0fG\0xaa\0x8a\0xbd*\0x06\0xb3;6\0xc76\0xcaL\0xc8\0x1e\0xbd\0x8e\0xbf3\0xb4\0x8d\n
    P|\0x05\0xb6\0xd3t.*\0x97\0xb1\0xfd\0x11H\r\0xcfpq\0x1b\0xfbQ\0xe2\0x82\0xb6I_\0xc5\0x87\0xccO\0x85\0xb0^O\0x8b\0xfe\0xec\0x03k\0x1fO(0\0xc57\0x16+\0x18\0xf13\0xb1$@\0xe5P\0x94\0xa7l\0x88\0xfdfS\0x97\0xf6|A\0xeb\0xf8'\0xbd\0xf0/\0xf6\0xf1... the rest is skipped (non-printable data)
    <<< Incoming TCP packet END
    TL_INFO(TF_PROTOCOL) [1]08B8.1A00::12/07/2010-15:50:50.035.00006aa2 (S4,SipMessage.DataLoggingHelper:sipmessage.cs(686))[4249208145]
    <<<<<<<<<<<<Incoming SipMessage c=[<SipTlsConnection_3F77835>], 10.64.60.49:5067<-10.64.60.215:62772
    ACK sips:+442070315555@mhlucs01.EDITED.net;user=phone SIP/2.0
    FROM: "+447875555555" <sips:+447875555555@sipgateway.EDITED.net;user=phone>;tag=1c2074891151
    TO: <sips:+442070315555@mhlucs01.EDITED.net;user=phone>;epid=28A62EF385;tag=e21a1f3995
    CSEQ: 1 ACK
    CALL-ID:
    20748901727122010155049@10.64.60.215
    MAX-FORWARDS: 70
    VIA: SIP/2.0/TLS 10.64.60.215;branch=z9hG4bKac2074901910;alias
    CONTACT: <sips:+447875555555@10.64.60.215:5061;user=phone;transport=tls>
    CONTENT-LENGTH: 0
    SUPPORTED: em,timer,replaces,path,early-session,resource-priority
    USER-AGENT: Audiocodes-Sip-Gateway-/v.5.80A.023.006
    ALLOW: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE

    ------------EndOfIncoming SipMessage

    TL_INFO(TF_COMPONENT) [1]08B8.1A00::12/07/2010-15:50:50.035.00006aa3 (S4,IncomingInviteTransaction.TerminateEarlyDialogs:sipcorelayer.cs(7165))(0000000000DB60BA)Terminating early dialogs
    TL_INFO(TF_COMPONENT) [0]08B8.08F8::12/07/2010-15:50:50.035.00006aa4 (Collaboration,RealTimeServerConnectionManager.RetrieveSpecialComponentHandlers:connectionmanager.cs(6074))(0000000003C3F1A4)<RealTimeServerTlsConnectionManager_63173028> StrictDispatching: Found component handlers = True

    Tuesday, December 7, 2010 3:56 PM
  • You will find

    "Gateway peer in inbound call is not found in topology document"

    in this trace - as I suspected in my earlier post!

    What about "sips:" in your SIP URIs? That should be sip: - you may be trying to use secured messages but without TLS; check you gateway settings...


    Johann Deutinger | MCTS Exchange 2007/2010 / OCS 2007 | ucblog.deutinger.de
    Tuesday, December 7, 2010 6:02 PM
  • thanks johann, i tried changing the transport type from TCP to TLS to see if it made a difference i'll change it back.

     

    as for the topology ive checked it and also re-published it, plus also ran the setup wizard again as i'm not sure what changes are required after a topology change.

     

    rather than deleting it all (dial plans, routes, etc.) and re-doing it from scratch is there a step im missing in the topology?

    under topology, PSTN gateways i see an entry for "sipgateway.mydomain.net" with the below settings:

    IP/FQDN : sipgateway.mydomain.net

    Listening Port: 5060

    SIP Transport Protocol: TCP

    Alternate Media IP address: None (does this need to have something in it?)

    Mediation Server: mhlucs01.mydomain.net (MHL)

    Wednesday, December 8, 2010 9:30 AM
  • ok, i removed sipgateway.blah from the Voice Route then i deleted it from the topology and created a new PSTN gateway by IP address, and associated that. then reattached it to the voice route. tested outbound, works ok. now i've removed the "gateway name" setting from the mediant so it passes it's from address over as sip:+44xxxxxxx@10.64.60.215;user=phone same error in the log, Gateway peer in inbound call is not found in topology document is this an "undocumented feature" or am i being really stupid and missing an obvious step?
    Wednesday, December 8, 2010 9:58 AM
  • got it!

     

    http://social.technet.microsoft.com/Forums/en/ocsvoice/thread/25893916-6c37-4882-9f18-21337d9446e2

     

    The port used for Inbound and Outbound has to be the same! This is not at all clear from the documentation or GUI, suggest microsoft make it clearer.

     

    once I changed my sip gateway to use 5066/TCP (was 5060) everything worked fine!

     

    many thanks for your help

    • Marked as answer by Jim Galbally Wednesday, December 8, 2010 10:29 AM
    Wednesday, December 8, 2010 10:29 AM
  • In a Lync 2013 installation, I received a report of something very similar:

    Gateway peer in inbound call is not found in topology document or does not depend on this Mediation Server

    In this case, calls from mediation server > gateway worked, but calls gateway > mediation server failed.  This particular gateway provided the ability for separate certificates when the gateway was the client (caller) TLS role or the server (callee) TLS role.

    Although the gateway's server TLS certficate was correct, the gateway's client TLS certificate was not the correct certificate.  Since the Common Name (FQDN of the certificate) did not match the gateway FQDN configured in the lync server topology, the mediation server threw an error indicating that the gateway did not match the gateway defined in the topology.

    Using the appropriate gateway certificate as the gateway's client certificate resolved the problem.

    BTW: We often use different mediation server and gateway listening ports without issue (except that the lync server often needs to be rebooted if the ports or transports (TCP/TLS) are changed in the topo.)




    • Edited by tropsmr2 Friday, September 6, 2013 3:59 PM
    Friday, September 6, 2013 3:57 PM