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@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@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@202.131.119.122:53814 entering state [completed][200] 2018-09-23 12:41:24.758055 [DEBUG] sofia.c:7052 Channel sofia/register/ 1025@202.131.119.122:53814 entering state [ready][200] 2018-09-23 12:41:24.898112 [DEBUG] sofia.c:7052 Channel sofia/register/ 1013@ecosmob.sip.teledge.com entering state [calling][0] 2018-09-23 12:41:25.218098 [DEBUG] sofia.c:7052 Channel sofia/register/ 1013@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@8000 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4706 Set telephone-event payload to 101@8000 2018-09-23 12:41:25.218098 [DEBUG] switch_core_media.c:4765 sofia/register/ 1013@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@ecosmob.sip.teledge.com. 2018-09-23 12:41:25.218098 [DEBUG] sofia.c:7052 Channel sofia/register/ 1025@202.131.119.122:53814 entering state [calling][0] 2018-09-23 12:41:25.238128 [DEBUG] sofia.c:7052 Channel sofia/register/ 1013@ecosmob.sip.teledge.com entering state [ready][200] 2018-09-23 12:41:25.498123 [DEBUG] sofia.c:7052 Channel sofia/register/ 1025@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@8000* *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4706 Set telephone-event payload to 101@8000* *2018-09-23 12:41:25.498123 [DEBUG] switch_core_media.c:4765 sofia/register/1025@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