locked
OCS 2007 R2->MS->AudiocodesMP-118 = stumped! RRS feed

  • Question

  • I am setting up a test lab for OCS 2007 R2. I have OCS up and running internally. I have installed a Mediation server and I have set up an audiocodes MP-118 Gateway. When I attempt to make an outside, say to my cell phone, I get a 503 service unavailable error. It states 'The message received was unexpected or badly formatted.' Could some help to interpret this error message?

    Thanks in advance for any help.
    Tuesday, May 5, 2009 10:41 PM

All replies

  • Can you please post any related errors on your FE and Mediation servers.  Also, a sip log from the MP-118 might help.

    -kp
    MCSE/MCSA/MCTS/CCNA/Security+
    • Proposed as answer by noai Saturday, September 26, 2009 1:57 AM
    Wednesday, May 6, 2009 12:25 AM
  • Have you reviewed your location profile and the normalization rules?
    mitch
    • Proposed as answer by noai Saturday, September 26, 2009 2:45 AM
    Wednesday, May 6, 2009 12:18 PM
  • Here is the error info from Event Viewer on the computer where I placed the call

    A SIP request made by Communicator failed in an unexpected manner (status code 80ef01f7). More information is contained in the following technical data:

     

    RequestUri: sip:+16028030993@rockford-fosgate.com;user=phone

    From: sip:Dan.Madsen@rockford-fosgate.com;tag=3ab1b783c7

    To: sip:+16028030993@rockford-fosgate.com;user=phone;tag=c053914c43

    Call-ID: da1501463f9141679c3b256ccabb9842

    Content-type: application/sdp;call-type=audiovideo

    (null)

    Response Data:

    101 Progress Report

    ms-diagnostics: 12006;reason="Trying next hop";source="RTOCS-01.RT.AD";PhoneUsage="CN={283BD424-41EB-4420-9EEA-6A7556269622},CN=Phone Route Usages,CN=RTC Service,CN=Services,CN=Configuration,DC=RT,DC=AD";PhoneRoute="MP-118";Gateway="rtms-01.rt.ad:5061";appName="OutboundRouting"

     

    183 Session Progress

     

    199 Early Dialog Terminated

     

    503 Service Unavailable

    Ms-diagnostics: 0;source="RTMS-01.RT.AD";reason="Internal error";component="MediationServer";Exception="The message received was unexpected or badly formatted";GatewayFqdn="RTMG-01.RT.AD"

    Here is the Event logger from the OCS server

    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.888.00000001 (OutboundRouting,OutboundRoutingTransaction.constructor:outboundroutingtransaction.cs(194))Creating a OutboundRoutingTransaction object (4)
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.889.00000002 (OutboundRouting,OutboundRouting.OnRequest:outboundrouting.cs(242))( 000000000259F3B6 )Enter
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.891.00000003 (OutboundRouting,Settings.ProcessPendingWmiEvents:settings.cs(941))( 0000000003003957 )Enter.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.893.00000004 (OutboundRouting,OutboundRoutingDispatcher.OnRequest:outboundroutingdispatcher.cs(124))( 000000000308499C )From uri: Dan.Madsen@rockford-fosgate.com
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.893.00000005 (OutboundRouting,OutboundRoutingDispatcher.OnRequest:outboundroutingdispatcher.cs(125))( 000000000308499C )From User Uc Enabled: True
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.893.00000006 (OutboundRouting,OutboundRoutingDispatcher.OnRequest:outboundroutingdispatcher.cs(126))( 000000000308499C )From User Policy: <instance><property name="Name"><![CDATA[Default Policy]]></property><property name="AllowSimultaneousRinging"><![CDATA[true]]></property><property name="PhoneRouteUsages"><element><![CDATA[CN={283BD424-41EB-4420-9EEA-6A7556269622},CN=Phone Route Usages,CN=RTC Service,CN=Services,CN=Configuration,DC=RT,DC=AD]]></element><element><![CDATA[CN={C491D082-9CD3-4A41-9A79-9DCEE38670EB},CN=Phone Route Usages,CN=RTC Service,CN=Services,CN=Configuration,DC=RT,DC=AD]]></element></property></instance>

    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.894.00000007 (OutboundRouting,OutboundRoutingDispatcher.OnRequest:outboundroutingdispatcher.cs(136))( 000000000308499C )Referrer URI: <null>
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.894.00000008 (OutboundRouting,OutboundRoutingDispatcher.OnRequest:outboundroutingdispatcher.cs(138))( 000000000308499C )IsAvMCUDialOut: False
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.894.00000009 (OutboundRouting,OutboundRoutingDispatcher.OnRequest:outboundroutingdispatcher.cs(206))( 000000000308499C )Applying From URI's outbound policy
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.894.0000000a (OutboundRouting,OutboundRoutingDispatcher.ApplyCallerPolicyAndRouteRequest:outboundroutingdispatcher.cs(589))( 000000000308499C )Routing request based on caller: Dan.Madsen@rockford-fosgate.com
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.896.0000000b (OutboundRouting,OutboundRoutingDispatcher.PrepareNewRequest:outboundroutingdispatcher.cs(729))( 000000000308499C )User Dan.Madsen@rockford-fosgate.com has UC policy: Default Policy
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.898.0000000c (OutboundRouting,PhoneRouter.GetRoutes:phonerouter.cs(152))( 000000000032A3AB )target phone number: +16028030993, PhoneRouteUsage: MP-118
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.899.0000000d (OutboundRouting,PhoneRouter.GetRoutes:phonerouter.cs(221))( 000000000032A3AB )#hits: 1, route names: MP-118
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.900.0000000e (OutboundRouting,PhoneRouter.GetRoutes:phonerouter.cs(224))( 000000000032A3AB )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.901.0000000f (OutboundRouting,OutboundRoutingTransaction.GetNextHop:outboundroutingtransaction.cs(506))( 000000000351A7BF )Attempting to find GetNextHop: Length:1; StartAt:0; Tried:1; FQDN/State:rtms-01.rt.ad:5061-Up
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.902.00000010 (OutboundRouting,OutboundRoutingDispatcher.PrepareRequest:outboundroutingdispatcher.cs(674))( 000000000308499C )New request line: sip:+16028030993@rtms-01.rt.ad:5061;user=phone;maddr=rtms-01.rt.ad. Exit.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.902.00000011 (OutboundRouting,OutboundRoutingDispatcher.SendRequest:outboundroutingdispatcher.cs(558))( 000000000308499C )Enter
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.903.00000012 (OutboundRouting,OutboundRoutingTransaction.SetState:outboundroutingtransaction.cs(254))( 000000000351A7BF )Set transaction 4 state to ClientTimerRunning
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.904.00000013 (OutboundRouting,ORTransactionTimer.AddPendingTransaction:ortransactiontimer.cs(210))( 0000000002CD3EB4 )Enter
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.904.00000014 (OutboundRouting,ORTransactionTimer.AddPendingTransaction:ortransactiontimer.cs(239))( 0000000002CD3EB4 )Global timer started.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.904.00000015 (OutboundRouting,ORTransactionTimer.AddPendingTransaction:ortransactiontimer.cs(246))( 0000000002CD3EB4 )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.904.00000016 (OutboundRouting,OutboundRoutingDispatcher.SendRequest:outboundroutingdispatcher.cs(580))( 000000000308499C )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.904.00000017 (OutboundRouting,OutboundRouting.OnRequest:outboundrouting.cs(246))( 000000000259F3B6 )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:57.939.00000018 (OutboundRouting,OutboundRoutingDispatcher.OnResponse:outboundroutingdispatcher.cs(259))( 000000000308499C )Received status code 100 for request sip:+16028030993@rtms-01.rt.ad:5061;user=phone;maddr=rtms-01.rt.ad
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.055.00000019 (OutboundRouting,OutboundRoutingDispatcher.OnResponse:outboundroutingdispatcher.cs(259))( 000000000308499C )Received status code 183 for request sip:+16028030993@rtms-01.rt.ad:5061;user=phone;maddr=rtms-01.rt.ad
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.055.0000001a (OutboundRouting,OutboundRoutingTransaction.SetState:outboundroutingtransaction.cs(254))( 000000000351A7BF )Set transaction 4 state to ClientTimerStopped
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.055.0000001b (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(252))( 0000000002CD3EB4 )Enter
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.057.0000001c (OutboundRouting,ORTransactionTimer.RemoveListEntry:ortransactiontimer.cs(187))( 0000000002CD3EB4 )Remove list entry. Id: 4
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.057.0000001d (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(259))( 0000000002CD3EB4 )Global timer stopped.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.057.0000001e (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(264))( 0000000002CD3EB4 )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.057.0000001f (OutboundRouting,OutboundRoutingDispatcher.OnResponse:outboundroutingdispatcher.cs(341))( 000000000308499C )Forward response.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.322.00000020 (OutboundRouting,OutboundRoutingDispatcher.OnResponse:outboundroutingdispatcher.cs(259))( 000000000308499C )Received status code 503 for request sip:+16028030993@rtms-01.rt.ad:5061;user=phone;maddr=rtms-01.rt.ad
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.322.00000021 (OutboundRouting,OutboundRoutingTransaction.SetState:outboundroutingtransaction.cs(254))( 000000000351A7BF )Set transaction 4 state to ClientTerminated
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.322.00000022 (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(252))( 0000000002CD3EB4 )Enter
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.322.00000023 (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(259))( 0000000002CD3EB4 )Global timer stopped.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.322.00000024 (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(264))( 0000000002CD3EB4 )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.324.00000025 (OutboundRouting,Gateway.set_State:gateway.cs(102))( 0000000003AA1014 )Gateway rtms-01.rt.ad:5061 -- ConsecutiveFailures:3; State:Up.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.324.00000026 (OutboundRouting,OutboundRoutingDispatcher.OnResponse:outboundroutingdispatcher.cs(313))( 000000000308499C )Attempt rerouting...
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.325.00000027 (OutboundRouting,OutboundRoutingDispatcher.GenerateEarlyDialogTerminatedResponse:outboundroutingdispatcher.cs(845))( 000000000308499C )Sent 199 response.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.325.00000028 (OutboundRouting,PhoneRouter.GetRoutes:phonerouter.cs(152))( 000000000032A3AB )target phone number: +16028030993, PhoneRouteUsage: Default Usage
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.325.00000029 (OutboundRouting,PhoneRouter.GetRoutes:phonerouter.cs(221))( 000000000032A3AB )#hits: 1, route names: MP-118
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.325.0000002a (OutboundRouting,PhoneRouter.GetRoutes:phonerouter.cs(224))( 000000000032A3AB )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.325.0000002b (OutboundRouting,OutboundRoutingTransaction.GetNextHop:outboundroutingtransaction.cs(506))( 000000000351A7BF )Attempting to find GetNextHop: Length:1; StartAt:0; Tried:1; FQDN/State:rtms-01.rt.ad:5061-Up
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.325.0000002c (OutboundRouting,OutboundRoutingDispatcher.PrepareRequest:outboundroutingdispatcher.cs(651))( 000000000308499C )Next hop not found.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.325.0000002d (OutboundRouting,OutboundRoutingDispatcher.ReRouteRequest:outboundroutingdispatcher.cs(898))( 000000000308499C )Cannot reroute request: Routes available, but no next hop available
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.326.0000002e (OutboundRouting,OutboundRoutingDispatcher.ServerTransactionEnded:outboundroutingdispatcher.cs(1027))( 000000000308499C )Processing SIP server transaction termination for transaction 4
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.326.0000002f (OutboundRouting,OutboundRoutingTransaction.SetState:outboundroutingtransaction.cs(254))( 000000000351A7BF )Set transaction 4 state to ServerTerminated
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.326.00000030 (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(252))( 0000000002CD3EB4 )Enter
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.326.00000031 (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(259))( 0000000002CD3EB4 )Global timer stopped.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:41:58.326.00000032 (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(264))( 0000000002CD3EB4 )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.387.00000033 (OutboundRouting,OutboundRoutingTransaction.constructor:outboundroutingtransaction.cs(194))Creating a OutboundRoutingTransaction object (5)
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.387.00000034 (OutboundRouting,OutboundRouting.OnRequest:outboundrouting.cs(242))( 000000000259F3B6 )Enter
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.387.00000035 (OutboundRouting,Settings.ProcessPendingWmiEvents:settings.cs(941))( 0000000003003957 )Enter.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.387.00000036 (OutboundRouting,OutboundRoutingDispatcher.OnRequest:outboundroutingdispatcher.cs(124))( 000000000308499C )From uri: Dan.Madsen@rockford-fosgate.com
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.387.00000037 (OutboundRouting,OutboundRoutingDispatcher.OnRequest:outboundroutingdispatcher.cs(125))( 000000000308499C )From User Uc Enabled: True
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.387.00000038 (OutboundRouting,OutboundRoutingDispatcher.OnRequest:outboundroutingdispatcher.cs(126))( 000000000308499C )From User Policy: <instance><property name="Name"><![CDATA[Default Policy]]></property><property name="AllowSimultaneousRinging"><![CDATA[true]]></property><property name="PhoneRouteUsages"><element><![CDATA[CN={283BD424-41EB-4420-9EEA-6A7556269622},CN=Phone Route Usages,CN=RTC Service,CN=Services,CN=Configuration,DC=RT,DC=AD]]></element><element><![CDATA[CN={C491D082-9CD3-4A41-9A79-9DCEE38670EB},CN=Phone Route Usages,CN=RTC Service,CN=Services,CN=Configuration,DC=RT,DC=AD]]></element></property></instance>

    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.387.00000039 (OutboundRouting,OutboundRoutingDispatcher.OnRequest:outboundroutingdispatcher.cs(136))( 000000000308499C )Referrer URI: <null>
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.387.0000003a (OutboundRouting,OutboundRoutingDispatcher.OnRequest:outboundroutingdispatcher.cs(138))( 000000000308499C )IsAvMCUDialOut: False
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.387.0000003b (OutboundRouting,OutboundRoutingDispatcher.OnRequest:outboundroutingdispatcher.cs(206))( 000000000308499C )Applying From URI's outbound policy
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.387.0000003c (OutboundRouting,OutboundRoutingDispatcher.ApplyCallerPolicyAndRouteRequest:outboundroutingdispatcher.cs(589))( 000000000308499C )Routing request based on caller: Dan.Madsen@rockford-fosgate.com
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.388.0000003d (OutboundRouting,OutboundRoutingDispatcher.PrepareNewRequest:outboundroutingdispatcher.cs(729))( 000000000308499C )User Dan.Madsen@rockford-fosgate.com has UC policy: Default Policy
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.388.0000003e (OutboundRouting,PhoneRouter.GetRoutes:phonerouter.cs(152))( 000000000032A3AB )target phone number: +16028030993, PhoneRouteUsage: MP-118
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.388.0000003f (OutboundRouting,PhoneRouter.GetRoutes:phonerouter.cs(221))( 000000000032A3AB )#hits: 1, route names: MP-118
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.388.00000040 (OutboundRouting,PhoneRouter.GetRoutes:phonerouter.cs(224))( 000000000032A3AB )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.388.00000041 (OutboundRouting,OutboundRoutingTransaction.GetNextHop:outboundroutingtransaction.cs(506))( 0000000003945300 )Attempting to find GetNextHop: Length:1; StartAt:0; Tried:1; FQDN/State:rtms-01.rt.ad:5061-Up
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.388.00000042 (OutboundRouting,OutboundRoutingDispatcher.PrepareRequest:outboundroutingdispatcher.cs(674))( 000000000308499C )New request line: sip:+16028030993@rtms-01.rt.ad:5061;user=phone;maddr=rtms-01.rt.ad. Exit.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.388.00000043 (OutboundRouting,OutboundRoutingDispatcher.SendRequest:outboundroutingdispatcher.cs(558))( 000000000308499C )Enter
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.388.00000044 (OutboundRouting,OutboundRoutingTransaction.SetState:outboundroutingtransaction.cs(254))( 0000000003945300 )Set transaction 5 state to ClientTimerRunning
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.388.00000045 (OutboundRouting,ORTransactionTimer.AddPendingTransaction:ortransactiontimer.cs(210))( 0000000002CD3EB4 )Enter
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.388.00000046 (OutboundRouting,ORTransactionTimer.AddPendingTransaction:ortransactiontimer.cs(239))( 0000000002CD3EB4 )Global timer started.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.388.00000047 (OutboundRouting,ORTransactionTimer.AddPendingTransaction:ortransactiontimer.cs(246))( 0000000002CD3EB4 )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.388.00000048 (OutboundRouting,OutboundRoutingDispatcher.SendRequest:outboundroutingdispatcher.cs(580))( 000000000308499C )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.388.00000049 (OutboundRouting,OutboundRouting.OnRequest:outboundrouting.cs(246))( 000000000259F3B6 )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.391.0000004a (OutboundRouting,OutboundRoutingDispatcher.OnResponse:outboundroutingdispatcher.cs(259))( 000000000308499C )Received status code 100 for request sip:+16028030993@rtms-01.rt.ad:5061;user=phone;maddr=rtms-01.rt.ad
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.563.0000004b (OutboundRouting,OutboundRoutingDispatcher.OnResponse:outboundroutingdispatcher.cs(259))( 000000000308499C )Received status code 183 for request sip:+16028030993@rtms-01.rt.ad:5061;user=phone;maddr=rtms-01.rt.ad
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.563.0000004c (OutboundRouting,OutboundRoutingTransaction.SetState:outboundroutingtransaction.cs(254))( 0000000003945300 )Set transaction 5 state to ClientTimerStopped
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.563.0000004d (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(252))( 0000000002CD3EB4 )Enter
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.563.0000004e (OutboundRouting,ORTransactionTimer.RemoveListEntry:ortransactiontimer.cs(187))( 0000000002CD3EB4 )Remove list entry. Id: 5
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.563.0000004f (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(259))( 0000000002CD3EB4 )Global timer stopped.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.563.00000050 (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(264))( 0000000002CD3EB4 )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.563.00000051 (OutboundRouting,OutboundRoutingDispatcher.OnResponse:outboundroutingdispatcher.cs(341))( 000000000308499C )Forward response.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.00000052 (OutboundRouting,OutboundRoutingDispatcher.OnResponse:outboundroutingdispatcher.cs(259))( 000000000308499C )Received status code 503 for request sip:+16028030993@rtms-01.rt.ad:5061;user=phone;maddr=rtms-01.rt.ad
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.00000053 (OutboundRouting,OutboundRoutingTransaction.SetState:outboundroutingtransaction.cs(254))( 0000000003945300 )Set transaction 5 state to ClientTerminated
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.00000054 (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(252))( 0000000002CD3EB4 )Enter
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.00000055 (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(259))( 0000000002CD3EB4 )Global timer stopped.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.00000056 (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(264))( 0000000002CD3EB4 )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.00000057 (OutboundRouting,Gateway.set_State:gateway.cs(102))( 0000000003AA1014 )Gateway rtms-01.rt.ad:5061 -- ConsecutiveFailures:4; State:Up.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.00000058 (OutboundRouting,OutboundRoutingDispatcher.OnResponse:outboundroutingdispatcher.cs(313))( 000000000308499C )Attempt rerouting...
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.00000059 (OutboundRouting,OutboundRoutingDispatcher.GenerateEarlyDialogTerminatedResponse:outboundroutingdispatcher.cs(845))( 000000000308499C )Sent 199 response.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.0000005a (OutboundRouting,PhoneRouter.GetRoutes:phonerouter.cs(152))( 000000000032A3AB )target phone number: +16028030993, PhoneRouteUsage: Default Usage
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.0000005b (OutboundRouting,PhoneRouter.GetRoutes:phonerouter.cs(221))( 000000000032A3AB )#hits: 1, route names: MP-118
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.0000005c (OutboundRouting,PhoneRouter.GetRoutes:phonerouter.cs(224))( 000000000032A3AB )Exit
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.0000005d (OutboundRouting,OutboundRoutingTransaction.GetNextHop:outboundroutingtransaction.cs(506))( 0000000003945300 )Attempting to find GetNextHop: Length:1; StartAt:0; Tried:1; FQDN/State:rtms-01.rt.ad:5061-Up
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.0000005e (OutboundRouting,OutboundRoutingDispatcher.PrepareRequest:outboundroutingdispatcher.cs(651))( 000000000308499C )Next hop not found.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.0000005f (OutboundRouting,OutboundRoutingDispatcher.ReRouteRequest:outboundroutingdispatcher.cs(898))( 000000000308499C )Cannot reroute request: Routes available, but no next hop available
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.00000060 (OutboundRouting,OutboundRoutingDispatcher.ServerTransactionEnded:outboundroutingdispatcher.cs(1027))( 000000000308499C )Processing SIP server transaction termination for transaction 5
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.00000061 (OutboundRouting,OutboundRoutingTransaction.SetState:outboundroutingtransaction.cs(254))( 0000000003945300 )Set transaction 5 state to ServerTerminated
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.00000062 (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(252))( 0000000002CD3EB4 )Enter
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.00000063 (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(259))( 0000000002CD3EB4 )Global timer stopped.
    TL_INFO(TF_COMPONENT) [0]0D04.0404::05/05/2009-22:42:01.802.00000064 (OutboundRouting,ORTransactionTimer.RemovePendingTransaction:ortransactiontimer.cs(264))( 0000000002CD3EB4 )Exit

    Let me know if there's any other logs you may need. Thanks

    • Proposed as answer by noai Saturday, September 26, 2009 2:47 AM
    Wednesday, May 6, 2009 6:58 PM
  • I checked the rules and they seem to be working. I used the route helper to configure the routes. In the communicator error log it shows it is using the correct rules.
    • Proposed as answer by noai Saturday, September 26, 2009 2:48 AM
    Wednesday, May 6, 2009 7:00 PM
  • This looks like you have a problem with the configuration on the MP-118.  it appears that the mediation server does not like the response it is recieving from the MP-118.

    Any chance you can elaborate on the config?

    I have a 112 in the lab and the configuration stuff is very similar to the 118.


    mitch
    • Proposed as answer by noai Saturday, September 26, 2009 2:48 AM
    Sunday, May 10, 2009 2:29 AM
  • Looks like the FE->Mediation Server->MP-118 is working right, i.e. it looks like the call is going out correctly to the MP-118.

    We have a Mediant 1000.  For that call to go out correctly the "+" must be stripped inside the MP-118, and depending on your local calling rules, you need to strip the 1 also and perhaps the area code, depending on whether or not your area uses 7 or 10 digit dialing.

    It also often matters what the source number is.  I don't know what kind of connection you have with the PSTN on the MP-118, but on the Mediant 1000 over a T1, our PSTN requires a properly formatted source calling number, meaning the +1 has to be removed as we have 10 digit local calling here in Houston.

    Without further detail of your MP-118 configuration that's the best I can offer.

    -Barry
    • Proposed as answer by noai Saturday, September 26, 2009 2:48 AM
    Sunday, May 10, 2009 3:55 AM
  • Here is the ini file:
    ;**************
    ;** Ini File **
    ;**************

    ;Board: MP-118 FXS
    ;Serial Number: 1539133
    ;Slot Number: 1
    ;Software Version: 5.20A.027.004
    ;DSP Software Version: 204IM => 520.13
    ;Board IP Address: 10.84.1.120
    ;Board Subnet Mask: 255.255.255.0
    ;Board Default Gateway: 10.84.1.1
    ;Ram size: 32M   Flash size: 8M
    ;Num DSPs: 2  Num DSP channels: 8
    ;Profile: NONE
    ;-----------------------------------------


    [SYSTEM Params]

    DNSPriServerIP = 10.84.1.10
    SyslogServerIP = 10.1.1.89

    [BSP Params]

    PCMLawSelect = 3
    LocalMediaDefaultGW = 10.84.1.1
    LocalMediaIPAddress = 10.84.1.120
    LocalMediaSubnetMask = 255.255.255.0
    LocalControlIPAddress = 10.84.1.120
    LocalControlSubnetMask = 255.255.255.0
    LocalOAMIPAddress = 10.84.1.120
    LocalOAMSubnetMask = 255.255.255.0
    LocalOAMDefaultGW = 10.84.1.1

    [ATM Params]


    [Analog Params]

    MinFlashHookTime = 100
    FXSLoopCharacteristicsFilename = 'MP11x-02-1-FXS_16KHZ.dat'

    [ControlProtocols Params]

    AdminStateLockControl = 0

    [MGCP Params]


    [MEGACO Params]

    EP_Num_0 = 0
    EP_Num_1 = 1
    EP_Num_2 = 0
    EP_Num_3 = 0
    EP_Num_4 = 0

    [PSTN Params]


    [SS7 Params]


    [Voice Engine Params]

    RFC2833PayloadType = 101
    CallProgressTonesFilename = 'usa_tones_12.dat'

    [WEB Params]

    LogoWidth = '339'

    [SIP Params]

    MAXDIGITS = 32
    LOCALSIPPORT = 5040
    ISPROXYUSED = 1
    AUTHENTICATIONMODE = 1
    ROUTEMODEIP2TEL = 1
    CHANNELSELECTMODE = 2
    GWDEBUGLEVEL = 5
    ENABLEEARLYMEDIA = 1
    PROXYNAME = '10.84.1.51'
    SIPGATEWAYNAME = '10.84.1.120'
    ISFAXUSED = 1
    SUBSCRIPTIONMODE = 1
    SIPTRANSPORTTYPE = 2
    TCPLOCALSIPPORT = 5061
    GWREGISTRATIONNAME = '10.84.1.120'
    TLSLOCALSIPPORT = 5060

    [VXML Params]


    [IPsec Params]


    [Audio Staging Params]


    ;
    ;  *** TABLE DspTemplates ***
    ; This table contains hidden elements and will not be exposed.
    ; This table exists on board and will be saved during restarts
    ;

    ;
    ;  *** TABLE PREFIX ***

    ;

    [ PREFIX ]
    FORMAT PREFIX_Index = PREFIX_DestinationPrefix, PREFIX_DestAddress, PREFIX_SourcePrefix, PREFIX_ProfileId, PREFIX_MeteringCode;
    PREFIX 0 = *, 10.84.1.51, *, 0, 255;

    [ \PREFIX ]

    ;
    ;  *** TABLE CoderName ***

    ;

    [ CoderName ]
    FORMAT CoderName_Index = CoderName_Type, CoderName_PacketInterval, CoderName_rate, CoderName_PayloadType, CoderName_Sce;
    CoderName 0 = g711Alaw64k, 20, 255, 255, 0;
    CoderName 1 = g711Ulaw64k, 20, 255, 255, 0;

    [ \CoderName ]

    ;
    ;  *** TABLE TrunkGroup ***

    ;

    [ TrunkGroup ]
    FORMAT TrunkGroup_Index = TrunkGroup_TrunkGroupNum, TrunkGroup_FirstTrunkId, TrunkGroup_FirstBChannel, TrunkGroup_LastBChannel, TrunkGroup_FirstPhoneNumber, TrunkGroup_ProfileId, TrunkGroup_LastTrunkId, TrunkGroup_Module;
    TrunkGroup 0 = 1, 255, 1, 8, 101, 1, 255, 255;

    [ \TrunkGroup ]

    ;
    ;  *** TABLE NumberMapIp2Tel ***

    ;

    [ NumberMapIp2Tel ]
    FORMAT NumberMapIp2Tel_Index = NumberMapIp2Tel_DestinationPrefix, NumberMapIp2Tel_SourcePrefix, NumberMapIp2Tel_SourceAddress, NumberMapIp2Tel_NumberType, NumberMapIp2Tel_NumberPlan, NumberMapIp2Tel_RemoveFromLeft, NumberMapIp2Tel_RemoveFromRight, NumberMapIp2Tel_LeaveFromRight, NumberMapIp2Tel_Prefix2Add, NumberMapIp2Tel_Suffix2Add, NumberMapIp2Tel_IsPresentationRestricted;
    NumberMapIp2Tel 0 = *, *, *, 255, 255, 2, 0, 255, , , 255;

    [ \NumberMapIp2Tel ]

    ;
    ;  *** TABLE NumberMapTel2Ip ***

    ;

    [ NumberMapTel2Ip ]
    FORMAT NumberMapTel2Ip_Index = NumberMapTel2Ip_DestinationPrefix, NumberMapTel2Ip_SourcePrefix, NumberMapTel2Ip_SourceAddress, NumberMapTel2Ip_NumberType, NumberMapTel2Ip_NumberPlan, NumberMapTel2Ip_RemoveFromLeft, NumberMapTel2Ip_RemoveFromRight, NumberMapTel2Ip_LeaveFromRight, NumberMapTel2Ip_Prefix2Add, NumberMapTel2Ip_Suffix2Add, NumberMapTel2Ip_IsPresentationRestricted;
    NumberMapTel2Ip 0 = 480, *, *, 255, 255, 0, 0, 255, +1, , 255;

    [ \NumberMapTel2Ip ]

    ;
    ;  *** TABLE PstnPrefix ***

    ;

    [ PstnPrefix ]
    FORMAT PstnPrefix_Index = PstnPrefix_DestPrefix, PstnPrefix_TrunkGroupId, PstnPrefix_SourcePrefix, PstnPrefix_SourceAddress, PstnPrefix_ProfileId;
    PstnPrefix 0 = *, 1, *, *, 1;

    [ \PstnPrefix ]

    ;
    ;  *** TABLE ProxyIp ***

    ;

    [ ProxyIp ]
    FORMAT ProxyIp_Index = ProxyIp_IpAddress;
    ProxyIp 0 = 10.84.1.51;

    [ \ProxyIp ]

    ;
    ;  *** TABLE TxDtmfOption ***

    ;

    [ TxDtmfOption ]
    FORMAT TxDtmfOption_Index = TxDtmfOption_Type;
    TxDtmfOption 0 = 4;

    [ \TxDtmfOption ]

    ;
    ;  *** TABLE TrunkGroupSettings ***

    ;

    [ TrunkGroupSettings ]
    FORMAT TrunkGroupSettings_Index = TrunkGroupSettings_TrunkGroupId, TrunkGroupSettings_ChannelSelectMode, TrunkGroupSettings_RegistrationMode, TrunkGroupSettings_GatewayName;
    TrunkGroupSettings 0 = 1, 2, 4, ;

    [ \TrunkGroupSettings ]


    Thanks again for the help

    Thursday, May 14, 2009 7:22 PM
  • I'd love to try and help you as I've recently struggled with similar issues getting our deployment done using the Mediant 1000.

    I am not really familiar with your model gateway, but can you tell me what kind of connection you have to the PSTN?  Is it a T1, or just multiple line modules connected to the PSTN?

    How does your gateway handle dial outs?  For example, when a call comes to our Mediant 1000 to go out on the PSTN over a T1, the source and destination numbers are formatted like "+17135551212", and I must strip the "+1" from the source number and always strip the "+" from the destination number, and then depending on whether it is a local or long distance call I have to optionally strip the "1" from the destination number.  All of this occurs inside the Mediant 1000.

    The OCS Mediation Server is handing your gateway a source/destination phone number in the format in which your normalization is configured, which is likely as I've described above.  You will have to take this and do whatever with it your gateway can do to properly either dial out or hand off the call to the PSTN.

    -Barry
    • Proposed as answer by noai Saturday, September 26, 2009 2:48 AM
    Friday, May 15, 2009 8:13 PM
  • I have phone lines connected to the MP-118. I am testing calling out to local numbers, so for now I have the Gateway stripping the "+1". It seems like it's something in the hand off from the Mediation server to the Gateway. I tested the phone lines themselves and they are working fine.

    I also have a few calls in to Audiocodes support and I've posted on their forums, but no help yet.

    Thanks
    • Proposed as answer by noai Saturday, September 26, 2009 2:48 AM
    Monday, May 18, 2009 9:52 PM
  • I assume the 118 has log support.  Have you turned on the log so you can see what is getting passed to the MP-118?

    It probably has a setting to set debug level to 0 thru 5.  In the web interface for the Mediant 1000 it has this setting.

    If you send me an email I can send you a link to a tool that tells you how to syslog and lets you save it on a computer at an IP address.  This works for the Mediant 1000, I don't know if it works for the MP-118.

    I saw your post on the Audiocodes forum.  Almost nobody reads that forum.  You can tell by how few posts are there and how little it is read.  Don't expect any help from there.

    I was able to get help from their support people.

    email to Barry at daram dot com.

    -Barry

    • Proposed as answer by noai Saturday, September 26, 2009 2:48 AM
    Tuesday, May 19, 2009 1:31 AM
  • I have setup syslog and I have it being sent to a server that I set up. Here is what I get when I try to make a call:

    05-19-2009 12:31:24 Local0.Notice 10.84.1.120 (     sip_stack)(106       )  SIPTCPMngr::ReturnTLSTransportObj - #6
    05-19-2009 12:31:24 Local0.Notice 10.84.1.120 (     sip_stack)(105       )  TlsTransportObject#6::CloseConnection - Closing connection for socket 43
    05-19-2009 12:31:24 Local0.Notice 10.84.1.120 (     sip_stack)(104       )  TlsTransportObject#6::Handle_AC_CB_REMOTE_CLOSE_EV - Remote Close event was received
    05-19-2009 12:31:24 Local0.Warning 10.84.1.120 (    sips_stack)(103       ) !! [ERROR] TLSComm::HandleSSLError - SSL Error Was: error:1408A0C1:SSL routines:SSL3_GET_CLIENT_HELLO:no shared cipher (Error Flag is SSL_ERROR_SSL)
    05-19-2009 12:31:24 Local0.Warning 10.84.1.120 (    sips_stack)(102       ) !! [ERROR] TLSComm::HandleSSLError - SSL Error Was: error:1408A0C1:SSL routines:SSL3_GET_CLIENT_HELLO:no shared cipher (Error Flag is SSL_ERROR_SSL)
    05-19-2009 12:31:24 Local0.Notice 10.84.1.120 (     sip_stack)(101       )  new TLSTransportObject created - #6

    Tuesday, May 19, 2009 7:38 PM
  • I just got off of the phone with Audiocodes support and I have sent them the information. Hopefully they will have an answer for me soon.

    Thanks for your help and I'll keep you up to date.
    • Proposed as answer by noai Saturday, September 26, 2009 2:48 AM
    Tuesday, May 19, 2009 7:49 PM
  • We couldn't get TLS working, so we switched it over to TCP and it works just fine!

    Thanks for the help guys.
    • Proposed as answer by noai Saturday, September 26, 2009 2:48 AM
    Wednesday, June 17, 2009 7:57 PM
  • I am setting up a test lab for OCS 2007 R2. I have OCS up and running internally. I have installed a Mediation server and I have set up an audiocodes MP-118 Gateway. When I attempt to make an outside, say to my cell phone, I get a 503 service unavailable error. It states 'The message received was unexpected or badly formatted.' Could some help to interpret this error message?

    Thanks in advance for any help.

    • Proposed as answer by noai Saturday, September 26, 2009 2:49 AM
    Saturday, September 26, 2009 1:54 AM