如何分析SharedPreferences中的ANR问题

17

在sharedPreferences中遇到了ANR问题,不知道如何定位问题。

以下是来自跟踪的三个部分,大多数其他线程处于“WAIT”或“TIMED_WAIT”状态。“main”线程因为countdownlatch.await()而被阻塞。 第二个线程“pool-1-thread-1”正在等待fsync。 最后一个线程正在尝试读取一些内容。

我认为第二个线程已经阻塞了主线程,因为如果这个线程无法完成,它就不会调用countdownlatch.countdown(),所以主线程必须等待。

但我无法弄清楚为什么在fsync中停止。第三个线程与此有关吗?

线程1

"main" prio=5 tid=1 WAIT
| group="main" sCount=1 dsCount=0 obj=0x418efe58 self=0x4180b6e8
| sysTid=4178 nice=-6 sched=0/0 cgrp=apps handle=1074565460
| state=S schedstat=( 3385090416 1929697750 7848 ) utm=278 stm=60 core=3
at java.lang.Object.wait(Native Method)
- waiting on <0x418eff28> (a java.lang.VMThread) held by tid=1 (main)
at java.lang.Thread.parkFor(Thread.java:1205)
at sun.misc.Unsafe.park(Unsafe.java:325)
at java.util.concurrent.locks.LockSupport.park(LockSupport.java:157)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.parkAndCheckInterrupt(AbstractQueuedSynchronizer.java:813)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.doAcquireSharedInterruptibly(AbstractQueuedSynchronizer.java:973)
at java.util.concurrent.locks.AbstractQueuedSynchronizer.acquireSharedInterruptibly(AbstractQueuedSynchronizer.java:1281)
at java.util.concurrent.CountDownLatch.await(CountDownLatch.java:202)
at android.app.SharedPreferencesImpl$EditorImpl$1.run(SharedPreferencesImpl.java:364)
at android.app.QueuedWork.waitToFinish(QueuedWork.java:88)
at android.app.ActivityThread.handleServiceArgs(ActivityThread.java:2697)
at android.app.ActivityThread.access$2100(ActivityThread.java:138)
at android.app.ActivityThread$H.handleMessage(ActivityThread.java:1296)
at android.os.Handler.dispatchMessage(Handler.java:102)
at android.os.Looper.loop(Looper.java:136)
at android.app.ActivityThread.main(ActivityThread.java:5095)
at java.lang.reflect.Method.invokeNative(Native Method)
at java.lang.reflect.Method.invoke(Method.java:515)
at com.android.internal.os.ZygoteInit$MethodAndArgsCaller.run(ZygoteInit.java:786)
at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:602)

线程2

"pool-1-thread-1" prio=5 tid=10 SUSPENDED
| group="main" sCount=1 dsCount=0 obj=0x41ca62e0 self=0x6034b008
| sysTid=4246 nice=0 sched=0/0 cgrp=apps handle=1612996584
| state=S schedstat=( 189967314 218846863 555 ) utm=15 stm=3 core=2
#00  pc 00021af0  /system/lib/libc.so (__futex_syscall3+8)
#01  pc 0000f0b4  /system/lib/libc.so (__pthread_cond_timedwait_relative+48)
#02  pc 0000f114  /system/lib/libc.so (__pthread_cond_timedwait+64)
#03  pc 000566e7  /system/lib/libdvm.so
#04  pc 00056ca9  /system/lib/libdvm.so (dvmChangeStatus(Thread*, ThreadStatus)+34)
#05  pc 0005115f  /system/lib/libdvm.so (dvmCallJNIMethod(unsigned int const*, JValue*, Method const*, Thread*)+406)
#06  pc 00029960  /system/lib/libdvm.so
#07  pc 00030dec  /system/lib/libdvm.so (dvmMterpStd(Thread*)+76)
#08  pc 0002e484  /system/lib/libdvm.so (dvmInterpret(Thread*, Method const*, JValue*)+184)
#09  pc 000635b9  /system/lib/libdvm.so (dvmCallMethodV(Thread*, Method const*, Object*, bool, JValue*, std::__va_list)+336)
#10  pc 000635dd  /system/lib/libdvm.so (dvmCallMethod(Thread*, Method const*, Object*, JValue*, ...)+20)
#11  pc 000582bb  /system/lib/libdvm.so
#12  pc 0000d2c0  /system/lib/libc.so (__thread_entry+72)
#13  pc 0000d458  /system/lib/libc.so (pthread_create+240)
at libcore.io.Posix.fsync(Native Method)
at libcore.io.BlockGuardOs.fsync(BlockGuardOs.java:97)
at java.io.FileDescriptor.sync(FileDescriptor.java:74)
at android.os.FileUtils.sync(FileUtils.java:154)
at android.app.SharedPreferencesImpl.writeToFile(SharedPreferencesImpl.java:597)
at android.app.SharedPreferencesImpl.access$800(SharedPreferencesImpl.java:52)
at android.app.SharedPreferencesImpl$2.run(SharedPreferencesImpl.java:511)
at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1112)
at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:587)
at java.lang.Thread.run(Thread.java:841)

