老化失败，整片整片的

第一次出现失败问题点

1970-01-12 19:48:49:393
software_Mainmic:FAIL
software_Mainmic:FINISH

01-12 19:54:06.440  4000  4644 D xxTestII CycleControlService:  item is TimeOut itemname: software_Mainmic
01-12 19:54:06.442  4000  4644 D xxTestII FileUtils: Test result = {200=2, 201=4}
01-12 19:54:06.444  4000  4644 D xxTestII ItemDataMap: checkConfigIsOK
01-12 19:54:06.444  4000  4644 D xxTestII CycleControlService: mResultListener action = com.xx.xxxtestii.mainmicmSectIndex = 1
01-12 19:54:06.683 28448 28448 W ServiceManagerCppClient: Waited one second for android.hardware.secure_element.ISecureElement/eSE1 (is service started? Number of threads started in the threadpool: 16. Are binder threads started and available?)
01-12 19:54:06.706   776 20449 W libc    : Unable to set property "ctl.interface_start" to "aidl/android.hardware.secure_element.ISecureElement/eSE1": PROP_ERROR_HANDLE_CONTROL_MESSAGE (0x20)
01-12 19:54:06.945  4000  4644 W ContextImpl: Calling a method in the system process without a qualified user: android.app.ContextImpl.stopService:1940 android.content.ContextWrapper.stopService:837 com.xx.xxxtestii.CycleControlService$1.onResultChanged:76 com.xx.xxxtestii.utils.ResultUtils.setItemResultCtl:48 com.xx.xxxtestii.utils.FileUtils.setItemResult:220 
01-12 19:54:06.950  1401  1401 I vendor.qti.hardware.servicetrackeraidl-service: destroyService is called for service : com.xx.xxxtestii/.item.software.voice.MainMicTest
01-12 19:54:06.953  4000  4644 D xxTestII ItemDataMap: checkConfigIsOK

Line 26990: 01-12 19:48:49.402  4000  4000 D xxTestII SpeakerService: stopPlay mPlayer = 
Line 26991: 01-12 19:48:49.402  4000  4000 D xxTestII ItemBackgroundService: onDestroy
Line 29143: 01-12 19:54:06.440  4000  4644 D xxTestII CycleControlService:  item is TimeOut itemname: software_Mainmic
Line 29144: 01-12 19:54:06.442  4000  4644 D xxTestII FileUtils: Test result = {200=2, 201=4}

进程4000，5分钟没有更新消息过来，卡死了

这个更新消息的还是子线程，主线程卡到22点才回复

Line 26991: 01-12 19:48:49.402  4000  4000 D xxTestII ItemBackgroundService: onDestroy
Line 30548: 01-12 19:57:27.032  4000  4000 I libc    : Requested dump for pid 4000 (ory.runintestii)
Line 31890: 01-12 19:57:42.590  4000  4000 I libc    : Requested dump for pid 4000 (ory.runintestii)

//中间有dump
Line 217346: 01-12 22:00:11.804  4000  4000 D xxTestII MainMicTest: onFinish: result = true
Line 217347: 01-12 22:00:11.805  4000  4000 D xxTestII MainMicTest: Result is OK

问题出现点，但是dump的日志都没找到

01-12 19:57:26.979  1795  2440 W ActivityManager: Timeout executing service: ServiceRecord{dac4868 u0 com.xx.xxxtestii/.item.software.SystemSleepService c:com.xx.xxxtestii}
01-12 19:57:26.981  1795  2440 D CompatChangeReporter: Compat change id reported: 313677553; UID 1000; state: ENABLED
01-12 19:57:26.981  1795  2440 D CompatChangeReporter: Compat change id reported: 311208749; UID 1000; state: ENABLED
01-12 19:57:26.986  1795 20769 D ActivityManager: created ANR temporary file:/data/anr/temp_anr_5453010372450389396.txt
01-12 19:57:26.986  1795 20769 I ActivityManager: Collecting stacks for pid 4000 into temporary file temp_anr_5453010372450389396.txt
01-12 19:57:26.987  1795 20770 D ActivityManager: created ANR temporary file:/data/anr/temp_anr_6649737073063467072.txt
01-12 19:57:26.987  1795 20770 I ActivityManager: Collecting stacks for pid 4000 into temporary file temp_anr_6649737073063467072.txt
01-12 19:57:26.994  1795 20771 I ActivityManager: dumpStackTraces pids={3412=true, 3421=true, 3495=true, 3536=true, 3589=true, 3677=true, 3770=true, 3801=true, 4084=true, 4226=true, 4317=true, 4419=true, 4465=true, 4486=true, 4511=true, 4535=true, 4576=true, 4594=true, 4638=true, 4685=true, 4704=true, 4721=true, 4741=true, 4742=true, 4772=true, 4785=true, 4807=true, 4825=true, 4843=true, 4858=true, 4878=true, 4897=true, 4921=true, 4943=true, 4957=true, 4972=true, 10113=true}
01-12 19:57:26.996  1795 20771 I ActivityManager: Dumping to /data/anr/anr_1970-01-12-19-57-26-994
01-12 19:57:27.010  1795 20770 I system_server: libdebuggerd_client: started dumping process 4000
01-12 19:57:27.011   910   910 I tombstoned: registered intercept for pid 4000 and type kDebuggerdJavaBacktrace

