Doesn't always re-REGISTER on push even without a socket open

brianjmurrell commented 3 years ago

Describe the bug Doesn't always re-REGISTER when sent a push notification

To Reproduce Steps to reproduce the behavior:

Configure Linphone to register to a SIP server
Wait a while
Send a push notification
Observe that Linphone doesn't re-register to the SIP server and that there is no current TCP socket open to the Linphone client on the SIP server.

Expected behavior When not already connected on an open socket, when Linphone receives a push, it should re-REGISTER

Please complete the following information

Device: Huawei Honor 8
OS: Android 8.1
Version of the App 21a00a562
Version of the SDK c67027b

SDK logs Log

In the above log, Linphone is being sent a push at :15 past every hour.

at 0:15 it received a push, woke up and took 10 seconds to complete a REGISTER (pretty long)
at 1:15, it received a push, woke up and took 10 seconds to complete a REGISTER (also pretty long)
at 2:15, a push was not even sent because it's TCP socket to the server was still connected
at 3:15, it received a push, woke up and took 8 seconds to complete a REGISTER (still pretty long)
at 4:15, it received a push, woke up did not re-REGISTER and the socket on the server was closed
at 5:15, same thing
at 6:15 same thing
at 7:15, it received a push, woke up and took 7 seconds to complete a REGISTER (still kind of long)
at 8:15 it received a push, woke up and took 3 seconds to complete a REGISTER (better)
at 9:15 it received a push, woke up and took 5 seconds to complete a REGISTER (not so much better)

Viish commented 3 years ago

at 4:15, it received a push, woke up did not re-REGISTER and the socket on the server was closed at 5:15, same thing at 6:15 same thing

Not according to the client:

2021-03-22 04:15:02:791 [org.linphone.debug/linphone-android] MESSAGE [Push Notification] Notifying Core
2021-03-22 04:15:02:803 [org.linphone.debug/liblinphone] MESSAGE Push notification received
2021-03-22 04:15:02:806 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [Push received] acquired [ref=0x2aba]
2021-03-22 04:15:02:806 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): cast long of wakelock 10938
2021-03-22 04:15:02:810 [org.linphone.debug/liblinphone] MESSAGE Started push notif background task [10938]
2021-03-22 04:15:02:815 [org.linphone.debug/liblinphone] MESSAGE Sending keep-alive to ensure sockets aren't broken
2021-03-22 04:15:02:818 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a47540]: keep alive sent to [TCP://example.com:5060]
2021-03-22 04:15:22:831 [org.linphone.debug/liblinphone] WARNING Ending push received background task [10938]

at 0:15 it received a push, woke up and took 10 seconds to complete a REGISTER (pretty long)

It takes a while to register each time because your DNS server is quite slow... (fd31:aeb1:48df::2,10.75.22.247)

2021-03-22 00:15:09:385 [org.linphone.debug/belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop
2021-03-22 00:15:10:376 [org.linphone.debug/belle-sip] MESSAGE resolver_process_data dns_res_check() in progress
2021-03-22 00:15:10:397 [org.linphone.debug/belle-sip] MESSAGE resolver_process_data dns_res_check() in progress
2021-03-22 00:15:10:397 [org.linphone.debug/belle-sip] MESSAGE resolver_process_data dns_res_check() in progress
2021-03-22 00:15:11:374 [org.linphone.debug/belle-sip] MESSAGE resolver_process_data dns_res_check() in progress
2021-03-22 00:15:11:392 [org.linphone.debug/belle-sip] MESSAGE resolver_process_data dns_res_check() in progress
2021-03-22 00:15:11:395 [org.linphone.debug/belle-sip] MESSAGE resolver_process_data dns_res_check() in progress
2021-03-22 00:15:11:411 [org.linphone.debug/belle-sip] MESSAGE SRV _sip._tcp.example.com resolved to [target:pbx.example.com. port:5060 prio:0 weight:0]
2021-03-22 00:15:11:411 [org.linphone.debug/belle-sip] MESSAGE Starting A/AAAA query for srv result [pbx.example.com]
2021-03-22 00:15:12:430 [org.linphone.debug/belle-sip] MESSAGE resolver_process_data dns_res_check() in progress
2021-03-22 00:15:13:423 [org.linphone.debug/belle-sip] MESSAGE resolver_process_data dns_res_check() in progress
2021-03-22 00:15:13:442 [org.linphone.debug/belle-sip] MESSAGE pbx.example.com resolved to 10.75.22.8
2021-03-22 00:15:13:443 [org.linphone.debug/belle-sip] MESSAGE A query finished for srv result [pbx.example.com]
2021-03-22 00:15:13:443 [org.linphone.debug/belle-sip] MESSAGE All A/AAAA results for combined resolution have arrived.

From the cold start of the app to receiving the 200 OK of the REGISTER

2021-03-22 00:15:08:267 [org.linphone.debug/linphone-android] MESSAGE [Application] Core context created 
[...]
2021-03-22 00:15:13:470 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a2ff40]: message sent to [TCP://example.com:5060], size: [997] bytes
REGISTER sip:example.com SIP/2.0
[...]
2021-03-22 00:15:13:589 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a2ff40]: received [707] new bytes from [TCP://example.com:5060]:
SIP/2.0 200 OK

only 5 seconds have elapsed and not 10, and 4 of those 5 seconds were used to wait for your DNS server...

brianjmurrell commented 3 years ago

Not according to the client:

According to the server, there is no open socket to the client. I will dump the entire socket table on future failures. It would be useful if the debug would print the local port number to match with the server.

It takes a while to register each time because your DNS server is quite slow... (fd31:aeb1:48df::2,10.75.22.247)

My DNS server is not slow. Not by any measure, and certainly not on the order of several seconds. But what we don't know (when blaming DNS servers) is if the network is actually functioning when linphone sends the DNS request or if it's slow to wake up and doesn't wake up until some number of retries have been sent. I have a recollection that IPv6 on some versions of Android gets put to sleep and is slow to wake up compared to IPv4. My understanding is that this is done due to the much chattier multicast on IPv6.

[At least as an experiment of such, if not permanently,] Can/should Linphone ignore IPv6 resolvers, if it's Allow IPv6 option is disabled?

Viish commented 3 years ago

My DNS server is not slow.

It is likely it doesn't support SRV records then. Anyway you can try using a tool like dig or nslookup to check.

Can/should Linphone ignore IPv6 resolvers

No.

Viish commented 3 years ago

According to the server, there is no open socket to the client.

According to the client it is opened, and we even receive a few things (probably keep alives):

2021-03-22 04:15:02:129 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a47540]: ending recv background task with id=[2b0a].
2021-03-22 04:15:02:131 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x2b0a]

However it is possible a firewall or some other device between your server and your client is maintaining the socket alive for the client. Switching to TLS will prevent that.

brianjmurrell commented 3 years ago

It is likely it doesn't support SRV records then.

Yes it does:

$ time dig _sip._tcp.example.com. srv

; <<>> DiG 9.11.28-RedHat-9.11.28-1.fc33 <<>> _sip._tcp.example.com. srv
;; global options: +cmd
;; Got answer:
;; ->>HEADER<<- opcode: QUERY, status: NOERROR, id: 55296
;; flags: qr aa rd ra; QUERY: 1, ANSWER: 1, AUTHORITY: 1, ADDITIONAL: 6

;; OPT PSEUDOSECTION:
; EDNS: version: 0, flags:; udp: 4096
; COOKIE: [redacted] (good)
;; QUESTION SECTION:
;_sip._tcp.example.com. IN  SRV

;; ANSWER SECTION:
_sip._tcp.example.com. 86400 IN SRV 0 0 5060 pbx.example.com.

;; AUTHORITY SECTION:
example.com.    86400   IN  NS  server.example.com.

;; ADDITIONAL SECTION:
pbx.example.com.    86400   IN  AAAA    2001:123:ab:123::2
server.example.com. 1200    IN  AAAA    fd31:aeb1:48df::2
server.example.com. 1200    IN  AAAA    2001:123:ab:123::2
pbx.example.com.    86400   IN  A   10.75.22.8
server.example.com. 1200    IN  A   10.75.22.247

;; Query time: 0 msec
;; SERVER: fd31:aeb1:48df::2#53(fd31:aeb1:48df::2)
;; WHEN: Tue Mar 23 14:01:11 EDT 2021
;; MSG SIZE  rcvd: 258

real    0m0.030s
user    0m0.015s
sys 0m0.010s

and you can see it's quite fast.

Can/should Linphone ignore IPv6 resolvers

No.

Why should it use IPv6 resolvers if it's been told not to use IPv6? Also given the state of IPv6 on Android, maybe that's another reason to (at least optionally) ignore IPv6 resolvers.

However it is possible a firewall or some other device between your server and your client is maintaining the socket alive for the client.

The client and server are on the same subnet with no firewalls in between. No NAT to keep alive, etc.

I really do suspect this (time to register) is using the IPv6 resolver. I suppose that is done in the SDK though, yes? Nothing in the linphone-android client that can be done to ignore the IPv6 resolver?

Additional evidence that this is an IPv6 problem. In this case, the phone is on, but Linphone has been killed. This ensures that the IPv6 stack has not been put to sleep.

First the traffic between the phone and the DNS server/SIP server:

14:16:19.875618 IP6 fd31:aeb1:48df:0:419a:fea9:3e0e:3149.34243 > fd31:aeb1:48df::2.domain: 16446+ A? stun.l.google.com. (35)
14:16:19.875919 IP6 fd31:aeb1:48df::2.domain > fd31:aeb1:48df:0:419a:fea9:3e0e:3149.34243: 16446 1/4/8 A 172.217.219.127 (299)
14:16:19.885030 IP6 fd31:aeb1:48df:0:419a:fea9:3e0e:3149.39072 > fd31:aeb1:48df::2.domain: 35952+ SRV? _sip._tcp.example.com. (43)
14:16:19.885283 IP6 fd31:aeb1:48df::2.domain > fd31:aeb1:48df:0:419a:fea9:3e0e:3149.39072: 35952* 1/1/5 SRV pbx.example.com.:5060 0 0 (219)
14:16:19.886031 IP6 fd31:aeb1:48df:0:419a:fea9:3e0e:3149.34327 > fd31:aeb1:48df::2.domain: 6549+ A? example.com. (33)
14:16:19.886209 IP6 fd31:aeb1:48df::2.domain > fd31:aeb1:48df:0:419a:fea9:3e0e:3149.34327: 6549* 0/1/0 (87)
14:16:19.901431 IP6 fd31:aeb1:48df:0:419a:fea9:3e0e:3149.57103 > fd31:aeb1:48df::2.domain: 30134+ A? pbx.example.com. (37)
14:16:19.901608 IP6 fd31:aeb1:48df::2.domain > fd31:aeb1:48df:0:419a:fea9:3e0e:3149.57103: 30134* 1/1/3 A 10.75.22.8 (146)
14:16:19.929990 IP 10.75.22.32.49896 > 10.75.22.8.sip: Flags [S], seq 2480512341, win 65535, options [mss 1460,sackOK,TS val 33235613 ecr 0,nop,wscale 8], length 0
14:16:19.930044 IP 10.75.22.8.sip > 10.75.22.32.49896: Flags [S.], seq 695163853, ack 2480512342, win 28960, options [mss 1460,sackOK,TS val 626079638 ecr 33235613,nop,wscale 7], length 0
14:16:19.931310 IP 10.75.22.32.49896 > 10.75.22.8.sip: Flags [.], ack 1, win 343, options [nop,nop,TS val 33235613 ecr 626079638], length 0
14:16:19.941851 IP 10.75.22.32.49896 > 10.75.22.8.sip: Flags [P.], seq 1:998, ack 1, win 343, options [nop,nop,TS val 33235614 ecr 626079638], length 997
14:16:19.941892 IP 10.75.22.8.sip > 10.75.22.32.49896: Flags [.], ack 998, win 249, options [nop,nop,TS val 626079650 ecr 33235614], length 0
14:16:19.942254 IP 10.75.22.8.sip > 10.75.22.32.49896: Flags [P.], seq 1:512, ack 998, win 249, options [nop,nop,TS val 626079650 ecr 33235614], length 511
14:16:19.943293 IP 10.75.22.32.49896 > 10.75.22.8.sip: Flags [.], ack 512, win 347, options [nop,nop,TS val 33235614 ecr 626079650], length 0
14:16:19.963547 IP 10.75.22.32.49896 > 10.75.22.8.sip: Flags [P.], seq 998:2275, ack 512, win 347, options [nop,nop,TS val 33235616 ecr 626079650], length 1277
14:16:20.003487 IP 10.75.22.8.sip > 10.75.22.32.49896: Flags [.], ack 2275, win 272, options [nop,nop,TS val 626079712 ecr 33235616], length 0
14:16:20.090573 IP 10.75.22.8.sip > 10.75.22.32.49896: Flags [P.], seq 512:1219, ack 2275, win 272, options [nop,nop,TS val 626079799 ecr 33235616], length 707
14:16:20.151189 IP 10.75.22.32.49896 > 10.75.22.8.sip: Flags [.], ack 1219, win 352, options [nop,nop,TS val 33235635 ecr 626079799], length 0
14:16:20.261445 IP6 fd31:aeb1:48df:0:419a:fea9:3e0e:3149.54300 > fd31:aeb1:48df::2.domain: 59246+ A? lime.linphone.org. (35)
14:16:20.261719 IP6 fd31:aeb1:48df::2.domain > fd31:aeb1:48df:0:419a:fea9:3e0e:3149.54300: 59246 2/3/6 CNAME sip7.linphone.org., A 5.135.31.161 (279)

