[Freeswitch-users] FREESWITCH NOT PARSING SDP OF HOLD REINVITE PROPERLY
sagar malam
sagarmalam at gmail.com
Tue Oct 30 12:37:05 UTC 2018
Can anyone help me on this ?
Please advice if this is not right place to ask for fix and need to create
a Jira.
Thanks.
On Sun, Sep 23, 2018 at 6:36 PM sagar malam <sagarmalam at gmail.com> wrote:
> Hello ,
>
> I am working on developing PBX application using FS 1.6.17.I am facing
> issue which i think is a bug in FS.
> 1) User A calls User B
> 2) User B blind transfers to User C
> 3) Now User A and User C is connected.
> 4) User A puts call on hold.At this step ,When FS executes code(in
> switch_core_media.c file ) to check if HOLD RE-INVITE has any crypto
> attributes , it gets positive even though RE-INIVITE does not have any
> crypto attributes.Below are the logs for fs_cli ( look for logs in BOLD )
>
> =======================LOGS TAKEN FROM FS_CLI================
>
> 2018-09-23 12:41:24.408118 [DEBUG] sofia.c:7052 Channel sofia/register/
> 1025 at 202.131.119.122:53814 entering state [received][100]
> 2018-09-23 12:41:24.408118 [DEBUG] sofia.c:7062 Remote SDP:
> v=0
> o=- 1537706456 1537706459 IN IP4 10.50.7.253
> s=Polycom IP Phone
> c=IN IP4 10.50.7.253
> t=0 0
> a=sendonly
> m=audio 14852 RTP/AVP 18 101
> a=rtpmap:18 G729/8000
> a=fmtp:18 annexb=no
> a=rtpmap:101 telephone-event/8000
> a=sendonly
> m=video 0 RTP/AVP 103 31 34
>
> 2018-09-23 12:41:24.658118 [DEBUG] sofia.c:7052 Channel sofia/register/
> 1025 at 202.131.119.122:53814 entering state [completed][200]
> 2018-09-23 12:41:24.758055 [DEBUG] sofia.c:7052 Channel sofia/register/
> 1025 at 202.131.119.122:53814 entering state [ready][200]
> 2018-09-23 12:41:24.898112 [DEBUG] sofia.c:7052 Channel sofia/register/
> 1013 at ecosmob.sip.teledge.com entering state [calling][0]
> 2018-09-23 12:41:25.218098 [DEBUG] sofia.c:7052 Channel sofia/register/
> 1013 at ecosmob.sip.teledge.com entering state [completing][200]
> 2018-09-23 12:41:25.218098 [DEBUG] sofia.c:7062 Remote SDP:
> v=0
> o=- 1537706452 1537706455 IN IP4 10.50.7.253
> s=Polycom IP Phone
> c=IN IP4 10.50.7.253
> t=0 0
> a=sendrecv
> m=audio 34858 RTP/AVP 0 8 18 9 101
> a=rtpmap:0 PCMU/8000
> a=rtpmap:8 PCMA/8000
> a=rtpmap:18 G729/8000
> a=fmtp:18 annexb=no
> a=rtpmap:9 G722/8000
> a=rtpmap:101 telephone-event/8000
> a=direction:active
> m=video 0 RTP/AVP 103 31 34
> a=direction:active
> a=oldmediaip:172.16.16.241
>
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4502 Audio Codec
> Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4502 Audio Codec
> Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4502 Audio Codec
> Compare [G729:18:8000:20:8000:1] ++++ is saved as a match
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G722:9:8000:20:64000:1]/[G729:18:8000:20:8000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4502 Audio Codec
> Compare [G722:9:8000:20:64000:1] ++++ is saved as a match
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4363 Set
> telephone-event payload to 101 at 8000
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4706 Set
> telephone-event payload to 101 at 8000
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4765 sofia/register/
> 1013 at ecosmob.sip.teledge.com Set 2833 dtmf send payload to 101 recv
> payload to 101
> 2018-09-23 12:41:25.218098 [DEBUG] sofia.c:7344 RESTABLISH MEDIA SDP:
> v=0
> o=FreeSWITCH 1537693452 1537693458 IN IP4 10.50.7.251
> s=FreeSWITCH
> c=IN IP4 10.50.7.251
> t=0 0
> m=audio 13002 RTP/AVP 0 101
> a=rtpmap:0 PCMU/8000
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-16
> a=ptime:20
> a=sendrecv
> m=video 17310 RTP/AVP 97 31 34
> b=AS:1024
> a=rtpmap:97 H264/90000
> a=rtpmap:31 H261/90000
> a=rtpmap:34 H263/90000
>
> 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:6860 Audio params
> are unchanged for sofia/register/1013 at ecosmob.sip.teledge.com.
> 2018-09-23 12:41:25.218098 [DEBUG] sofia.c:7052 Channel sofia/register/
> 1025 at 202.131.119.122:53814 entering state [calling][0]
> 2018-09-23 12:41:25.238128 [DEBUG] sofia.c:7052 Channel sofia/register/
> 1013 at ecosmob.sip.teledge.com entering state [ready][200]
> 2018-09-23 12:41:25.498123 [DEBUG] sofia.c:7052 Channel sofia/register/
> 1025 at 202.131.119.122:53814 entering state [completing][200]
> 2018-09-23 12:41:25.498123 [DEBUG] sofia.c:7062 Remote SDP:
> *v=0*
> *o=- 1537706456 1537706460 IN IP4 10.50.7.253*
> *s=Polycom IP Phone*
> *c=IN IP4 10.50.7.253*
> *t=0 0*
> *a=sendonly*
> *m=audio 14852 RTP/AVP 18 0 8 9 101*
> *a=rtpmap:18 G729/8000*
> *a=fmtp:18 annexb=no*
> *a=rtpmap:0 PCMU/8000*
> *a=rtpmap:8 PCMA/8000*
> *a=rtpmap:9 G722/8000*
> *a=rtpmap:101 telephone-event/8000*
> *a=direction:active*
> *m=video 0 RTP/AVP 103 31 34*
> *a=direction:active*
> *a=oldmediaip:172.16.16.249*
>
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G729:18:8000:20:8000:1]/[G729:18:8000:20:8000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4502 Audio Codec
> Compare [G729:18:8000:20:8000:1] ++++ is saved as a match*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G729:18:8000:20:8000:1]/[PCMU:0:8000:20:64000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G729:18:8000:20:8000:1]/[PCMA:8:8000:20:64000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G729:18:8000:20:8000:1]/[G722:9:8000:20:64000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMU:0:8000:20:64000:1]/[G729:18:8000:20:8000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMU:0:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4502 Audio Codec
> Compare [PCMU:0:8000:20:64000:1] ++++ is saved as a match*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMU:0:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMU:0:8000:20:64000:1]/[G722:9:8000:20:64000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMA:8:8000:20:64000:1]/[G729:18:8000:20:8000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMA:8:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMA:8:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4502 Audio Codec
> Compare [PCMA:8:8000:20:64000:1] ++++ is saved as a match*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [PCMA:8:8000:20:64000:1]/[G722:9:8000:20:64000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G722:9:8000:20:64000:1]/[G729:18:8000:20:8000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G722:9:8000:20:64000:1]/[PCMU:0:8000:20:64000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G722:9:8000:20:64000:1]/[PCMA:8:8000:20:64000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4447 Audio Codec
> Compare [G722:9:8000:20:64000:1]/[G722:9:8000:20:64000:1]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4502 Audio Codec
> Compare [G722:9:8000:20:64000:1] ++++ is saved as a match*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4363 Set
> telephone-event payload to 101 at 8000*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4706 Set
> telephone-event payload to 101 at 8000*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4765
> sofia/register/1025 at 202.131.119.122:53814
> <http://1025@202.131.119.122:53814> Set 2833 dtmf send payload to 101 recv
> payload to 101*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> audio crypto Key [1 AEAD_AES_256_GCM_8
> inline:R/pN8McEpZCkZFA8lsTNl19uoua1R8bSPnthVWaqMpse/3n/Nqk8Mb7FXcQ]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> video crypto Key [1 AEAD_AES_256_GCM_8
> inline:JVeqouE9G4frRG7FF5pLNBJfufVOKkrObsLVUTLImMpzRo2fKYVa2ZNU4ko]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> audio crypto Key [2 AEAD_AES_128_GCM_8
> inline:IJcN/B4PZbaX/WiLGLRMcGukkvHnQp/7SXLOdg]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> video crypto Key [2 AEAD_AES_128_GCM_8
> inline:dAlbL/sVbX2VkpVzWpWFt8/9wixwugT9pnwHeA]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> audio crypto Key [3 AES_CM_256_HMAC_SHA1_80
> inline:bCEgc1rOR76vYxwpTnXoblfXgSQIDLJ0jQrMEghJh9akfkn6jcSoyzgsF4JHtw]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> video crypto Key [3 AES_CM_256_HMAC_SHA1_80
> inline:Ae2/2r0cISukm84lGWzNL0O+r54lJgeLsjxKzxrBWAdIOlVt6fuPqXbMj+fgmA]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> audio crypto Key [4 AES_CM_192_HMAC_SHA1_80
> inline:Ux4vMz/xZsXLDsc1YD7cPDUSsaC/Xs8KJv+QBm5nj8v9O70NR8E]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> video crypto Key [4 AES_CM_192_HMAC_SHA1_80
> inline:XLdnwildLOkebarqHXItf3RnJoXM/2+6tqwL925rjW4WJtIkF74]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> audio crypto Key [5 AES_CM_128_HMAC_SHA1_80
> inline:soWN8HkHRNDRz2itaR8JcJ3C6PRzWYjoyLZGl2d5]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> video crypto Key [5 AES_CM_128_HMAC_SHA1_80
> inline:f3fCmj6oXqrCGj/Wh1H38lY9liE6Awiev4Mo4syD]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> audio crypto Key [6 AES_CM_256_HMAC_SHA1_32
> inline:ikMbUpS+1V5t4baA+GNGP+T3iBnN83VzOJqdI0gL3LLThnhCg/M3IxEO0Crxww]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> video crypto Key [6 AES_CM_256_HMAC_SHA1_32
> inline:d5RXBHswLr0PC+9In+CxOQVtrQ9puAdOXsM/dfyTLuTVzp1nxVDotupDNgClgw]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> audio crypto Key [7 AES_CM_192_HMAC_SHA1_32
> inline:jZ9p+h023hhYcRw6KesYK/cLOn8FYkHfWksh8vEGSktPCG6wiz0]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> video crypto Key [7 AES_CM_192_HMAC_SHA1_32
> inline:oI21eom6/FW+T8fXWYdYZ76w8RYJGJ1a3n/VKFWip/brFXEF/w8]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> audio crypto Key [8 AES_CM_128_HMAC_SHA1_32
> inline:vWCsqgSoEf8lNmgmHqTz8GUYSvm21JBbNou/fext]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> video crypto Key [8 AES_CM_128_HMAC_SHA1_32
> inline:XUEC/U88QVT3YhCBunaxMftY8jY2GW4GOq2ivrhN]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> audio crypto Key [9 AES_CM_128_NULL_AUTH
> inline:En7TIXDB7g3YBNp8SSaSyn8W/b3BjDZapNo19sbY]*
> *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:1124 Set Local
> video crypto Key [9 AES_CM_128_NULL_AUTH
> inline:0IunsVM9Agglm6leczPQI11+bpkaZuM4x21lVK1D]*
> 2018-09-23 12:41:25.498123 [DEBUG] sofia.c:7344 RESTABLISH MEDIA SDP:
> v=0
> o=FreeSWITCH 1537686256 1537686263 IN IP4 10.50.7.251
> s=FreeSWITCH
> c=IN IP4 10.50.7.251
> t=0 0
> m=audio 20198 RTP/AVP 18 101
> a=rtpmap:18 G729/8000
> a=fmtp:18 annexb=no
> a=rtpmap:101 telephone-event/8000
> a=fmtp:101 0-16
> a=ptime:20
> a=recvonly
> m=video 29982 RTP/SAVP 97 31 34
> b=AS:1024
> a=rtpmap:97 H264/90000
> a=rtpmap:31 H261/90000
> a=rtpmap:34 H263/90000
> a=crypto:1 AEAD_AES_256_GCM_8
> inline:JVeqouE9G4frRG7FF5pLNBJfufVOKkrObsLVUTLImMpzRo2fKYVa2ZNU4ko
> a=crypto:2 AEAD_AES_128_GCM_8 inline:dAlbL/sVbX2VkpVzWpWFt8/9wixwugT9pnwHeA
> a=crypto:3 AES_CM_256_HMAC_SHA1_80
> inline:Ae2/2r0cISukm84lGWzNL0O+r54lJgeLsjxKzxrBWAdIOlVt6fuPqXbMj+fgmA
> a=crypto:4 AES_CM_192_HMAC_SHA1_80
> inline:XLdnwildLOkebarqHXItf3RnJoXM/2+6tqwL925rjW4WJtIkF74
> a=crypto:5 AES_CM_128_HMAC_SHA1_80
> inline:f3fCmj6oXqrCGj/Wh1H38lY9liE6Awiev4Mo4syD
> a=crypto:6 AES_CM_256_HMAC_SHA1_32
> inline:d5RXBHswLr0PC+9In+CxOQVtrQ9puAdOXsM/dfyTLuTVzp1nxVDotupDNgClgw
> a=crypto:7 AES_CM_192_HMAC_SHA1_32
> inline:oI21eom6/FW+T8fXWYdYZ76w8RYJGJ1a3n/VKFWip/brFXEF/w8
> a=crypto:8 AES_CM_128_HMAC_SHA1_32
> inline:XUEC/U88QVT3YhCBunaxMftY8jY2GW4GOq2ivrhN
> a=crypto:9 AES_CM_128_NULL_AUTH
> inline:0IunsVM9Agglm6leczPQI11+bpkaZuM4x21lVK1D
> m=video 29982 RTP/AVP 97 31 34
> b=AS:1024
> a=rtpmap:97 H264/90000
> a=rtpmap:31 H261/90000
> a=rtpmap:34 H263/90000
>
>
> ========================================================================
> Please note :
> 1) After each call is answered i am bypassing media from FS using
> 'uuid_media' API.So bypass media API was executed on step 1 and Step 3.
> 2) Sofia parameter : "resume-media-on-hold" is enabled.
> 3) All the SRTP and ZRTP related parameters are disabled.
> 4) Above issue happens only when media bypassed from FS for transferred
> calls.
> 5) NO SRTP/ZRTP channel variables were set in dialplan.
>
>
> Thanks in advance.I can provide more information if needed.
>
>
>
>
>
>
--
Thanks,
Sagar
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.freeswitch.org/pipermail/freeswitch-users/attachments/20181030/5cee01e9/attachment-0001.html>
More information about the FreeSWITCH-users
mailing list