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

 

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)?

 

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

 

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.

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

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

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...

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)

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?

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

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.)