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:+xxxxxxxxxx...@sip.pstnhub.microsoft.com:5061;user=phone>;tag=87bcce1e760c4752b396766bedd72f73
Jul 16 13:55:37 msteams-sbc opensips[28748]: To:
<sip:+xxxxxxxxxxxxx@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@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 !
_______________________________________________
Users mailing list
Users@lists.opensips.org
http://lists.opensips.org/cgi-bin/mailman/listinfo/users
