Hi! I use freepbx+flexisip server for sip calling with linphone.
When I register in flexisip the outbound calling (linphone->flexisip->freepbx->userfreepbx) work, but inbound calling (userfreepbx->freepbx->flexisip->linphone) drop after 32 sec.
But when I register in freepbx calling working fine.
Media-Relay module is started and flexisip debug log I see ACK:
2018-02-06 14:35:01:762 flexisip-debug-Response is sent through an incoming transaction.
2018-02-06 14:35:01:762 flexisip-debug-tport_tsend(0x26e8f80) tpn = TLS/[192.168.0.10]:5061
2018-02-06 14:35:01:762 flexisip-debug-tport_tls_writevec: vec 0x26e91b0 0x27398d0 835 (835)
2018-02-06 14:35:01:762 flexisip-debug-tport_tls_writevec: vec 0x26e91b0 0x2738fb0 205 (205)
2018-02-06 14:35:01:762 flexisip-debug-tport_vsend returned 1040
2018-02-06 14:35:01:762 flexisip-debug-tport(0x26e8f80): set timer at 1800000 ms because keepalive
2018-02-06 14:35:01:762 flexisip-debug-nta: sent 200 Ok for INVITE (27825)
2018-02-06 14:35:01:762 flexisip-debug-nta: timer shortened to 5000 ms
2018-02-06 14:35:01:762 flexisip-debug-nta_incoming_destroy: 0x2733f30
2018-02-06 14:35:01:762 flexisip-debug-Terminate SipEvent 0x2704438
2018-02-06 14:35:01:762 flexisip-debug-Terminate SipEvent 0x2720e58
2018-02-06 14:35:01:762 flexisip-debug-nta: outgoing_free(0x2721160)
2018-02-06 14:35:01:762 flexisip-debug-Delete OutgoingTransaction 0x2733578
2018-02-06 14:35:01:762 flexisip-debug-tport(0x26f5a50): set timer at 1799771 ms because keepalive
2018-02-06 14:35:01:762 flexisip-debug-tport(0x26f5a50): set timer at 1799771 ms because keepalive
2018-02-06 14:35:01:762 flexisip-debug-Delete IncomingTransaction 0x26f91d8
2018-02-06 14:35:01:762 flexisip-debug-Looking at fork contexts with key 1691@192.168.0.10
2018-02-06 14:35:01:762 flexisip-debug-Remove fork 1691@192.168.0.10 from store
2018-02-06 14:35:01:762 flexisip-debug-Unsubscribe topic = 1691@192.168.0.10
2018-02-06 14:35:01:762 flexisip-debug-Destroy ForkCallContext 0x2727d38
2018-02-06 14:35:01:775 flexisip-debug-tport_wakeup(0x26e8f80): events IN
2018-02-06 14:35:01:775 flexisip-debug-tport_recv_event(0x26e8f80)
2018-02-06 14:35:01:775 flexisip-debug-tport_tls_recv(0x26e8f80): tls_read() returned 603
2018-02-06 14:35:01:775 flexisip-debug-tport_recv_iovec(0x26e8f80) msg 0x26e1860 from (tls/[192.168.0.10]:5061) has 603 bytes, veclen = 1
2018-02-06 14:35:01:775 flexisip-debug-tport_deliver(0x26e8f80): msg 0x26e1860 (603 bytes) from tls/[192.168.0.10]:5061 next=(nil)
2018-02-06 14:35:01:775 flexisip-debug-nta: received ACK sip:1691@[192.168.0.8:4410]:60776;transport=tls;verified SIP/2.0 (CSeq 27825)
2018-02-06 14:35:01:775 flexisip-debug-nta: ACK (27825) to message callback
2018-02-06 14:35:01:775 flexisip-debug-New SipEvent 0x270ec68 - msg 0x26e1860
2018-02-06 14:35:01:775 flexisip-debug-Receiving new Request SIP message ACK from sip:1589@[192.168.0.10] :
ACK sip:1691@[192.168.0.8:4410]:60776;transport=tls;verified SIP/2.0
Via: SIP/2.0/TLS [192.168.0.10]:5061;rport=5061;branch=z9hG4bKPjdb341c8e-b57a-40a5-aafc-81ddf2284070;alias
From: "CITI" sip:1589@[192.168.0.10];tag=ce30e67c-a860-4f8f-b09f-45cfbe223d20
To: sip:1691@[192.168.0.9];pn-silent=1;CtRt55f0a5f302247000=tls:192.168.0.10;tag=~ORnvo9
Call-ID: 5dba3a95-6e1a-4cff-9446-0b50fed8ae68
CSeq: 27825 ACK
Route: <sips:[192.168.0.9]:38547;transport=TLS;lr>
Max-Forwards: 70
User-Agent: FPBX-14.0.1.36(14.7.5)
Content-Length: 0
2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module DoSProtection
2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module SanityChecker
2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module GarbageIn
2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module NatHelper
2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module Authentication
2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module Redirect
2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module GatewayAdapter
2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module Presence
2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module Registrar
2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module StatisticsCollector
2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module ContactRouteInserter
2018-02-06 14:35:01:775 flexisip-debug-No countact route parameter found
2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module Router
2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module PushNotification
2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module LoadBalancer
2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module MediaRelay
2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module Transcoder
2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module Forward
2018-02-06 14:35:01:775 flexisip-debug-tport: not found from primary 0x26bd370, trying another one...TLS/192.168.0.9:38547
2018-02-06 14:35:01:775 flexisip-debug-tport: not found from primary 0x26d12b0, trying another one...TLS/192.168.0.9:38547
2018-02-06 14:35:01:775 flexisip-debug-Sending Request SIP message to sips:[192.168.0.9]:38547;transport=TLS;lr
ACK sip:1691@[192.168.0.8:4410]:60776;transport=tls;verified SIP/2.0
Via: SIP/2.0/TLS [192.168.0.10]:5061;rport=5061;branch=z9hG4bKPjdb341c8e-b57a-40a5-aafc-81ddf2284070;alias
From: "CITI" sip:1589@[192.168.0.10];tag=ce30e67c-a860-4f8f-b09f-45cfbe223d20
To: sip:1691@[192.168.0.9];pn-silent=1;CtRt55f0a5f302247000=tls:192.168.0.10r;tag=~ORnvo9
Call-ID: 5dba3a95-6e1a-4cff-9446-0b50fed8ae68
CSeq: 27825 ACK
Route: <sips:[192.168.0.9]:38547;transport=TLS;lr>
Max-Forwards: 69
User-Agent: FPBX-14.0.1.36(14.7.5)
Content-Length: 0
Hi! I use freepbx+flexisip server for sip calling with linphone. When I register in flexisip the outbound calling (linphone->flexisip->freepbx->userfreepbx) work, but inbound calling (userfreepbx->freepbx->flexisip->linphone) drop after 32 sec. But when I register in freepbx calling working fine. Media-Relay module is started and flexisip debug log I see ACK:
2018-02-06 14:35:01:762 flexisip-debug-Response is sent through an incoming transaction. 2018-02-06 14:35:01:762 flexisip-debug-tport_tsend(0x26e8f80) tpn = TLS/[192.168.0.10]:5061 2018-02-06 14:35:01:762 flexisip-debug-tport_tls_writevec: vec 0x26e91b0 0x27398d0 835 (835) 2018-02-06 14:35:01:762 flexisip-debug-tport_tls_writevec: vec 0x26e91b0 0x2738fb0 205 (205) 2018-02-06 14:35:01:762 flexisip-debug-tport_vsend returned 1040 2018-02-06 14:35:01:762 flexisip-debug-tport(0x26e8f80): set timer at 1800000 ms because keepalive 2018-02-06 14:35:01:762 flexisip-debug-nta: sent 200 Ok for INVITE (27825) 2018-02-06 14:35:01:762 flexisip-debug-nta: timer shortened to 5000 ms 2018-02-06 14:35:01:762 flexisip-debug-nta_incoming_destroy: 0x2733f30 2018-02-06 14:35:01:762 flexisip-debug-Terminate SipEvent 0x2704438 2018-02-06 14:35:01:762 flexisip-debug-Terminate SipEvent 0x2720e58 2018-02-06 14:35:01:762 flexisip-debug-nta: outgoing_free(0x2721160) 2018-02-06 14:35:01:762 flexisip-debug-Delete OutgoingTransaction 0x2733578 2018-02-06 14:35:01:762 flexisip-debug-tport(0x26f5a50): set timer at 1799771 ms because keepalive 2018-02-06 14:35:01:762 flexisip-debug-tport(0x26f5a50): set timer at 1799771 ms because keepalive 2018-02-06 14:35:01:762 flexisip-debug-Delete IncomingTransaction 0x26f91d8 2018-02-06 14:35:01:762 flexisip-debug-Looking at fork contexts with key 1691@192.168.0.10 2018-02-06 14:35:01:762 flexisip-debug-Remove fork 1691@192.168.0.10 from store 2018-02-06 14:35:01:762 flexisip-debug-Unsubscribe topic = 1691@192.168.0.10 2018-02-06 14:35:01:762 flexisip-debug-Destroy ForkCallContext 0x2727d38 2018-02-06 14:35:01:775 flexisip-debug-tport_wakeup(0x26e8f80): events IN 2018-02-06 14:35:01:775 flexisip-debug-tport_recv_event(0x26e8f80) 2018-02-06 14:35:01:775 flexisip-debug-tport_tls_recv(0x26e8f80): tls_read() returned 603 2018-02-06 14:35:01:775 flexisip-debug-tport_recv_iovec(0x26e8f80) msg 0x26e1860 from (tls/[192.168.0.10]:5061) has 603 bytes, veclen = 1 2018-02-06 14:35:01:775 flexisip-debug-tport_deliver(0x26e8f80): msg 0x26e1860 (603 bytes) from tls/[192.168.0.10]:5061 next=(nil) 2018-02-06 14:35:01:775 flexisip-debug-nta: received ACK sip:1691@[192.168.0.8:4410]:60776;transport=tls;verified SIP/2.0 (CSeq 27825) 2018-02-06 14:35:01:775 flexisip-debug-nta: ACK (27825) to message callback 2018-02-06 14:35:01:775 flexisip-debug-New SipEvent 0x270ec68 - msg 0x26e1860 2018-02-06 14:35:01:775 flexisip-debug-Receiving new Request SIP message ACK from sip:1589@[192.168.0.10] : ACK sip:1691@[192.168.0.8:4410]:60776;transport=tls;verified SIP/2.0 Via: SIP/2.0/TLS [192.168.0.10]:5061;rport=5061;branch=z9hG4bKPjdb341c8e-b57a-40a5-aafc-81ddf2284070;alias From: "CITI" sip:1589@[192.168.0.10];tag=ce30e67c-a860-4f8f-b09f-45cfbe223d20 To: sip:1691@[192.168.0.9];pn-silent=1;CtRt55f0a5f302247000=tls:192.168.0.10;tag=~ORnvo9 Call-ID: 5dba3a95-6e1a-4cff-9446-0b50fed8ae68 CSeq: 27825 ACK Route: <sips:[192.168.0.9]:38547;transport=TLS;lr> Max-Forwards: 70 User-Agent: FPBX-14.0.1.36(14.7.5) Content-Length: 0
2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module DoSProtection 2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module SanityChecker 2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module GarbageIn 2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module NatHelper 2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module Authentication 2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module Redirect 2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module GatewayAdapter 2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module Presence 2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module Registrar 2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module StatisticsCollector 2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module ContactRouteInserter 2018-02-06 14:35:01:775 flexisip-debug-No countact route parameter found 2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module Router 2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module PushNotification 2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module LoadBalancer 2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module MediaRelay 2018-02-06 14:35:01:775 flexisip-debug-Skipping onRequest() on module Transcoder 2018-02-06 14:35:01:775 flexisip-debug-Invoking onRequest() on module Forward 2018-02-06 14:35:01:775 flexisip-debug-tport: not found from primary 0x26bd370, trying another one...TLS/192.168.0.9:38547 2018-02-06 14:35:01:775 flexisip-debug-tport: not found from primary 0x26d12b0, trying another one...TLS/192.168.0.9:38547 2018-02-06 14:35:01:775 flexisip-debug-Sending Request SIP message to sips:[192.168.0.9]:38547;transport=TLS;lr ACK sip:1691@[192.168.0.8:4410]:60776;transport=tls;verified SIP/2.0 Via: SIP/2.0/TLS [192.168.0.10]:5061;rport=5061;branch=z9hG4bKPjdb341c8e-b57a-40a5-aafc-81ddf2284070;alias From: "CITI" sip:1589@[192.168.0.10];tag=ce30e67c-a860-4f8f-b09f-45cfbe223d20 To: sip:1691@[192.168.0.9];pn-silent=1;CtRt55f0a5f302247000=tls:192.168.0.10r;tag=~ORnvo9 Call-ID: 5dba3a95-6e1a-4cff-9446-0b50fed8ae68 CSeq: 27825 ACK Route: <sips:[192.168.0.9]:38547;transport=TLS;lr> Max-Forwards: 69 User-Agent: FPBX-14.0.1.36(14.7.5) Content-Length: 0
Any idea?