And the Linphone log of a 1-2 wakeup:

2021-03-23 14:16:05:881 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d286df40]: keep alive sent to [TCP://example.com:5060]
2021-03-23 14:16:05:908 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] [Interactivity Receiver] Device screen is ON
2021-03-23 14:16:06:042 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] Activity created:org.linphone.activities.launcher.LauncherActivity@993f379
2021-03-23 14:16:06:141 [org.linphone.debug/linphone-android] MESSAGE Activity started:org.linphone.activities.launcher.LauncherActivity@993f379
2021-03-23 14:16:06:147 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] Activity resumed:org.linphone.activities.launcher.LauncherActivity@993f379
2021-03-23 14:16:06:147 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] runningActivities=1
2021-03-23 14:16:06:147 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] App has left background mode
2021-03-23 14:16:06:147 [org.linphone.debug/linphone-android] MESSAGE [Core Manager] App has left background mode
2021-03-23 14:16:06:153 [org.linphone.debug/linphone-android] MESSAGE [Generic Activity] Device orientation is 0 (raw value is 0)
2021-03-23 14:16:06:643 [org.linphone.debug/linphone-android] MESSAGE [Launcher] Core is ready
2021-03-23 14:16:06:659 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] Activity paused:org.linphone.activities.launcher.LauncherActivity@993f379
2021-03-23 14:16:06:659 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] runningActivities=0
2021-03-23 14:16:06:684 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] Activity created:org.linphone.activities.main.MainActivity@fae69c4
2021-03-23 14:16:06:763 [org.linphone.debug/linphone-android] MESSAGE Activity started:org.linphone.activities.main.MainActivity@fae69c4
2021-03-23 14:16:06:779 [org.linphone.debug/liblinphone] MESSAGE Core callbacks [0x74d0a16e60] registered on core [0x74e3a17800]
2021-03-23 14:16:06:902 [org.linphone.debug/liblinphone] MESSAGE Core callbacks [0x74d0a16e10] registered on core [0x74e3a17800]
2021-03-23 14:16:06:903 [org.linphone.debug/liblinphone] MESSAGE Core callbacks [0x74d0a16fa0] registered on core [0x74e3a17800]
2021-03-23 14:16:06:909 [org.linphone.debug/linphone-android] MESSAGE [Dialer] Is pending call transfer: false
2021-03-23 14:16:06:910 [org.linphone.debug/linphone-android] MESSAGE [Dialer] Pending call transfer mode = false
2021-03-23 14:16:06:936 [org.linphone.debug/liblinphone] MESSAGE Core callbacks [0x74d0a16ff0] registered on core [0x74e3a17800]
2021-03-23 14:16:07:065 [org.linphone.debug/liblinphone] MESSAGE Core callbacks [0x74d0a17040] registered on core [0x74e3a17800]
2021-03-23 14:16:07:073 [org.linphone.debug/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_sip_account, realm=asterisk
2021-03-23 14:16:07:085 [org.linphone.debug/liblinphone] MESSAGE Core callbacks [0x74d0a170e0] registered on core [0x74e3a17800]
2021-03-23 14:16:07:105 [org.linphone.debug/linphone-android] WARNING [Data Binding] [Glide] Can't load null
2021-03-23 14:16:07:111 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] Activity resumed:org.linphone.activities.main.MainActivity@fae69c4
2021-03-23 14:16:07:112 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] runningActivities=1
2021-03-23 14:16:07:113 [org.linphone.debug/linphone-android] MESSAGE [Generic Activity] Device orientation is 0 (raw value is 0)
2021-03-23 14:16:10:486 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] Activity paused:org.linphone.activities.main.MainActivity@fae69c4
2021-03-23 14:16:10:486 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] runningActivities=0
2021-03-23 14:16:10:577 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] Activity stopped:org.linphone.activities.launcher.LauncherActivity@993f379
2021-03-23 14:16:10:579 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] Activity destroyed:org.linphone.activities.launcher.LauncherActivity@993f379
2021-03-23 14:16:10:617 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] Activity stopped:org.linphone.activities.main.MainActivity@fae69c4
2021-03-23 14:16:12:274 [org.linphone.debug/liblinphone] MESSAGE Callbacks [0x74d0a16ff0] unregistered on core [0x74e3a17800]
2021-03-23 14:16:12:278 [org.linphone.debug/linphone-android] MESSAGE [Activity Monitor] Activity destroyed:org.linphone.activities.main.MainActivity@fae69c4
2021-03-23 14:16:12:285 [org.linphone.debug/liblinphone] MESSAGE Callbacks [0x74d0a16e60] unregistered on core [0x74e3a17800]
2021-03-23 14:16:12:300 [org.linphone.debug/liblinphone] MESSAGE Callbacks [0x74d0a16fa0] unregistered on core [0x74e3a17800]
2021-03-23 14:16:12:300 [org.linphone.debug/liblinphone] MESSAGE Callbacks [0x74d0a16e10] unregistered on core [0x74e3a17800]
2021-03-23 14:16:19:558 [org.linphone.debug/linphone-android] MESSAGE [Application] Core context created 
2021-03-23 14:16:19:579 [org.linphone.debug/liblinphone] MESSAGE Initializing LinphoneCore 4.5.0-beta-38-g1e6441089
2021-03-23 14:16:19:580 [org.linphone.debug/liblinphone] MESSAGE Sal nat helper [enabled]
2021-03-23 14:16:19:580 [org.linphone.debug/linphone-android] MESSAGE ==== Phone information dump ====
2021-03-23 14:16:19:580 [org.linphone.debug/linphone-android] MESSAGE DEVICE=HWFRD
2021-03-23 14:16:19:580 [org.linphone.debug/linphone-android] MESSAGE MODEL=FRD-L04
2021-03-23 14:16:19:580 [org.linphone.debug/linphone-android] MESSAGE MANUFACTURER=HUAWEI
2021-03-23 14:16:19:581 [org.linphone.debug/linphone-android] MESSAGE ANDROID SDK=26
2021-03-23 14:16:19:581 [org.linphone.debug/linphone-android] MESSAGE ABIs=arm64-v8a, armeabi-v7a, armeabi
2021-03-23 14:16:19:581 [org.linphone.debug/linphone-android] MESSAGE =========================================
2021-03-23 14:16:19:581 [org.linphone.debug/linphone-android] MESSAGE ==== Linphone SDK information dump ====
2021-03-23 14:16:19:581 [org.linphone.debug/linphone-android] MESSAGE VERSION=4.5.0-beta.50+c67027b
2021-03-23 14:16:19:581 [org.linphone.debug/linphone-android] MESSAGE BRANCH=remotes/origin/release/4.5
2021-03-23 14:16:19:582 [org.linphone.debug/linphone-android] MESSAGE PLUGINS=libmswebrtc.so, libmsandroidcamera2.so
2021-03-23 14:16:19:582 [org.linphone.debug/linphone-android] MESSAGE PACKAGE=org.linphone.core
2021-03-23 14:16:19:582 [org.linphone.debug/linphone-android] MESSAGE BUILD TYPE=release
2021-03-23 14:16:19:582 [org.linphone.debug/linphone-android] MESSAGE =========================================
2021-03-23 14:16:19:589 [org.linphone.debug/linphone-android] MESSAGE [Audio Helper] Helper created
2021-03-23 14:16:19:592 [org.linphone.debug/linphone-android] MESSAGE [Bluetooth] Adapter found
2021-03-23 14:16:19:595 [org.linphone.debug/linphone-android] MESSAGE [Bluetooth] SCO available off call, continue
2021-03-23 14:16:19:595 [org.linphone.debug/linphone-android] MESSAGE [Bluetooth] Bluetooth receiver created
2021-03-23 14:16:19:597 [org.linphone.debug/linphone-android] MESSAGE [Bluetooth] Bluetooth helper created
2021-03-23 14:16:19:597 [org.linphone.debug/linphone-android] MESSAGE [Core Manager] Registering shutdown receiver
2021-03-23 14:16:19:598 [org.linphone.debug/linphone-android] MESSAGE [Core Manager] Ready
2021-03-23 14:16:19:598 [org.linphone.debug/liblinphone] MESSAGE [Android Platform Helper] CoreManager is fully initialised.
2021-03-23 14:16:19:600 [org.linphone.debug/mediastreamer] MESSAGE [Device] hasLowLatencyFeature: false, hasProFeature: false
2021-03-23 14:16:19:604 [org.linphone.debug/mediastreamer] MESSAGE [Device] Output frames per buffer: 960, output sample rate: 48000.
2021-03-23 14:16:19:604 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Created, wifi only mode is disabled
2021-03-23 14:16:19:610 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Starting copy from assets to application files directory
2021-03-23 14:16:19:618 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource cpim_grammar
2021-03-23 14:16:19:619 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource identity_grammar
2021-03-23 14:16:19:620 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource vcard_grammar
2021-03-23 14:16:19:625 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource bc_s.png
2021-03-23 14:16:19:625 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource bdwn.png
2021-03-23 14:16:19:626 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource closed.png
2021-03-23 14:16:19:627 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource doc.png
2021-03-23 14:16:19:627 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource doxygen.png
2021-03-23 14:16:19:628 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource folderclosed.png
2021-03-23 14:16:19:629 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource folderopen.png
2021-03-23 14:16:19:629 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource nav_f.png
2021-03-23 14:16:19:630 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource nav_g.png
2021-03-23 14:16:19:630 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource nav_h.png
2021-03-23 14:16:19:631 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource open.png
2021-03-23 14:16:19:632 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource splitbar.png
2021-03-23 14:16:19:632 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource structismapcontainer.png
2021-03-23 14:16:19:633 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource structprivate_1_1ismapcontainerimpl.png
2021-03-23 14:16:19:633 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource structprivate_1_1ismapcontainerimpl_3_01t_00_01void__t_3_01typename_01t_1_1key__type_00_01typena75682bb14a0833e99fd03a070051a2d9.png
2021-03-23 14:16:19:634 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource sync_off.png
2021-03-23 14:16:19:635 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource sync_on.png
2021-03-23 14:16:19:635 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource tab_a.png
2021-03-23 14:16:19:636 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource tab_b.png
2021-03-23 14:16:19:636 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource tab_h.png
2021-03-23 14:16:19:637 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource tab_s.png
2021-03-23 14:16:19:639 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource nowebcamcif.jpg
2021-03-23 14:16:19:641 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Installing Resource rootca.pem
2021-03-23 14:16:19:648 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Resource dont_wait_too_long.mkv already installed, skipping...
2021-03-23 14:16:19:648 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Resource hello16000.wav already installed, skipping...
2021-03-23 14:16:19:648 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Resource hello8000.wav already installed, skipping...
2021-03-23 14:16:19:649 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Resource incoming_chat.wav already installed, skipping...
2021-03-23 14:16:19:649 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Resource ringback.wav already installed, skipping...
2021-03-23 14:16:19:650 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Resource four_hands_together.mkv already installed, skipping...
2021-03-23 14:16:19:650 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Resource house_keeping.mkv already installed, skipping...
2021-03-23 14:16:19:651 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Resource its_a_game.mkv already installed, skipping...
2021-03-23 14:16:19:651 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Resource leaving_dreams.mkv already installed, skipping...
2021-03-23 14:16:19:651 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Resource notes_of_the_optimistic.mkv already installed, skipping...
2021-03-23 14:16:19:652 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Resource oldphone-mono.wav already installed, skipping...
2021-03-23 14:16:19:652 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Resource soft_as_snow.mkv already installed, skipping...
2021-03-23 14:16:19:652 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Resource silence.mkv already installed, skipping...
2021-03-23 14:16:19:652 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Resource toy-mono.wav already installed, skipping...
2021-03-23 14:16:19:652 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Copy from assets done
2021-03-23 14:16:19:659 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] [Network Manager 26] Found DNS host fd31:aeb1:48df::2 from active network WIFI
2021-03-23 14:16:19:659 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] [Network Manager 26] Found DNS host 10.75.22.247 from active network WIFI
2021-03-23 14:16:19:659 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] DNS servers list updated
2021-03-23 14:16:19:660 [org.linphone.debug/belle-sip] MESSAGE belle_sip_set_jvm(): initialization succeed
2021-03-23 14:16:19:660 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_init(): initialization succeed
2021-03-23 14:16:19:660 [org.linphone.debug/liblinphone] MESSAGE [Android Platform Helper] AndroidPlatformHelper is fully initialised.
2021-03-23 14:16:19:660 [org.linphone.debug/mediastreamer] MESSAGE Mediastreamer2 factory 4.5.0 (git: 4.5.0-alpha-59-gcdb292d5) initialized.
2021-03-23 14:16:19:660 [org.linphone.debug/mediastreamer] MESSAGE CPU count set to 8
2021-03-23 14:16:19:660 [org.linphone.debug/mediastreamer] MESSAGE ms_factory_init() done: platform_tags=linux,android,x86,embedded
2021-03-23 14:16:19:660 [org.linphone.debug/mediastreamer] MESSAGE srtp init
2021-03-23 14:16:19:671 [org.linphone.debug/mediastreamer] MESSAGE SDK version [26] detected
2021-03-23 14:16:19:717 [org.linphone.debug/mediastreamer] MESSAGE Codec video/avc successfully created.
2021-03-23 14:16:19:737 [org.linphone.debug/mediastreamer] MESSAGE Codec video/avc successfully created.
2021-03-23 14:16:19:743 [org.linphone.debug/mediastreamer] MESSAGE MediaCodec: 'video/avc' format supported
2021-03-23 14:16:19:771 [org.linphone.debug/mediastreamer] ERROR Failed to create codec !
2021-03-23 14:16:19:772 [org.linphone.debug/mediastreamer] WARNING MediaCodec: 'video/hevc' format not supported for encoding
2021-03-23 14:16:19:785 [org.linphone.debug/mediastreamer] MESSAGE Codec video/hevc successfully created.
2021-03-23 14:16:19:791 [org.linphone.debug/mediastreamer] MESSAGE Registering all soundcard handlers
2021-03-23 14:16:19:791 [org.linphone.debug/mediastreamer] MESSAGE SDK version [26] detected
2021-03-23 14:16:19:791 [org.linphone.debug/mediastreamer] MESSAGE Native android sound support not tested on SDK [26], disabled.
2021-03-23 14:16:19:795 [org.linphone.debug/mediastreamer] MESSAGE [OpenSLES] libOpenSLES correctly loaded, creating OpenSLES MS soundcard
2021-03-23 14:16:19:827 [org.linphone.debug/mediastreamer] MESSAGE This device (HUAWEI/FRD-L04/hi3650) says it has no built-in echo canceller.
2021-03-23 14:16:19:827 [org.linphone.debug/mediastreamer] MESSAGE No information available for [HUAWEI/FRD-L04/hi3650],
2021-03-23 14:16:19:827 [org.linphone.debug/mediastreamer] MESSAGE Using android >= 8 sound device descriptor.
2021-03-23 14:16:19:827 [org.linphone.debug/mediastreamer] MESSAGE Sound device information for [HUAWEI/FRD-L04/hi3650] is: builtin=[yes], delay=[0] ms
2021-03-23 14:16:19:827 [org.linphone.debug/mediastreamer] MESSAGE [Android Audio Utils] Using 48000 for sample rate value
2021-03-23 14:16:19:827 [org.linphone.debug/mediastreamer] MESSAGE [Android Audio Utils] Using 960 for buffer size value
2021-03-23 14:16:19:827 [org.linphone.debug/mediastreamer] MESSAGE SDK version [26] detected
2021-03-23 14:16:19:834 [org.linphone.debug/mediastreamer] MESSAGE [getAudioDevices] DEBUG Found device: name FRD-L04 ID 2 type 2 isSource false isSink true.
2021-03-23 14:16:19:835 [org.linphone.debug/mediastreamer] MESSAGE [getAudioDevices] DEBUG Found device: name FRD-L04 ID 1 type 1 isSource false isSink true.
2021-03-23 14:16:19:835 [org.linphone.debug/mediastreamer] MESSAGE [getAudioDevices] DEBUG Found device: name FRD-L04 ID 3 type 18 isSource false isSink true.
2021-03-23 14:16:19:836 [org.linphone.debug/mediastreamer] MESSAGE [getAudioDevices] DEBUG Found device: name FRD-L04 ID 5 type 15 isSource true isSink false.
2021-03-23 14:16:19:836 [org.linphone.debug/mediastreamer] MESSAGE [getAudioDevices] DEBUG Found device: name FRD-L04 ID 7 type 18 isSource true isSink false.
2021-03-23 14:16:19:836 [org.linphone.debug/mediastreamer] MESSAGE [OpenSLES] Create soundcards for 5 devices
2021-03-23 14:16:19:850 [org.linphone.debug/mediastreamer] MESSAGE This device (HUAWEI/FRD-L04/hi3650) says it has no built-in echo canceller.
2021-03-23 14:16:19:850 [org.linphone.debug/mediastreamer] MESSAGE No information available for [HUAWEI/FRD-L04/hi3650],
2021-03-23 14:16:19:850 [org.linphone.debug/mediastreamer] MESSAGE Using android >= 8 sound device descriptor.
2021-03-23 14:16:19:850 [org.linphone.debug/mediastreamer] MESSAGE Sound device information for [HUAWEI/FRD-L04/hi3650] is: builtin=[yes], delay=[0] ms
2021-03-23 14:16:19:850 [org.linphone.debug/mediastreamer] MESSAGE Card 'openSLES Speaker: FRD-L04' added with capabilities [playback]
2021-03-23 14:16:19:850 [org.linphone.debug/mediastreamer] MESSAGE [OpenSLES] Added card [0x74f3ccde90]: name [FRD-L04] device ID [2] type [Speaker]
2021-03-23 14:16:19:859 [org.linphone.debug/mediastreamer] MESSAGE This device (HUAWEI/FRD-L04/hi3650) says it has no built-in echo canceller.
2021-03-23 14:16:19:859 [org.linphone.debug/mediastreamer] MESSAGE No information available for [HUAWEI/FRD-L04/hi3650],
2021-03-23 14:16:19:859 [org.linphone.debug/mediastreamer] MESSAGE Using android >= 8 sound device descriptor.
2021-03-23 14:16:19:859 [org.linphone.debug/mediastreamer] MESSAGE Sound device information for [HUAWEI/FRD-L04/hi3650] is: builtin=[yes], delay=[0] ms
2021-03-23 14:16:19:859 [org.linphone.debug/mediastreamer] MESSAGE Card 'openSLES Earpiece: FRD-L04' added with capabilities [playback]
2021-03-23 14:16:19:859 [org.linphone.debug/mediastreamer] MESSAGE [OpenSLES] Added card [0x74f3ccdee0]: name [FRD-L04] device ID [1] type [Earpiece]
2021-03-23 14:16:19:859 [org.linphone.debug/mediastreamer] MESSAGE SDK version [26] detected
2021-03-23 14:16:19:869 [org.linphone.debug/mediastreamer] MESSAGE This device (HUAWEI/FRD-L04/hi3650) says it has no built-in echo canceller.
2021-03-23 14:16:19:869 [org.linphone.debug/mediastreamer] MESSAGE No information available for [HUAWEI/FRD-L04/hi3650],
2021-03-23 14:16:19:869 [org.linphone.debug/mediastreamer] MESSAGE Using android >= 8 sound device descriptor.
2021-03-23 14:16:19:869 [org.linphone.debug/mediastreamer] MESSAGE Sound device information for [HUAWEI/FRD-L04/hi3650] is: builtin=[yes], delay=[0] ms
2021-03-23 14:16:19:869 [org.linphone.debug/mediastreamer] MESSAGE Card 'openSLES Microphone: FRD-L04' added with capabilities [capture]
2021-03-23 14:16:19:869 [org.linphone.debug/mediastreamer] MESSAGE [OpenSLES] Added card [0x74f3ccdf30]: name [FRD-L04] device ID [5] type [Microphone]
2021-03-23 14:16:19:869 [org.linphone.debug/mediastreamer] MESSAGE SDK version [26] detected
2021-03-23 14:16:19:869 [org.linphone.debug/mediastreamer] MESSAGE SDK version [26] detected
2021-03-23 14:16:19:876 [org.linphone.debug/mediastreamer] MESSAGE This device (HUAWEI/FRD-L04/hi3650) says it has no built-in echo canceller.
2021-03-23 14:16:19:877 [org.linphone.debug/mediastreamer] MESSAGE No information available for [HUAWEI/FRD-L04/hi3650],
2021-03-23 14:16:19:877 [org.linphone.debug/mediastreamer] MESSAGE Using android >= 8 sound device descriptor.
2021-03-23 14:16:19:877 [org.linphone.debug/mediastreamer] MESSAGE Sound device information for [HUAWEI/FRD-L04/hi3650] is: builtin=[yes], delay=[0] ms
2021-03-23 14:16:19:877 [org.linphone.debug/mediastreamer] MESSAGE Card 'ANDROID SND (deprecated) Unknown: Android Sound card' added with capabilities [capture, playback]
2021-03-23 14:16:19:877 [org.linphone.debug/mediastreamer] MESSAGE Registering all webcam handlers
2021-03-23 14:16:19:877 [org.linphone.debug/mediastreamer] MESSAGE Found Android plugin libmswebrtc.so
2021-03-23 14:16:19:877 [org.linphone.debug/mediastreamer] MESSAGE Found Android plugin libmsandroidcamera2.so
2021-03-23 14:16:19:877 [org.linphone.debug/mediastreamer] WARNING [Legacy Capture] Android >= 8.0 detected and msAndroidCamera2 plugin found, disabling legacy capture filter
2021-03-23 14:16:19:877 [org.linphone.debug/mediastreamer] MESSAGE Webcam StaticImage: Static picture added
2021-03-23 14:16:19:879 [org.linphone.debug/mediastreamer] MESSAGE MSAndroidDisplay registered.
2021-03-23 14:16:19:879 [org.linphone.debug/mediastreamer] MESSAGE MSAndroidDisplay (OpenGL ES2) registered (id=80).
2021-03-23 14:16:19:879 [org.linphone.debug/mediastreamer] MESSAGE ms_factory_init_voip() done
2021-03-23 14:16:19:879 [org.linphone.debug/mediastreamer] MESSAGE Found Android plugin libmswebrtc.so
2021-03-23 14:16:19:879 [org.linphone.debug/mediastreamer] MESSAGE Found Android plugin libmsandroidcamera2.so
2021-03-23 14:16:19:879 [org.linphone.debug/mediastreamer] MESSAGE Loading ms plugins from list
2021-03-23 14:16:19:879 [org.linphone.debug/mediastreamer] MESSAGE Loading plugin libmswebrtc.so...
2021-03-23 14:16:19:886 [org.linphone.debug/bctbx] MESSAGE libmswebrtc 1.1.1 plugin loaded, iSAC codec version 3.6.0, iLBC codec version 1.1.1
2021-03-23 14:16:19:886 [org.linphone.debug/mediastreamer] MESSAGE Plugin loaded (libmswebrtc.so)
2021-03-23 14:16:19:886 [org.linphone.debug/mediastreamer] MESSAGE Loading plugin libmsandroidcamera2.so...
2021-03-23 14:16:19:891 [org.linphone.debug/bctbx] MESSAGE [Camera2 Capture] libmsandroidcamera2 plugin loaded
2021-03-23 14:16:19:891 [org.linphone.debug/bctbx] MESSAGE [Camera2 Capture] Detecting cameras
2021-03-23 14:16:19:899 [org.linphone.debug/bctbx] MESSAGE [Camera2 Capture] Camera 0 is facing back with angle 90, hardware level is limited
2021-03-23 14:16:19:899 [org.linphone.debug/mediastreamer] MESSAGE Webcam BackFacingCamera prepended
2021-03-23 14:16:19:899 [org.linphone.debug/bctbx] MESSAGE [Camera2 Capture] Camera 1 is facing front with angle 270, hardware level is limited
2021-03-23 14:16:19:899 [org.linphone.debug/mediastreamer] MESSAGE Webcam FrontFacingCamera prepended
2021-03-23 14:16:19:900 [org.linphone.debug/mediastreamer] MESSAGE Plugin loaded (libmsandroidcamera2.so)
2021-03-23 14:16:19:900 [org.linphone.debug/mediastreamer] MESSAGE All plugins in list correctly loaded
2021-03-23 14:16:19:902 [org.linphone.debug/liblinphone] MESSAGE Core callbacks [0x74f3ccd620] registered on core [0x74f3c9f400]
2021-03-23 14:16:19:902 [org.linphone.debug/ortp] MESSAGE oRTP-4.5.0 initialized.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec opus/48000 fmtp=[useinbandfec=1] number=-1, default enablement: 1) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec SILK/16000 fmtp=[] number=-1, default enablement: 1) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec speex/16000 fmtp=[vbr=on] number=-1, default enablement: 1) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec speex/8000 fmtp=[vbr=on] number=-1, default enablement: 1) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec PCMU/8000 fmtp=[] number=0, default enablement: 1) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec PCMA/8000 fmtp=[] number=8, default enablement: 1) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec red/1000 fmtp=[] number=-1, default enablement: 1) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec t140/1000 fmtp=[] number=-1, default enablement: 1) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec GSM/8000 fmtp=[] number=3, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec G722/8000 fmtp=[] number=9, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec iLBC/8000 fmtp=[mode=30] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec AMR/8000 fmtp=[octet-align=1] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec AMR-WB/16000 fmtp=[octet-align=1] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec G729/8000 fmtp=[annexb=yes] number=18, default enablement: 1) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec mpeg4-generic/16000 fmtp=[config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec mpeg4-generic/22050 fmtp=[config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec mpeg4-generic/32000 fmtp=[config=F8E82000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec mpeg4-generic/44100 fmtp=[config=F8E82000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec mpeg4-generic/48000 fmtp=[config=F8EE2000; constantDuration=512; indexDeltaLength=3; indexLength=3; mode=AAC-hbr; profile-level-id=76; sizeLength=13; streamType=5] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec iSAC/16000 fmtp=[] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec speex/32000 fmtp=[vbr=on] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec SILK/8000 fmtp=[] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec SILK/12000 fmtp=[] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:903 [org.linphone.debug/liblinphone] MESSAGE Codec SILK/24000 fmtp=[] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:904 [org.linphone.debug/liblinphone] MESSAGE Codec G726-16/8000 fmtp=[] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:904 [org.linphone.debug/liblinphone] MESSAGE Codec G726-24/8000 fmtp=[] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:904 [org.linphone.debug/liblinphone] MESSAGE Codec G726-32/8000 fmtp=[] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:904 [org.linphone.debug/liblinphone] MESSAGE Codec G726-40/8000 fmtp=[] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:904 [org.linphone.debug/liblinphone] MESSAGE Codec AAL2-G726-16/8000 fmtp=[] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:904 [org.linphone.debug/liblinphone] MESSAGE Codec AAL2-G726-24/8000 fmtp=[] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:904 [org.linphone.debug/liblinphone] MESSAGE Codec AAL2-G726-32/8000 fmtp=[] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:904 [org.linphone.debug/liblinphone] MESSAGE Codec AAL2-G726-40/8000 fmtp=[] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:904 [org.linphone.debug/liblinphone] MESSAGE Codec CODEC2/8000 fmtp=[] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:904 [org.linphone.debug/liblinphone] MESSAGE Codec BV16/8000 fmtp=[] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/liblinphone] MESSAGE Codec VP8/90000 fmtp=[] number=-1, default enablement: 1) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/liblinphone] MESSAGE Codec H264/90000 fmtp=[profile-level-id=42801F] number=-1, default enablement: 1) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/liblinphone] MESSAGE Codec H265/90000 fmtp=[] number=-1, default enablement: 1) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/liblinphone] MESSAGE Codec MP4V-ES/90000 fmtp=[profile-level-id=3] number=-1, default enablement: 1) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/liblinphone] MESSAGE Codec H263-1998/90000 fmtp=[CIF=1;QCIF=1] number=-1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/liblinphone] MESSAGE Codec H263/90000 fmtp=[] number=34, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/liblinphone] MESSAGE Codec 1016/8000 fmtp=[] number=1, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/liblinphone] MESSAGE Codec G723/8000 fmtp=[] number=4, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/liblinphone] MESSAGE Codec LPC/8000 fmtp=[] number=7, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/liblinphone] MESSAGE Codec L16/44100 fmtp=[] number=10, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/liblinphone] MESSAGE Codec L16/44100 fmtp=[] number=11, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/liblinphone] MESSAGE Codec CN/8000 fmtp=[] number=13, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/liblinphone] MESSAGE Codec H261/90000 fmtp=[] number=31, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/liblinphone] MESSAGE Codec MPV/90000 fmtp=[] number=32, default enablement: 0) added to the list of possible codecs.
2021-03-23 14:16:19:905 [org.linphone.debug/belle-sip] MESSAGE Root ca path set to /system/etc/security/cacerts
2021-03-23 14:16:19:905 [org.linphone.debug/belle-sip] MESSAGE Root ca path set to /system/etc/security/cacerts
2021-03-23 14:16:19:920 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] getDnsServers() returning fd31:aeb1:48df::2, 10.75.22.247, 
2021-03-23 14:16:19:920 [org.linphone.debug/liblinphone] MESSAGE [Android Platform Helper] Found DNS server fd31:aeb1:48df::2
2021-03-23 14:16:19:921 [org.linphone.debug/liblinphone] MESSAGE [Android Platform Helper] Found DNS server 10.75.22.247
2021-03-23 14:16:19:921 [org.linphone.debug/mediastreamer] MESSAGE Found Android plugin libmswebrtc.so
2021-03-23 14:16:19:921 [org.linphone.debug/mediastreamer] MESSAGE Found Android plugin libmsandroidcamera2.so
2021-03-23 14:16:19:921 [org.linphone.debug/mediastreamer] MESSAGE Loading ms plugins from list
2021-03-23 14:16:19:921 [org.linphone.debug/mediastreamer] MESSAGE Loading plugin libmswebrtc.so...
2021-03-23 14:16:19:922 [org.linphone.debug/bctbx] MESSAGE libmswebrtc 1.1.1 plugin loaded, iSAC codec version 3.6.0, iLBC codec version 1.1.1
2021-03-23 14:16:19:922 [org.linphone.debug/mediastreamer] MESSAGE Plugin loaded (libmswebrtc.so)
2021-03-23 14:16:19:922 [org.linphone.debug/mediastreamer] MESSAGE Loading plugin libmsandroidcamera2.so...
2021-03-23 14:16:19:923 [org.linphone.debug/bctbx] MESSAGE [Camera2 Capture] libmsandroidcamera2 plugin loaded
2021-03-23 14:16:19:923 [org.linphone.debug/mediastreamer] MESSAGE Plugin loaded (libmsandroidcamera2.so)
2021-03-23 14:16:19:923 [org.linphone.debug/mediastreamer] MESSAGE All plugins in list correctly loaded
2021-03-23 14:16:19:923 [org.linphone.debug/liblinphone] WARNING Device [Android Sound card] type is unknown
2021-03-23 14:16:19:923 [org.linphone.debug/liblinphone] MESSAGE linphone_core_set_playback_gain_db(): no active call.
2021-03-23 14:16:19:923 [org.linphone.debug/liblinphone] MESSAGE linphone_core_set_mic_gain_db(): no active call.
2021-03-23 14:16:19:925 [org.linphone.debug/liblinphone] MESSAGE [ToneManager] create ToneManager()
2021-03-23 14:16:19:925 [org.linphone.debug/liblinphone] MESSAGE Sal nat helper [enabled]
2021-03-23 14:16:19:925 [org.linphone.debug/liblinphone] MESSAGE Sal use rports [enabled]
2021-03-23 14:16:19:925 [org.linphone.debug/liblinphone] MESSAGE MTU is supposed to be 1300, rtp payload max size will be 1240
2021-03-23 14:16:19:926 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for SILK
2021-03-23 14:16:19:926 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for AMR
2021-03-23 14:16:19:926 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for AMR-WB
2021-03-23 14:16:19:926 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for G729
2021-03-23 14:16:19:926 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for mpeg4-generic
2021-03-23 14:16:19:926 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for mpeg4-generic
2021-03-23 14:16:19:926 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for mpeg4-generic
2021-03-23 14:16:19:926 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for mpeg4-generic
2021-03-23 14:16:19:926 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for mpeg4-generic
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for SILK
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for SILK
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for SILK
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for G726-16
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for G726-24
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for G726-32
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for G726-40
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for AAL2-G726-16
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for AAL2-G726-24
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for AAL2-G726-32
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for AAL2-G726-40
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for CODEC2
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for BV16
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for 1016
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for G723
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for LPC
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for CN
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for H265
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for MP4V-ES
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for H263-1998
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for H263
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for H261
2021-03-23 14:16:19:927 [org.linphone.debug/mediastreamer] MESSAGE Could not find encoder for MPV
2021-03-23 14:16:19:927 [org.linphone.debug/liblinphone] MESSAGE Supported codec red/1000 fmtp= automatically added to codec list.
2021-03-23 14:16:19:927 [org.linphone.debug/liblinphone] MESSAGE Supported codec t140/1000 fmtp= automatically added to codec list.
2021-03-23 14:16:19:927 [org.linphone.debug/liblinphone] MESSAGE Sal use rports [enabled]
2021-03-23 14:16:19:928 [org.linphone.debug/belle-sip] MESSAGE belle_sip_stack_set_well_know_port() : set to [5060]
2021-03-23 14:16:19:928 [org.linphone.debug/belle-sip] MESSAGE belle_sip_stack_set_well_know_port_tls() : set to [5061]
2021-03-23 14:16:19:928 [org.linphone.debug/belle-sip] MESSAGE Root ca path set to /data/user/0/org.linphone.debug/files/share/linphone/rootca.pem
2021-03-23 14:16:19:929 [org.linphone.debug/liblinphone] MESSAGE [LIME] instanciate a LimeX3dhEncryption engine 0x74d2e6af60 on server https://lime.linphone.org/lime-server/lime-server.php
2021-03-23 14:16:19:933 [org.linphone.debug/liblinphone] MESSAGE linphone_proxy_config_is_server_config_changed : 0
2021-03-23 14:16:19:934 [org.linphone.debug/liblinphone] MESSAGE Publish params have changed on proxy config [0x74f3c44680]
2021-03-23 14:16:19:934 [org.linphone.debug/liblinphone] MESSAGE Invalidating friends maps for list [0x74e3acff60]
2021-03-23 14:16:19:934 [org.linphone.debug/liblinphone] ERROR Given algorithm is not correct. Set algorithm failed
2021-03-23 14:16:19:934 [org.linphone.debug/liblinphone] ERROR Given algorithm is not correct. Add algorithm failed
2021-03-23 14:16:19:934 [org.linphone.debug/liblinphone] ERROR Given algorithm is not correct. Set algorithm failed
2021-03-23 14:16:19:934 [org.linphone.debug/liblinphone] ERROR Given algorithm is not correct. Add algorithm failed
2021-03-23 14:16:19:935 [org.linphone.debug/linphone-android] MESSAGE [Context] Ready
2021-03-23 14:16:19:935 [org.linphone.debug/linphone-android] MESSAGE [Context] Starting
2021-03-23 14:16:20:022 [org.linphone.debug/liblinphone] MESSAGE Core callbacks [0x74d2e45ec0] registered on core [0x74f3c9f400]
2021-03-23 14:16:20:022 [org.linphone.debug/liblinphone] MESSAGE Core callbacks [0x74d2e45e70] registered on core [0x74f3c9f400]
2021-03-23 14:16:20:023 [org.linphone.debug/linphone-android] MESSAGE [Context] Global state changed [Startup]
2021-03-23 14:16:20:023 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [global_state_changed]
2021-03-23 14:16:20:024 [org.linphone.debug/liblinphone] MESSAGE Using ["/data/user/0/org.linphone.debug/files/linphone.db"] as default database path
2021-03-23 14:16:20:024 [org.linphone.debug/liblinphone] MESSAGE Opening linphone database "/data/user/0/org.linphone.debug/files/linphone.db" with backend Sqlite3
2021-03-23 14:16:20:030 [org.linphone.debug/liblinphone] MESSAGE Start measurement of [Get chat rooms.].
2021-03-23 14:16:20:033 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:033 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:034 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:034 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:036 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:036 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:038 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:038 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:039 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:039 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:041 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:041 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:042 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:042 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:043 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:043 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:045 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:045 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:045 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:045 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:046 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:047 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:047 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:048 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:048 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:048 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:049 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:049 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:051 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:051 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:052 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:052 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:053 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:053 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:054 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:054 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:056 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:056 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:057 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:057 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:058 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:058 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:059 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:060 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:061 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:061 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:062 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:062 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:063 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:063 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:064 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:064 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:065 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:065 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:067 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:067 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:068 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:068 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:069 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:069 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:070 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:070 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:071 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:071 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:072 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:072 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:073 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:074 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:074 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:075 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:076 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:076 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:077 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:077 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:078 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:078 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:079 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:079 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:080 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:080 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:081 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:081 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:082 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:082 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:083 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:083 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [chat_room_state_changed]
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Duration of [Get chat rooms.]: 53ms.
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:asterisk@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@10.75.22.32)) to core map
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:my_sip_account@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:084 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@10.75.23.144)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:085 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:086 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:086 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:086 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:086 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:086 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:pc@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:086 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:pidgin@example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:086 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:086 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:086 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:086 [org.linphone.debug/liblinphone] MESSAGE Insert chat room ConferenceId(peer=IdentityAddress(sip:[redacted]@pbx.example.com), local=IdentityAddress(sip:my_sip_account@example.com)) to core map
2021-03-23 14:16:20:087 [org.linphone.debug/liblinphone] MESSAGE Using [/data/user/0/org.linphone.debug/files/call-history.db] as default call history database path
2021-03-23 14:16:20:087 [org.linphone.debug/liblinphone] MESSAGE Table already up to date: duplicate column name: call_id.
2021-03-23 14:16:20:109 [org.linphone.debug/liblinphone] MESSAGE linphone_core_get_call_history(): completed in 21 ms
2021-03-23 14:16:20:109 [org.linphone.debug/liblinphone] WARNING nothing to migrate, skipping...
2021-03-23 14:16:20:109 [org.linphone.debug/liblinphone] MESSAGE Using [/data/user/0/org.linphone.debug/files/zrtp-secrets.db] as default zrtp secrets database path
2021-03-23 14:16:20:110 [org.linphone.debug/belle-sip] MESSAGE Root ca path set to /data/user/0/org.linphone.debug/files/share/linphone/rootca.pem
2021-03-23 14:16:20:112 [org.linphone.debug/linphone-android] MESSAGE [Core Manager] Call to core.iterate() scheduled every 20ms
2021-03-23 14:16:20:113 [org.linphone.debug/liblinphone] MESSAGE Core callbacks [0x74d2f800f0] registered on core [0x74f3c9f400]
2021-03-23 14:16:20:115 [org.linphone.debug/linphone-android] MESSAGE [Core Manager] Found a service that herits from org.linphone.core.tools.service.CoreService: org.linphone.core.CoreService
2021-03-23 14:16:20:115 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] onLinphoneCoreStart, network monitoring is true
2021-03-23 14:16:20:115 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Registering network callbacks
2021-03-23 14:16:20:116 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] [Network Manager 26] ACCESS_NETWORK_STATE permission is granted
2021-03-23 14:16:20:129 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Registering doze receiver
2021-03-23 14:16:20:131 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Registering interactivity receiver
2021-03-23 14:16:20:134 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] No connectivity: setting network unreachable
2021-03-23 14:16:20:135 [org.linphone.debug/linphone-android] MESSAGE [Context] Global state changed [Configuring]
2021-03-23 14:16:20:135 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [global_state_changed]
2021-03-23 14:16:20:135 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [configuring_status]
2021-03-23 14:16:20:135 [org.linphone.debug/liblinphone] MESSAGE Sal::unlistenPorts(): done
2021-03-23 14:16:20:136 [org.linphone.debug/belle-sip] MESSAGE Creating listening point [0x74d2f8a280] on [sip:0.0.0.0:-1;transport=UDP]
2021-03-23 14:16:20:136 [org.linphone.debug/belle-sip] MESSAGE Random UDP port is 51561
2021-03-23 14:16:20:136 [org.linphone.debug/belle-sip] MESSAGE Creating listening point [0x74d2f8a300] on [sip:0.0.0.0:-1;transport=TCP]
2021-03-23 14:16:20:136 [org.linphone.debug/belle-sip] MESSAGE Random TCP port is 42615
2021-03-23 14:16:20:136 [org.linphone.debug/belle-sip] MESSAGE Creating listening point [0x74d2f8a380] on [sip:0.0.0.0:-1;transport=TLS]
2021-03-23 14:16:20:136 [org.linphone.debug/belle-sip] MESSAGE Random TCP port is 42165
2021-03-23 14:16:20:136 [org.linphone.debug/belle-sip] MESSAGE Root ca path set to /system/etc/security/cacerts
2021-03-23 14:16:20:136 [org.linphone.debug/belle-sip] MESSAGE Root ca path set to /system/etc/security/cacerts
2021-03-23 14:16:20:136 [org.linphone.debug/belle-sip] MESSAGE Root ca path set to /data/user/0/org.linphone.debug/files/share/linphone/rootca.pem
2021-03-23 14:16:20:139 [org.linphone.debug/linphone-android] MESSAGE [Context] Global state changed [On]
2021-03-23 14:16:20:141 [org.linphone.debug/linphone-android] MESSAGE [Contacts Manager] Register contacts observer
2021-03-23 14:16:20:149 [org.linphone.debug/linphone-android] MESSAGE [Contacts Manager] Found account with name [linphone contacts] and type [org.linphone.sync]
2021-03-23 14:16:20:150 [org.linphone.debug/linphone-android] MESSAGE [Contacts Manager] Created
2021-03-23 14:16:20:151 [org.linphone.debug/linphone-android] MESSAGE [Contacts Loader] Synchronization started
2021-03-23 14:16:20:152 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [global_state_changed]
2021-03-23 14:16:20:152 [org.linphone.debug/linphone-android] MESSAGE [Context] Configuring Core
2021-03-23 14:16:20:152 [org.linphone.debug/linphone-android] MESSAGE [Contacts Loader] Background synchronization started
2021-03-23 14:16:20:154 [org.linphone.debug/linphone-android] MESSAGE [Contacts Loader] Only fetching contacts in default directory
2021-03-23 14:16:20:157 [org.linphone.debug/linphone-android] MESSAGE [Context] Core configured
2021-03-23 14:16:20:158 [org.linphone.debug/linphone-android] MESSAGE [Context] Registering phone state listener
2021-03-23 14:16:20:162 [org.linphone.debug/linphone-android] MESSAGE [Application] Created
2021-03-23 14:16:20:182 [org.linphone.debug/linphone-android] MESSAGE [Core Manager] Push notification app id is [755770037818] and token is [eEvxOl5B8DY:APA91bFJeVX_gCmijzsOwvRXbKpoIyFcwgfuNHjfxN2ayVRZxPQPie1BZGV0nmDpnVlXev1yiQqi4-YzCFB1wfIAf4Kc7cZZY3szYTN9nptYdurfRvoIOvXQ-HoahkFLyr_U8zVm_oaj]
2021-03-23 14:16:20:183 [org.linphone.debug/liblinphone] MESSAGE Push notification information updated: param [755770037818], prid [eEvxOl5B8DY:APA91bFJeVX_gCmijzsOwvRXbKpoIyFcwgfuNHjfxN2ayVRZxPQPie1BZGV0nmDpnVlXev1yiQqi4-YzCFB1wfIAf4Kc7cZZY3szYTN9nptYdurfRvoIOvXQ-HoahkFLyr_U8zVm_oaj]
2021-03-23 14:16:20:186 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] [Network Manager 26] A network is available: WIFI, wifi only is disabled
2021-03-23 14:16:20:191 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Active network type is WIFI, state CONNECTED / CONNECTED
2021-03-23 14:16:20:198 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] [Network Manager 26] Found DNS host fd31:aeb1:48df::2 from active network WIFI
2021-03-23 14:16:20:199 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] [Network Manager 26] Found DNS host 10.75.22.247 from active network WIFI
2021-03-23 14:16:20:199 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] DNS servers list updated
2021-03-23 14:16:20:199 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] Network reachability enabled
2021-03-23 14:16:20:200 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] getDnsServers() returning fd31:aeb1:48df::2, 10.75.22.247, 
2021-03-23 14:16:20:200 [org.linphone.debug/liblinphone] MESSAGE [Android Platform Helper] Found DNS server fd31:aeb1:48df::2
2021-03-23 14:16:20:200 [org.linphone.debug/liblinphone] MESSAGE [Android Platform Helper] Found DNS server 10.75.22.247
2021-03-23 14:16:20:201 [org.linphone.debug/liblinphone] MESSAGE SIP network reachability state is now [UP]
2021-03-23 14:16:20:202 [org.linphone.debug/liblinphone] MESSAGE Media network reachability state is now [UP]
2021-03-23 14:16:20:203 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [network_reachable]
2021-03-23 14:16:20:203 [org.linphone.debug/liblinphone] MESSAGE Starting stun server resolution [stun.l.google.com]
2021-03-23 14:16:20:205 [org.linphone.debug/belle-sip] MESSAGE resconf using application supplied dns server list.
2021-03-23 14:16:20:205 [org.linphone.debug/belle-sip] MESSAGE Resolver is using DNS server(s):
2021-03-23 14:16:20:205 [org.linphone.debug/belle-sip] MESSAGE  fd31:aeb1:48df::2
2021-03-23 14:16:20:205 [org.linphone.debug/belle-sip] MESSAGE  10.75.22.247
2021-03-23 14:16:20:206 [org.linphone.debug/belle-sip] MESSAGE Resolver is not using connect().
2021-03-23 14:16:20:207 [org.linphone.debug/belle-sip] MESSAGE resolver_process_data dns_res_check() in progress
2021-03-23 14:16:20:207 [org.linphone.debug/belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop
2021-03-23 14:16:20:207 [org.linphone.debug/liblinphone] MESSAGE LinphoneProxyConfig [0x74f3c44680] about to register (LinphoneCore version: 4.5.0-beta-38-g1e6441089)
2021-03-23 14:16:20:213 [org.linphone.debug/linphone-android] MESSAGE [Contacts Loader] Found 1572 entries in cursor
2021-03-23 14:16:20:214 [org.linphone.debug/belle-sip] MESSAGE get_jni_env(): thread successfuly attached
2021-03-23 14:16:20:216 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x74f3df80c0)] acquired [ref=0x2af6]
2021-03-23 14:16:20:216 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): cast long of wakelock 10998
2021-03-23 14:16:20:216 [org.linphone.debug/belle-sip] MESSAGE transaction [0x74f3df80c0]: starting transaction background task with id=[2af6].
2021-03-23 14:16:20:216 [org.linphone.debug/belle-sip] MESSAGE belle_sip_channel_init(); peer_cname=example.com
2021-03-23 14:16:20:216 [org.linphone.debug/belle-sip] MESSAGE belle_sip_client_transaction_send_request(): waiting channel to be ready
2021-03-23 14:16:20:219 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip send channel] acquired [ref=0x2b16]
2021-03-23 14:16:20:219 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): cast long of wakelock 11030
2021-03-23 14:16:20:220 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a38f40]: starting send background task with id=[2b16].
2021-03-23 14:16:20:220 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a38f40]: starting resolution of example.com
2021-03-23 14:16:20:220 [org.linphone.debug/belle-sip] MESSAGE channel[0x74d2a38f40]: entering state RES_IN_PROGRESS
2021-03-23 14:16:20:220 [org.linphone.debug/belle-sip] MESSAGE transaction [0x74f3df80c0] channel state changed to [RES_IN_PROGRESS]
2021-03-23 14:16:20:220 [org.linphone.debug/belle-sip] MESSAGE resconf using application supplied dns server list.
2021-03-23 14:16:20:220 [org.linphone.debug/belle-sip] MESSAGE Resolver is using DNS server(s):
2021-03-23 14:16:20:220 [org.linphone.debug/belle-sip] MESSAGE  fd31:aeb1:48df::2
2021-03-23 14:16:20:220 [org.linphone.debug/belle-sip] MESSAGE  10.75.22.247
2021-03-23 14:16:20:220 [org.linphone.debug/belle-sip] MESSAGE Resolver is not using connect().
2021-03-23 14:16:20:221 [org.linphone.debug/belle-sip] MESSAGE resolver_process_data dns_res_check() in progress
2021-03-23 14:16:20:221 [org.linphone.debug/belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop
2021-03-23 14:16:20:221 [org.linphone.debug/belle-sip] MESSAGE resconf using application supplied dns server list.
2021-03-23 14:16:20:221 [org.linphone.debug/belle-sip] MESSAGE Resolver is using DNS server(s):
2021-03-23 14:16:20:221 [org.linphone.debug/belle-sip] MESSAGE  fd31:aeb1:48df::2
2021-03-23 14:16:20:221 [org.linphone.debug/belle-sip] MESSAGE  10.75.22.247
2021-03-23 14:16:20:221 [org.linphone.debug/belle-sip] MESSAGE Resolver is not using connect().
2021-03-23 14:16:20:222 [org.linphone.debug/belle-sip] MESSAGE resolver_process_data dns_res_check() in progress
2021-03-23 14:16:20:222 [org.linphone.debug/belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop
2021-03-23 14:16:20:222 [org.linphone.debug/belle-sip] MESSAGE Neither Expires header nor corresponding Contact header found, checking from original request
2021-03-23 14:16:20:222 [org.linphone.debug/belle-sip] MESSAGE Refresher [0x74f3dc2f40] takes ownership of transaction [0x74f3df80c0]
2021-03-23 14:16:20:222 [org.linphone.debug/liblinphone] MESSAGE Proxy config [0x74f3c44680] for identity ["[redacted]" <sip:my_sip_account@example.com>] moving from state [LinphoneRegistrationNone] to [LinphoneRegistrationProgress] on core [0x74f3c9f400]
2021-03-23 14:16:20:222 [org.linphone.debug/liblinphone] MESSAGE _linphone_update_dependent_proxy_config(): 0x74f3c44680 is registered, checking for [0x74f3c44680] ->dependency=0x0
2021-03-23 14:16:20:223 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [registration_state_changed]
2021-03-23 14:16:20:229 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] [Network Manager 26] onLinkPropertiesChanged {InterfaceName: wlan0 LinkAddresses: [*]  Routes: [*] DnsAddresses: [fd31:aeb1:48df::2,10.75.22.247,] Domains: example.com ilinx MTU: 0 TcpBufferSizes: 524288,1048576,2097152,262144,524288,1048576}
2021-03-23 14:16:20:236 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] [Network Manager 26] Found DNS host fd31:aeb1:48df::2 from active network WIFI
2021-03-23 14:16:20:236 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] [Network Manager 26] Found DNS host 10.75.22.247 from active network WIFI
2021-03-23 14:16:20:236 [org.linphone.debug/linphone-android] MESSAGE [Platform Helper] DNS servers list updated
2021-03-23 14:16:20:236 [org.linphone.debug/belle-sip] MESSAGE resolver[0x74f3cdd980]: starting SRV timeout since A/AAAA fallback response is received.
2021-03-23 14:16:20:236 [org.linphone.debug/belle-sip] MESSAGE SRV _sip._tcp.example.com resolved to [target:pbx.example.com. port:5060 prio:0 weight:0]
2021-03-23 14:16:20:236 [org.linphone.debug/belle-sip] MESSAGE Starting A/AAAA query for srv result [pbx.example.com]
2021-03-23 14:16:20:236 [org.linphone.debug/belle-sip] MESSAGE resconf using application supplied dns server list.
2021-03-23 14:16:20:236 [org.linphone.debug/belle-sip] MESSAGE Resolver is using DNS server(s):
2021-03-23 14:16:20:236 [org.linphone.debug/belle-sip] MESSAGE  fd31:aeb1:48df::2
2021-03-23 14:16:20:236 [org.linphone.debug/belle-sip] MESSAGE  10.75.22.247
2021-03-23 14:16:20:236 [org.linphone.debug/belle-sip] MESSAGE Resolver is not using connect().
2021-03-23 14:16:20:237 [org.linphone.debug/belle-sip] MESSAGE resolver_process_data dns_res_check() in progress
2021-03-23 14:16:20:237 [org.linphone.debug/belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop
2021-03-23 14:16:20:237 [org.linphone.debug/belle-sip] MESSAGE stun.l.google.com resolved to 172.217.219.127
2021-03-23 14:16:20:237 [org.linphone.debug/liblinphone] MESSAGE Stun server resolution successful.
2021-03-23 14:16:20:237 [org.linphone.debug/linphone-android] MESSAGE [Context] Phone state is idle
2021-03-23 14:16:20:254 [org.linphone.debug/belle-sip] MESSAGE pbx.example.com resolved to 10.75.22.8
2021-03-23 14:16:20:254 [org.linphone.debug/belle-sip] MESSAGE A query finished for srv result [pbx.example.com]
2021-03-23 14:16:20:254 [org.linphone.debug/belle-sip] MESSAGE All A/AAAA results for combined resolution have arrived.
2021-03-23 14:16:20:265 [org.linphone.debug/belle-sip] MESSAGE channel[0x74d2a38f40]: current peer hostname is [pbx.example.com].
2021-03-23 14:16:20:265 [org.linphone.debug/belle-sip] MESSAGE channel[0x74d2a38f40]: entering state RES_DONE
2021-03-23 14:16:20:265 [org.linphone.debug/belle-sip] MESSAGE transaction [0x74f3df80c0] channel state changed to [RES_DONE]
2021-03-23 14:16:20:265 [org.linphone.debug/belle-sip] MESSAGE channel[0x74d2a38f40]: entering state CONNECTING
2021-03-23 14:16:20:265 [org.linphone.debug/belle-sip] MESSAGE transaction [0x74f3df80c0] channel state changed to [CONNECTING]
2021-03-23 14:16:20:265 [org.linphone.debug/belle-sip] MESSAGE Trying to connect to [TCP://10.75.22.8:5060]
2021-03-23 14:16:20:276 [org.linphone.debug/belle-sip] MESSAGE Channel has local address 10.75.22.32:49896
2021-03-23 14:16:20:277 [org.linphone.debug/belle-sip] MESSAGE channel[0x74d2a38f40]: entering state READY
2021-03-23 14:16:20:277 [org.linphone.debug/belle-sip] MESSAGE transaction [0x74f3df80c0] channel state changed to [READY]
2021-03-23 14:16:20:277 [org.linphone.debug/belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x74f3df80c0], from state [INIT] to [TRYING]
2021-03-23 14:16:20:278 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a38f40]: message sent to [TCP://example.com:5060], size: [997] bytes
REGISTER sip:example.com SIP/2.0
Via: SIP/2.0/TCP 10.75.22.32:49896;alias;branch=z9hG4bK.TO1YPsHSf;rport
From: "[redacted]" <sip:my_sip_account@example.com>;tag=ZUv-RGyxY
To: "[redacted]" <sip:my_sip_account@example.com>
CSeq: 20 REGISTER
Call-ID: LsbwEnO0yR
Max-Forwards: 70
Supported: replaces, outbound, gruu
Accept: application/sdp
Accept: text/plain
Accept: application/vnd.gsma.rcs-ft-http+xml
Contact: "[redacted]" <sip:my_sip_account@10.75.22.32:49896;pn-type=firebase;app-id==[redacted];pn-tok==[redacted];pn-timeout=0;pn-silent=1;transport=tcp>;+sip.instance="<urn:uuid:312eea04-3559-00ae-9920-aac6701fd648>";+org.linphone.specs="ephemeral,groupchat/1.1,lime"
Expires: 31536000
User-Agent: MyLinphone/4.3-alpha.345+4e96dc99a (FRD-L04) LinphoneSDK/4.5.0-beta.50+c67027b (remotes/origin/release/4.5)
Content-Length: 0

2021-03-23 14:16:20:278 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a38f40]: ending send background task with id=[2b16].
2021-03-23 14:16:20:280 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x2b16]
2021-03-23 14:16:20:281 [org.linphone.debug/linphone-android] MESSAGE [Push Notification] Notifying Core
2021-03-23 14:16:20:281 [org.linphone.debug/liblinphone] MESSAGE Push notification received
2021-03-23 14:16:20:284 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [Push received] acquired [ref=0x2b46]
2021-03-23 14:16:20:284 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): cast long of wakelock 11078
2021-03-23 14:16:20:284 [org.linphone.debug/liblinphone] MESSAGE Started push notif background task [11078]
2021-03-23 14:16:20:286 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2b8a]
2021-03-23 14:16:20:286 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): cast long of wakelock 11146
2021-03-23 14:16:20:286 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a38f40]: starting recv background task with id=[2b8a].
2021-03-23 14:16:20:287 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a38f40]: received [511] new bytes from [TCP://example.com:5060]:
SIP/2.0 401 Unauthorized
Via: SIP/2.0/TCP 10.75.22.32:49896;rport=49896;received=10.75.22.32;branch=z9hG4bK.TO1YPsHSf;alias
Call-ID: LsbwEnO0yR
From: "[redacted]" <sip:my_sip_account@example.com>;tag=ZUv-RGyxY
To: "[redacted]" <sip:my_sip_account@example.com>;tag=z9hG4bK.TO1YPsHSf
CSeq: 20 REGISTER
WWW-Authenticate: [redacted]
Server: Asterisk PBX 18.2.2
Content-Length:  0

2021-03-23 14:16:20:295 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a38f40] [511] bytes parsed
2021-03-23 14:16:20:295 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a38f40]: discovered public ip and port are [10.75.22.32:49896]
2021-03-23 14:16:20:295 [org.linphone.debug/belle-sip] MESSAGE Found transaction matching response.
2021-03-23 14:16:20:295 [org.linphone.debug/belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x74f3df80c0], from state [TRYING] to [COMPLETED]
2021-03-23 14:16:20:296 [org.linphone.debug/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_sip_account, realm=asterisk
2021-03-23 14:16:20:296 [org.linphone.debug/liblinphone] MESSAGE AuthStack::authFound() for Username[my_sip_account];Userid[];Realm[asterisk];Domain[example.com];Algorithm[];AvailableAlgorithms[];
2021-03-23 14:16:20:296 [org.linphone.debug/belle-sip] MESSAGE Auth info found for [my_sip_account] realm [asterisk]
2021-03-23 14:16:20:298 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip transaction(0x74d2b1a6c0)] acquired [ref=0x2bc6]
2021-03-23 14:16:20:298 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): cast long of wakelock 11206
2021-03-23 14:16:20:298 [org.linphone.debug/belle-sip] MESSAGE transaction [0x74d2b1a6c0]: starting transaction background task with id=[2bc6].
2021-03-23 14:16:20:298 [org.linphone.debug/belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x74d2b1a6c0], from state [INIT] to [TRYING]
2021-03-23 14:16:20:299 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a38f40]: message sent to [TCP://example.com:5060], size: [1277] bytes
REGISTER sip:example.com SIP/2.0
Via: SIP/2.0/TCP 10.75.22.32:49896;alias;branch=z9hG4bK.N1k2-Knpk;rport
From: "[redacted]" <sip:my_sip_account@example.com>;tag=ZUv-RGyxY
To: "[redacted]" <sip:my_sip_account@example.com>
CSeq: 21 REGISTER
Call-ID: LsbwEnO0yR
Max-Forwards: 70
Supported: replaces, outbound, gruu
Accept: application/sdp
Accept: text/plain
Accept: application/vnd.gsma.rcs-ft-http+xml
Contact: "[redacted]" <sip:my_sip_account@10.75.22.32:49896;pn-type=firebase;app-id==[redacted];pn-tok==[redacted];pn-timeout=0;pn-silent=1;transport=tcp>;+sip.instance="<urn:uuid:312eea04-3559-00ae-9920-aac6701fd648>";+org.linphone.specs="ephemeral,groupchat/1.1,lime"
Expires: 31536000
User-Agent: MyLinphone/4.3-alpha.345+4e96dc99a (FRD-L04) LinphoneSDK/4.5.0-beta.50+c67027b (remotes/origin/release/4.5)
Content-Length: 0
Authorization: :[redacted]

2021-03-23 14:16:20:299 [org.linphone.debug/belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x74f3df80c0], from state [COMPLETED] to [TERMINATED]
2021-03-23 14:16:20:299 [org.linphone.debug/belle-sip] MESSAGE Client internal REGISTER transaction [0x74f3df80c0] terminated
2021-03-23 14:16:20:299 [org.linphone.debug/belle-sip] MESSAGE transaction [0x74f3df80c0]: ending transaction background task with id=[2af6].
2021-03-23 14:16:20:301 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x2af6]
2021-03-23 14:16:20:301 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a38f40]: ending recv background task with id=[2b8a].
2021-03-23 14:16:20:303 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x2b8a]
2021-03-23 14:16:20:460 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2c06]
2021-03-23 14:16:20:460 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): cast long of wakelock 11270
2021-03-23 14:16:20:460 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a38f40]: starting recv background task with id=[2c06].
2021-03-23 14:16:20:460 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a38f40]: received [707] new bytes from [TCP://example.com:5060]:
SIP/2.0 200 OK
Via: SIP/2.0/TCP 10.75.22.32:49896;rport=49896;received=10.75.22.32;branch=z9hG4bK.N1k2-Knpk;alias
Call-ID: LsbwEnO0yR
From: "[redacted]" <sip:my_sip_account@example.com>;tag=ZUv-RGyxY
To: "[redacted]" <sip:my_sip_account@example.com>;tag=z9hG4bK.N1k2-Knpk
CSeq: 21 REGISTER
Date: Tue, 23 Mar 2021 18:16:20 GMT
Contact: <sip:my_sip_account@10.75.22.32:49896;transport=tcp;pn-type=firebase;app-id==[redacted];pn-tok==[redacted];pn-timeout=0;pn-silent=1>;expires=31535999
Expires: 31536000
Server: Asterisk PBX 18.2.2
Content-Length:  0

2021-03-23 14:16:20:467 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a38f40] [707] bytes parsed
2021-03-23 14:16:20:467 [org.linphone.debug/belle-sip] MESSAGE Found transaction matching response.
2021-03-23 14:16:20:467 [org.linphone.debug/belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x74d2b1a6c0], from state [TRYING] to [COMPLETED]
2021-03-23 14:16:20:467 [org.linphone.debug/belle-sip] MESSAGE Refresher [0x74f3dc2f40]:  has no contact for request [0x74f3db9880].
2021-03-23 14:16:20:467 [org.linphone.debug/belle-sip] MESSAGE Refresher[0x74f3dc2f40]: scheduling next timer in -1682371972 ms for purpose [normal refresh]
2021-03-23 14:16:20:468 [org.linphone.debug/liblinphone] MESSAGE Register refresher [200] reason [OK] for proxy [<sip:example.com;transport=tcp>]
2021-03-23 14:16:20:468 [org.linphone.debug/liblinphone] MESSAGE Proxy config [0x74f3c44680] for identity ["[redacted]" <sip:my_sip_account@example.com>] moving from state [LinphoneRegistrationProgress] to [LinphoneRegistrationOk] on core [0x74f3c9f400]
2021-03-23 14:16:20:468 [org.linphone.debug/liblinphone] MESSAGE Updating friends for identity ["[redacted]" <sip:my_sip_account@example.com>] on core [0x74f3c9f400]
2021-03-23 14:16:20:468 [org.linphone.debug/liblinphone] MESSAGE Friends list [0x74e3acff60] subscription update skipped since subscriptions not enabled yet
2021-03-23 14:16:20:468 [org.linphone.debug/liblinphone] MESSAGE _linphone_update_dependent_proxy_config(): 0x74f3c44680 is registered, checking for [0x74f3c44680] ->dependency=0x0
2021-03-23 14:16:20:510 [org.linphone.debug/lime] MESSAGE Create Lime user sip:my_sip_account@10.75.22.32
2021-03-23 14:16:20:585 [org.linphone.debug/belle-sip] MESSAGE belle_sip_channel_init(); peer_cname=(null)
2021-03-23 14:16:20:588 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip send channel] acquired [ref=0x2be2]
2021-03-23 14:16:20:588 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): cast long of wakelock 11234
2021-03-23 14:16:20:588 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: starting send background task with id=[2be2].
2021-03-23 14:16:20:588 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: starting resolution of lime.linphone.org
2021-03-23 14:16:20:588 [org.linphone.debug/belle-sip] MESSAGE channel[0x74d2a64680]: entering state RES_IN_PROGRESS
2021-03-23 14:16:20:588 [org.linphone.debug/belle-sip] MESSAGE resconf using application supplied dns server list.
2021-03-23 14:16:20:588 [org.linphone.debug/belle-sip] MESSAGE Resolver is using DNS server(s):
2021-03-23 14:16:20:588 [org.linphone.debug/belle-sip] MESSAGE  fd31:aeb1:48df::2
2021-03-23 14:16:20:588 [org.linphone.debug/belle-sip] MESSAGE  10.75.22.247
2021-03-23 14:16:20:588 [org.linphone.debug/belle-sip] MESSAGE Resolver is not using connect().
2021-03-23 14:16:20:589 [org.linphone.debug/belle-sip] MESSAGE resolver_process_data dns_res_check() in progress
2021-03-23 14:16:20:589 [org.linphone.debug/belle-sip] MESSAGE DNS resolution awaiting response, queued to main loop
2021-03-23 14:16:20:591 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [registration_state_changed]
2021-03-23 14:16:20:591 [org.linphone.debug/belle-sip] MESSAGE Changing [client] [REGISTER] transaction [0x74d2b1a6c0], from state [COMPLETED] to [TERMINATED]
2021-03-23 14:16:20:591 [org.linphone.debug/belle-sip] MESSAGE Client internal REGISTER transaction [0x74d2b1a6c0] terminated
2021-03-23 14:16:20:591 [org.linphone.debug/belle-sip] MESSAGE transaction [0x74d2b1a6c0]: ending transaction background task with id=[2bc6].
2021-03-23 14:16:20:593 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x2bc6]
2021-03-23 14:16:20:593 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a38f40]: ending recv background task with id=[2c06].
2021-03-23 14:16:20:594 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x2c06]
2021-03-23 14:16:20:595 [org.linphone.debug/belle-sip] WARNING There is no object pool created in thread [501748835568]. Use belle_sip_object_pool_push() to create one. Unowned objects not unref'd will be leaked.
2021-03-23 14:16:20:600 [org.linphone.debug/belle-sip] MESSAGE lime.linphone.org resolved to 5.135.31.161
2021-03-23 14:16:20:600 [org.linphone.debug/belle-sip] MESSAGE channel[0x74d2a64680]: entering state RES_DONE
2021-03-23 14:16:20:600 [org.linphone.debug/belle-sip] MESSAGE channel[0x74d2a64680]: entering state CONNECTING
2021-03-23 14:16:20:600 [org.linphone.debug/belle-sip] MESSAGE Trying to connect to [TLS://5.135.31.161:443]
2021-03-23 14:16:20:714 [org.linphone.debug/linphone-android] MESSAGE [Contacts Loader] Found 221 native contacts plus 0 friends in the configuration file
2021-03-23 14:16:20:760 [org.linphone.debug/belle-sip] MESSAGE Channel [0x74d2a64680]: Connected at TCP level, now doing TLS handshake with cname=lime.linphone.org
2021-03-23 14:16:20:761 [org.linphone.debug/belle-sip] MESSAGE Channel [0x74d2a64680]: SSL handshake in progress...
2021-03-23 14:16:20:883 [org.linphone.debug/belle-sip] MESSAGE Found certificate depth=[2], flags=[]:
cert. version     : 3
serial number     : 44:AF:B0:80:D6:A3:27:BA:89:30:39:86:2E:F8:40:6B
issuer name       : O=Digital Signature Trust Co., CN=DST Root CA X3
subject name      : O=Digital Signature Trust Co., CN=DST Root CA X3
issued  on        : 2000-09-30 21:12:19
expires on        : 2021-09-30 14:01:15
signed using      : RSA with SHA1
RSA key size      : 2048 bits
basic constraints : CA=true
key usage         : Key Cert Sign, CRL Sign

2021-03-23 14:16:20:883 [org.linphone.debug/belle-sip] MESSAGE Found certificate depth=[1], flags=[]:
cert. version     : 3
serial number     : 40:01:75:04:83:14:A4:C8:21:8C:84:A9:0C:16:CD:DF
issuer name       : O=Digital Signature Trust Co., CN=DST Root CA X3
subject name      : C=US, O=Let's Encrypt, CN=R3
issued  on        : 2020-10-07 19:21:40
expires on        : 2021-09-29 19:21:40
signed using      : RSA with SHA-256
RSA key size      : 2048 bits
basic constraints : CA=true, max_pathlen=0
key usage         : Digital Signature, Key Cert Sign, CRL Sign
ext key usage     : TLS Web Server Authentication, TLS Web Client Authentication
certificate policies : ???, ???

2021-03-23 14:16:20:883 [org.linphone.debug/belle-sip] MESSAGE Found certificate depth=[0], flags=[]:
cert. version     : 3
serial number     : 04:2C:2E:55:67:E0:D2:CA:53:3F:EA:DA:A3:78:8D:69:86:5A
issuer name       : C=US, O=Let's Encrypt, CN=R3
subject name      : CN=sip7.linphone.org
issued  on        : 2021-02-18 17:16:57
expires on        : 2021-05-19 17:16:57
signed using      : RSA with SHA-256
RSA key size      : 4096 bits
basic constraints : CA=false
subject alt name  :
    dNSName : lime.linphone.org
    dNSName : sip7.linhome.org
    dNSName : sip7.linphone.org
    dNSName : subscribe.linhome.org
    dNSName : subscribe.linphone.org
key usage         : Digital Signature, Key Encipherment
ext key usage     : TLS Web Server Authentication, TLS Web Client Authentication
certificate policies : ???, ???

2021-03-23 14:16:20:930 [org.linphone.debug/belle-sip] MESSAGE Channel [0x74d2a64680]: SSL handshake in progress...
2021-03-23 14:16:20:977 [org.linphone.debug/linphone-android] MESSAGE [Contacts Loader] Background synchronization finished
2021-03-23 14:16:20:978 [org.linphone.debug/linphone-android] MESSAGE [Contacts Loader] 142 contacts found in which 4 are SIP
2021-03-23 14:16:20:987 [org.linphone.debug/liblinphone] MESSAGE Unknown ccc for e164 like number [+883510009901886]
2021-03-23 14:16:20:998 [org.linphone.debug/liblinphone] MESSAGE Unknown ccc for e164 like number [+883510000000094]
2021-03-23 14:16:21:026 [org.linphone.debug/linphone-android] MESSAGE [Contacts Manager] Async fetching finished, notifying observers
2021-03-23 14:16:21:026 [org.linphone.debug/linphone-android] MESSAGE [Contacts Loader] Synchronization finished
2021-03-23 14:16:21:063 [org.linphone.debug/belle-sip] MESSAGE Channel [0x74d2a64680]: SSL handshake finished, SSL version is [TLSv1.2], selected ciphersuite is [TLS-ECDHE-RSA-WITH-AES-256-GCM-SHA384]
2021-03-23 14:16:21:063 [org.linphone.debug/belle-sip] MESSAGE Channel has local address 10.75.22.32:38536
2021-03-23 14:16:21:063 [org.linphone.debug/belle-sip] MESSAGE channel[0x74d2a64680]: entering state READY
2021-03-23 14:16:21:064 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: found binary data in buffer, will stop logging it now.
2021-03-23 14:16:21:064 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: message sent to [TLS://lime.linphone.org:443], size: [3965] bytes
POST /lime-server/lime-server.php HTTP/1.1
User-Agent: Linphone/4.5.0-beta-38-g1e6441089 (Lime) Belle-sip/4.5.0
Content-Type: x3dh/octet-stream
From: sip:my_sip_account@10.75.22.32
Host: lime.linphone.org
Content-Length: 3737

   ... (first 231 bytes shown)
2021-03-23 14:16:21:064 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: ending send background task with id=[2be2].
2021-03-23 14:16:21:067 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x2be2]
2021-03-23 14:16:21:217 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2bca]
2021-03-23 14:16:21:218 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): cast long of wakelock 11210
2021-03-23 14:16:21:218 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: starting recv background task with id=[2bca].
2021-03-23 14:16:21:219 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: received [646] new bytes from [TLS://lime.linphone.org:443]:
HTTP/1.1 401 Unauthorized
Date: Tue, 23 Mar 2021 18:17:45 GMT
Server: Apache/2.4.34 (Red Hat) OpenSSL/1.0.2k-fips PHP/7.3.11
Strict-Transport-Security: max-age=63072000
X-Powered-By: PHP/7.3.11
WWW-Authenticate: [redacted]
WWW-Authenticate: [redacted]
Content-Length: 0
Content-Type: text/html; charset=UTF-8

2021-03-23 14:16:21:229 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680] [646] bytes parsed
2021-03-23 14:16:21:230 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680] read [0] bytes of body from [lime.linphone.org:443]
2021-03-23 14:16:21:231 [org.linphone.debug/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_sip_account, realm=asterisk
2021-03-23 14:16:21:232 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: found binary data in buffer, will stop logging it now.
2021-03-23 14:16:21:232 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: message sent to [TLS://lime.linphone.org:443], size: [4302] bytes
POST /lime-server/lime-server.php HTTP/1.1
User-Agent: Linphone/4.5.0-beta-38-g1e6441089 (Lime) Belle-sip/4.5.0
Content-Type: x3dh/octet-stream
From: sip:my_sip_account@10.75.22.32
Host: lime.linphone.org
Content-Length: 3737
Authorization: :[redacted]

   ... (first 568 bytes shown)
2021-03-23 14:16:21:232 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: ending recv background task with id=[2bca].
2021-03-23 14:16:21:235 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x2bca]
2021-03-23 14:16:21:235 [org.linphone.debug/belle-sip] MESSAGE Garbage collecting unowned object of type belle_sip_header_address_t
2021-03-23 14:16:21:396 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2bb6]
2021-03-23 14:16:21:396 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): cast long of wakelock 11190
2021-03-23 14:16:21:397 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: starting recv background task with id=[2bb6].
2021-03-23 14:16:21:398 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: received [646] new bytes from [TLS://lime.linphone.org:443]:
HTTP/1.1 401 Unauthorized
Date: Tue, 23 Mar 2021 18:17:45 GMT
Server: Apache/2.4.34 (Red Hat) OpenSSL/1.0.2k-fips PHP/7.3.11
Strict-Transport-Security: max-age=63072000
X-Powered-By: PHP/7.3.11
WWW-Authenticate: [redacted]
WWW-Authenticate: [redacted]
Content-Length: 0
Content-Type: text/html; charset=UTF-8

2021-03-23 14:16:21:409 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680] [646] bytes parsed
2021-03-23 14:16:21:409 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680] read [0] bytes of body from [lime.linphone.org:443]
2021-03-23 14:16:21:410 [org.linphone.debug/liblinphone] MESSAGE linphone_core_find_auth_info(): returning auth info username=my_sip_account, realm=asterisk
2021-03-23 14:16:21:412 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: found binary data in buffer, will stop logging it now.
2021-03-23 14:16:21:412 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: message sent to [TLS://lime.linphone.org:443], size: [4302] bytes
POST /lime-server/lime-server.php HTTP/1.1
User-Agent: Linphone/4.5.0-beta-38-g1e6441089 (Lime) Belle-sip/4.5.0
Content-Type: x3dh/octet-stream
From: sip:my_sip_account@10.75.22.32
Host: lime.linphone.org
Content-Length: 3737
Authorization: :[redacted]

   ... (first 568 bytes shown)
2021-03-23 14:16:21:412 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: ending recv background task with id=[2bb6].
2021-03-23 14:16:21:415 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x2bb6]
2021-03-23 14:16:21:415 [org.linphone.debug/belle-sip] MESSAGE Garbage collecting unowned object of type belle_sip_header_address_t
2021-03-23 14:16:21:586 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): Android wake lock [belle-sip recv channel] acquired [ref=0x2ba2]
2021-03-23 14:16:21:587 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_acquire(): cast long of wakelock 11170
2021-03-23 14:16:21:587 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: starting recv background task with id=[2ba2].
2021-03-23 14:16:21:588 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: received [646] new bytes from [TLS://lime.linphone.org:443]:
HTTP/1.1 401 Unauthorized
Date: Tue, 23 Mar 2021 18:17:45 GMT
Server: Apache/2.4.34 (Red Hat) OpenSSL/1.0.2k-fips PHP/7.3.11
Strict-Transport-Security: max-age=63072000
X-Powered-By: PHP/7.3.11
WWW-Authenticate: [redacted]
WWW-Authenticate: [redacted]
Content-Length: 0
Content-Type: text/html; charset=UTF-8

2021-03-23 14:16:21:608 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680] [646] bytes parsed
2021-03-23 14:16:21:609 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680] read [0] bytes of body from [lime.linphone.org:443]
2021-03-23 14:16:21:609 [org.linphone.debug/liblinphone] WARNING [LIME] user sip:my_sip_account@10.75.22.32 creation failed
2021-03-23 14:16:21:611 [org.linphone.debug/liblinphone] MESSAGE Linphone core [0x74f3c9f400] notified [imee_user_registration]
2021-03-23 14:16:21:634 [org.linphone.debug/belle-sip] MESSAGE channel [0x74d2a64680]: ending recv background task with id=[2ba2].
2021-03-23 14:16:21:637 [org.linphone.debug/belle-sip] MESSAGE bellesip_wake_lock_release(): Android wake lock released [ref=0x2ba2]