anr/anr_1970-01-12-19-57-26-994的log都被冲掉了

在dropbox里面找到了

----- dumping pid: 4000 at 16269970
proc 4000
context binder
thread 4000: l 10 need_return 0 tr 0
outgoing transaction 6254902: ffffff88190ee500 from 4000:4000 to 9961:13700 code 2 flags 10 pri 0:110 r1 elapsed 517498ms
transaction complete
libdebuggerd_client: unexpected registration response: 0

----- Waiting Channels: pid 4000 at 1970-01-12 19:57:27.016054962+0000 ----- //时间点对得上
Cmd line: com.xx.xxxtestii

sysTid=4000      binder_thread_read
sysTid=4008      do_sigtimedwait
sysTid=4010      pipe_read
sysTid=4026      futex_wait_queue
sysTid=4031      futex_wait_queue
sysTid=4048      futex_wait_queue
sysTid=4049      futex_wait_queue
sysTid=4050      futex_wait_queue
sysTid=4090      binder_thread_read
sysTid=4134      binder_thread_read
sysTid=4152      binder_thread_read
sysTid=4154      futex_wait_queue
sysTid=4219      futex_wait_queue
sysTid=4228      futex_wait_queue
sysTid=4618      ep_poll
sysTid=4632      binder_thread_read
sysTid=4643      futex_wait_queue
sysTid=4644      futex_wait_queue
sysTid=4659      ep_poll
sysTid=4661      ep_poll
sysTid=4666      futex_wait_queue
sysTid=4667      futex_wait_queue
sysTid=4671      binder_thread_read
sysTid=4674      futex_wait_queue
sysTid=4701      ep_poll
sysTid=4702      binder_thread_read
sysTid=4740      ep_poll
sysTid=4993      futex_wait_queue
sysTid=4994      futex_wait_queue
sysTid=4995      futex_wait_queue
sysTid=4996      futex_wait_queue
sysTid=5063      binder_thread_read
sysTid=5083      ep_poll
sysTid=26114     binder_thread_read
sysTid=15595     futex_wait_queue
sysTid=15596     futex_wait_queue
sysTid=15612     futex_wait_queue

----- end 4000 -----

proc 4000
context binder
thread 4000: l 10 need_return 0 tr 0
outgoing transaction 6254902: ffffff88190ee500 from 4000:4000 to 9961:13700 code 2 flags 10 pri 0:110 r1 elapsed 517525ms
transaction complete

9961:13700

9961是audioserver

01-12 19:48:49.400  9961 13700 D DeviceHalAidl: setParameters: parameters: "mmitest=off"

com.xx.xxxtestii.item.software.voice.MainMicTest.StopAudioRecord

----- pid 4000 at 1970-01-12 19:57:27.244831160+0000 -----
Cmd line: com.xx.xxxtestii
ABI: 'arm64'

