[OpenSIPS-Users] ms teams outgoing calls fails

[Pasan Meemaduma]

Hi Guys,
Set up
msteams client  <--> msteams sbc <--> opensips <--> asterisk  <--> pstn


I recently ran in to an issue with handling outgoing calls to msteams, call fails as msteams unable to send ACK after 200 OK reply. below is the debug log I got from opensips as per logs it seems like opensips unable to accept the connection back from msteams sbc to read ACK.

Jul 16 13:55:38 msteams-sbc opensips[28748]: Jul 16 13:55:38 [28764] ERROR:proto_tls:tls_accept: New TLS connection from 52.114.14.70:5248 failed to accept
Jul 16 13:55:38 msteams-sbc opensips[28748]: Jul 16 13:55:38 [28764] ERROR:proto_tls:tls_read_req: failed to do pre-tls reading

There's no other error other than above. Any clue as to what could be wrong ?
incoming calls toward teams works fine.
Thanks
Regards,Pasan



Jul 16 13:55:37 msteams-sbc opensips[28748]: Jul 16 13:55:37 [28763] DBG:tm:insert_timer_unsafe: [2]: 0x7f6b6be7b0b0 (55)
Jul 16 13:55:37 msteams-sbc opensips[28748]: Jul 16 13:55:37 [28763] DBG:core:tcp_conn_get: con found in state 0
Jul 16 13:55:37 msteams-sbc opensips[28748]: Jul 16 13:55:37 [28763] DBG:core:tcp_conn_get: tcp connection found (0x7f6b6be79a28), acquiring fd
Jul 16 13:55:37 msteams-sbc opensips[28748]: Jul 16 13:55:37 [28763] DBG:core:tcp_conn_get: c= 0x7f6b6be79a28, n=16, Usock=56
Jul 16 13:55:37 msteams-sbc opensips[28748]: Jul 16 13:55:37 [28766] DBG:core:handle_worker: read response= 7f6b6be79a28, 1, fd -1 from 12 (28763)
Jul 16 13:55:37 msteams-sbc opensips[28748]: Jul 16 13:55:37 [28763] DBG:core:tcp_conn_get: after receive_fd: c= 0x7f6b6be79a28 n=8 fd=63
Jul 16 13:55:37 msteams-sbc opensips[28748]: Jul 16 13:55:37 [28763] DBG:proto_tls:proto_tls_send: sending via fd 63...
Jul 16 13:55:37 msteams-sbc opensips[28748]: Jul 16 13:55:37 [28763] DBG:proto_tls:tls_update_fd: New fd is 63
Jul 16 13:55:37 msteams-sbc opensips[28748]: Jul 16 13:55:37 [28763] DBG:proto_tls:tls_write: write was successful (1113 bytes)
Jul 16 13:55:37 msteams-sbc opensips[28748]: Jul 16 13:55:37 [28763] DBG:proto_tls:proto_tls_send: after write: c= 0x7f6b6be79a28 n=1113 fd=63
Jul 16 13:55:37 msteams-sbc opensips[28748]: Jul 16 13:55:37 [28763] DBG:proto_tls:proto_tls_send: buf=
Jul 16 13:55:37 msteams-sbc opensips[28748]: SIP/2.0 200 OK
Jul 16 13:55:37 msteams-sbc opensips[28748]: Via: SIP/2.0/TLS 52.114.14.70:5061;rport=5888;received=52.114.14.70;branch=z9hG4bK642d4611
Jul 16 13:55:37 msteams-sbc opensips[28748]: Record-Route: <sip:52.65.18.59:5061;transport=tls;lr;ftag=87bcce1e760c4752b396766bedd72f73>
Jul 16 13:55:37 msteams-sbc opensips[28748]: Record-Route: <sip:sip-du-a-as.pstnhub.microsoft.com:5061;transport=tls;lr>
Jul 16 13:55:37 msteams-sbc opensips[28748]: From: Pasan Meemaduma<sip:+xxxxxxxxxxxxx at sip.pstnhub.microsoft.com:5061;user=phone>;tag=87bcce1e760c4752b396766bedd72f73
Jul 16 13:55:37 msteams-sbc opensips[28748]: To: <sip:+xxxxxxxxxxxxx at voippabx.cloud:5061;user=phone>;tag=as3b49d4e8
Jul 16 13:55:37 msteams-sbc opensips[28748]: Call-ID: 8696a372ea105ccf99693a5189831870
Jul 16 13:55:37 msteams-sbc opensips[28748]: CSeq: 1 INVITE
Jul 16 13:55:37 msteams-sbc opensips[28748]: Server: Asterisk PBX 13.32.0
Jul 16 13:55:37 msteams-sbc opensips[28748]: Allow: INVITE, ACK, CANCEL, OPTIONS, BYE, REFER, SUBSCRIBE, NOTIFY, INFO, PUBLISH, MESSAGE
Jul 16 13:55:37 msteams-sbc opensips[28748]: Supported: replaces
Jul 16 13:55:37 msteams-sbc opensips[28748]: Contact: <sip:+xxxxxxxxxxxxx at 175.103.19.67:5061;transport=tls>
Jul 16 13:55:37 msteams-sbc opensips[28748]: Content-Type: application/sdp
Jul 16 13:55:37 msteams-sbc opensips[28748]: Content-Length: 327
Jul 16 13:55:37 msteams-sbc opensips[28748]: #015
Jul 16 13:55:37 msteams-sbc opensips[28748]: v=0
Jul 16 13:55:37 msteams-sbc opensips[28748]: o=root 1092739735 1092739735 IN IP4 175.103.19.67
Jul 16 13:55:37 msteams-sbc opensips[28748]: s=Asterisk PBX 13.32.0
Jul 16 13:55:37 msteams-sbc opensips[28748]: c=IN IP4 175.103.19.67
Jul 16 13:55:37 msteams-sbc opensips[28748]: t=0 0
Jul 16 13:55:37 msteams-sbc opensips[28748]: m=audio 17398 RTP/SAVP 8 101
Jul 16 13:55:37 msteams-sbc opensips[28748]: a=crypto:1 AES_CM_128_HMAC_SHA1_80 inline:qiRc8pQTLEPOxR+Yov731H1YTSgkrDvolTefqIVo
Jul 16 13:55:37 msteams-sbc opensips[28748]: a=rtpmap:8 PCMA/8000
Jul 16 13:55:37 msteams-sbc opensips[28748]: a=rtpmap:101 telephone-event/8000
Jul 16 13:55:37 msteams-sbc opensips[28748]: a=fmtp:101 0-16
Jul 16 13:55:37 msteams-sbc opensips[28748]: a=maxptime:150
Jul 16 13:55:37 msteams-sbc opensips[28748]: a=sendrecv

