locked
Unable to dial external number from a analog phone connected to a AudioCode MP-112 FXS RRS feed

  • Question

  • Hi !
     
        I just installed a MP-112FXS following the instructions at http://www.lynclog.com/2011/04/analog-phone-support-with-lync-2010.html and I'm able to dial the analog phone extension from a Lync Client and vice versa. But I'm unable to dial an external number. I created a pool dial plan, I tested the dialing rule in the Lync control panel and everything seems to work fine. But when I dial the external number, I get the following error message:
    Start-Line
    : SIP/2.0 404 Not Found

    It is if like the Lync server thinks that the external number I'm dialing is an internal number belonging to a user.

     

    What can I do ?

     

    Thanks,


    Antonio

    Tuesday, November 1, 2011 2:03 PM

Answers

  • Hi Antonio,

    can you post your Dial Plan and normalization rules?

    As Holger mentioned, you need to have E.164 (+001....) normalized numbers for external calls.

    Maybe you have a mistake in the normalization rule

    Falk

    • Marked as answer by Antonio Saraca Tuesday, November 8, 2011 4:48 PM
    Thursday, November 3, 2011 10:45 AM
  • Hi Antonio,

     

    this will not work:

    FROM: <sip:701@gateway.com>;tag=1c1199379579
    TO: <sip:5148312524@10.0.0.179;user=phone>;tag=bddd4b11d;epid=E85CEEEA71

    You have to translate all Numbers to E.164 if you route the call from the FXS port to Lync.


    regards Holger Technical Specialist UC
    • Marked as answer by Antonio Saraca Tuesday, November 8, 2011 4:48 PM
    Thursday, November 3, 2011 9:31 PM
  • what i meant when you configure CS-AnalogDevice, you have to configure the Line URI as tel:+701.

    LineURI : tel:701
    DisplayName : Ascenceur 1
    DisplayNumber : 701

    not like that.

    Thamara

    • Marked as answer by Antonio Saraca Tuesday, November 8, 2011 4:48 PM
    Saturday, November 5, 2011 2:10 PM
  • PS C:\ get-csanalogdevice


    Identity      : CN={d5c5cc98-1bbf-4c82-a8bb-df01f535efe7},OU=Contacts,DC=academ
                    ie,DC=ste-therese,DC=com
    VoicePolicy   : Interurbain
    RegistrarPool : lync.academie.ste-therese.com
    Gateway       : 10.0.0.15
    AnalogFax     : True

    what cu level are you running on the lync server and is gateway with this fxs port also you PSTN egress?

    If you are running latest CU updates and 10.0.0.15 is not your pstn egress (you have a sip trunk, don't you?) Then set "analogfax" to $false.  With analogfax set to true, lync would send the outgoing call back to this gateway, overriding the normal voice routes.

    however running traces on the frontend is your best bet to get insight info on what is happening

    • Marked as answer by Antonio Saraca Tuesday, November 8, 2011 4:48 PM
    Saturday, November 5, 2011 10:12 PM

All replies

  • Use a Sylog Daemon, and get the logs from the Audiocodes. There you can see what happens with the dialed number.

    Falk

    Tuesday, November 1, 2011 2:32 PM
  • Here is the log from the audiocode unit. If I understand the log correctly, I see the same error than on the Lync server, 404 user not found

    (6d:3h:57m:43s SIP/2.0 404 Not Found). But the number I dialed is an external number (my cell phone number)

    Thanks,

    Antonio

    Activated  6d:3h:57m:32s (   lgr_psbrdex)(161490    )  recv <-- OFF_HOOK Ch:0 [Time: 03:57:32]
    6d:3h:57m:32s (      lgr_flow)(161491    )  #0:OFF_HOOK_EV [Time: 03:57:32]
    6d:3h:57m:32s (      lgr_flow)(161492    )  |       #0:OFF_HOOK_EV [Time: 03:57:32]
    6d:3h:57m:32s (   lgr_psbrdif)(161493    )  UpdateChannelParams, Channel 0
     [Time: 03:57:32]
    6d:3h:57m:32s (   lgr_psbrdif)(161494    )  #0:PSOSBoardInterface::ConfigureFaxModemChannelParams FAXTransportType=3 Modem configuration VxxTransportType=2 not allowed, forced to 3
     [Time: 03:57:32]
    6d:3h:57m:32s (   lgr_psbrdif)(161495    )  #0:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=3, VxxTranType=3, VoiceVol= 0, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) [Time: 03:57:32]
    6d:3h:57m:32s (   lgr_psbrdif)(161496    )  ActivateDigitMap for channel : 0, MaxDialStringLength = 15, MaxEndDialTimer = 4000,
    MaxLongInterDigitTimer = 8000, MaxStartTimer = 16000, DigitMap = [0-9*#ABCD][0-9ABCD].T, DialPlanIndex = -1 [Time: 03:57:32]
    6d:3h:57m:32s (      lgr_flow)(161497    )  #-100: StartDigitMapDetection with params:
     <Pattern=[0-9*#ABCD][0-9ABCD].T>
    <MaxStartTimer=16000>
    <SendEachDigit=1>
    <UseEndDialKey=0>
    <MaxLongInterDigitTimer=8000>
    <MaxEndDialTimer=4000>
    <MaxDialStringLength=15>
    <MaxShortInterDigitTimer=0>
    <MinInterDigitLen=-2>
    <MinDigitLen=-2>
    <EndDialWithHashMark=0> [Time: 03:57:32]
    6d:3h:57m:32s (lgr_digitmap_mngr)(161498    )  #0:Activate DigitMapMngr pattern:[0-9*#ABCD][0-9ABCD].T, Max Length is: 15, DialPlanIndex: -1 [Time: 03:57:32]
    6d:3h:57m:32s (   lgr_psbrdif)(161499    )  #0:PSOSBoardInterface::StopPlayTone- Called [Time: 03:57:32]
    6d:3h:57m:32s (   lgr_psbrdex)(161500    )  PCIIFChangeChannelParams failed  ECNlpMode [Time: 03:57:32]
    6d:3h:57m:32s (   lgr_psbrdif)(161501    )  Changed ECNlpMOde to: 1  [Time: 03:57:32]
    6d:3h:57m:32s (   lgr_psbrdif)(161502    )  #0:PSOSBoardInterface::PlayTone - Called Tone=DIAL_TONE Direction=PLAY_TONE_2_TEL [Time: 03:57:32]
    6d:3h:57m:36s (   lgr_psbrdex)(161503    )  recv <-- DIGIT(5) Ch:0 OnTime:0 InterTime:446255860 Direction:0 System:1 [Time: 03:57:36]
    6d:3h:57m:36s (      lgr_flow)(161504    )  #0:DIGIT_EV [Time: 03:57:36]
    6d:3h:57m:36s (      lgr_flow)(161505    )  |       #0:DIGIT_EV [Time: 03:57:36]
    6d:3h:57m:36s (   lgr_psbrdif)(161506    )  #0:PSOSBoardInterface::StopPlayTone- Called [Time: 03:57:36]
    6d:3h:57m:36s (   lgr_psbrdex)(161507    )  recv <-- DIGIT(5) Ch:0 OnTime:130 InterTime:446255860 Direction:0 System:1 [Time: 03:57:36]
    6d:3h:57m:36s (      lgr_flow)(161508    )  #0:DIGIT_EV [Time: 03:57:36]
    6d:3h:57m:36s (      lgr_flow)(161509    )  |       #0:DIGIT_EV [Time: 03:57:36]
    6d:3h:57m:36s (   lgr_psbrdex)(161510    )  recv <-- DIGIT(1) Ch:0 OnTime:0 InterTime:140 Direction:0 System:1 [Time: 03:57:36]
    6d:3h:57m:36s (      lgr_flow)(161511    )  #0:DIGIT_EV [Time: 03:57:36]
    6d:3h:57m:36s (      lgr_flow)(161512    )  |       #0:DIGIT_EV [Time: 03:57:36]
    6d:3h:57m:36s (   lgr_psbrdex)(161513    )  recv <-- DIGIT(1) Ch:0 OnTime:80 InterTime:140 Direction:0 System:1 [Time: 03:57:36]
    6d:3h:57m:36s (      lgr_flow)(161514    )  #0:DIGIT_EV [Time: 03:57:36]
    6d:3h:57m:36s (      lgr_flow)(161515    )  |       #0:DIGIT_EV [Time: 03:57:36]
    6d:3h:57m:36s (   lgr_psbrdex)(161516    )  recv <-- DIGIT(4) Ch:0 OnTime:0 InterTime:130 Direction:0 System:1 [Time: 03:57:36]
    6d:3h:57m:36s (      lgr_flow)(161517    )  #0:DIGIT_EV [Time: 03:57:36]
    6d:3h:57m:36s (      lgr_flow)(161518    )  |       #0:DIGIT_EV [Time: 03:57:36]
    6d:3h:57m:36s (   lgr_psbrdex)(161519    )  recv <-- DIGIT(4) Ch:0 OnTime:80 InterTime:130 Direction:0 System:1 [Time: 03:57:36]
    6d:3h:57m:36s (      lgr_flow)(161520    )  #0:DIGIT_EV [Time: 03:57:36]
    6d:3h:57m:36s (      lgr_flow)(161521    )  |       #0:DIGIT_EV [Time: 03:57:36]
    6d:3h:57m:37s (   lgr_psbrdex)(161522    )  recv <-- DIGIT(8) Ch:0 OnTime:0 InterTime:370 Direction:0 System:1 [Time: 03:57:37]
    6d:3h:57m:37s (      lgr_flow)(161523    )  #0:DIGIT_EV [Time: 03:57:37]
    6d:3h:57m:37s (      lgr_flow)(161524    )  |       #0:DIGIT_EV [Time: 03:57:37]
    6d:3h:57m:37s (   lgr_psbrdex)(161525    )  recv <-- DIGIT(8) Ch:0 OnTime:70 InterTime:370 Direction:0 System:1 [Time: 03:57:37]
    6d:3h:57m:37s (      lgr_flow)(161526    )  #0:DIGIT_EV [Time: 03:57:37]
    6d:3h:57m:37s (      lgr_flow)(161527    )  |       #0:DIGIT_EV [Time: 03:57:37]
    6d:3h:57m:37s (   lgr_psbrdex)(161528    )  recv <-- DIGIT(3) Ch:0 OnTime:0 InterTime:190 Direction:0 System:1 [Time: 03:57:37]
    6d:3h:57m:37s (      lgr_flow)(161529    )  #0:DIGIT_EV [Time: 03:57:37]
    6d:3h:57m:37s (      lgr_flow)(161530    )  |       #0:DIGIT_EV [Time: 03:57:37]
    6d:3h:57m:37s (   lgr_psbrdex)(161531    )  recv <-- DIGIT(3) Ch:0 OnTime:90 InterTime:190 Direction:0 System:1 [Time: 03:57:37]
    6d:3h:57m:37s (      lgr_flow)(161532    )  #0:DIGIT_EV [Time: 03:57:37]
    6d:3h:57m:37s (      lgr_flow)(161533    )  |       #0:DIGIT_EV [Time: 03:57:37]
    6d:3h:57m:37s (   lgr_psbrdex)(161534    )  recv <-- DIGIT(1) Ch:0 OnTime:0 InterTime:110 Direction:0 System:1 [Time: 03:57:37]
    6d:3h:57m:37s (      lgr_flow)(161535    )  #0:DIGIT_EV [Time: 03:57:37]
    6d:3h:57m:37s (      lgr_flow)(161536    )  |       #0:DIGIT_EV [Time: 03:57:37]
    6d:3h:57m:37s (   lgr_psbrdex)(161537    )  recv <-- DIGIT(1) Ch:0 OnTime:80 InterTime:110 Direction:0 System:1 [Time: 03:57:37]
    6d:3h:57m:37s (      lgr_flow)(161538    )  #0:DIGIT_EV [Time: 03:57:37]
    6d:3h:57m:37s (      lgr_flow)(161539    )  |       #0:DIGIT_EV [Time: 03:57:37]
    6d:3h:57m:37s (   lgr_psbrdex)(161540    )  recv <-- DIGIT(2) Ch:0 OnTime:0 InterTime:120 Direction:0 System:1 [Time: 03:57:37]
    6d:3h:57m:37s (      lgr_flow)(161541    )  #0:DIGIT_EV [Time: 03:57:37]
    6d:3h:57m:37s (      lgr_flow)(161542    )  |       #0:DIGIT_EV [Time: 03:57:37]
    6d:3h:57m:38s (   lgr_psbrdex)(161543    )  recv <-- DIGIT(2) Ch:0 OnTime:90 InterTime:120 Direction:0 System:1 [Time: 03:57:38]
    6d:3h:57m:38s (      lgr_flow)(161544    )  #0:DIGIT_EV [Time: 03:57:38]
    6d:3h:57m:38s (      lgr_flow)(161545    )  |       #0:DIGIT_EV [Time: 03:57:38]
    6d:3h:57m:38s (   lgr_psbrdex)(161546    )  recv <-- DIGIT(5) Ch:0 OnTime:0 InterTime:100 Direction:0 System:1 [Time: 03:57:38]
    6d:3h:57m:38s (      lgr_flow)(161547    )  #0:DIGIT_EV [Time: 03:57:38]
    6d:3h:57m:38s (      lgr_flow)(161548    )  |       #0:DIGIT_EV [Time: 03:57:38]
    6d:3h:57m:38s (   lgr_psbrdex)(161549    )  recv <-- DIGIT(5) Ch:0 OnTime:80 InterTime:100 Direction:0 System:1 [Time: 03:57:38]
    6d:3h:57m:38s (      lgr_flow)(161550    )  #0:DIGIT_EV [Time: 03:57:38]
    6d:3h:57m:38s (      lgr_flow)(161551    )  |       #0:DIGIT_EV [Time: 03:57:38]
    6d:3h:57m:38s (   lgr_psbrdex)(161552    )  recv <-- DIGIT(2) Ch:0 OnTime:0 InterTime:80 Direction:0 System:1 [Time: 03:57:38]
    6d:3h:57m:38s (      lgr_flow)(161553    )  #0:DIGIT_EV [Time: 03:57:38]
    6d:3h:57m:38s (      lgr_flow)(161554    )  |       #0:DIGIT_EV [Time: 03:57:38]
    6d:3h:57m:38s (   lgr_psbrdex)(161555    )  recv <-- DIGIT(2) Ch:0 OnTime:80 InterTime:80 Direction:0 System:1 [Time: 03:57:38]
    6d:3h:57m:38s (      lgr_flow)(161556    )  #0:DIGIT_EV [Time: 03:57:38]
    6d:3h:57m:38s (      lgr_flow)(161557    )  |       #0:DIGIT_EV [Time: 03:57:38]
    6d:3h:57m:38s (   lgr_psbrdex)(161558    )  recv <-- DIGIT(4) Ch:0 OnTime:0 InterTime:120 Direction:0 System:1 [Time: 03:57:38]
    6d:3h:57m:38s (      lgr_flow)(161559    )  #0:DIGIT_EV [Time: 03:57:38]
    6d:3h:57m:38s (      lgr_flow)(161560    )  |       #0:DIGIT_EV [Time: 03:57:38]
    6d:3h:57m:38s (   lgr_psbrdex)(161561    )  recv <-- DIGIT(4) Ch:0 OnTime:80 InterTime:120 Direction:0 System:1 [Time: 03:57:38]
    6d:3h:57m:38s (      lgr_flow)(161562    )  #0:DIGIT_EV [Time: 03:57:38]
    6d:3h:57m:38s (      lgr_flow)(161563    )  |       #0:DIGIT_EV [Time: 03:57:38]
    6d:3h:57m:42s (   lgr_psbrdex)(161564    )  recv <-- EV_DIALED_STRING Ch:0 Str:5148312524 MapNum:0 CM:FM Match:1 EI: [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161565    )  #0:DIALED_STRING_EV [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161566    )  |       #0:DIALED_STRING_EV [Time: 03:57:42]
    6d:3h:57m:42s (lgr_digitmap_mngr)(161567    )  DigitMapMngr::HandleDialStringEv Match = 1, MatchNum = 0 STR = 5148312524 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_call)(161568    )  Call Allocated ResourceID: 5 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161569    )  |       #0:NEW_CALL_EV (send)  : (UnKnown) [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161570    )  |       |       #5:NEW_CALL_EV:(UnKnown) [Time: 03:57:42]
    6d:3h:57m:42s (  lgr_stk_mngr)(161571    )  Resource StackSession <#5> Allocated [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161572    )  |       |       #5:Call changing states from:IdleState to:NewCallState_Tel2IP [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161573    )  |       |       |       #5:NEW_CALL_EV(Unknown) [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_call)(161574    )  |       |       #5GetNextUI:GlobalUI=136285110, mACAddrLsb=3299323 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_call)(161575    )  |       |       #5GetNextUI:GlobalUI=136285111 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161576    )  |       (to 5148312524) [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161577    )  |       #0:SETUP_EV (send)  : (UnKnown) [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161578    )  |       |       #5:SETUP (TO:5148312524, FROM:701):(UnKnown) [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_call)(161579    )  new call from EndPoint [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161580    )  |       |       #5:Call changing states from:NewCallState_Tel2IP to:InitiatedState_Tel2IP [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161581    )  |       |       |       #5:SETUP_EV(Unknown) [Time: 03:57:42]
    6d:3h:57m:42s (     lgr_stack)(161582    )  FindIpDestination: rmRc:5 (FAIL) SrcIpGroup:-1 IpconnHndl:-1 DstPrefix:5148312524 DstIp: [Time: 03:57:42]
    6d:3h:57m:42s (     lgr_stack)(161583    )  RoutingInstance (#5) GetProxyParams: Setting persistent connection to proxy: 10.0.0.179:5060  [Time: 03:57:42]
    6d:3h:57m:42s (     lgr_stack)(161584    )  RoutingInstance (#5) RouteToProxyIfExists: find route to proxy DestIPGroup 0 [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_stk_ses)(161585    )  <SESSION #5> UpdateAfterDecidingRouting: IpProfileId (0), ChargeCode (255), NewIndex (-100) [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_call)(161586    )  Call::SetCoderListForCall #5 Found 2 Common Coders For Call [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_call)(161587    )  <Call #5> Coder g711Alaw64k20 : 20 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_call)(161588    )  <Call #5> Coder g711Ulaw64k20 : 20 [Time: 03:57:42]
    6d:3h:57m:42s ( lgr_profiling)(161589    )  <Call 5> Profiled<Tel=0,Ip=0>: JBMinDel=10 JBOptF=10 EEarlyM=1 FaxTM=1 IPDS=46 IsFaxU=2 PI2IP=-1 SigIPDF=40 CNGMode=0 DTMFUsed=0 NSEMode=0 PlayRBTone2IP=0 RBUdpPort=0 RTPRD=0 SCE=0 VxxTT=2 Dst2Rdrt=0 DTMFVol=20 ECE=1 ECurDis=0 EDigDel=0 ERevP=0 FHPer=700 InG=32 MWIA=0 MWID=0 VVol=32 ReorderTime=255 DIDWink=0 2StageDial=1 DiscOnBusyT=1 DiscOnBrok=1 DPInd=255 [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_stk_ses)(161590    )  DecideRoutingSetup DestIpGroupId:0 [Time: 03:57:42]
    6d:3h:57m:42s (     sip_stack)(161591    )  New SIPMessage created - #9 [Time: 03:57:42]
    6d:3h:57m:42s (     sip_stack)(161592    )  new AcSIPCallAPI created - #6 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161593    )  gwGroup::GetSIPGatewayName GroupName of group 1 is not defined. Use the default. [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161594    )  |       | new GetNewSIPCall created - #6 [Time: 03:57:42]
    6d:3h:57m:42s (     sip_stack)(161595    )  SIPSDPSession#5 - Changing state from SIP_MEDIA_IDLE to SIP_MEDIA_OFFERING [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161596    )  |       |(SIPTU#6)SETUP_REQ State:Idle() [Time: 03:57:42]
    6d:3h:57m:42s (     sip_stack)(161597    )  SIPCall(#6) changes state from Idle to Inviting [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161598    )  ---- Outgoing SIP Message to 10.0.0.179:5060 from SIPInterface #0 ---- [Time: 03:57:42]
    6d:3h:57m:42s INVITE sip:5148312524@10.0.0.179;user=phone SIP/2.0
    Via: SIP/2.0/TCP 10.0.0.15:5066;branch=z9hG4bKac1199383722;alias
    Max-Forwards: 70
    From: <sip:701@gateway.com>;tag=1c1199379579
    To: <sip:5148312524@10.0.0.179;user=phone>
    Call-ID: 119937910961200035742@10.0.0.15
    CSeq: 1 INVITE
    Contact: <sip:701@10.0.0.15:5066;transport=tcp>
    Supported: em,100rel,timer,replaces,path,early-session,resource-priority,sdp-anat
    Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE
    User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.023.006
    Content-Type: application/sdp
    Content-Disposition: session
    Content-Length: 251

    v=0
    o=AudiocodesGW 1199367480 1199367358 IN IP4 10.0.0.15
    s=Phone-Call
    c=IN IP4 10.0.0.15
    t=0 0
    m=audio 6030 RTP/AVP 8 0 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
     [Time:
    6d:3h:57m:42s (     sip_stack)(161600    )  Resource SIPMessage deleted - #9 [Time: 03:57:42]
    6d:3h:57m:42s (     sip_stack)(161601    )  New SIPMessage created - #3 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161602    )  ---- Incoming SIP Message from 10.0.0.179:5060 to SIPInterface #0 ---- [Time: 03:57:42]
    6d:3h:57m:42s SIP/2.0 100 Trying
    FROM: <sip:701@gateway.com>;tag=1c1199379579
    TO: <sip:5148312524@10.0.0.179;user=phone>
    CSEQ: 1 INVITE
    CALL-ID: 119937910961200035742@10.0.0.15
    VIA: SIP/2.0/TCP 10.0.0.15:5066;branch=z9hG4bKac1199383722;alias
    CONTENT-LENGTH: 0

     [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161604    )  |       |(SIPTU#6)100 State:Inviting(119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s (     sip_stack)(161605    )  SIPCall(#6) changes state from Inviting to Proceeding [Time: 03:57:42]
    6d:3h:57m:42s (     sip_stack)(161606    )  Resource SIPMessage deleted - #3 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161607    )  |       |       |       #5:SIP_TRYING_EV(119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_stk_ses)(161608    )  <SESSION #5> SendToCall - event: PROCEEDING_EV  m_Call#5 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161609    )  |       |       #5:PROCEEDING_EV:(119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161610    )  |       #0:PROCEEDING_EV   : (119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s (     sip_stack)(161611    )  New SIPMessage created - #10 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161612    )  ---- Incoming SIP Message from 10.0.0.179:5060 to SIPInterface #0 ---- [Time: 03:57:42]
    6d:3h:57m:42s SIP/2.0 183 Session Progress
    FROM: <sip:701@gateway.com>;tag=1c1199379579
    TO: <sip:5148312524@10.0.0.179;user=phone>;tag=bddd4b11d;epid=E85CEEEA71
    CSEQ: 1 INVITE
    CALL-ID: 119937910961200035742@10.0.0.15
    VIA: SIP/2.0/TCP 10.0.0.15:5066;branch=z9hG4bKac1199383722;alias
    CONTACT: <sip:lync-mds.academie.ste-therese.com:5060;transport=Tcp;maddr=10.0.0.179>
    CONTENT-LENGTH: 250
    CONTENT-TYPE: application/sdp
    ALLOW: CANCEL
    ALLOW: BYE
    ALLOW: UPDATE
    ALLOW: PRACK
    REQUIRE: 100rel
    SERVER: RTCC/4.0.0.0 MediationServer
    Rseq: 1

    v=0
    o=- 1514 1 IN IP4 10.0.0.179
    s=session
    c=IN IP4 10.0.0.179
    b=CT:1000
    t=0 0
    m=audio 53096 RTP/AVP 8 101
    c=IN IP4 10.0.0.179
    a=rtcp:53097
    a=label:Audio
    a=sendrecv
    a=rtpmap:8 PCMA/8000
    a=rtpmap:101 telephone-event/8000
    a=fmtp:101 0-16
     [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161614    )  |       |(SIPTU#6)183 State:Proceeding(119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161615    )  |       |       |       #5:SIP_ALERT_EV(119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s (     sip_stack)(161616    )  New SIPMessage created - #0 [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_stk_ses)(161617    )  <SESSION #5> SendToCall - event: PROGRESS_INDICATOR_EV  m_Call#5 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161618    )  |       |       #5:PROGRESS_INDICATOR_EV(PI=8)(PC=-1):(119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_stk_ses)(161619    )  <SESSION #5> SendToCall - event: PROGRESS_EV  m_Call#5 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161620    )  |       |       #5:PROGRESS_EV:(119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161621    )  |       #0:PROGRESS_EV   : (119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s (     sip_stack)(161622    )  SIPSDPSession#5 - Changing state from SIP_MEDIA_OFFERING to SIP_MEDIA_COMPLETED [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_stk_ses)(161623    )  DtmfCapNegotiationAlgorithm :: TxDtmfMethod = DTMF_RFC2833_SUPPORTED [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_stk_ses)(161624    )  DtmfCapNegotiationAlgorithm :: TxRtpRfc2833Payload = 101 [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_stk_ses)(161625    )  <SESSION #5> SendToCall - event: DTMF_CONTROL_EV  m_Call#5 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161626    )  |       |       #5:DTMF_CONTROL_EV:(119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_stk_ses)(161627    )  <SESSION #5> SendToCall - event: OPEN_LOGICAL_CHANNEL_ACK_EV  m_Call#5 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161628    )  |       |       #5:OPEN_LOGICAL_CHANNEL_ACK_EV:(119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161629    )  |       #0:OPEN_LOGICAL_CHANNEL_ACK_EV   : (119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161630    )  |       #0:OPEN_VOICE (IP:10.0.0.179, RTP:53096, RTCP:0, VoiceCoder:g711Alaw64k20, VbdCoder: InvalidCoder255, Dtmf:gwRFC2833RalayDTMF,Rx payload:101,Tx payload:101 ,RTPmode:1, FaxTransportType: 3,AVoIPMediaType: gwMediaTypeAudioOnly) [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdif)(161631    )  activate channel port=53096, BChannel:0, ip=10.0.0.179 (Voice:1,Vbd:0,T38:0,Video:0) [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdif)(161632    )  #0:ActivateChannel: Socks=18 CID=0 Trunk:-1 BChannel:0 RemoteIP=10.0.0.179 RemotePort=53096 RemoteT38IP= RemoteT38Port=0 RemoteRTCPIP= RemoteRTCPPort=0 FaxModemDet=NO_FAX_MODEM_DETECTED [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdif)(161633    )  Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdif)(161634    )  #0:OpenChannel:on Trunk -1 BChannel:0 CID=0 with VoiceCoder: g711Alaw64k20 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdif)(161635    )  #0:OpenChannel VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000
     [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdif)(161636    )  RFC2833RTPPayloadType: Rx=101 Tx=101 DTMF Transport=3 [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdif)(161637    )  OpenChannel, CoderType = 0, Interval = 3, M = 1
     [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdif)(161638    )  #0:FAXTransportType = 3 [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdif)(161639    )  #0:PSOSBoardInterface::ConfigureFaxModemChannelParams FAXTransportType=3 Modem configuration VxxTransportType=2 not allowed, forced to 3
     [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdif)(161640    )  #0:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=3, VxxTranType=3, VoiceVol= 0, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdif)(161641    )  Detectors: Amd:On=0,Diretion=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdif)(161642    )  #0:Channel will be open WITH DSP [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdex)(161643    )  PCIIFChangeChannelParams failed  ECNlpMode [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdif)(161644    )  Setting ActivateRTP_RTCPCmd.Cmd.IpTosFieldInUdpPacket to 184 [Time: 03:57:42]
    6d:3h:57m:42s (   lgr_psbrdif)(161645    )  #0:ActivateChannel:RtpPayload: 8 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161646    )  |       |(SIPTU#6)PRACK_REQ State:Proceeding(119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s [Src=10.0.0.179/53096 Dst=6030 PType=6] ErrMgs=9 Invalid RTP version (= 0)
     [Code:3700e] [CID:0] [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161647    )  ---- Outgoing SIP Message to 10.0.0.179:5060 from SIPInterface #0 ---- [Time: 03:57:42]
    6d:3h:57m:42s PRACK sip:lync-mds.academie.ste-therese.com:5060;transport=Tcp;maddr=10.0.0.179 SIP/2.0
    Via: SIP/2.0/TCP 10.0.0.15:5066;branch=z9hG4bKac1200103605;alias
    Max-Forwards: 70
    From: <sip:701@gateway.com>;tag=1c1199379579
    To: <sip:5148312524@10.0.0.179;user=phone>;tag=bddd4b11d;epid=E85CEEEA71
    Call-ID: 119937910961200035742@10.0.0.15
    CSeq: 2 PRACK
    Contact: <sip:701@10.0.0.15:5066;transport=tcp>
    Supported: em,timer,replaces,path,early-session,resource-priority
    Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE
    RAck: 1 1 INVITE
    User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.023.006
    Content-Length: 0

     [Time: 03:57:42]
    6d:3h:57m:42s (     sip_stack)(161649    )  Resource SIPMessage deleted - #0 [Time: 03:57:42]
    6d:3h:57m:42s (     sip_stack)(161650    )  Resource SIPMessage deleted - #10 [Time: 03:57:42]
    6d:3h:57m:42s (     sip_stack)(161651    )  New SIPMessage created - #6 [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161652    )  ---- Incoming SIP Message from 10.0.0.179:5060 to SIPInterface #0 ---- [Time: 03:57:42]
    6d:3h:57m:42s SIP/2.0 200 OK
    FROM: <sip:701@gateway.com>;tag=1c1199379579
    TO: <sip:5148312524@10.0.0.179;user=phone>;tag=bddd4b11d;epid=E85CEEEA71
    CSEQ: 2 PRACK
    CALL-ID: 119937910961200035742@10.0.0.15
    VIA: SIP/2.0/TCP 10.0.0.15:5066;branch=z9hG4bKac1200103605;alias
    CONTENT-LENGTH: 0
    SERVER: RTCC/4.0.0.0 MediationServer

     [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161654    )  |       |(SIPTU#6)200 OK State:Proceeding(119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s (      lgr_flow)(161655    )  |       |       |       #5:SIP_PRACK_RESPONSE_EV(119937910961200035742@10.0.0.15) [Time: 03:57:42]
    6d:3h:57m:42s (     sip_stack)(161656    )  Resource SIPMessage deleted - #6 [Time: 03:57:42]
    6d:3h:57m:43s [Src=10.0.0.179/53097 Dst=6031 PType=6] (   lgr_psbrdex)(161657    )  recv <-- UnHandled event: 21 [Time: 03:57:43]
    6d:3h:57m:43s RP:1  [Code:3a002] [CID:0] [Time: 03:57:43]
    6d:3h:57m:43s [Src=10.0.0.179/53097 Dst=6031 PType=6] (   lgr_psbrdex)(161658    )  recv <-- UnHandled event: 21 [Time: 03:57:43]
    6d:3h:57m:43s (     sip_stack)(161659    )  New SIPMessage created - #5 [Time: 03:57:43]
    6d:3h:57m:43s (      lgr_flow)(161660    )  ---- Incoming SIP Message from 10.0.0.179:5060 to SIPInterface #0 ---- [Time: 03:57:43]
    6d:3h:57m:43s SIP/2.0 404 Not Found
    FROM: <sip:701@gateway.com>;tag=1c1199379579
    TO: <sip:5148312524@10.0.0.179;user=phone>;tag=bddd4b11d;epid=E85CEEEA71
    CSEQ: 1 INVITE
    CALL-ID: 119937910961200035742@10.0.0.15
    VIA: SIP/2.0/TCP 10.0.0.15:5066;branch=z9hG4bKac1199383722;alias
    CONTENT-LENGTH: 0
    SERVER: RTCC/4.0.0.0 MediationServer

     [Time: 03:57:43]
    6d:3h:57m:43s (      lgr_flow)(161662    )  |       |(SIPTU#6)404 State:Proceeding(119937910961200035742@10.0.0.15) [Time: 03:57:43]
    6d:3h:57m:43s (     sip_stack)(161663    )  New SIPMessage created - #2 [Time: 03:57:43]
    6d:3h:57m:43s (      lgr_flow)(161664    )  ---- Outgoing SIP Message to 10.0.0.179:5060 from SIPInterface #0 ---- [Time: 03:57:43]
    6d:3h:57m:43s ACK sip:5148312524@10.0.0.179;user=phone SIP/2.0
    Via: SIP/2.0/TCP 10.0.0.15:5066;branch=z9hG4bKac1199383722;alias
    Max-Forwards: 70
    From: <sip:701@gateway.com>;tag=1c1199379579
    To: <sip:5148312524@10.0.0.179;user=phone>;tag=bddd4b11d;epid=E85CEEEA71
    Call-ID: 119937910961200035742@10.0.0.15
    CSeq: 1 ACK
    Contact: <sip:701@10.0.0.15:5066;transport=tcp>
    Supported: em,timer,replaces,path,early-session,resource-priority
    Allow: REGISTER,OPTIONS,INVITE,ACK,CANCEL,BYE,NOTIFY,PRACK,REFER,INFO,SUBSCRIBE,UPDATE
    User-Agent: Audiocodes-Sip-Gateway-/v.5.80A.023.006
    Content-Length: 0

     [Time: 03:57:43]
    6d:3h:57m:43s (     sip_stack)(161666    )  Resource SIPMessage deleted - #2 [Time: 03:57:43]
    6d:3h:57m:43s (     sip_stack)(161667    )  SIPCall(#6) changes state from Proceeding to Disconnected [Time: 03:57:43]
    6d:3h:57m:43s (      lgr_flow)(161668    )  |       |       |       #5:SIP_DISCONNECT_EV(119937910961200035742@10.0.0.15) [Time: 03:57:43]
    6d:3h:57m:43s (   lgr_stk_ses)(161669    )  <SESSION #5> SendToCall - event: RELEASE_EV  m_Call#5 [Time: 03:57:43]
    6d:3h:57m:43s (      lgr_flow)(161670    )  |       |       #5:RELEASE_EV:(119937910961200035742@10.0.0.15) [Time: 03:57:43]
    6d:3h:57m:43s (      lgr_flow)(161671    )  |       |       #5:Call changing states from:InitiatedState_Tel2IP to:DisconnectingState [Time: 03:57:43]
    6d:3h:57m:43s (      lgr_flow)(161672    )  |       |       #5:RELEASE_ACK_EV:(119937910961200035742@10.0.0.15) [Time: 03:57:43]
    6d:3h:57m:43s (      lgr_flow)(161673    )  |       #0:RELEASE_EV  GWAPP_UNASSIGNED_NUMBER : (119937910961200035742@10.0.0.15) [Time: 03:57:43]
    6d:3h:57m:43s (   lgr_psbrdif)(161674    )  #0:cpDigitMapHndlr_Stop - Stoped (0) [Time: 03:57:43]
    6d:3h:57m:43s (   lgr_psbrdif)(161675    )  #0:CloseChannel: ChannelNum=0 [Time: 03:57:43]
    6d:3h:57m:43s (   lgr_psbrdif)(161676    )  Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 [Time: 03:57:43]
    6d:3h:57m:43s (   lgr_psbrdif)(161677    )  #0:OpenChannel:on Trunk -1 BChannel:0 CID=0 with VoiceCoder: g711Alaw64k20 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED [Time: 03:57:43]
    6d:3h:57m:43s (   lgr_psbrdif)(161678    )  #0:OpenChannel VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000
     [Time: 03:57:43]
    6d:3h:57m:43s (   lgr_psbrdif)(161679    )  RFC2833RTPPayloadType: Rx=101 Tx=101 DTMF Transport=3 [Time: 03:57:43]
    6d:3h:57m:43s (   lgr_psbrdif)(161680    )  OpenChannel, CoderType = 0, Interval = 3, M = 1
     [Time: 03:57:43]
    6d:3h:57m:43s (   lgr_psbrdif)(161681    )  #0:FAXTransportType = 3 [Time: 03:57:43]
    6d:3h:57m:43s (   lgr_psbrdif)(161682    )  #0:PSOSBoardInterface::ConfigureFaxModemChannelParams FAXTransportType=3 Modem configuration VxxTransportType=2 not allowed, forced to 3
     [Time: 03:57:43]
    6d:3h:57m:43s (   lgr_psbrdif)(161683    )  #0:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=3, VxxTranType=3, VoiceVol= 0, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) [Time: 03:57:43]
    6d:3h:57m:43s (   lgr_psbrdif)(161684    )  Detectors: Amd:On=0,Diretion=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 [Time: 03:57:43]
    6d:3h:57m:43s (   lgr_psbrdif)(161685    )  #0:Channel will be open WITH DSP [Time: 03:57:43]
    6d:3h:57m:43s (   lgr_psbrdif)(161686    )  #0:PSOSBoardInterface::PlayTone - Called Tone=REORDER_TONE Direction=PLAY_TONE_2_TEL [Time: 03:57:43]
    6d:3h:57m:43s (      lgr_flow)(161687    )  |       #0:RELEASE_ACK_EV (send)  : (119937910961200035742@10.0.0.15) [Time: 03:57:43]
    6d:3h:57m:43s (      lgr_flow)(161688    )  |       |       #5:RELEASE_ACK_EV:(119937910961200035742@10.0.0.15) [Time: 03:57:43]
    6d:3h:57m:43s (      lgr_flow)(161689    )  |       |       |       #5:RELEASE_ACK_EV(119937910961200035742@10.0.0.15) [Time: 03:57:43]
    6d:3h:57m:43s (     sip_stack)(161690    )  New SIPMessage created - #1 [Time: 03:57:43]
    6d:3h:57m:43s (      lgr_flow)(161691    )  |       |(SIPTU#6)DISCONNECT_RESPONSE State:Disconnected(119937910961200035742@10.0.0.15) [Time: 03:57:43]
    6d:3h:57m:43s (     sip_stack)(161692    )  AcSIPStackAPI::FreeCallAPI - #6 [Time: 03:57:43]
    6d:3h:57m:43s (     sip_stack)(161693    )  Setting ApplicationCall of AcSIPCall 31428472 to NULL [Time: 03:57:43]
    6d:3h:57m:43s (  lgr_stk_mngr)(161694    )  Resource StackSession <#5> Deleted [Time: 03:57:43]
    6d:3h:57m:43s (     sip_stack)(161695    )  Resource SIPMessage deleted - #1 [Time: 03:57:43]
    6d:3h:57m:43s (      lgr_call)(161696    )  Call Returned to Pool ResourceID: 5 [Time: 03:57:43]
    6d:3h:57m:43s (     sip_stack)(161697    )  Resource SIPMessage deleted - #5 [Time: 03:57:43]
    6d:3h:57m:43s (      lgr_flow)(161698    )  |       | TransactionUserMngr::ReturnSIPCall - #6 [Time: 03:57:43]
    6d:3h:57m:43s (     sip_stack)(161699    )  SIPCall(#6) changes state from Disconnected to Idle [Time: 03:57:43]
    6d:3h:57m:44s [Src=10.0.0.179 PType=9] (      lgr_flow)(161700    )  TcpTransportObject#8- CTcpClientSocket::HandleSocketEvent socket close/reset [Time: 03:57:44]
    6d:3h:57m:44s (     sip_stack)(161701    )  TcpTransportObject#8::CloseConnection - Closing connection [Time: 03:57:44]
    6d:3h:57m:44s (     sip_stack)(161702    )  Socket buffer released - #4 [Time: 03:57:44]
    6d:3h:57m:44s (     sip_stack)(161703    )  SIPTCPMngr::ReturnTCPTransportObj - #8 [Time: 03:57:44]
    6d:3h:57m:48s (   lgr_psbrdex)(161704    )  recv <-- ON_HOOK Ch:0 [Time: 03:57:48]
    6d:3h:57m:48s (      lgr_flow)(161705    )  #0:ON_HOOK_EV [Time: 03:57:48]
    6d:3h:57m:48s (      lgr_flow)(161706    )  |       #0:ON_HOOK_EV [Time: 03:57:48]
    6d:3h:57m:48s (   lgr_psbrdif)(161707    )  #0:cpDigitMapHndlr_Stop - Stoped (0) [Time: 03:57:48]
    6d:3h:57m:48s (   lgr_psbrdif)(161708    )  #0:CloseChannel: ChannelNum=0 [Time: 03:57:48]
    6d:3h:57m:48s (   lgr_psbrdif)(161709    )  Open channel: IsVoiceOn: 1, IsT38On: 0, IsVbdOn: 0, IsVideoOn: 0 [Time: 03:57:48]
    6d:3h:57m:48s (   lgr_psbrdif)(161710    )  #0:OpenChannel:on Trunk -1 BChannel:0 CID=0 with VoiceCoder: g711Alaw64k20 VbdCoder: InvalidCoder255 DetectorSide: 0 FaxModemDet NO_FAX_MODEM_DETECTED [Time: 03:57:48]
    6d:3h:57m:48s (   lgr_psbrdif)(161711    )  #0:OpenChannel VoiceVolume= 0, DTMFVolume = -11, InputGain = 0, RTPRedundancyDepth = 0 FlashHookPeriod = 700 AgcCmd = 0x13180000
     [Time: 03:57:48]
    6d:3h:57m:48s (   lgr_psbrdif)(161712    )  RFC2833RTPPayloadType: Rx=101 Tx=101 DTMF Transport=3 [Time: 03:57:48]
    6d:3h:57m:48s (   lgr_psbrdif)(161713    )  OpenChannel, CoderType = 0, Interval = 3, M = 1
     [Time: 03:57:48]
    6d:3h:57m:48s (   lgr_psbrdif)(161714    )  #0:FAXTransportType = 3 [Time: 03:57:48]
    6d:3h:57m:48s (   lgr_psbrdif)(161715    )  #0:PSOSBoardInterface::ConfigureFaxModemChannelParams FAXTransportType=3 Modem configuration VxxTransportType=2 not allowed, forced to 3
     [Time: 03:57:48]
    6d:3h:57m:48s (   lgr_psbrdif)(161716    )  #0:ConfigFaxModemChannelParams NSEMode=0, CNGDetMode=0, FAXTranType=3, VxxTranType=3, VoiceVol= 0, DTMFVol=-11, InGain=0, RTPRedDepth=0, ECE=1, SCE=0, ECNlpMode=0, DJBufMinDelay=10, DJBufOptFac=10, Result=1) [Time: 03:57:48]
    6d:3h:57m:48s (   lgr_psbrdif)(161717    )  Detectors: Amd:On=0,Diretion=0, Ans:On=0,Direction=0 En:On=0,Direction=0 Board IBScmd:0xa1 [Time: 03:57:48]
    6d:3h:57m:48s (   lgr_psbrdif)(161718    )  #0:Channel will be open WITH DSP [Time: 03:57:48]
    6d:3h:57m:48s (   lgr_psbrdif)(161719    )  #0:PSOSBoardInterface::StopPlayTone- Called [Time: 03:57:48]
    6d:3h:57m:48s (      lgr_flow)(161720    )  #0:LOCAL_END_PLAYING_CALL_PROGRESS_TONE_EV [Time: 03:57:48]
    6d:3h:57m:48s (      lgr_flow)(161721    )  |       #0:LOCAL_END_PLAYING_CALL_PROGRESS_TONE_EV [Time: 03:57:48]

    Tuesday, November 1, 2011 4:15 PM
  • Hi,

    6d:3h:57m:42s SIP/2.0 183 Session Progress
    FROM: <sip:701@gateway.com>;tag=1c1199379579
    TO: <sip:5148312524@10.0.0.179;user=phone>;tag=bddd4b11d;epid=E85CEEEA71

    i guess 5148312524 is a PSTN Number. have you test that dialing this number, using the Pool dial plan that you created, is going out to the PSTN route using a test case? if it shows that it's going out successfully, then it should work.

    Thamara.

     

    Tuesday, November 1, 2011 4:43 PM
  • Hi !

       I tested this number in the Lync Control Panel and the result shows that it should go out through our SIP trunk as it does with our normal Lync client. That's why I don't understant why it doesn't work and why it tries to find the number in our local system.

     

    Thanks,


    Antonio

    Tuesday, November 1, 2011 4:51 PM
  • run the wireshark on the mediation server and see where the call is going. and where it get dropped.

    Thamara.

    Tuesday, November 1, 2011 5:05 PM
  • The logs on the audiocode and Lync server show that the call is going to the Mediation server (10.0.0.179) and gets dropped there because of the 404 NOT FOUND error.

    Antonio

     

    Tuesday, November 1, 2011 5:27 PM
  • Hi Antonio,

    in the trace i can see, that you dont use the E.164 format. If you route the analog line to the Lync server, than you have to use allways the E.164 format and configure the analog extension in Lync.

    The call will then rout through lync external.

    Or you configure the audiocodes to route the call direct to the pstn.


    regards Holger Technical Specialist UC
    Tuesday, November 1, 2011 5:50 PM
  • Hi Antonio,

    Do you create the PSTN gateway in the topology and create voice policy to route the calls to the PSTN gateway?


    Please remember to click “Mark as Answer” on the post that helps you, and to click “Unmark as Answer” if a marked post does not actually answer your question. This can be beneficial to other community members reading the thread.
    Wednesday, November 2, 2011 8:25 AM
  • Hi Antonio,

    can you post your Dial Plan and normalization rules?

    As Holger mentioned, you need to have E.164 (+001....) normalized numbers for external calls.

    Maybe you have a mistake in the normalization rule

    Falk

    • Marked as answer by Antonio Saraca Tuesday, November 8, 2011 4:48 PM
    Thursday, November 3, 2011 10:45 AM
  • Hi !

     

    Here is the result of the test on the dial plan of the audiocode gateway (10.0.0.15) in the Lync Control Panel:

     

    As you can see, the number is normalized properly and the itinerary selected is the right one (SIP trunk to our external provider).

     

    Thanks,


    Antonio

     

    Thursday, November 3, 2011 12:35 PM
  • i think with this configuration, this is how it'll work. you won't be able to call PSTN via the Lync server since the analog device is not controlled by the Lync Server. Follow the below URL and configure the FXO station in Lync Server using the CSAnalogDevice cmdlet and get Lync server involve with the Analog device configuration. Try this and let us know the outcome.

    http://technet.microsoft.com/en-us/library/gg398748.aspx

    Thamara.

    Thursday, November 3, 2011 2:14 PM
  • I already configured the audiocode with the Set-CsAnalogdevice cmdlet:

     

    PS C:\ get-csanalogdevice


    Identity      : CN={d5c5cc98-1bbf-4c82-a8bb-df01f535efe7},OU=Contacts,DC=academ
                    ie,DC=ste-therese,DC=com
    VoicePolicy   : Interurbain
    RegistrarPool : lync.academie.ste-therese.com
    Gateway       : 10.0.0.15
    AnalogFax     : True
    Enabled       : True
    SipAddress    : sip:b05598e5-19ee-4e44-b8a9-7f530d6c270a@academie.ste-therese.c
                    om
    LineURI       : tel:701
    DisplayName   : Ascenceur 1
    DisplayNumber : 701

    Thursday, November 3, 2011 2:20 PM
  • the Line URI should be in E.164 format. it should be +701 change it and try.

    Thamara.

    Thursday, November 3, 2011 2:29 PM
  • Hi Antonio,

     

    this will not work:

    FROM: <sip:701@gateway.com>;tag=1c1199379579
    TO: <sip:5148312524@10.0.0.179;user=phone>;tag=bddd4b11d;epid=E85CEEEA71

    You have to translate all Numbers to E.164 if you route the call from the FXS port to Lync.


    regards Holger Technical Specialist UC
    • Marked as answer by Antonio Saraca Tuesday, November 8, 2011 4:48 PM
    Thursday, November 3, 2011 9:31 PM
  • Hi !

     

        How do I do that on the Audiocode ? I thought it would be enough to have the Lync server normalize the number that it receives from the audiocode gateway.

     

    Thanks,


    Antonio

    Friday, November 4, 2011 7:41 PM
  • what i meant when you configure CS-AnalogDevice, you have to configure the Line URI as tel:+701.

    LineURI : tel:701
    DisplayName : Ascenceur 1
    DisplayNumber : 701

    not like that.

    Thamara

    • Marked as answer by Antonio Saraca Tuesday, November 8, 2011 4:48 PM
    Saturday, November 5, 2011 2:10 PM
  • PS C:\ get-csanalogdevice


    Identity      : CN={d5c5cc98-1bbf-4c82-a8bb-df01f535efe7},OU=Contacts,DC=academ
                    ie,DC=ste-therese,DC=com
    VoicePolicy   : Interurbain
    RegistrarPool : lync.academie.ste-therese.com
    Gateway       : 10.0.0.15
    AnalogFax     : True

    what cu level are you running on the lync server and is gateway with this fxs port also you PSTN egress?

    If you are running latest CU updates and 10.0.0.15 is not your pstn egress (you have a sip trunk, don't you?) Then set "analogfax" to $false.  With analogfax set to true, lync would send the outgoing call back to this gateway, overriding the normal voice routes.

    however running traces on the frontend is your best bet to get insight info on what is happening

    • Marked as answer by Antonio Saraca Tuesday, November 8, 2011 4:48 PM
    Saturday, November 5, 2011 10:12 PM
  • Finally I applied all the suggestions I received and it seems to work:

    1) Added "+" to the tel uri.

    2) Put Analogfax to $False.

    3) Added +1 in the estination Phone Number Manipulation Table for Tel -> IP Call

    Thanks to everyone for their help !

     

     

    Tuesday, November 8, 2011 4:35 PM
  • say thank you by marking as answer :)

    Thamara.

    Tuesday, November 8, 2011 4:46 PM