OH and local network

11198 · September 15, 2021, 4:19pm

Platform information:
- OS: Windows 7
- Java Runtime Environment: zulu11.50.19-ca-jdk11.0.12-win_x64
- openHAB version: 3

Help solve the problem. I am at home, the WiFi signal is excellent, the internet works great. And still, a smartphone to openHAB almost always connects not over a local network, but through the cloud. About 8 times out of 10.

I will add. The IP on the OH host is, of course, static. Ping is wonderful. If you connect to the local OH from a laptop (not from the one on which the OH is installed, of course) by IP address, there are no problems.

11194 · September 15, 2021, 7:34pm

Предположений может быть множество, может динамический ИП-адрес у хоста Опенхаба, может на смарте включено ускорение интернета через мобильные сети, и смарт по умолчанию лезет именно через них, может виндошный фаерволл закрывает порт, может роутер от провайдера чудит, может Вай-фай через беспроводной репитер - там что угодно может теряться. Как вписан адрес Опенхаба в локалке? Хотя бы пинги сделайте с телефона до хоста Опенхаба. Потом в логи приложения посмотрите.

Sascha_Billian · September 15, 2021, 7:39pm

As this is an international forum, I ask you to switch to English so others can follow the thread as well.

11194 · September 17, 2021, 11:21pm