线程3

"Thread-5346" prio=5 tid=48 SUSPENDED
| group="main" sCount=1 dsCount=0 obj=0x42b9e3c0 self=0x6278f280
| sysTid=4841 nice=0 sched=0/0 cgrp=apps handle=1652085768
| state=S schedstat=( 6396036717 2412660825 15121 ) utm=612 stm=27 core=1
at java.lang.StackTraceElement.<init>(StackTraceElement.java:~61)
at java.lang.Throwable.nativeGetStackTrace(Native Method)
at java.lang.Throwable.getInternalStackTrace(Throwable.java:264)
at java.lang.Throwable.getStackTrace(Throwable.java:200)
at org.apache.commons.logging.impl.Jdk14Logger.log(Jdk14Logger.java:88)
at org.apache.commons.logging.impl.Jdk14Logger.debug(Jdk14Logger.java:113)
at org.apache.http.impl.conn.Wire.wire(Wire.java:64)
at org.apache.http.impl.conn.Wire.input(Wire.java:116)
at org.apache.http.impl.conn.LoggingSessionInputBuffer.read(LoggingSessionInputBuffer.java:74)
at org.apache.http.impl.io.ContentLengthInputStream.read(ContentLengthInputStream.java:174)
at org.apache.http.conn.EofSensorInputStream.read(EofSensorInputStream.java:159)
at org.qiyi.android.coreplayer.a.aux.a(SourceFile:206)
at org.qiyi.android.coreplayer.a.aux.a(SourceFile:140)
at org.qiyi.android.coreplayer.a.aux.a(SourceFile:105)
at org.qiyi.android.coreplayer.a.com7.a(SourceFile:361)
at org.qiyi.android.coreplayer.a.nul.run(SourceFile:158)
at java.lang.Thread.run(Thread.java:841)

3
请也发布您的代码,这样我们可以更好地理解问题。 - Tharindu Welagedara
1
事实上,这是一个大项目,我无法确定哪部分代码出了问题...SharedPreferences在太多地方被使用了。 - mjgrrrr
3
你好,我刚注意到你的问题,看起来你和这个问题 stackoverflow.com/q/37549578 有相同的问题。我尝试回答另一个问题,但不确定该怎么做。简而言之,onPause 调用触发了 SharedPreferences 代码中主线程的等待。不确定这是否是 SharedPreferences 中的 bug。你可以尝试从后台线程中使用 commit(),而不是 apply()。或者你可以减小 SharedPreferences 文件的大小,以查看它是否能更快地同步。 - Samuel Peter
你还能看到这个问题吗?是因为你使用了“commit”而不是“apply”吗?还是只是从SharedPreferences中读取? - android developer
2个回答

9

我在SharedPreferences.Editor.commit()上看到了它。 - user1608385
1
在主线程中提交可能会导致ANR,因此这并不令人惊讶。 - Hanson Char
这个 bug 在 2018 年被标记为“修复”。但在现在的生产环境中仍然存在。这是否意味着修复程序需要应用到 Android 框架代码中? - David Pisoni
为什么执行apply会导致ANR呢?即使我在主线程中同步了几个数据到SharePreference中,我认为它应该很快完成,为什么还是会导致ANR呢? - Kevin Ding

5

我已经弄清楚了发生了什么。

从三个跟踪中,我们可以看到:
- 共享首选项被文件同步锁定。
- fsync 等待某些东西(应该是磁盘)。
- 一个线程正在进行磁盘操作。

在我仔细检查源代码后,我发现在应用程序启动时使用了大量 SP get/set 来记录状态。同时,有一个新线程试图从服务器下载大数据,如 .jar 或 .so。 对于一些旧设备,下载大数据可能会导致重度 GC,并且 "apache" lib 尝试通过 "Wire" 记录所有内容。所以,有时它需要花费太长时间并导致 SP 超时。

问题的解决方法是:
- 关闭 apache 日志
- 在内存中保存状态并将它们作为一个对象一起设置。
- 将一些后台行为移动到初始化部分之外。


2
我不明白,通过Apache库下载文件与SP有什么关系? - Naruto26

网页内容由stack overflow 提供, 点击上面的
可以查看英文原文,
原文链接