[Date Prev][Date Next][Thread Prev][Thread Next][Date Index][Thread Index]
Flexisip restart
|
From: |
phillip |
|
Subject: |
Flexisip restart |
|
Date: |
Fri, 05 Jun 2020 02:41:55 -0700 |
|
User-agent: |
Roundcube Webmail/1.4.4 |
Hello
I'm experiencing an issue with Flexisip, I'm using as a proxy for Push
notifications and all appears to be working well, however after a couple
of hours I go back to the Linphone app and it shows "Connection in
progress", I can still make calls though. This appears to have happened
since I enabled TLS. If I restart Flexisip it all starts working
instantly and the app connects.
Could this be a bug, or something I have setup incorrectly?
Any advice appreciated.
Thanks
Phillip
Flexisip config
---------------
[global]
aliases=localhost flexisip.example.com
log-level=debug
syslog-level=debug
tls-ciphers=HIGH:!SSLv2:!SSLv3:!TLSv1:!EXP:!ADH:!RC4:!3DES:!aNULL:!eNULL
transports=sips:flexisip.example.com:5061
[module::Forward]
enabled=true
default-transport=tls
route=sips:sip-server-1.example.com:5061
[module::Registrar]
max-expires=604800
enabled=true
reg-domains=*
reg-on-response=true
[module::Router]
filter=(is_request && request.uri.params contains 'doroute') ||
is_response
fork-late=true
message-delivery-timeout=604800
[module::PushNotification]
enabled=true
timeout=0
firebase=true
firebase-projects-api-keys=XXXXXXXXXXXX:XXXXXXXXX:XXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXXX
[module::ContactRouteInserter]
enabled=true
insert-domain=true
Debuug logs
-----------
2020-06-05 08:58:31:700 flexisip-debug-tport_wakeup(0x561727f0e390):
events IN
2020-06-05 08:58:31:700 flexisip-debug-tport_recv_event(0x561727f0e390)
2020-06-05 08:58:31:701 flexisip-debug-tport_tls_recv(0x561727f0e390):
tls_read() returned 0
2020-06-05 08:58:31:701 flexisip-debug-tport_deliver(0x561727f0e390):
bad msg 0x561727f58ac0 (4 bytes) from tls/94.175.53.X:60440 next=(nil)
2020-06-05 08:58:31:701 flexisip-debug-nta_agent: received garbage from
tls/94.175.53.X:60440
2020-06-05 08:58:31:702 flexisip-debug-tport(0x561727f0e390): set timer
at 1407668 ms because keepalive
2020-06-05 08:58:31:702
flexisip-debug-tport_tls_set_events(0x561727f0e390): logical events real
2020-06-05 08:58:31:703 flexisip-debug-tport_shutdown0(0x561727f0e390,
2)
2020-06-05 08:58:31:703 flexisip-debug-tport_close(0x561727f0e390):
tls/94.175.53.X:60440
2020-06-05 08:58:31:704 flexisip-debug-tport_shutdown0(0x561727f0e390,
2)
2020-06-05 09:46:44:223 flexisip-debug-tport_wakeup_pri(0x561727ef8030):
events IN
2020-06-05 09:46:44:223
flexisip-debug-tport_alloc_secondary(0x561727ef8030): new secondary
tport 0x561727f0e390
2020-06-05 09:46:44:223 flexisip-debug-tport_tls_accept(0x561727f0e390):
new connection from tls/94.175.53.X:60619
2020-06-05 09:46:44:230 flexisip-debug-tls_connect(0x561727f0e390):
events NEGOTIATING
2020-06-05 09:46:44:332 flexisip-debug-tls_connect(0x561727f0e390):
events NEGOTIATING
2020-06-05 09:46:44:335
flexisip-debug-tls_post_connection_check(0x561727f0e390): Peer did not
provide X.509 Certificate.
2020-06-05 09:46:44:335 flexisip-debug-tport(0x561727f0e390): set timer
at 1799888 ms because keepalive
2020-06-05 09:46:44:383 flexisip-debug-tport_wakeup(0x561727f0e390):
events IN
2020-06-05 09:46:44:383 flexisip-debug-tport_recv_event(0x561727f0e390)
2020-06-05 09:46:44:384 flexisip-debug-tport_tls_recv(0x561727f0e390):
tls_read() returned 946
2020-06-05 09:46:44:384 flexisip-debug-tport_recv_iovec(0x561727f0e390)
msg 0x561727f56870 from (tls/94.175.53.X:60619) has 946 bytes, veclen =
1
2020-06-05 09:46:44:385 flexisip-debug-tport_deliver(0x561727f0e390):
msg 0x561727f56870 (946 bytes) from tls/94.175.53.X:60619 next=(nil)
2020-06-05 09:46:44:385 flexisip-debug-nta: received REGISTER
sip:sip-server-1.example.com SIP/2.0 (CSeq 23)
2020-06-05 09:46:44:386 flexisip-debug-nta: Via check:
received=94.175.53.X
2020-06-05 09:46:44:386 flexisip-debug-nta: REGISTER (23) to message
callback
2020-06-05 09:46:44:387 flexisip-debug-New SipEvent 0x561727f556b0 - msg
0x561727f56870
2020-06-05 09:46:44:388 flexisip-debug-Receiving new Request SIP message
REGISTER from sip:01234567890@sip-server-1.example.com :
REGISTER sip:sip-server-1.example.com SIP/2.0
Via: SIP/2.0/TLS
192.168.0.10:60619;alias;branch=z9hG4bK.Kj-QP2nIn;rport=60619;received=94.175.53.X
From: <sip:01234567890@sip-server-1.example.com>;tag=3-ahPz68B
To: sip:01234567890@sip-server-1.example.com
CSeq: 23 REGISTER
Call-ID: aLBrA6hmrm
Max-Forwards: 70
Supported: replaces, outbound, gruu
Accept: application/sdp, text/plain,
application/vnd.gsma.rcs-ft-http+xml
Contact:
<sip:01234567890@192.168.0.10:60619;app-id=org.APP.outboundsip2.voip.dev;pn-type=apple;pn-tok=5EF2BEBA92CC0D7A5B5CB1C58FD25BEF102CF1EDEB94535385731ABB5017XXXX;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf;pn-timeout=0;pn-silent=1;transport=tls>;+sip.instance="<urn:uuid:f22110e4-a023-002a-a0d9-a9463e4830e4>";+org.linphone.specs="lime"
Expires: 36000
User-Agent: Tamar SoftphoneiOS/1.0.12 (Phil)
LinphoneSDK/4.4.0-alpha.169+6f910f0
Content-Length: 0
2020-06-05 09:46:44:388 flexisip-debug-Invoking onRequest() on module
DoSProtection
2020-06-05 09:46:44:389 flexisip-debug-Invoking onRequest() on module
SanityChecker
2020-06-05 09:46:44:389 flexisip-debug-Skipping onRequest() on module
GarbageIn
2020-06-05 09:46:44:389 flexisip-debug-Invoking onRequest() on module
NatHelper
2020-06-05 09:46:44:389 flexisip-debug-Fixing contact header with
192.168.0.10:60619 to 94.175.53.X:60619
2020-06-05 09:46:44:389 flexisip-debug-Skipping onRequest() on module
Authentication
2020-06-05 09:46:44:390 flexisip-debug-Skipping onRequest() on module
Redirect
2020-06-05 09:46:44:390 flexisip-debug-Skipping onRequest() on module
GatewayAdapter
2020-06-05 09:46:44:390 flexisip-debug-Skipping onRequest() on module
Presence
2020-06-05 09:46:44:390 flexisip-debug-Invoking onRequest() on module
Registrar
2020-06-05 09:46:44:391 flexisip-debug-Path added to:
sips:flexisip.example.com:5061;lr
2020-06-05 09:46:44:391 flexisip-debug-New IncomingTransaction
0x561727f4f770
2020-06-05 09:46:44:391 flexisip-debug-nta_incoming_create: created
incoming transaction 0x561727f4f7f0
2020-06-05 09:46:44:391 flexisip-debug-Replying Request SIP message: 100
Trying
2020-06-05 09:46:44:391 flexisip-debug-tport_tsend(0x561727f0e390) tpn =
TLS/94.175.53.X:60619
2020-06-05 09:46:44:391 flexisip-debug-tport_tls_writevec: vec
0x561727f19440 0x561727f5a1c0 364 (364)
2020-06-05 09:46:44:392 flexisip-debug-tport_vsend returned 364
2020-06-05 09:46:44:392 flexisip-debug-tport(0x561727f0e390): set timer
at 1800000 ms because keepalive
2020-06-05 09:46:44:392 flexisip-debug-nta: sent 100 Trying for REGISTER
(23)
2020-06-05 09:46:44:392 flexisip-debug-New OutgoingTransaction
0x561727f5a5e0
2020-06-05 09:46:44:392 flexisip-debug-Contacts
:<sip:01234567890@94.175.53.X:60619;app-id=org.APP.outboundsip2.voip.dev;pn-type=apple;pn-tok=5EF2BEBA92CC0D7A5B5CB1C58FD25BEF102CF1EDEB94535385731ABB5017XXXX;pn-msg-str=IM_MSG;pn-call-str=IC_MSG;pn-call-snd=notes_of_the_optimistic.caf;pn-msg-snd=msg.caf;pn-timeout=0;pn-silent=1;transport=tls;fs-conn-id=d115b9f5679ba521>;+sip.instance="<urn:uuid:f22110e4-a023-002a-a0d9-a9463e4830e4>";+org.linphone.specs="lime"
2020-06-05 09:46:44:392 flexisip-debug-Removed instance and push params:
<sip:01234567890@94.175.53.X:60619;transport=tls;fs-conn-id=d115b9f5679ba521>;+sip.instance="<urn:uuid:f22110e4-a023-002a-a0d9-a9463e4830e4>";+org.linphone.specs="lime"
2020-06-05 09:46:44:393 flexisip-debug-Removed paths
2020-06-05 09:46:44:393 flexisip-debug-Skipping onRequest() on module
StatisticsCollector
2020-06-05 09:46:44:393 flexisip-debug-Invoking onRequest() on module
ContactRouteInserter
2020-06-05 09:46:44:393 flexisip-debug-Masquerading contact
2020-06-05 09:46:44:393 flexisip-debug-Rewriting contact with param
[CtRt112bec5da683917e=tls:sip-server-1.example.com]
2020-06-05 09:46:44:393 flexisip-debug-Contact has been rewritten to
sips:01234567890@flexisip.example.com:5061;fs-conn-id=d115b9f5679ba521;CtRt112bec5da683917e=tls:sip-server-1.example.com
2020-06-05 09:46:44:393 flexisip-debug-Skipping onRequest() on module
Router
2020-06-05 09:46:44:394 flexisip-debug-Invoking onRequest() on module
PushNotification
2020-06-05 09:46:44:394 flexisip-debug-Skipping onRequest() on module
LoadBalancer
2020-06-05 09:46:44:394 flexisip-debug-Invoking onRequest() on module
MediaRelay
2020-06-05 09:46:44:394 flexisip-debug-Skipping onRequest() on module
Transcoder
2020-06-05 09:46:44:394 flexisip-debug-Invoking onRequest() on module
Forward
2020-06-05 09:46:44:394 flexisip-debug-tport(0x561727ef8030): EXPENSIVE
unresolved tls/sip-server-1.example.com:5061
2020-06-05 09:46:44:394 flexisip-debug-tport(0x561727f39430): found from
primary tls/sip-server-1.example.com:5061
2020-06-05 09:46:44:394 flexisip-debug-Path added to:
sips:flexisip.example.com:5061;fs-proxy-id=112bec5da683917e;lr
2020-06-05 09:46:44:395 flexisip-debug-Sending Request SIP message to
sips:sip-server-1.example.com:5061
REGISTER sip:sip-server-1.example.com SIP/2.0
Via: SIP/2.0/TLS
192.168.0.10:60619;alias;branch=z9hG4bK.Kj-QP2nIn;rport=60619;received=94.175.53.X
From: <sip:01234567890@sip-server-1.example.com>;tag=3-ahPz68B
To: sip:01234567890@sip-server-1.example.com
CSeq: 23 REGISTER
Call-ID: aLBrA6hmrm
Max-Forwards: 69
Supported: replaces, outbound, gruu
Accept: application/sdp, text/plain,
application/vnd.gsma.rcs-ft-http+xml
Contact:
<sips:01234567890@flexisip.example.com:5061;fs-conn-id=d115b9f5679ba521;CtRt112bec5da683917e=tls:sip-server-1.example.com>;+sip.instance="<urn:uuid:f22110e4-a023-002a-a0d9-a9463e4830e4>";+org.linphone.specs="lime"
Expires: 36000
User-Agent: Tamar SoftphoneiOS/1.0.12 (Phil)
LinphoneSDK/4.4.0-alpha.169+6f910f0
Content-Length: 0
Path: <sips:flexisip.example.com:5061;fs-proxy-id=112bec5da683917e;lr>
2020-06-05 09:46:44:395 flexisip-debug-Message is sent through an
outgoing transaction.
2020-06-05 09:46:44:395 flexisip-debug-tport_tsend(0x561727f39430) tpn =
tls/X.X.X.X:5061
2020-06-05 09:46:44:395 flexisip-debug-tport_queue(0x561727f39430):
queueing 0x561727f56870 for tls/X.X.X.X:5061
2020-06-05 09:46:44:395 flexisip-debug-nta: sent REGISTER (23) to
tls/X.X.X.X:5061
2020-06-05 09:46:44:395 flexisip-debug-tport_pend(0x561727f39430):
pending 0x561727f56870 for tls/X.X.X.X:5061 (already 0)
2020-06-05 09:46:44:395 flexisip-debug-nta: timer set to 32000 ms
2020-06-05 09:46:44:395 flexisip-debug-Terminate SipEvent 0x561727f556b0
2020-06-05 09:46:44:396 flexisip-debug-tport(0x561727f0e390): set timer
at 1799996 ms because keepalive
2020-06-05 09:47:13:726 flexisip-debug-tport_wakeup(0x561727f0e390):
events IN
2020-06-05 09:47:13:726 flexisip-debug-tport_recv_event(0x561727f0e390)
2020-06-05 09:47:13:727 flexisip-debug-tport_tls_recv(0x561727f0e390):
tls_read() returned 4
2020-06-05 09:47:13:727 flexisip-debug-tport_recv_iovec(0x561727f0e390)
msg 0x561727f63f60 from (tls/94.175.53.X:60619) has 4 bytes, veclen = 1
2020-06-05 09:47:13:727 flexisip-debug-tport(0x561727f0e390): set timer
at 600000 ms because recv timeout
2020-06-05 09:47:16:385 flexisip-debug-nta: timer F fired, timeout
REGISTER (23)
2020-06-05 09:47:16:385 flexisip-debug-tport_release(0x561727f39430):
0x561727f56870 by 0x561727f631c0 with (nil)
2020-06-05 09:47:16:385 flexisip-debug-OutgoingTransaction callback
0x561727f5a5e0
2020-06-05 09:47:16:385 flexisip-debug-New SipEvent 0x561727f651a0 -
0x561727f647f0
2020-06-05 09:47:16:385 flexisip-debug-Receiving new Response SIP
message: 408
SIP/2.0 408 Request Timeout
Via: SIP/2.0/TLS
flexisip.example.com;rport;branch=z9hG4bK.N6tSDy3K9tmXZcHetB6Hme2rFH
Via: SIP/2.0/TLS
192.168.0.10:60619;alias;branch=z9hG4bK.Kj-QP2nIn;rport=60619;received=94.175.53.X
From: <sip:01234567890@sip-server-1.example.com>;tag=3-ahPz68B
To: sip:01234567890@sip-server-1.example.com;tag=04B7rmmr21Naj
Call-ID: aLBrA6hmrm
CSeq: 23 REGISTER
Content-Length: 0
2020-06-05 09:47:16:385 flexisip-debug-Invoking onResponse() on module
DoSProtection
2020-06-05 09:47:16:385 flexisip-debug-Invoking onResponse() on module
SanityChecker
2020-06-05 09:47:16:385 flexisip-debug-Skipping onResponse() on module
GarbageIn
2020-06-05 09:47:16:385 flexisip-debug-Invoking onResponse() on module
NatHelper
2020-06-05 09:47:16:385 flexisip-debug-Skipping onResponse() on module
Authentication
2020-06-05 09:47:16:385 flexisip-debug-Skipping onResponse() on module
Redirect
2020-06-05 09:47:16:385 flexisip-debug-Skipping onResponse() on module
GatewayAdapter
2020-06-05 09:47:16:385 flexisip-debug-Skipping onResponse() on module
Presence
2020-06-05 09:47:16:385 flexisip-debug-Invoking onResponse() on module
Registrar
2020-06-05 09:47:16:385 flexisip-debug-Skipping onResponse() on module
StatisticsCollector
2020-06-05 09:47:16:385 flexisip-debug-Invoking onResponse() on module
ContactRouteInserter
2020-06-05 09:47:16:385 flexisip-debug-Invoking onResponse() on module
Router
2020-06-05 09:47:16:385 flexisip-debug-Invoking onResponse() on module
PushNotification
2020-06-05 09:47:16:385 flexisip-debug-Skipping onResponse() on module
LoadBalancer
2020-06-05 09:47:16:385 flexisip-debug-Invoking onResponse() on module
MediaRelay
2020-06-05 09:47:16:385 flexisip-debug-Skipping onResponse() on module
Transcoder
2020-06-05 09:47:16:385 flexisip-debug-Invoking onResponse() on module
Forward
2020-06-05 09:47:16:385 flexisip-debug-Sending response: (via popped)
SIP/2.0 408 Request Timeout
Via: SIP/2.0/TLS
192.168.0.10:60619;alias;branch=z9hG4bK.Kj-QP2nIn;rport=60619;received=94.175.53.X
From: <sip:01234567890@sip-server-1.example.com>;tag=3-ahPz68B
To: sip:01234567890@sip-server-1.example.com;tag=04B7rmmr21Naj
Call-ID: aLBrA6hmrm
CSeq: 23 REGISTER
Content-Length: 0
2020-06-05 09:47:16:385 flexisip-debug-Response is sent through an
incoming transaction.
2020-06-05 09:47:16:385 flexisip-debug-nta: not sending 408 Request
Timeout for REGISTER (23)
2020-06-05 09:47:16:385 flexisip-debug-nta:
incoming_free(0x561727f4f7f0)
2020-06-05 09:47:16:385 flexisip-debug-incoming_reclaim: 0x561727f4f7f0
2020-06-05 09:47:16:385 flexisip-debug-nta_incoming_destroy:
0x561727f4f7f0
2020-06-05 09:47:16:385 flexisip-debug-Terminate SipEvent 0x561727f651a0
2020-06-05 09:47:16:385 flexisip-debug-tport(0x561727f0e390): set timer
at 597341 ms because recv timeout
2020-06-05 09:47:16:385 flexisip-debug-Delete IncomingTransaction
0x561727f4f770
2020-06-05 09:47:16:385 flexisip-debug-Delete OutgoingTransaction
0x561727f5a5e0
2020-06-05 09:47:16:386 flexisip-debug-nta_outgoing_timer: 0/0 resent,
1/1 tout, 0/0 term, 0/1 free
2020-06-05 09:47:16:386 flexisip-debug-nta: timer not set
2020-06-05 09:47:16:386 flexisip-debug-nta:
outgoing_free(0x561727f631c0)
2020-06-05 09:47:16:386 flexisip-debug-tport(0x561727f39430): reset
timer
| [Prev in Thread] |
Current Thread |
[Next in Thread] |
- Flexisip restart,
phillip <=