Closed eduardoaddad closed 1 year ago
Same issue on Samsung Galaxy S20, latest OneUI 3.1 with Android 11. Still occurs on AdGuard 4.0.50 Log:
15:15:51.259 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.259153] [3672] [DNS proxy] [info] Done
15:15:51.261 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.261700] [3672] [DNS forwarder] [info] Deinitializing...
15:15:51.263 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.263195] [3672] [DNS forwarder] [info] Cancelling unstarted async requests...
15:15:51.263 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.263467] [3672] [DNS forwarder] [info] Wait for started async requests to finish...
15:15:51.263 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.263646] [3672] [DNS forwarder] [info] Done
15:15:51.263 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.263839] [3672] [DNS forwarder] [info] All async requests are cancelled
15:15:51.264 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.264019] [3672] [DNS forwarder] [info] Destroying upstreams...
15:15:51.271 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.271375] [3672] [DNS forwarder] [info] Done
15:15:51.271 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.271920] [3672] [DNS forwarder] [info] Destroying fallback upstreams...
15:15:51.272 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.272051] [3672] [DNS forwarder] [info] Done
15:15:51.272 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.272241] [3672] [DNS forwarder] [info] Destroying DNS filter...
15:15:51.275 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.275426] [3672] [DNS forwarder] [info] Done
15:15:51.275 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.275961] [3672] [DNS forwarder] [info] Clearing cache...
15:15:51.276 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.276118] [3672] [DNS forwarder] [info] Done
15:15:51.276 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.276342] [3672] [DNS forwarder] [info] Deinitialized
15:15:51.276 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.276537] [3672] [DNS proxy] [info] Proxy module deinitialized
15:15:51.308 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.308827] [3672] [DNS proxy] [info] Initializing proxy module...
15:15:51.309 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.309621] [3672] [DNS forwarder] [info] Initializing forwarder...
15:15:51.316 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.316392] [3672] [DNS forwarder] [info] Initializing upstreams...
15:15:51.317 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.317318] [3672] [DNS forwarder] [info] Initializing upstream 10.166.244.46...
15:15:51.319 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.319925] [3672] [DNS forwarder] [info] Upstream created successfully
15:15:51.320 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.320593] [3672] [DNS forwarder] [info] Initializing upstream 10.166.244.62...
15:15:51.321 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.321375] [3672] [DNS forwarder] [info] Upstream created successfully
15:15:51.321 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.321916] [3672] [DNS forwarder] [info] Upstreams initialized
15:15:51.322 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:51.322132] [3672] [DNS forwarder] [info] Initializing the filtering module...
15:15:53.183 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.183910] [3672] [100001::filter_100001.txt] [info] Unique domains table size: 67841
15:15:53.184 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.184637] [3672] [100001::filter_100001.txt] [info] Non-unique domains table size: 5
15:15:53.184 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.184919] [3672] [100001::filter_100001.txt] [info] Shortcuts table size: 0
15:15:53.185 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.185119] [3672] [100001::filter_100001.txt] [info] Leftovers table size: 0
15:15:53.185 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.185405] [3672] [100001::filter_100001.txt] [info] Badfilter table size: 0
15:15:53.185 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.185530] [3672] [100001::filter_100001.txt] [info] Approximate memory usage: 1061K
15:15:53.185 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.185648] [3672] [dnsfilter] [info] Filter 100001 added successfully
15:15:53.188 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.188861] [3672] [-3::filter_-3.txt] [info] Unique domains table size: 16
15:15:53.189 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.189568] [3672] [-3::filter_-3.txt] [info] Non-unique domains table size: 1
15:15:53.189 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.189903] [3672] [-3::filter_-3.txt] [info] Shortcuts table size: 0
15:15:53.190 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.190086] [3672] [-3::filter_-3.txt] [info] Leftovers table size: 0
15:15:53.190 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.190325] [3672] [-3::filter_-3.txt] [info] Badfilter table size: 0
15:15:53.190 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.190473] [3672] [-3::filter_-3.txt] [info] Approximate memory usage: 1K
15:15:53.190 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.190605] [3672] [dnsfilter] [info] Filter -3 added successfully
15:15:53.206 [main] INFO com.adguard.android.AdguardService - Trim memory 15 called
15:15:53.578 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.578784] [3672] [15::filter_15.txt] [info] Unique domains table size: 37061
15:15:53.579 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.579477] [3672] [15::filter_15.txt] [info] Non-unique domains table size: 9
15:15:53.579 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.579782] [3672] [15::filter_15.txt] [info] Shortcuts table size: 323
15:15:53.579 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.579929] [3672] [15::filter_15.txt] [info] Leftovers table size: 23
15:15:53.580 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.580032] [3672] [15::filter_15.txt] [info] Badfilter table size: 0
15:15:53.580 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.580253] [3672] [15::filter_15.txt] [info] Approximate memory usage: 646K
15:15:53.580 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.580358] [3672] [dnsfilter] [info] Filter 15 added successfully
15:15:53.580 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.580493] [3672] [DNS forwarder] [info] Filtering module initialized
15:15:53.580 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.580612] [3672] [DNS forwarder] [info] Forwarder initialized
15:15:53.580 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.580743] [3672] [DNS proxy] [info] Initializing listeners...
15:15:53.582 [threadmanager-cached-pool-18-thread-24] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.582073] [3672] [DNS proxy] [info] Proxy module initialized
15:15:53.595 [Thread-159] INFO com.adguard.dnslibs.proxy.DnsProxy - [2021-04-12 15:15:53.594491] [6306] [listener(UDP 127.0.0.1:1103)] [info] Listening on 127.0.0.1:1103 (UDP)
15:15:54.780 [main] INFO com.adguard.android.AdguardService - Trim memory 10 called
15:15:55.734 [main] INFO com.adguard.android.AdguardService - Trim memory 10 called
15:15:57.321 [main] INFO com.adguard.android.AdguardService - Trim memory 15 called
15:15:57.723 [main] INFO com.adguard.android.service.s - The event 'on Megazord service disconnected' received
15:15:57.740 [protection-pool-12-thread-1] INFO c.a.a.service.ProtectionServiceImpl - Restarting protection...
Oh, I know why the application does it. This architecture bug a little bit bigger than we could imagine.
It will already totally improved in the 4.0 version. Please stay on the nightly version. We will deploy a big update soon.
As a workaround solution is not to use the app autostart.
@eduardoaddad how often does it happen? Could you please send me full logs once it happens next time to am@adguard.com
@eduardoaddad how often does it happen? Could you please send me full logs once it happens next time to
am@adguard.com
2-4 times a day but not every day
Is this bug getting fixed soon? It's critical as it stops randomly during idle multiple times per day. Many times I'll only realize after seeing cookie notices (then it's too late and privacy was breached).
Also there's nothing of use in the logs.
02:05:13.748 [protection-pool-12-thread-1] ERROR com.adguard.android.filtering.api.g - An error occurred while starting protection. Making sure that the proxy servers are stopped
02:09:58.403 [Thread-1484] ERROR Native.AGAndroidProxyServer - onNewConnection: null native inet address
06:56:24.380 [Thread-1621] ERROR Native.AGAndroidProxyServer - onNewConnection: null native inet address
09:08:48.665 [Thread-1653] ERROR Native.AGProxyServer - failed to resolve local.adguard.org (No address associated with hostname)
09:08:48.671 [Thread-1654] ERROR Native.AGProxyServer - failed to resolve dns.adguard.com (No address associated with hostname)
09:19:24.972 [protection-pool-12-thread-1] ERROR com.adguard.android.filtering.api.g - An error occurred while starting protection. Making sure that the proxy servers are stopped
09:24:55.923 [main] ERROR com.adguard.android.a.f - Thread: Thread[main,5,main]; Uncaught exception:
android.app.RemoteServiceException: Context.startForegroundService() did not then call Service.startForeground(): ServiceRecord{6040d64 u0 com.adguard.android/.AdguardService}
at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2240)
at android.os.Handler.dispatchMessage(Handler.java:106)
at android.os.Looper.loop(Looper.java:246)
at android.app.ActivityThread.main(ActivityThread.java:8506)
at java.lang.reflect.Method.invoke(Native Method)
at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:602)
at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:1130)
It happened after Android 11 update. App is not getting killed, it's in always allowed list of apps. It's very likely the WiFi goes to sleep, but there's no power savings for WiFi enabled.
@ValiumBear @eduardoaddad @123jjck
Since we cannot reproduce it on our side, it would really help if you could record & send the logs to us.
Once the issue happens again, simply do the following:
devteam@adguard.com
It seems the issue is now fixed after Android 12/One UI 4 upgrade. I have not had noticable disconnects since a month. Can others confirm?
This issue should be gone in the 4.0 version, please follow updates
Version: 3.6.1 Android version: 11 Device: Samsung Galaxy S21 Background : yes
Often the application crashes and emits on the main screen or during open applications that AdGuard was closed due to an error
java.lang.IllegalStateException: Not allowed to start service Intent { act=Start cmp=com.adguard.android/.filtering.api.LocalVpnService (has extras) }: app is in background uid UidRecord{867c9ba u0a313 CEM bg:+1d23h49m15s441ms idle change:cached procs:1 seq(0,0,0)} (this exception is shown in the log)
Log: