2016-04-28 11 views
6

在sharedPreferences中遇到一个ANR,不知道如何找到问题。如何分析sharedPreferences中的ANR

这里有三部分来自trace,其他大部分线程都是“WAIT”或“TIMED_WAIT”。由于countdownlatch.await(),“主”线程被阻塞。 等待fsync的第二个线程“pool-1-thread-1”。 最后一个尝试读取的东西。

我想第二个线程阻塞了主线程,因为如果这个线程无法完成,它不会调用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) 

thread3

"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

请张贴您的代码。所以我们可以很好地理解这个问题? – TdSoft

+1

事实上,这是一个很大的项目,我不能说出哪部分代码会发生这种情况...... SharedPreferences在太多的地方使用。 – mjgrrrr

+2

你好,我只是注意到你的问题,似乎你有这个问题相同的问题:stackoverflow.com/q/37549578。我试图回答另一个问题,但我不确定要做什么。简而言之,'onPause'调用触发'SharedPreferences'代码中主线程的等待。不确定这是否是'SharedPreferences'中的错误。你可以尝试从后台线程使用'commit()'而不是'apply()'。或者你可以减少'SharedPreferences'文件的大小,以查看它是否更快同步。 –

回答

4

我已经弄清楚了什么事情。

从三条曲线中,我们可以看到:
- 共享首选项被文件同步阻止。
- fsync正在等待什么(应该是磁盘)。
- 一个线程正在执行磁盘操作。

当我仔细检查源代码后,许多SP的get/set用于记录启动应用程序时的状态。同时,还有一个新线索试图从服务器下载大数据,例如.jar或.so。 对于一些旧设备,下载大数据可能会发生严重的GC和“apache”lib尝试通过“Wire”记录所有内容。所以,有时候,这会花费太长时间,导致SP超时。

解决方法是
- 关闭Apache日志
- 保存在存储器中的状态和共同它们设置为一个对象。
- 从初始化部分移动一些背景行为。