You don't think this deserves any consideration?

Viish commented 3 years ago

You can try to edit your DNS settings on your WiFi router to remove the IPv6 address if you want to test without it. You can also try to build this old branch I created 9 months ago because I thought IPv6 DNS might be an issue (and it was not, so it was never merged): feature/allow_ipv6_dns_servers_to_be_disabled. It does exactly what you want: remove IPv6 DNS server IP from DNS list if IPv6 is disabled at app level.

brianjmurrell commented 3 years ago

You can try to edit your DNS settings on your WiFi router to remove the IPv6 address if you want to test without it.

Heh. I wish I could. Even when I do that, for some reason it's still including the previously configured IPv6 resolver in the RAs. :-( Bug I suppose.

You can also try to build this old branch I created 9 months ago because I thought IPv6 DNS might be an issue (and it was not, so it was never merged): feature/allow_ipv6_dns_servers_to_be_disabled. It does exactly what you want: remove IPv6 DNS server IP from DNS list if IPv6 is disabled at app level.

Is that a linphone-android branch or an SDK branch? I don't see it in the list of branches in linphone-android.

Viish commented 3 years ago

Is that a linphone-android branch or an SDK branch?

SDK branch.

brianjmurrell commented 3 years ago

:-( I have never had any luck building the SDK.

brianjmurrell commented 3 years ago

Before I continue spending more and more time on this, If it can be proven that using IPv6 resolvers is troublesome, will you consider doing something about it? Or is this just an issue you just don't want to deal with and I'd be wasting my time?

FWIW, there is quite a bit of Q&A and discussion in various Internet forums about a number of vendors at least (if not ASOP itself) sleeping IPv6 even when they keep IPv4 awake on a dozing phone. I suspect your instincts were not wrong when you created the feature/allow_ipv6_dns_servers_to_be_disabled branch.

brianjmurrell commented 3 years ago

Oh, and FWIW, you were correct about the client knowing it was still connected when receiving a push. I had an error in my code on the server to detect if the client is still connected and it was only detecting it some of the time.

Viish commented 3 years ago

So far we haven't have a real proof of an IPv6 DNS issue, that's why the branch was never merged. As of today you're the only one, so we won't make any change regarding this. If in the future we see more reports of this kind of problem, we will take actions then.

brianjmurrell commented 3 years ago

It doesn't seem like proof that after linphone-android is woken by a push, after several seconds, the DNS requests it makes to REGISTER are on IPv4:

09:15:06.311518 IP 10.75.22.32.42241 > 10.75.22.247.domain: 16538+ SRV? _sip._tcp.example.com. (43)
09:15:06.311838 IP 10.75.22.247.domain > 10.75.22.32.42241: 16538* 1/1/5 SRV pbx.example.com.:5060 0 0 (219)
09:15:06.311898 IP 10.75.22.32.48189 > 10.75.22.247.domain: 14212+ A? example.com. (33)
09:15:06.312081 IP 10.75.22.247.domain > 10.75.22.32.48189: 14212* 0/1/0 (87)
09:15:08.344744 IP 10.75.22.32.36432 > 10.75.22.247.domain: 45804+ A? pbx.example.com. (37)
09:15:08.344967 IP 10.75.22.247.domain > 10.75.22.32.36432: 45804* 1/1/3 A 10.75.22.8 (146)

Rather than an IPv6 query, when we know (a) that linphone knows there is an IPv6 resolver and (b) that it tries that first, yet none of the packets from that query actually make it to the server and only the fall-back IPv4 query makes it.

As of today you're the only one, so we won't make any change regarding this.

Given the hurdles to actually making linphone-android work with Google's push notifications, I wonder how many people are actually using push with linphone-android, with IPv6 and are analysing the performance of the push->wake->REGISTER as closely as I am. I suspect that number is quite small. As small as 1 even. :-)

What might be most interesting would be for linphone to do a happy-eyeballs DNS query when receiving a push and see how much more quickly the IPv4 response comes than the IPv6 response. I suspect if you did that, you'd get more/better data on the poor performance/experience of IPv6 on sleeping phones. phone this data home with all of the other phone-home stuff linphone does to gather some data. linphone-android should probably even tell the user than IPv6 while sleeping on their phone performs poorly.

BelledonneCommunications / linphone-android

Doesn't always re-REGISTER on push even without a socket open #1323