Jul 16 13:55:38 msteams-sbc opensips[28748]: Jul 16 13:55:38 [28764] DBG:proto_tls:tls_update_fd: New fd is 64
Jul 16 13:55:38 msteams-sbc opensips[28748]: Jul 16 13:55:38 [28764] DBG:proto_tls:tls_update_fd: New fd is 64
Jul 16 13:55:38 msteams-sbc opensips[28748]: Jul 16 13:55:38 [28764] DBG:proto_tls:tcp_handle_req: We didn't manage to read a full request
Jul 16 13:55:38 msteams-sbc opensips[28748]: Jul 16 13:55:38 [28764] DBG:proto_tls:tls_read_req: tls_read_req end
Jul 16 13:55:38 msteams-sbc opensips[28748]: Jul 16 13:55:38 [28764] DBG:proto_tls:tls_read_req: Using the per connection buff
Jul 16 13:55:38 msteams-sbc opensips[28748]: Jul 16 13:55:38 [28764] DBG:proto_tls:tls_update_fd: New fd is 64
Jul 16 13:55:38 msteams-sbc opensips[28748]: Jul 16 13:55:38 [28764] ERROR:proto_tls:tls_accept: New TLS connection from 52.114.14.70:5249 failed to accept
Jul 16 13:55:38 msteams-sbc opensips[28748]: Jul 16 13:55:38 [28764] ERROR:proto_tls:tls_read_req: failed to do pre-tls reading
Jul 16 13:55:38 msteams-sbc opensips[28748]: Jul 16 13:55:38 [28764] DBG:core:io_watch_del: [TCP_worker] io_watch_del op on index 5 64 (0x55e1c28b8580, 64, 5, 0x10,0x3) fd_no=10 called 
         Distinguishing What && How !
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <http://lists.opensips.org/pipermail/users/attachments/20200717/06dfdf2c/attachment-0001.html>