I meant ping from smartphone. And logs from Openhab App on smartphone - the best way to see, why it`s connecting to cloud.

11198 · September 18, 2021, 4:51am

--------- beginning of system
09-18 05:04:10.168 23026 23026 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 837ms late (wall=241ms running=141ms ClientTransaction{ callbacks=[android.app.servertransaction.LaunchActivityItem] lifecycleRequest=android.app.servertransaction.ResumeActivityItem }) because of 2 msg, msg 2 took 832ms (seq=2 running=381ms runnable=55ms io=219ms late=10ms h=android.app.ActivityThread$H w=110)
09-18 05:04:10.169 23026 23026 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 1078ms late (wall=1ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 3 msg, msg 2 took 832ms (seq=2 running=381ms runnable=55ms io=219ms late=10ms h=android.app.ActivityThread$H w=110), msg 3 took 241ms (seq=3 running=141ms runnable=20ms io=48ms late=837ms h=android.app.ActivityThread$H w=159)
--------- beginning of events
09-18 06:58:44.960 29205 29205 I auditd : type=1400 audit(0.0:25522): avc: denied { read } for comm=“b.habdroid.beta” name=“u:object_r:vendor_default_prop:s0” dev=“tmpfs” ino=21833 scontext=u:r:untrusted_app:s0:c80,c257,c512,c768 tcontext=u:object_r:vendor_default_prop:s0 tclass=file permissive=0
09-18 06:58:45.540 29205 29205 I am_on_create_called: [0,org.openhab.habdroid.ui.MainActivity,performCreate,105]
09-18 06:58:45.570 29205 29205 I am_on_start_called: [0,org.openhab.habdroid.ui.MainActivity,handleStartActivity,29]
09-18 06:58:45.612 29205 29205 I am_on_resume_called: [0,org.openhab.habdroid.ui.MainActivity,RESUME_ACTIVITY,40]
09-18 06:58:45.642 29205 29205 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 917ms late (wall=258ms running=144ms ClientTransaction{ callbacks=[android.app.servertransaction.LaunchActivityItem] lifecycleRequest=android.app.servertransaction.ResumeActivityItem }) because of 2 msg, msg 2 took 917ms (seq=2 running=357ms runnable=56ms io=299ms late=7ms h=android.app.ActivityThread$H w=110)
09-18 06:58:45.642 29205 29205 I am_on_top_resumed_gained_called: [0,org.openhab.habdroid.ui.MainActivity,topStateChangedWhenResumed]
09-18 06:58:45.642 29205 29205 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 1176ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 3 msg, msg 2 took 917ms (seq=2 running=357ms runnable=56ms io=299ms late=7ms h=android.app.ActivityThread$H w=110), msg 3 took 258ms (seq=3 running=144ms runnable=22ms io=59ms late=917ms h=android.app.ActivityThread$H w=159)
09-18 06:59:00.704 29205 29205 I am_on_top_resumed_lost_called: [0,org.openhab.habdroid.ui.MainActivity,topStateChangedWhenResumed]
09-18 06:59:00.711 29205 29205 I am_on_paused_called: [0,org.openhab.habdroid.ui.MainActivity,performPause,5]
09-18 06:59:01.276 29205 29205 I am_on_stop_called: [0,org.openhab.habdroid.ui.MainActivity,LIFECYCLER_STOP_ACTIVITY,6]
09-18 06:59:01.281 29205 29205 I am_on_destroy_called: [0,org.openhab.habdroid.ui.MainActivity,performDestroy,5]
09-18 07:05:50.085 29205 29205 I am_on_create_called: [0,org.openhab.habdroid.ui.MainActivity,performCreate,232]
09-18 07:05:50.173 29205 29205 I am_on_start_called: [0,org.openhab.habdroid.ui.MainActivity,handleStartActivity,79]
09-18 07:05:50.206 29205 29205 I am_on_resume_called: [0,org.openhab.habdroid.ui.MainActivity,RESUME_ACTIVITY,25]
09-18 07:05:50.217 29205 29205 I am_on_top_resumed_gained_called: [0,org.openhab.habdroid.ui.MainActivity,topStateChangedWhenResumed]
09-18 07:05:50.217 29205 29205 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 637ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 1 msg, msg 1 took 638ms (seq=707 running=243ms runnable=58ms io=115ms late=1ms h=android.app.ActivityThread$H w=159)
09-18 07:05:53.876 29205 30522 I dvm_lock_sample: [org.openhab.habdroid.beta,0,OkHttp https:///…,9,DiskLruCache.kt,-1,okhttp3.internal.cache.DiskLruCache$Snapshot okhttp3.internal.cache.DiskLruCache.get(java.lang.String),-,-1,okhttp3.internal.cache.DiskLruCache$Snapshot okhttp3.internal.cache.DiskLruCache.get(java.lang.String),1]
09-18 07:05:53.895 29205 30525 I dvm_lock_sample: [org.openhab.habdroid.beta,0,OkHttp https:///…,13,DiskLruCache.kt,-1,okhttp3.internal.cache.DiskLruCache$Snapshot okhttp3.internal.cache.DiskLruCache.get(java.lang.String),-,-1,okhttp3.internal.cache.DiskLruCache$Snapshot okhttp3.internal.cache.DiskLruCache.get(java.lang.String),2]
09-18 07:06:15.418 29205 29205 I am_on_top_resumed_lost_called: [0,org.openhab.habdroid.ui.MainActivity,topStateChangedWhenResumed]
09-18 07:06:15.426 29205 29205 I am_on_paused_called: [0,org.openhab.habdroid.ui.MainActivity,performPause,4]
09-18 07:06:15.953 29205 29205 I am_on_stop_called: [0,org.openhab.habdroid.ui.MainActivity,LIFECYCLER_STOP_ACTIVITY,19]
09-18 07:06:16.025 29205 29205 I am_on_destroy_called: [0,org.openhab.habdroid.ui.MainActivity,performDestroy,72]
09-18 07:38:38.261 32227 32227 I auditd : type=1400 audit(0.0:25577): avc: denied { read } for comm=“b.habdroid.beta” name=“u:object_r:vendor_default_prop:s0” dev=“tmpfs” ino=21833 scontext=u:r:untrusted_app:s0:c80,c257,c512,c768 tcontext=u:object_r:vendor_default_prop:s0 tclass=file permissive=0
09-18 07:38:38.810 32227 32227 I am_on_create_called: [0,org.openhab.habdroid.ui.MainActivity,performCreate,78]
09-18 07:38:38.844 32227 32227 I am_on_start_called: [0,org.openhab.habdroid.ui.MainActivity,handleStartActivity,32]
09-18 07:38:38.882 32227 32227 I am_on_resume_called: [0,org.openhab.habdroid.ui.MainActivity,RESUME_ACTIVITY,36]
09-18 07:38:38.903 32227 32227 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 855ms late (wall=209ms running=135ms ClientTransaction{ callbacks=[android.app.servertransaction.LaunchActivityItem] lifecycleRequest=android.app.servertransaction.ResumeActivityItem }) because of 1 msg, msg 1 took 855ms (seq=2 running=398ms runnable=72ms io=199ms late=2ms h=android.app.ActivityThread$H w=110)
09-18 07:38:38.903 32227 32227 I am_on_top_resumed_gained_called: [0,org.openhab.habdroid.ui.MainActivity,topStateChangedWhenResumed]
09-18 07:38:38.904 32227 32227 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 1064ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 2 msg, msg 1 took 855ms (seq=2 running=398ms runnable=72ms io=199ms late=2ms h=android.app.ActivityThread$H w=110), msg 2 took 209ms (seq=3 running=135ms runnable=18ms io=29ms late=855ms h=android.app.ActivityThread$H w=159)
09-18 07:38:41.184 32227 32227 W BpBinder: Slow Binder: BpBinder transact took 404 ms, interface=android.content.pm.IShortcutService, code=4 oneway=false
09-18 07:38:41.184 32227 32227 I binder_sample: [android.content.pm.IShortcutService,4,405,org.openhab.habdroid.beta,81]
09-18 07:38:41.227 32227 32227 W Looper : Slow Looper main: doFrame is 419ms late because of 3 msg, msg 1 took 418ms (seq=148 running=6ms h=android.os.Handler c=kotlinx.coroutines.internal.DispatchedContinuation)
09-18 07:38:41.492 32227 32366 I dvm_lock_sample: [org.openhab.habdroid.beta,0,OkHttp http://:8080/…,26,DiskLruCache.kt,-1,okhttp3.internal.cache.DiskLruCache$Snapshot okhttp3.internal.cache.DiskLruCache.get(java.lang.String),-,-1,okhttp3.internal.cache.DiskLruCache$Snapshot okhttp3.internal.cache.DiskLruCache.get(java.lang.String),5]
09-18 07:38:47.172 32227 32227 I am_on_top_resumed_lost_called: [0,org.openhab.habdroid.ui.MainActivity,topStateChangedWhenResumed]
09-18 07:38:47.181 32227 32227 I am_on_paused_called: [0,org.openhab.habdroid.ui.MainActivity,performPause,5]
09-18 07:38:47.622 32227 32227 I am_on_stop_called: [0,org.openhab.habdroid.ui.MainActivity,LIFECYCLER_STOP_ACTIVITY,5]
09-18 07:38:47.629 32227 32227 I am_on_destroy_called: [0,org.openhab.habdroid.ui.MainActivity,performDestroy,4]
--------- beginning of main
09-18 07:40:59.608 32227 32227 I chatty : uid=10336(org.openhab.habdroid.beta) expire 319 lines
09-18 07:40:59.922 32227 32227 I am_on_create_called: [0,org.openhab.habdroid.ui.MainActivity,performCreate,218]
09-18 07:41:00.100 32227 32227 I am_on_start_called: [0,org.openhab.habdroid.ui.MainActivity,handleStartActivity,175]
09-18 07:41:00.100 32227 32227 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onStart took 175ms
09-18 07:41:00.117 32227 32227 I am_on_resume_called: [0,org.openhab.habdroid.ui.MainActivity,RESUME_ACTIVITY,15]
09-18 07:41:00.138 32227 32227 I am_on_top_resumed_gained_called: [0,org.openhab.habdroid.ui.MainActivity,topStateChangedWhenResumed]
09-18 07:41:00.139 32227 32227 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 527ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 1 msg, msg 1 took 526ms (seq=367 running=253ms runnable=96ms io=83ms h=android.app.ActivityThread$H w=159)
09-18 07:41:00.144 32227 32376 I chatty : uid=10336(org.openhab.habdroid.beta) DefaultDispatch expire 2 lines
09-18 07:41:00.177 32227 32295 I chatty : uid=10336(org.openhab.habdroid.beta) RenderThread expire 5 lines
09-18 07:41:00.942 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.942 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.943 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.944 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.947 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.948 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.949 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.950 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.953 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.953 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.963 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.964 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.967 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.967 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.968 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.970 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:00.971 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:01.012 32227 32227 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.MainActivity@90c2e22
09-18 07:41:01.020 32227 32726 D FcmRegistrationWorker: Run with action org.openhab.habdroid.action.REGISTER_GCM
09-18 07:41:01.039 32227 32726 D FcmRegistrationWorker: Register device at openHAB-cloud with URL: addAndroidRegistration

11194 · September 19, 2021, 2:52pm

Now let’s call @mueller-ma for help =)
Thats’s not a clear app start, isn’t it? Cause my logs are different from yours, there is a server config load, then availability check, then connect.

mueller-ma · September 20, 2021, 3:32pm

I recommend to turn on “Extended error messages” and make a screenshot of the error screen.

11198 · September 21, 2021, 5:54pm

09-21 05:12:26.765 14775 14775 W Looper : Slow Looper main: Long Msg: seq=2 plan=05:12:25.381 late=30ms wall=1353ms running=504ms runnable=109ms io=415ms h=android.app.ActivityThread$H w=110

09-21 05:12:27.035 14775 14775 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 1362ms late (wall=270ms running=146ms ClientTransaction{ callbacks=[android.app.servertransaction.LaunchActivityItem] lifecycleRequest=android.app.servertransaction.ResumeActivityItem }) because of 2 msg, msg 2 took 1353ms (seq=2 running=504ms runnable=109ms io=415ms late=30ms h=android.app.ActivityThread$H w=110)

09-21 05:12:27.035 14775 14775 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 1632ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 3 msg, msg 2 took 1353ms (seq=2 running=504ms runnable=109ms io=415ms late=30ms h=android.app.ActivityThread$H w=110), msg 3 took 270ms (seq=3 running=146ms runnable=15ms io=61ms late=1362ms h=android.app.ActivityThread$H w=159)

09-21 05:12:49.621 14775 14775 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onPause took 57ms

09-21 05:13:29.952 14775 14775 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onStart took 257ms

09-21 05:13:30.131 14775 14775 W Looper : Slow Looper main: doFrame is 332ms late because of 1 msg, msg 1 took 340ms (seq=549 running=66ms runnable=38ms io=107ms late=18ms h=android.app.ActivityThread$H w=159)

09-21 05:36:00.682 14775 14775 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 538ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 1 msg, msg 1 took 537ms (seq=1568 running=260ms runnable=70ms io=104ms late=1ms h=android.app.ActivityThread$H w=159)

09-21 05:37:10.938 14775 14775 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onPause took 116ms

09-21 05:37:11.220 14775 14775 W BpBinder: Slow Binder: BpBinder transact took 281 ms, interface=android.app.IActivityTaskManager, code=20 oneway=false

09-21 05:37:11.358 14775 14775 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onStop took 120ms

09-21 05:37:52.056 14775 14775 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onStart took 225ms

09-21 05:37:52.490 14775 14775 W Looper : Slow Looper main: doFrame is 387ms late because of 8 msg, msg 1 took 387ms (seq=2224 running=61ms runnable=15ms io=45ms reclaim=14ms late=275ms h=android.view.Choreographer$FrameHandler c=android.view.Choreographer$FrameDisplayEventReceiver)

09-21 05:38:01.581 14775 14775 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onDestroy took 103ms

09-21 05:38:02.499 14775 14775 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onStart took 120ms

09-21 05:38:02.553 14775 14775 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 374ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 1 msg, msg 1 took 374ms (seq=2350 running=186ms runnable=51ms io=28ms h=android.app.ActivityThread$H w=159)

09-21 05:38:14.769 14775 14775 I Timeline: Timeline: Activity_launch_request time:182993628

09-21 05:38:15.183 14775 14775 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.PreferencesActivity onStart took 314ms

09-21 05:38:15.192 14775 14775 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.PreferencesActivity is 398ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 4 msg, msg 4 took 392ms (seq=2611 running=76ms runnable=10ms io=148ms late=6ms h=android.app.ActivityThread$H w=159)

09-21 05:38:15.420 14775 14775 W Looper : Slow Looper main: doFrame is 384ms late because of 2 msg, msg 1 took 392ms (seq=2611 running=76ms runnable=10ms io=148ms late=6ms h=android.app.ActivityThread$H w=159)

09-21 05:52:47.837 19054 19054 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 968ms late (wall=270ms running=152ms ClientTransaction{ callbacks=[android.app.servertransaction.LaunchActivityItem] lifecycleRequest=android.app.servertransaction.ResumeActivityItem }) because of 2 msg, msg 2 took 968ms (seq=2 running=428ms runnable=107ms io=180ms late=11ms h=android.app.ActivityThread$H w=110)

09-21 05:52:47.837 19054 19054 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 1239ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 3 msg, msg 2 took 968ms (seq=2 running=428ms runnable=107ms io=180ms late=11ms h=android.app.ActivityThread$H w=110), msg 3 took 270ms (seq=3 running=152ms runnable=23ms io=67ms late=968ms h=android.app.ActivityThread$H w=159)

09-21 06:25:44.254 22443 22443 W Looper : Slow Looper main: Long Msg: seq=2 plan=06:25:42.770 late=8ms wall=1476ms running=593ms runnable=103ms io=396ms h=android.app.ActivityThread$H w=110

09-21 06:25:44.509 22443 22443 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 1474ms late (wall=255ms running=142ms ClientTransaction{ callbacks=[android.app.servertransaction.LaunchActivityItem] lifecycleRequest=android.app.servertransaction.ResumeActivityItem }) because of 1 msg, msg 1 took 1476ms (seq=2 running=593ms runnable=103ms io=396ms late=8ms h=android.app.ActivityThread$H w=110)

09-21 06:25:44.509 22443 22443 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 1727ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 2 msg, msg 1 took 1476ms (seq=2 running=593ms runnable=103ms io=396ms late=8ms h=android.app.ActivityThread$H w=110), msg 2 took 255ms (seq=3 running=142ms runnable=21ms io=57ms late=1474ms h=android.app.ActivityThread$H w=159)

09-21 06:42:36.285 23337 23337 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 863ms late (wall=238ms running=131ms ClientTransaction{ callbacks=[android.app.servertransaction.LaunchActivityItem] lifecycleRequest=android.app.servertransaction.ResumeActivityItem }) because of 1 msg, msg 1 took 917ms (seq=2 running=404ms runnable=65ms io=223ms late=12ms h=android.app.ActivityThread$H w=110)

09-21 06:42:36.285 23337 23337 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 1099ms late (wall=1ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 2 msg, msg 1 took 917ms (seq=2 running=404ms runnable=65ms io=223ms late=12ms h=android.app.ActivityThread$H w=110), msg 2 took 238ms (seq=3 running=131ms runnable=18ms io=58ms late=863ms h=android.app.ActivityThread$H w=159)

09-21 11:49:05.915 20856 20856 W Looper : Slow Looper main: Long Msg: seq=2 plan=11:49:04.853 late=18ms wall=1043ms running=327ms runnable=56ms io=454ms h=android.app.ActivityThread$H w=110

09-21 11:49:06.205 20856 20856 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 945ms late (wall=291ms running=123ms ClientTransaction{ callbacks=[android.app.servertransaction.LaunchActivityItem] lifecycleRequest=android.app.servertransaction.ResumeActivityItem }) because of 1 msg, msg 1 took 1043ms (seq=2 running=327ms runnable=56ms io=454ms late=18ms h=android.app.ActivityThread$H w=110)

09-21 11:49:06.205 20856 20856 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 1234ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 2 msg, msg 1 took 1043ms (seq=2 running=327ms runnable=56ms io=454ms late=18ms h=android.app.ActivityThread$H w=110), msg 2 took 291ms (seq=3 running=123ms runnable=17ms io=85ms late=945ms h=android.app.ActivityThread$H w=159)

09-21 15:01:10.116 8529 8529 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 936ms late (wall=285ms running=149ms ClientTransaction{ callbacks=[android.app.servertransaction.LaunchActivityItem] lifecycleRequest=android.app.servertransaction.ResumeActivityItem }) because of 2 msg, msg 2 took 928ms (seq=2 running=383ms runnable=54ms io=326ms late=9ms h=android.app.ActivityThread$H w=110)

09-21 15:01:10.116 8529 8529 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 1213ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 3 msg, msg 2 took 928ms (seq=2 running=383ms runnable=54ms io=326ms late=9ms h=android.app.ActivityThread$H w=110), msg 3 took 285ms (seq=3 running=149ms runnable=17ms io=84ms late=936ms h=android.app.ActivityThread$H w=159)

09-21 18:25:26.773 15848 15848 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 845ms late (wall=290ms running=143ms ClientTransaction{ callbacks=[android.app.servertransaction.LaunchActivityItem] lifecycleRequest=android.app.servertransaction.ResumeActivityItem }) because of 1 msg, msg 1 took 922ms (seq=2 running=380ms runnable=79ms io=251ms late=25ms h=android.app.ActivityThread$H w=110)

09-21 18:25:26.773 15848 15848 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 1134ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 2 msg, msg 1 took 922ms (seq=2 running=380ms runnable=79ms io=251ms late=25ms h=android.app.ActivityThread$H w=110), msg 2 took 290ms (seq=3 running=143ms runnable=19ms io=76ms late=845ms h=android.app.ActivityThread$H w=159)

09-21 20:00:08.279 22970 22970 W Looper : Slow Looper main: Long Msg: seq=2 plan=20:00:06.973 late=10ms wall=1297ms running=405ms runnable=168ms io=272ms reclaim=6ms h=android.app.ActivityThread$H w=110

09-21 20:00:08.646 22970 22970 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 1303ms late (wall=367ms running=168ms ClientTransaction{ callbacks=[android.app.servertransaction.LaunchActivityItem] lifecycleRequest=android.app.servertransaction.ResumeActivityItem }) because of 2 msg, msg 2 took 1297ms (seq=2 running=405ms runnable=168ms io=272ms reclaim=6ms late=10ms h=android.app.ActivityThread$H w=110)

09-21 20:00:08.650 22970 22970 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 1672ms late (wall=1ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 3 msg, msg 2 took 1297ms (seq=2 running=405ms runnable=168ms io=272ms reclaim=6ms late=10ms h=android.app.ActivityThread$H w=110), msg 3 took 367ms (seq=3 running=168ms runnable=17ms io=103ms late=1303ms h=android.app.ActivityThread$H w=159)

09-21 20:00:25.298 22970 22970 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onStart took 160ms

09-21 20:00:25.376 22970 22970 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 482ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 1 msg, msg 1 took 480ms (seq=157 running=196ms runnable=103ms io=25ms late=1ms h=android.app.ActivityThread$H w=159)

09-21 20:00:29.946 22970 22970 W Looper : Slow Looper main: Long Msg: seq=287 plan=20:00:27.580 late=90ms wall=2277ms running=311ms runnable=44ms io=1567ms h=android.os.Handler c=androidx.fragment.app.FragmentManager$5

09-21 20:00:30.071 22970 22970 W Looper : Slow Looper main: doFrame is 2333ms late because of 5 msg, msg 1 took 2277ms (seq=287 running=311ms runnable=44ms io=1567ms late=90ms h=android.os.Handler c=androidx.fragment.app.FragmentManager$5)

09-21 20:01:33.850 22970 22970 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onStop took 101ms

09-21 20:01:36.364 22970 22970 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onStart took 234ms

09-21 20:01:36.440 22970 22970 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 594ms late (wall=1ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 1 msg, msg 1 took 594ms (seq=1295 running=279ms runnable=108ms io=69ms h=android.app.ActivityThread$H w=159)

09-21 20:02:01.729 22970 22970 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onStart took 168ms

09-21 20:02:01.807 22970 22970 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 489ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 1 msg, msg 1 took 489ms (seq=2040 running=214ms runnable=112ms io=12ms h=android.app.ActivityThread$H w=159)

09-21 20:02:18.172 22970 22970 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onStart took 113ms

09-21 20:02:18.227 22970 22970 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 312ms late (wall=0ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 1 msg, msg 1 took 313ms (seq=2231 running=145ms runnable=38ms h=android.app.ActivityThread$H w=159)

09-21 20:03:00.169 22970 22970 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onStart took 150ms

09-21 20:03:00.238 22970 22970 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 404ms late (wall=1ms running=0ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 1 msg, msg 1 took 404ms (seq=3654 running=204ms runnable=60ms io=4ms h=android.app.ActivityThread$H w=159)

09-21 20:03:17.802 22970 22970 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onStart took 111ms

09-21 20:03:17.874 22970 22970 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 343ms late (wall=1ms running=1ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 1 msg, msg 1 took 344ms (seq=3986 running=173ms runnable=37ms h=android.app.ActivityThread$H w=159)

09-21 20:03:52.877 22970 22970 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity onStart took 125ms

09-21 20:03:52.924 22970 22970 W Looper : Slow Looper main: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.MainActivity is 319ms late (wall=1ms running=1ms ClientTransaction{ callbacks=[android.app.servertransaction.TopResumedActivityChangeItem] }) because of 1 msg, msg 1 took 319ms (seq=4136 running=149ms runnable=40ms late=1ms h=android.app.ActivityThread$H w=159)

11198 · September 21, 2021, 5:55pm

--------- beginning of main

09-21 20:44:16.504 28356 28356 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.LogActivity@e6db554

09-21 20:44:16.516 28356 28356 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.LogActivity@e6db554

09-21 20:44:16.531 28356 28356 D ForceDarkHelper: updateByCheckExcludeList: pkg: org.openhab.habdroid.beta activity: org.openhab.habdroid.ui.LogActivity@e6db554

09-21 20:44:17.593 28356 28356 D TextClassifierService: No configured system TextClassifierService

09-21 20:44:17.594 28356 30580 D TextClassifierService: No configured system TextClassifierService

09-21 20:44:17.913 28356 28356 I sysui_multi_action: [757,1100,758,1103,759,1,806,org.openhab.habdroid.beta,1117,1498,1118,1421,1119,c9cd404a-1907-40ac-9a3b-dff0c94cead4,1120,2,1250,-29557,1251,229,1252,0,1253,1,1254,1255,textview,1256,und_v713]

09-21 20:44:17.914 28356 28356 W InputEventReceiver: App Input: Dispatching InputEvent took 324ms in main thread! (MotionEvent: event_seq=602, seq=324979, action=ACTION_UP)

09-21 20:44:17.931 28356 28356 W Looper : Slow Looper main: doFrame is 312ms late

09-21 20:44:18.180 28356 28368 I b.habdroid.bet: Background concurrent copying GC freed 268808(8557KB) AllocSpace objects, 2(376KB) LOS objects, 49% free, 10MB/21MB, paused 104us total 115.275ms

09-21 20:44:18.908 28356 28368 I b.habdroid.bet: NativeAlloc concurrent copying GC freed 216608(6837KB) AllocSpace objects, 0(0B) LOS objects, 49% free, 9477KB/18MB, paused 185us total 101.356ms

09-21 20:44:19.352 28356 28368 I b.habdroid.bet: NativeAlloc concurrent copying GC freed 238700(7536KB) AllocSpace objects, 0(0B) LOS objects, 49% free, 9509KB/18MB, paused 183us total 106.738ms

09-21 20:44:19.812 28356 28368 I b.habdroid.bet: NativeAlloc concurrent copying GC freed 237334(7501KB) AllocSpace objects, 0(0B) LOS objects, 49% free, 9527KB/18MB, paused 452us total 104.921ms

09-21 20:44:20.294 28356 28368 I b.habdroid.bet: NativeAlloc concurrent copying GC freed 249819(7826KB) AllocSpace objects, 0(0B) LOS objects, 49% free, 9812KB/19MB, paused 169us total 109.836ms

09-21 20:44:20.902 28356 28356 W Looper : Slow Looper main: Long Msg: seq=7676 plan=20:44:17.793 late=401ms wall=2708ms running=2539ms runnable=10ms h=android.view.ViewRootImpl$ViewRootHandler c=android.widget.Editor$HandleView$2

09-21 20:44:20.902 28356 28356 W Looper : Slow Looper main: MotionEvent is 2178ms late (event_seq=603, action=ACTION_DOWN) because of 1 msg, msg 1 took 2708ms (seq=7676 running=2539ms runnable=10ms late=401ms h=android.view.ViewRootImpl$ViewRootHandler c=android.widget.Editor$HandleView$2)

09-21 20:44:20.902 28356 28356 W InputEventReceiver: App Input: 2178ms before dispatchInputEvent (MotionEvent: event_seq=603, seq=324983, action=ACTION_DOWN)

09-21 20:44:20.902 28356 28356 W InputEventReceiver: App Input: 2113ms before dispatchInputEvent (MotionEvent: event_seq=604, seq=324985, action=ACTION_UP)

09-21 20:44:20.956 28356 28356 I sysui_multi_action: [757,1100,758,1109,759,1,806,org.openhab.habdroid.beta,1117,4540,1118,3042,1119,c9cd404a-1907-40ac-9a3b-dff0c94cead4,1120,3,1250,229,1251,229,1252,0,1253,1,1254,1255,textview,1256,und_v713]

09-21 20:44:20.957 28356 28356 W InputEventReceiver: Attempted to finish an input event but the input event receiver has already been disposed.

09-21 20:44:20.957 28356 28356 I Choreographer: Skipped 181 frames! The application may be doing too much work on its main thread.

09-21 20:44:20.957 28356 28356 I [30089] : 181

09-21 20:44:20.965 28356 28356 W Looper : Slow Looper main: doFrame is 3024ms late because of 2 msg, msg 1 took 263ms (seq=7675 running=239ms runnable=1ms late=337ms h=android.os.AsyncTask$InternalHandler w=1), msg 2 took 2708ms (seq=7676 running=2539ms runnable=10ms late=401ms h=android.view.ViewRootImpl$ViewRootHandler c=android.widget.Editor$HandleView$2)

09-21 20:44:20.970 28356 28444 I OpenGLRenderer: Davey! duration=3036ms; Flags=0, IntendedVsync=201102237827898, Vsync=201105254494444, OldestInputEvent=9223372036854775807, NewestInputEvent=0, HandleInputStart=201105266623698, AnimationStart=201105266674115, PerformTraversalsStart=201105266679063, DrawStart=201105267477813, SyncQueued=201105267916146, SyncStart=201105268558750, IssueDrawCommandsStart=201105268961198, SwapBuffers=201105273009115, FrameCompleted=201105274472553, DequeueBufferDuration=216000, QueueBufferDuration=570000,

09-21 20:44:23.703 28356 28356 I am_on_top_resumed_lost_called: [0,org.openhab.habdroid.ui.LogActivity,topStateChangedWhenResumed]

09-21 20:44:23.717 28356 28356 I am_on_paused_called: [0,org.openhab.habdroid.ui.LogActivity,performPause,0]

09-21 20:44:24.266 28356 28356 I am_on_stop_called: [0,org.openhab.habdroid.ui.LogActivity,STOP_ACTIVITY_ITEM,0]

09-21 20:45:45.368 28356 28356 I am_on_restart_called: [0,org.openhab.habdroid.ui.LogActivity,performRestartActivity,0]

09-21 20:45:45.498 28356 28356 I am_on_start_called: [0,org.openhab.habdroid.ui.LogActivity,handleStartActivity,126]

09-21 20:45:45.498 28356 28356 W Activity: Slow Operation: Activity org.openhab.habdroid.beta/org.openhab.habdroid.ui.LogActivity onStart took 126ms

09-21 20:45:45.530 28356 28356 I am_on_resume_called: [0,org.openhab.habdroid.ui.LogActivity,RESUME_ACTIVITY,29]

09-21 20:45:45.535 28356 28356 I am_on_top_resumed_gained_called: [0,org.openhab.habdroid.ui.LogActivity,topWhenResuming]

mueller-ma · September 21, 2021, 8:30pm

@11198 I couldn’t find anything useful in these logs. Can you try to capture them with ADB directly?

Please use code fences for logs.