none
Long delay before call start rining on Mobile Devices RRS feed

  • Question

  • Dear All,

    We have deployed Lync 2013 (2 FE and 2 Edge Servers) with DNS load balancing, We also have group series 500 devices, the issue when a call is made from the group series 500 device to another lync client, it would immediately start ringing at the Lync Client on normal pc , but takes a long delay to ring on the Mobile Client (Which may login via corporate firewall or via the 3g/4g network), before this call could actually complete and the call gets diverted due to call forwarding configured,

    The behavior is the same on both iphone and android phone,

    Please find below the log from the front end server, Kindly copy and paste the log below in snooper for your analysis,

    Thanks in Advance,

    regards,

    Hasan Reza,

    Tuesday, October 27, 2015 12:37 PM

Answers

  • Thanks for the trace.

    I can see a 603 Declined right at the beginning but can't see thē invite?

    Also, have you tried making a call with the app opened?

    • Marked as answer by Hasan Reza Tuesday, March 1, 2016 10:59 AM
    Tuesday, October 27, 2015 9:36 PM

All replies

  • Dear All,

    We have deployed Lync 2013 (2 FE and 2 Edge Servers) with DNS load balancing, We also have group series 500 devices, the issue when a call is made from the group series 500 device to another lync client, it would immediately start ringing at the Lync Client on normal pc , but takes a long delay to ring on the Mobile Client (Which may login via corporate firewall or via the 3g/4g network), before this call could actually complete and the call gets diverted due to call forwarding configured,

    The behavior is the same on both iphone and android phone,

    Please find below the log from the front end server, Kindly copy and paste the log below in snooper for your analysis,

    Thanks in Advance,

    regards,

    Hasan Reza,

    Tuesday, October 27, 2015 12:51 PM
  • Hi Hasan,

    There's no log from your FE attached.

    However, can you please answer the next question: if the mobile client is opened and active on your mobile device, will the call ring almost immediately? If yes, you might like to test your push notification settings.

    Tuesday, October 27, 2015 9:15 PM
  • Thanks for the response, I want to attached the file is there an option to do this,
    Tuesday, October 27, 2015 9:17 PM
  • You can probably upload it to OneDrive or a similar store and paste the share url here.
    Tuesday, October 27, 2015 9:21 PM
  • My push notification policy is

    PS C:\Users\lyncadmin> Get-CsPushNotificationConfiguration

    Identity                               : Global
    EnableApplePushNotificationService     : True
    EnableMicrosoftPushNotificationService : True

    Some part from Logs

    TL_INFO(TF_PROTOCOL) [0]06AC.0904::10/27/2015-11:10:17.715.0082005a (SIPStack,SIPAdminLog::ProtocolRecord::Flush:ProtocolRecord.cpp(265))[681260374] $$begin_record
    Trace-Correlation-Id: 681260374
    Instance-Id: 89CA9
    Direction: incoming
    Peer: 10.0.4.54:55933
    Message-Type: response
    Start-Line: SIP/2.0 603 Decline
    From: "HRH Local Affairs"<sip:hrh.room2@hrhoffice.ae>;tag=plcm_473733182-828;epid=82142341E175CV
    To: <sip:wmassud@hrhoffice.ae>;epid=43cf1b224b;tag=8117ddba95
    Call-ID:  473732774-828
    CSeq: 1 INVITE
    Via:  SIP/2.0/TLS 10.0.15.23:5061;branch=z9hG4bK46F3E33F.2B5CC7541752523C;branched=TRUE;ms-internal-info="aj_e9yL4CKU3m5vtvJeOo7Ar9XNMf_Q9I2qAEuq32QTypUx1wr3UREiwAA"
    Via:  SIP/2.0/TLS 10.0.12.10:5061;branch=z9hG4bK473733128-828;received=10.0.12.10;ms-received-port=33358;ms-received-cid=28B700
    Record-Route:  <sip:lyncfepool01.hrhoffice.ae:5061;transport=tls;ms-fe=HRH-LyncFE02.hrhoffice.local;opaque=state:T:F:Ci.R845a00;lr;ms-route-sig=ahe7pTUeYwOjdgse4JTUsFGHN-LXOC7bqtJSsv_3SK_RNUx1wr2tFJqgAA>;tag=E1F89CD122E4EF44E84D04197BBDD934
    Content-Length:  0
    Ms-client-diagnostics:  51004; reason="Action initiated by user"
    $$end_record

    TL_INFO(TF_DIAG) [0]06AC.0904::10/27/2015-11:10:17.719.0082091c (SIPStack,SIPAdminLog::WriteDiagnosticEvent:SIPAdminLog.cpp(802))[591900598] $$begin_record
    Severity: information
    Text: Routed a request on behalf of an application
    SIP-Start-Line: ACK sip:10.0.4.54:55933;transport=tls;ms-opaque=0e746e6edf;ms-received-cid=845A00 SIP/2.0
    SIP-Call-ID: 473732774-828
    SIP-CSeq: 1 ACK
    Peer: 10.0.4.54:55933
    Data: destination="wmassud@hrhoffice.ae";application="http://www.microsoft.com/LCS/DefaultRouting"
    $$end_record

    TL_INFO(TF_PROTOCOL) [0]06AC.0904::10/27/2015-11:10:17.719.0082097d (SIPStack,SIPAdminLog::ProtocolRecord::Flush:ProtocolRecord.cpp(265))[591900598] $$begin_record
    Trace-Correlation-Id: 591900598
    Instance-Id: 89CAA
    Direction: outgoing;source="local"
    Peer: 10.0.4.54:55933
    Message-Type: request
    Start-Line: ACK sip:10.0.4.54:55933;transport=tls;ms-opaque=0e746e6edf;ms-received-cid=845A00 SIP/2.0
    From: "HRH Local Affairs"<sip:hrh.room2@hrhoffice.ae>;tag=plcm_473733182-828;epid=82142341E175CV
    To: <sip:wmassud@hrhoffice.ae>;epid=43cf1b224b;tag=8117ddba95
    Call-ID: 473732774-828
    CSeq: 1 ACK
    Via: SIP/2.0/TLS 10.0.15.23:5061;branch=z9hG4bK46F3E33F.2B5CC7541752523C;branched=FALSE
    Max-Forwards: 70
    Content-Length: 0
    ms-diagnostics-public: 5012;reason="ACK is being generated on receipt of a failure final response for an INVITE forked by application";AppUri="http%3A%2F%2Fwww.microsoft.com%2FLCS%2FDefaultRouting"
    $$end_record

    TL_INFO(TF_DIAG) [1]06AC.0904::10/27/2015-11:10:17.722.008211f2 (SIPStack,SIPAdminLog::WriteDiagnosticEvent:SIPAdminLog.cpp(802))[591900598] $$begin_record
    Severity: information
    Text: Routed a request on behalf of an application
    SIP-Start-Line: CANCEL sip:wmassud@10.0.12.70:62411;transport=tls;ms-received-cid=282400 SIP/2.0
    SIP-Call-ID: 473732774-828
    SIP-CSeq: 1 CANCEL
    Peer: 10.0.12.70:62411
    Data: destination="wmassud@hrhoffice.ae";application="http://www.microsoft.com/LCS/DefaultRouting"
    $$end_record

    TL_INFO(TF_PROTOCOL) [1]06AC.0904::10/27/2015-11:10:17.722.00821249 (SIPStack,SIPAdminLog::ProtocolRecord::Flush:ProtocolRecord.cpp(265))[591900598] $$begin_record
    Trace-Correlation-Id: 591900598
    Instance-Id: 89C6F
    Direction: outgoing;source="local"
    Peer: 10.0.12.70:62411
    Message-Type: request
    Start-Line: CANCEL sip:wmassud@10.0.12.70:62411;transport=tls;ms-received-cid=282400 SIP/2.0
    From: "HRH Local Affairs"<sip:hrh.room2@hrhoffice.ae>;tag=plcm_473733182-828;epid=82142341E175CV
    To: <sip:wmassud@hrhoffice.ae>;epid=0004f258ed4f
    Call-ID: 473732774-828
    CSeq: 1 CANCEL
    Via: SIP/2.0/TLS 10.0.15.23:5061;branch=z9hG4bK46F3E33F.9763F6601751523C;branched=FALSE
    Max-Forwards: 70
    Content-Length: 0
    ms-diagnostics-public: 5025;reason="Cancel sent by application for INVITE client transaction.";AppUri="http%3A%2F%2Fwww.microsoft.com%2FLCS%2FDefaultRouting"
    $$end_record

    TL_INFO(TF_DIAG) [0]06AC.0904::10/27/2015-11:10:17.723.0082151c (SIPStack,SIPAdminLog::WriteDiagnosticEvent:SIPAdminLog.cpp(802))[591900598] $$begin_record
    Severity: information
    Text: Routed a request on behalf of an application
    SIP-Start-Line: CANCEL sip:wmassud@94.206.27.239:34456;transport=tls;ms-received-cid=F000 SIP/2.0
    SIP-Call-ID: 473732774-828
    SIP-CSeq: 1 CANCEL
    Peer: edgepool01.hrhoffice.ae:5061;ms-fe=HRH-LyncEdge02.hrhoffice.local
    Data: application="http://www.microsoft.com/LCS/DefaultRouting"
    $$end_record

    TL_INFO(TF_PROTOCOL) [0]06AC.0904::10/27/2015-11:10:17.723.0082157f (SIPStack,SIPAdminLog::ProtocolRecord::Flush:ProtocolRecord.cpp(265))[591900598] $$begin_record
    Trace-Correlation-Id: 591900598
    Instance-Id: 89C82
    Direction: outgoing;source="local"
    Peer: edgepool01.hrhoffice.ae:5061;ms-fe=HRH-LyncEdge02.hrhoffice.local
    Message-Type: request
    Start-Line: CANCEL sip:wmassud@94.206.27.239:34456;transport=tls;ms-received-cid=F000 SIP/2.0
    From: "HRH Local Affairs"<sip:hrh.room2@hrhoffice.ae>;tag=plcm_473733182-828;epid=82142341E175CV
    To: <sip:wmassud@hrhoffice.ae>;epid=0004f258ec06
    Call-ID: 473732774-828
    CSeq: 1 CANCEL
    Via: SIP/2.0/TLS 10.0.15.23:59880;branch=z9hG4bK46F3E33F.D6ED26911752523C;branched=FALSE
    Route: <sip:edgepool01.hrhoffice.ae:5061;transport=tls;ms-fe=HRH-LyncEdge02.hrhoffice.local;opaque=state:Ee.feqPLcsLSj7EcZlNi2tSmbRgAA;lr>
    Max-Forwards: 70
    Content-Length: 0
    ms-diagnostics-public: 5025;reason="Cancel sent by application for INVITE client transaction.";AppUri="http%3A%2F%2Fwww.microsoft.com%2FLCS%2FDefaultRouting"
    $$end_record

    TL_INFO(TF_DIAG) [0]06AC.0904::10/27/2015-11:10:17.734.0082218c (SIPStack,SIPAdminLog::WriteDiagnosticEvent:SIPAdminLog.cpp(802))[681260374] $$begin_record
    Severity: information
    Text: Routed a request on behalf of an application
    SIP-Start-Line: INVITE sip:0504562580;phone-context=hrh-office@hrhoffice.ae:5061;user=phone;transport=Tls;maddr=HRH-LyncFE02.hrhoffice.local SIP/2.0
    SIP-Call-ID: 473732774-828
    SIP-CSeq: 1 INVITE
    Peer: lyncfepool01.hrhoffice.ae:5061;ms-fe=HRH-LyncFE02.hrhoffice.local
    Data: application="http://www.microsoft.com/LCS/DefaultRouting"
    $$end_record

    TL_INFO(TF_PROTOCOL) [0]06AC.0904::10/27/2015-11:10:17.735.008221f5 (SIPStack,SIPAdminLog::ProtocolRecord::Flush:ProtocolRecord.cpp(265))[681260374] $$begin_record
    Trace-Correlation-Id: 681260374
    Instance-Id: 89CAC
    Direction: outgoing
    Peer: lyncfepool01.hrhoffice.ae:5061;ms-fe=HRH-LyncFE02.hrhoffice.local
    Message-Type: request
    Start-Line: INVITE sip:0504562580;phone-context=hrh-office@hrhoffice.ae:5061;user=phone;transport=Tls;maddr=HRH-LyncFE02.hrhoffice.local SIP/2.0
    From: "HRH Local Affairs"<sip:hrh.room2@hrhoffice.ae>;tag=plcm_473733182-828;epid=82142341E175CV
    To: <sip:wmassud@hrhoffice.ae>
    Call-ID: 473732774-828
    CSeq: 1 INVITE
    Contact: <sip:hrh.room2@hrhoffice.ae;opaque=user:epid:vLYugpyNMV6ZaX6Sj_BI5QAA;gruu>
    Via: SIP/2.0/TLS 10.0.15.23:60026;branch=z9hG4bK46F3E33F.7547F7CF22451269;branched=TRUE
    Via: SIP/2.0/TLS 10.0.12.10:5061;branch=z9hG4bK473733128-828;received=10.0.12.10;ms-received-port=33358;ms-received-cid=28B700
    Record-Route: <sip:lyncfepool01.hrhoffice.ae:5061;transport=tls;ms-fe=HRH-LyncFE02.hrhoffice.local;opaque=state:T;lr>;tag=E1F89CD122E4EF44E84D04197BBDD934
    Max-Forwards: 16
    Content-Length: 5121
    Content-Type: application/sdp
    Referred-By: <sip:wmassud@hrhoffice.ae>;ms-identity="MIIBywYJKoZIhvcNAQcCoIIBvDCCAbgCAQExDzANBgkqhkiG9w0BAQUFADALBgkqhkiG9w0BBwExggGTMIIBjwIBATBoMFExFTATBgoJkiaJk/IsZAEZFgVsb2NhbDEZMBcGCgmSJomT8ixkARkWCWhyaG9mZmljZTEdMBsGA1UEAxMUaHJob2ZmaWNlLUhSSC1BREMtQ0ECE0UAAAAUI9eA+NrJt60AAAAAABQwDQYJKoZIhvcNAQEFBQAwDQYJKoZIhvcNAQEBBQAEggEAMM42HSrJ6E5N2qJauDHl+2UvFV31crCJ/HXYvsWF82uGYoLnigNeW3giiexFSgCDnGQRbHUNVWJODSfzeBXqy2p/3tbdkhl6l+tTOx8fPrwyB8NaBp1WdLw9hOYF60zFPqgWUsg8hbwmEeShdSHXrZ+ytl4n03sN+T8+MAp7ISVL2PisqK9ARPmu5HutXQAVwdUuZwDv0CmVkRyNfIgRi7UKi3LOs9LyqtgPxVsTn1uNVxh8Mjo8IT0iut44MZ9TAbAGgSh0SaMdrlCPVvjTF/su3jjc0U0oQyJ+gncQvGRPEiUmbFF2m4pZurHkp4hrVspz6riTT81teSO/KDqmcg==:Tue, 27 Oct 2015 11:10:17 GMT";ms-identity-info="sip:lyncfepool01.hrhoffice.ae:5061;ms-fe=HRH-LyncFE02.hrhoffice.local;transport=tls";ms-identity-alg=rsa-sha1
    Message-Body: v=0
    o=GS82142341E175CV 835774850 0 IN IP4 10.0.12.10
    s=-
    c=IN IP4 10.0.12.10
    b=AS:1920
    t=0 0
    a=x-mediabw:main-video send=1920;recv=1920
    a=x-devicecaps:audio:send,recv;video:send,recv
    m=audio 16606 RTP/SAVP 115 102 9 15 0 8 18 101
    a=ice-pwd:QDnvGuwWa6I2NT9CFprOi3Yu
    a=ice-ufrag:N3up
    a=candidate:1 1 UDP 2130706431 10.0.12.10 16606 typ host
    a=candidate:1 2 UDP 2130706430 10.0.12.10 16607 typ host
    a=candidate:2 1 TCP-PASS 6619135 151.253.1.6 52635 typ relay raddr 10.0.12.10 rport 16608
    a=candidate:2 2 TCP-PASS 6619134 151.253.1.6 52635 typ relay raddr 10.0.12.10 rport 16608
    a=candidate:3 1 UDP 16777215 151.253.1.6 58398 typ relay raddr 10.0.12.10 rport 16608
    a=candidate:3 2 UDP 16777214 151.253.1.6 54615 typ relay raddr 10.0.12.10 rport 16609
    a=candidate:4 1 TCP-ACT 7012351 151.253.1.6 52635 typ relay raddr 10.0.12.10 rport 16608
    a=candidate:4 2 TCP-ACT 7012350 151.253.1.6 52635 typ relay raddr 10.0.12.10 rport 16608
    a=candidate:5 1 TCP-ACT 1684733951 10.0.12.10 16608 typ srflx raddr 10.0.12.10 rport 16608
    a=candidate:5 2 TCP-ACT 1684733950 10.0.12.10 16608 typ srflx raddr 10.0.12.10 rport 16608
    a=rtcp:16607
    a=x-ssrc-range:911832262-911832262
    a=rtcp-fb:* x-message app send:dsh recv:dsh
    a=label:main-audio
    a=rtcp-rsize
    a=x-source:main-audio
    a=maxptime:200
    a=crypto:4 AES_CM_128_HMAC_SHA1_80 inline:BAHC1l+t3xe92Z9iCQd8nU6KcQF5SmPe2I6//tU0|2^31
    a=rtpmap:115 G7221/32000
    a=fmtp:115 bitrate=48000
    a=rtpmap:102 G7221/16000
    a=fmtp:102 bitrate=32000
    a=rtpmap:9 G722/8000
    a=rtpmap:15 G728/8000
    a=rtpmap:0 PCMU/8000
    a=rtpmap:8 PCMA/8000
    a=rtpmap:18 G729/8000
    a=fmtp:18 annexb=no
    a=rtpmap:101 telephone-event/8000
    a=fmtp:101 0-15
    a=sendrecv
    m=video 16610 RTP/SAVP 116 109 110 111 122 123 96 34 31 117
    b=TIAS:1920000
    a=ice-pwd:QDnvGuwWa6I2NT9CFprOi3Yu
    a=ice-ufrag:N3up
    a=candidate:1 1 UDP 2130706431 10.0.12.10 16610 typ host
    a=candidate:1 2 UDP 2130706430 10.0.12.10 16611 typ host
    a=candidate:2 1 TCP-PASS 6619135 151.253.1.6 57851 typ relay raddr 10.0.12.10 rport 16612
    a=candidate:2 2 TCP-PASS 6619134 151.253.1.6 57851 typ relay raddr 10.0.12.10 rport 16612
    a=candidate:3 1 UDP 16777215 151.253.1.6 50906 typ relay raddr 10.0.12.10 rport 16612
    a=candidate:3 2 UDP 16777214 151.253.1.6 52742 typ relay raddr 10.0.12.10 rport 16613
    a=candidate:4 1 TCP-ACT 7012351 151.253.1.6 57851 typ relay raddr 10.0.12.10 rport 16612
    a=candidate:4 2 TCP-ACT 7012350 151.253.1.6 57851 typ relay raddr 10.0.12.10 rport 16612
    a=candidate:5 1 TCP-ACT 1684733951 10.0.12.10 16612 typ srflx raddr 10.0.12.10 rport 16612
    a=candidate:5 2 TCP-ACT 1684733950 10.0.12.10 16612 typ srflx raddr 10.0.12.10 rport 16612
    a=rtcp:16611
    a=x-ssrc-range:911832263-911832362

    Tuesday, October 27, 2015 9:27 PM
  • Thanks for the trace.

    I can see a 603 Declined right at the beginning but can't see thē invite?

    Also, have you tried making a call with the app opened?

    • Marked as answer by Hasan Reza Tuesday, March 1, 2016 10:59 AM
    Tuesday, October 27, 2015 9:36 PM