"ory.runintestii" sysTid=4000
#00 pc 00000000000c562c  /apex/com.android.runtime/lib64/bionic/libc.so (__ioctl+12) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
#01 pc 000000000006c6a0  /apex/com.android.runtime/lib64/bionic/libc.so (ioctl+156) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
#02 pc 000000000004dec0  /system/lib64/libbinder.so (android::IPCThreadState::talkWithDriver(bool)+244) (BuildId: 271122bc5a1ef38bd2ec300f06119643)
#03 pc 000000000004f200  /system/lib64/libbinder.so (android::IPCThreadState::waitForResponse(android::Parcel*, int*)+76) (BuildId: 271122bc5a1ef38bd2ec300f06119643)
#04 pc 000000000004ef48  /system/lib64/libbinder.so (android::IPCThreadState::transact(int, unsigned int, android::Parcel const&, android::Parcel*, unsigned int)+188) (BuildId: 271122bc5a1ef38bd2ec300f06119643)
#05 pc 000000000006492c  /system/lib64/libbinder.so (android::BpBinder::transact(unsigned int, android::Parcel const&, android::Parcel*, unsigned int)+172) (BuildId: 271122bc5a1ef38bd2ec300f06119643)
#06 pc 000000000004188c  /system/lib64/audioflinger-aidl-cpp.so (android::media::BpAudioRecord::stop()+644) (BuildId: 5393802c74e79e090465762d3bb34334)
#07 pc 0000000000066eec  /system/lib64/libaudioclient.so (android::AudioRecord::stop()+232) (BuildId: 593a16ccf9377542c87b6476c90198ec)
#08 pc 00000000001b17c8  /system/lib64/libandroid_runtime.so (android_media_AudioRecord_stop(_JNIEnv*, _jobject*)+136) (BuildId: 1689a13870cb76f5c522633e8ccf00c2)
#09 pc 00000000001ece80  /system/framework/arm64/boot-framework.oat (art_jni_trampoline+112) (BuildId: f2d2803eba63d196dcc1771a5bf7acacf0f877cd)
#10 pc 00000000004e6a6c  /system/framework/arm64/boot-framework.oat (android.media.AudioRecord.stop+108) (BuildId: f2d2803eba63d196dcc1771a5bf7acacf0f877cd)
#11 pc 000000000002b608  [anon_shmem:dalvik-jit-code-cache] (offset 0x2000000) (com.xx.xxxtestii.item.software.voice.MainMicTest.StopAudioRecord+104)
#12 pc 000000000001cf2c  [anon_shmem:dalvik-jit-code-cache] (offset 0x2000000) (com.xx.xxxtestii.item.software.voice.MainMicTest$1.onFinish+76)
#13 pc 000000000020a320  /apex/com.android.art/lib64/libart.so (nterp_helper+4016) (BuildId: 53e6f05a0c1ff6bdfebd3922f35fd693)
#14 pc 00000000001e4e64  /system/framework/framework.jar (android.os.CountDownTimer$1.handleMessage+64)
#15 pc 0000000000525f78  /system/framework/arm64/boot-framework.oat (android.os.Handler.dispatchMessage+152) (BuildId: f2d2803eba63d196dcc1771a5bf7acacf0f877cd)
#16 pc 0000000000528fe4  /system/framework/arm64/boot-framework.oat (android.os.Looper.loopOnce+980) (BuildId: f2d2803eba63d196dcc1771a5bf7acacf0f877cd)
#17 pc 0000000000528b94  /system/framework/arm64/boot-framework.oat (android.os.Looper.loop+244) (BuildId: f2d2803eba63d196dcc1771a5bf7acacf0f877cd)
#18 pc 00000000002f8a64  /system/framework/arm64/boot-framework.oat (android.app.ActivityThread.main+1572) (BuildId: f2d2803eba63d196dcc1771a5bf7acacf0f877cd)
#19 pc 0000000000210a40  /apex/com.android.art/lib64/libart.so (art_quick_invoke_static_stub+640) (BuildId: 53e6f05a0c1ff6bdfebd3922f35fd693)
#20 pc 0000000000472050  /apex/com.android.art/lib64/libart.so (art::ArtMethod::Invoke(art::Thread*, unsigned int*, unsigned int, art::JValue*, char const*)+216) (BuildId: 53e6f05a0c1ff6bdfebd3922f35fd693)
#21 pc 0000000000827278  /apex/com.android.art/lib64/libart.so (_jobject* art::InvokeMethod<(art::PointerSize)8>(art::ScopedObjectAccessAlreadyRunnable const&, _jobject*, _jobject*, _jobject*, unsigned long)+2108) (BuildId: 53e6f05a0c1ff6bdfebd3922f35fd693)
#22 pc 00000000007938a4  /apex/com.android.art/lib64/libart.so (art::Method_invoke(_JNIEnv*, _jobject*, _jobject*, _jobjectArray*) (.__uniq.165753521025965369065708152063621506277)+36) (BuildId: 53e6f05a0c1ff6bdfebd3922f35fd693)
#23 pc 00000000000a2114  /system/framework/arm64/boot.oat (art_jni_trampoline+116) (BuildId: f015ed62fb8d8bdb8a2059f6a503b935c5c0c9d0)
#24 pc 00000000007fe664  /system/framework/arm64/boot-framework.oat (com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run+116) (BuildId: f2d2803eba63d196dcc1771a5bf7acacf0f877cd)
#25 pc 0000000000807894  /system/framework/arm64/boot-framework.oat (com.android.internal.os.ZygoteInit.main+3028) (BuildId: f2d2803eba63d196dcc1771a5bf7acacf0f877cd)
#26 pc 0000000000210a40  /apex/com.android.art/lib64/libart.so (art_quick_invoke_static_stub+640) (BuildId: 53e6f05a0c1ff6bdfebd3922f35fd693)
#27 pc 0000000000472050  /apex/com.android.art/lib64/libart.so (art::ArtMethod::Invoke(art::Thread*, unsigned int*, unsigned int, art::JValue*, char const*)+216) (BuildId: 53e6f05a0c1ff6bdfebd3922f35fd693)
#28 pc 0000000000827c10  /apex/com.android.art/lib64/libart.so (art::JValue art::InvokeWithVarArgs<_jmethodID*>(art::ScopedObjectAccessAlreadyRunnable const&, _jobject*, _jmethodID*, std::__va_list)+472) (BuildId: 53e6f05a0c1ff6bdfebd3922f35fd693)
#29 pc 00000000006f3ae0  /apex/com.android.art/lib64/libart.so (art::JNI<true>::CallStaticVoidMethodV(_JNIEnv*, _jclass*, _jmethodID*, std::__va_list)+560) (BuildId: 53e6f05a0c1ff6bdfebd3922f35fd693)
#30 pc 00000000000dd42c  /system/lib64/libandroid_runtime.so (_JNIEnv::CallStaticVoidMethod(_jclass*, _jmethodID*, ...)+108) (BuildId: 1689a13870cb76f5c522633e8ccf00c2)
#31 pc 00000000000f4314  /system/lib64/libandroid_runtime.so (android::AndroidRuntime::start(char const*, android::Vector<android::String8> const&, bool)+916) (BuildId: 1689a13870cb76f5c522633e8ccf00c2)
#32 pc 0000000000004654  /system/bin/app_process64 (main+1428) (BuildId: b46373270a3dc7e7fead6c71e2d46848)
#33 pc 0000000000059ac0  /apex/com.android.runtime/lib64/bionic/libc.so (__libc_init+120) (BuildId: f98650a65aa2ec2d87236752af90aa7e)

这里可可以看带出调用栈，stop时候binder对端执行没有返回

Line 26990: 01-12 19:48:49.402  4000  4000 D xxTestII SpeakerService: stopPlay mPlayer = 
Line 26991: 01-12 19:48:49.402  4000  4000 D xxTestII ItemBackgroundService: onDestroy
Line 30548: 01-12 19:57:27.032  4000  4000 I libc    : Requested dump for pid 4000 (ory.runintestii)
Line 31890: 01-12 19:57:42.590  4000  4000 I libc    : Requested dump for pid 4000 (ory.runintestii)
Line 217346: 01-12 22:00:11.804  4000  4000 D xxTestII MainMicTest: onFinish: result = true
Line 217347: 01-12 22:00:11.805  4000  4000 D xxTestII MainMicTest: Result is OK
Line 217513: 01-12 22:00:12.319  4000  4000 D xxTestII FlashTestService: Test finish

卡到晚上22点恢复

01-12 22:00:11.305  7416  7416 I crash_dump64: obtaining output fd from tombstoned, type: kDebuggerdTombstoneProto
01-12 22:00:11.306   910   910 I tombstoned: received crash request for pid 9979
01-12 22:00:11.308  7416  7416 I crash_dump64: performing dump of process 9961 (target tid = 9979)
01-12 22:00:11.310  1518  1518 W Layer   : gatherBufferInfo: Received unknown dataspace from gralloc
01-12 22:00:11.311  1384 20871 W SDM     : SetLayerBuffer: format: 1, flags: 167772160
01-12 22:00:11.311  1384 20871 W SDM     : SetLayerBuffer: format: 1, flags: 167772160
01-12 22:00:11.336  7416  7416 E DEBUG   : failed to read process info: failed to open /proc/9961: No such file or directory
01-12 22:00:11.384  1384  1443 I SDM     : DisplayBase::CommitThread: Received idle timeout, panel: video, timeout: 70000 us
01-12 22:00:11.386  1391  1408 E PERFHAL-PERFCTRL: PERFHAL-PERFCTRL: verifyAndCreateRequest() 641: Perflock Submit request limit reached.
01-12 22:00:11.394  1384 20871 W SDM     : SetLayerBuffer: format: 1, flags: 167772160
01-12 22:00:11.396  1391  1408 E PERFHAL-PERFCTRL: PERFHAL-PERFCTRL: verifyAndCreateRequest() 641: Perflock Submit request limit reached.
01-12 22:00:11.403  1384 20871 W SDM     : SetLayerBuffer: format: 1, flags: 167772160
01-12 22:00:11.423  1391  1408 E PERFHAL-PERFCTRL: PERFHAL-PERFCTRL: PerflocksDLoop() 480: Unknown command 0
01-12 22:00:11.424  1384  1443 I SDM     : HWDeviceDRM::SetupConcurrentWriteback: Tear down the Concurrent Writeback topology
01-12 22:00:11.496  1384  1443 I SDM     : DisplayBase::CommitThread: Received idle timeout, panel: video, timeout: 70000 us
01-12 22:00:11.498  1391  1408 E PERFHAL-PERFCTRL: PERFHAL-PERFCTRL: verifyAndCreateRequest() 641: Perflock Submit request limit reached.
01-12 22:00:11.502  1384 20871 W SDM     : SetLayerBuffer: format: 1, flags: 167772160
01-12 22:00:11.676  7416  7416 F DEBUG   : *** *** *** *** *** *** *** *** *** *** *** *** *** *** *** ***
01-12 22:00:11.676  7416  7416 F DEBUG   : Build fingerprint: 'T-Mobile/Bremerton/Bremerton:15/AQ3A.240912.001/SW_S88532AA1_V007:userdebug/release-keys'
01-12 22:00:11.676  7416  7416 F DEBUG   : Revision: '0'
01-12 22:00:11.676  7416  7416 F DEBUG   : ABI: 'arm64'
01-12 22:00:11.676  7416  7416 F DEBUG   : Timestamp: 1970-01-12 22:00:11.335888820+0000
01-12 22:00:11.676  7416  7416 F DEBUG   : Process uptime: 0s
01-12 22:00:11.676  7416  7416 F DEBUG   : Cmdline: /system/bin/audioserver
01-12 22:00:11.676  7416  7416 F DEBUG   : pid: 9961, tid: 9979, name: binder:9961_1  >>> /system/bin/audioserver <<<
01-12 22:00:11.676  7416  7416 F DEBUG   : uid: 1041
01-12 22:00:11.676  7416  7416 F DEBUG   : tagged_addr_ctrl: 0000000000000001 (PR_TAGGED_ADDR_ENABLE)
01-12 22:00:11.676  7416  7416 F DEBUG   : signal 6 (SIGABRT), code -1 (SI_QUEUE), fault addr --------
01-12 22:00:11.683  7416  7416 F DEBUG   : Abort message: 'TimeCheck timeout for IAudioPolicyService::setMasterMono scheduled 22:00:00.044 on thread 9979
01-12 22:00:11.683  7416  7416 F DEBUG   : Timeout ms 10000.000000 elapsed steady ms 10000.619141 elapsed system ms 10000.628906
01-12 22:00:11.683  7416  7416 F DEBUG   : HAL pids [ 9962 ]
01-12 22:00:11.683  7416  7416 F DEBUG   : now 22:00:11.125
01-12 22:00:11.683  7416  7416 F DEBUG   : secondChanceCount 1
01-12 22:00:11.683  7416  7416 F DEBUG   : analysis [ mutex wait chain [ 9979, 10001 (by AudioPolicyService_Mutex), 15589 (by join) ] ]
01-12 22:00:11.683  7416  7416 F DEBUG   : timeout [ { IAudioPolicyService::setMasterMono scheduled 22:00:00.044 deadline :08.044 tid 9979 } ]
01-12 22:00:11.683  7416  7416 F DEBUG   : pending [  ]
01-12 22:00:11.683  7416  7416 F DEBUG   : retired [ { DeviceHalAidl::setParameters scheduled 21:38:06.047 deadline  tid 9979 } { DeviceHalAidl::setParameters scheduled 21:38:06.048 deadline  tid 9979 } { DeviceHalAidl::setParameters scheduled 21:38:06.050 deadline  tid 9979 } { IAudioFlinger::setParameters scheduled 21:38:06.043 deadline :14.043 tid 9979 } ]
01-12 22:00:11.683  7416  7416 F DEBUG   : timeout(9979) callstack [
01-12 22:00:11.683  7416  7416 F DEBUG   :   #00 pc 0000000000087d20  /apex/com.android.runtime/lib64/bionic/libc.so (syscall+32) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #01 pc 00000000000628cc  /apex/com.android.runtime/lib64/bionic/libc.so (__futex_pi_lock_ex(void volatile*, bool, bool, timespec const*)+332) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #02 pc 0000000000072f88  /apex/com.android.runtime/lib64/bionic/libc.so (PIMutexTimedLock(PIMutex&, bool, timespec const*)+212) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #03 pc 00000000000b1b60  /system/lib64/libc++.so (std::__1::mutex::lock()+12) (BuildId: 53e0091d25a788802d2d3a5324f79b527df4913f)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #04 pc 00000000000df46c  /system/bin/audioserver (android::audio_utils::mutex_impl<android::audio_utils::AudioMutexAttributes>::lock()+140) (BuildId: 19d54a5673e7c4cc54191cb2c0ab067b)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #05 pc 000000000020c194  /system/bin/audioserver (android::AudioPolicyService::setMasterMono(bool)+56) (BuildId: 19d54a5673e7c4cc54191cb2c0ab067b)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #06 pc 000000000003b2ac  /system/lib64/audiopolicy-aidl-cpp.so (android::media::BnAudioPolicyService::onTransact(unsigned int, android::Parcel const&, android::Parcel*, unsigned int)+19504) (BuildId: c3480068db4a5e975e043f9cd89662f2)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #07 pc 00000000001d7a28  /system/bin/audioserver (android::AudioPolicyService::onTransact(unsigned int, android::Parcel const&, android::Parcel*, unsigned int)+1084) (BuildId: 19d54a5673e7c4cc54191cb2c0ab067b)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #08 pc 0000000000061880  /system/lib64/libbinder.so (android::BBinder::transact(unsigned int, android::Parcel const&, android::Parcel*, unsigned int)+336) (BuildId: 271122bc5a1ef38bd2ec300f06119643)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #09 pc 000000000004e774  /system/lib64/libbinder.so (android::IPCThreadState::executeCommand(int)+1072) (BuildId: 271122bc5a1ef38bd2ec300f06119643)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #10 pc 000000000004e284  /system/lib64/libbinder.so (android::IPCThreadState::getAndExecuteCommand()+156) (BuildId: 271122bc5a1ef38bd2ec300f06119643)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #11 pc 000000000004ebbc  /system/lib64/libbinder.so (android::IPCThreadState::joinThreadPool(bool)+108) (BuildId: 271122bc5a1ef38bd2ec300f06119643)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #12 pc 000000000005718c  /system/lib64/libbinder.so (android::PoolThread::threadLoop()+28) (BuildId: 271122bc5a1ef38bd2ec300f06119643)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #13 pc 000000000001203c  /system/lib64/libutils.so (android::Thread::_threadLoop(void*)+416) (BuildId: 7898b82fed0acaf692cff1dba07b2a3c)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #14 pc 0000000000071b3c  /apex/com.android.runtime/lib64/bionic/libc.so (__pthread_start(void*)+196) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #15 pc 0000000000063c38  /apex/com.android.runtime/lib64/bionic/libc.so (__start_thread+68) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   : ]
01-12 22:00:11.683  7416  7416 F DEBUG   : mutex wait chain [
01-12 22:00:11.683  7416  7416 F DEBUG   : { tid: 10001 (holding AudioPolicyService_Mutex) callstack [
01-12 22:00:11.683  7416  7416 F DEBUG   :   #00 pc 0000000000087d20  /apex/com.android.runtime/lib64/bionic/libc.so (syscall+32) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #01 pc 000000000006272c  /apex/com.android.runtime/lib64/bionic/libc.so (__futex_wait_ex(void volatile*, bool, int, bool, timespec const*)+148) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #02 pc 0000000000070cf8  /apex/com.android.runtime/lib64/bionic/libc.so (pthread_cond_wait+76) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #03 pc 00000000000121d0  /system/lib64/libutils.so (android::Thread::requestExitAndWait()+76) (BuildId: 7898b82fed0acaf692cff1dba07b2a3c)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #04 pc 0000000000144340  /system/bin/audioserver (android::ThreadBase::exit()+376) (BuildId: 19d54a5673e7c4cc54191cb2c0ab067b)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #05 pc 00000000000de3f8  /system/bin/audioserver (android::AudioFlinger::closeOutput_nonvirtual(int)+3360) (BuildId: 19d54a5673e7c4cc54191cb2c0ab067b)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #06 pc 0000000000219d38  /system/bin/audioserver (android::AudioPolicyService::AudioPolicyClient::closeOutput(int)+168) (BuildId: 19d54a5673e7c4cc54191cb2c0ab067b)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #07 pc 0000000000041660  /system/lib64/libaudiopolicycomponents.so (android::SwAudioOutputDescriptor::close()+520) (BuildId: 98b5cfedb514615b46c67e8a758830ee)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #08 pc 0000000000043474  /system/lib64/libaudiopolicymanagerdefault.so (android::AudioPolicyManager::closeOutput(int)+1860) (BuildId: 0944fd36880cd5da913e43806458773f)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #09 pc 000000000006283c  /system/lib64/libaudiopolicymanagerdefault.so (android::AudioPolicyManager::releaseOutput(int)+420) (BuildId: 0944fd36880cd5da913e43806458773f)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #10 pc 00000000001f7308  /system/bin/audioserver (android::AudioPolicyService::doReleaseOutput(int)+412) (BuildId: 19d54a5673e7c4cc54191cb2c0ab067b)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #11 pc 00000000001e2b08  /system/bin/audioserver (android::AudioPolicyService::AudioCommandThread::threadLoop()+3884) (BuildId: 19d54a5673e7c4cc54191cb2c0ab067b)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #12 pc 000000000001203c  /system/lib64/libutils.so (android::Thread::_threadLoop(void*)+416) (BuildId: 7898b82fed0acaf692cff1dba07b2a3c)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #13 pc 0000000000071b3c  /apex/com.android.runtime/lib64/bionic/libc.so (__pthread_start(void*)+196) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #14 pc 0000000000063c38  /apex/com.android.runtime/lib64/bionic/libc.so (__start_thread+68) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   : ] }
01-12 22:00:11.683  7416  7416 F DEBUG   : { tid: 15589 (holding join) callstack [
01-12 22:00:11.683  7416  7416 F DEBUG   :   #00 pc 0000000000087d20  /apex/com.android.runtime/lib64/bionic/libc.so (syscall+32) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #01 pc 00000000000049ec  /system/lib64/libfmq.so (android::hardware::EventFlag::waitHelper(unsigned int, unsigned int*, long)+272) (BuildId: c55cb511e1a5ed336c00d7a14fbb7ef7)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #02 pc 0000000000004b1c  /system/lib64/libfmq.so (android::hardware::EventFlag::wait(unsigned int, unsigned int*, long, bool)+132) (BuildId: c55cb511e1a5ed336c00d7a14fbb7ef7)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #03 pc 0000000000116118  /system/lib64/libaudiohal@aidl.so (android::MessageQueueBase<android::details::AidlMQDescriptorShim, aidl::android::hardware::audio::core::StreamDescriptor::Reply, (android::hardware::MQFlavor)1>::readBlocking(aidl::android::hardware::audio::core::StreamDescriptor::Reply*, unsigned long, unsigned int, unsigned int, long, android::hardware::EventFlag*)+364) (BuildId: e48880edbcd5db9e02ef37cec4b5982c)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #04 pc 0000000000115500  /system/lib64/libaudiohal@aidl.so (android::StreamHalAidl::sendCommand(aidl::android::hardware::audio::core::StreamDescriptor::Command const&, aidl::android::hardware::audio::core::StreamDescriptor::Reply*, bool, android::StreamHalAidl::StatePositions*)+208) (BuildId: e48880edbcd5db9e02ef37cec4b5982c)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #05 pc 0000000000113e08  /system/lib64/libaudiohal@aidl.so (android::StreamHalAidl::standby()+768) (BuildId: e48880edbcd5db9e02ef37cec4b5982c)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #06 pc 000000000017ed6c  /system/bin/audioserver (android::PlaybackThread::threadLoop_standby()+72) (BuildId: 19d54a5673e7c4cc54191cb2c0ab067b)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #07 pc 000000000016af90  /system/bin/audioserver (android::PlaybackThread::threadLoop()+17260) (BuildId: 19d54a5673e7c4cc54191cb2c0ab067b)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #08 pc 000000000001203c  /system/lib64/libutils.so (android::Thread::_threadLoop(void*)+416) (BuildId: 7898b82fed0acaf692cff1dba07b2a3c)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #09 pc 0000000000071b3c  /apex/com.android.runtime/lib64/bionic/libc.so (__pthread_start(void*)+196) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   :   #10 pc 0000000000063c38  /apex/com.android.runtime/lib64/bionic/libc.so (__start_thread+68) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   : ] }
01-12 22:00:11.683  7416  7416 F DEBUG   : ]'
01-12 22:00:11.683  7416  7416 F DEBUG   :     x0  b400006f9fd23300  x1  000000000000008d  x2  0000000000000000  x3  0000000000000000
01-12 22:00:11.683  7416  7416 F DEBUG   :     x4  0000000000000000  x5  0000000000000000  x6  0000000000000000  x7  0000006eb1e8d2aa
01-12 22:00:11.683  7416  7416 F DEBUG   :     x8  0000000000000062  x9  000000000000008d  x10 000000003b9aca00  x11 0000000000000001
01-12 22:00:11.683  7416  7416 F DEBUG   :     x12 0000000000005c47  x13 000000007fffffff  x14 00000000009313c8  x15 0000007b4a7052b5
01-12 22:00:11.683  7416  7416 F DEBUG   :     x16 0000007159b5b008  x17 0000007159b07d00  x18 0000006eb131c000  x19 b400006f9fd23300
01-12 22:00:11.683  7416  7416 F DEBUG   :     x20 0000000000000000  x21 0000006eb1e85e30  x22 000000000000008d  x23 0000000000000004
01-12 22:00:11.683  7416  7416 F DEBUG   :     x24 0000006eb1e85e80  x25 0000007159b6f000  x26 0000006eb1e85e80  x27 0000006eb1e85e80
01-12 22:00:11.683  7416  7416 F DEBUG   :     x28 0000006eb1e853c0  x29 0000006eb1e84d00
01-12 22:00:11.683  7416  7416 F DEBUG   :     lr  0000007159ae28d0  sp  0000006eb1e84ce0  pc  0000007159b07d20  pst 0000000040001000
01-12 22:00:11.683  7416  7416 F DEBUG   : 16 total frames
01-12 22:00:11.683  7416  7416 F DEBUG   : backtrace:
01-12 22:00:11.683  7416  7416 F DEBUG   :       #00 pc 0000000000087d20  /apex/com.android.runtime/lib64/bionic/libc.so (syscall+32) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   :       #01 pc 00000000000628cc  /apex/com.android.runtime/lib64/bionic/libc.so (__futex_pi_lock_ex(void volatile*, bool, bool, timespec const*)+332) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   :       #02 pc 0000000000072f88  /apex/com.android.runtime/lib64/bionic/libc.so (PIMutexTimedLock(PIMutex&, bool, timespec const*)+212) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.683  7416  7416 F DEBUG   :       #03 pc 00000000000b1b60  /system/lib64/libc++.so (std::__1::mutex::lock()+12) (BuildId: 53e0091d25a788802d2d3a5324f79b527df4913f)
01-12 22:00:11.683  7416  7416 F DEBUG   :       #04 pc 00000000000df46c  /system/bin/audioserver (android::audio_utils::mutex_impl<android::audio_utils::AudioMutexAttributes>::lock()+140) (BuildId: 19d54a5673e7c4cc54191cb2c0ab067b)
01-12 22:00:11.683  7416  7416 F DEBUG   :       #05 pc 000000000020c194  /system/bin/audioserver (android::AudioPolicyService::setMasterMono(bool)+56) (BuildId: 19d54a5673e7c4cc54191cb2c0ab067b)
01-12 22:00:11.683  7416  7416 F DEBUG   :       #06 pc 000000000003b2ac  /system/lib64/audiopolicy-aidl-cpp.so (android::media::BnAudioPolicyService::onTransact(unsigned int, android::Parcel const&, android::Parcel*, unsigned int)+19504) (BuildId: c3480068db4a5e975e043f9cd89662f2)
01-12 22:00:11.684  7416  7416 F DEBUG   :       #07 pc 00000000001d7a28  /system/bin/audioserver (android::AudioPolicyService::onTransact(unsigned int, android::Parcel const&, android::Parcel*, unsigned int)+1084) (BuildId: 19d54a5673e7c4cc54191cb2c0ab067b)
01-12 22:00:11.684  7416  7416 F DEBUG   :       #08 pc 0000000000061880  /system/lib64/libbinder.so (android::BBinder::transact(unsigned int, android::Parcel const&, android::Parcel*, unsigned int)+336) (BuildId: 271122bc5a1ef38bd2ec300f06119643)
01-12 22:00:11.684  7416  7416 F DEBUG   :       #09 pc 000000000004e774  /system/lib64/libbinder.so (android::IPCThreadState::executeCommand(int)+1072) (BuildId: 271122bc5a1ef38bd2ec300f06119643)
01-12 22:00:11.684  7416  7416 F DEBUG   :       #10 pc 000000000004e284  /system/lib64/libbinder.so (android::IPCThreadState::getAndExecuteCommand()+156) (BuildId: 271122bc5a1ef38bd2ec300f06119643)
01-12 22:00:11.684  7416  7416 F DEBUG   :       #11 pc 000000000004ebbc  /system/lib64/libbinder.so (android::IPCThreadState::joinThreadPool(bool)+108) (BuildId: 271122bc5a1ef38bd2ec300f06119643)
01-12 22:00:11.684  7416  7416 F DEBUG   :       #12 pc 000000000005718c  /system/lib64/libbinder.so (android::PoolThread::threadLoop()+28) (BuildId: 271122bc5a1ef38bd2ec300f06119643)
01-12 22:00:11.684  7416  7416 F DEBUG   :       #13 pc 000000000001203c  /system/lib64/libutils.so (android::Thread::_threadLoop(void*)+416) (BuildId: 7898b82fed0acaf692cff1dba07b2a3c)
01-12 22:00:11.684  7416  7416 F DEBUG   :       #14 pc 0000000000071b3c  /apex/com.android.runtime/lib64/bionic/libc.so (__pthread_start(void*)+196) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.684  7416  7416 F DEBUG   :       #15 pc 0000000000063c38  /apex/com.android.runtime/lib64/bionic/libc.so (__start_thread+68) (BuildId: f98650a65aa2ec2d87236752af90aa7e)
01-12 22:00:11.721  1795  3081 W NativeCrashListener: Couldn't find ProcessRecord for pid 9961
01-12 22:00:11.723   910   910 E tombstoned: Tombstone written to: tombstone_03
01-12 22:00:11.724  1795  2830 E NativeTombstoneManager: Tombstone's UID (1041) not an app, ignoring
01-12 22:00:11.727  1795  2830 I DropBoxManagerService: add tag=SYSTEM_TOMBSTONE_PROTO_WITH_HEADERS isTagEnabled=true flags=0x4
01-12 22:00:11.743  1795  2830 E NativeTombstoneManager: Tombstone's UID (1041) not an app, ignoring
01-12 22:00:11.747  1795  2830 I BootReceiver: Copying /data/tombstones/tombstone_03 to DropBox (SYSTEM_TOMBSTONE)

晚上22点的调用栈里面没有线程13700

应该需要/data/tombstones/tombstone_03 这个文件看看

下次出现记得log导出tomstones

已读
因为主线程卡死在那里阻塞，所以整个老化测试就fail的很多 

Line 31890: 01-12 19:57:42.590  4000  4000 I libc    : Requested dump for pid 4000 (ory.runintestii)
Line 217346: 01-12 22:00:11.804  4000  4000 D xxTestII MainMicTest: onFinish: result = true

这个时间段之间的就可以不用看了 

software_Speaker:pass
software_Speaker:finish
1970-01-12 19:48:49:393
software_Mainmic:FAIL
software_Mainmic:FINISH
1970-01-12 19:54:06:955

software_Submic:FINISH
1970-01-12 21:55:29:351
software_sleeptest:FAIL
software_sleeptest:FINISH
1970-01-12 21:59:17:945
software_Mainmic:pass
software_Mainmic:finish

1970-01-13 02:26:41:535
software_Speaker:pass
software_Speaker:finish
1970-01-13 02:26:42:547
software_Mainmic:FAIL
software_Mainmic:FINISH

1970-01-13 06:57:26:458
software_Magnetic:FAIL
software_Magnetic:FINISH
1970-01-13 06:57:26:972
software_Mainmic:pass
software_Mainmic:finish

1970-01-13 09:33:06:851
software_Speaker:pass
software_Speaker:finish
1970-01-13 09:33:07:882
software_Mainmic:FAIL
software_Mainmic:FINISH