382230 – valgrind and program crashes on launching using am start command

Bug 382230 - valgrind and program crashes on launching using am start command

Summary: valgrind and program crashes on launching using am start command

Status:	RESOLVED NOT A BUG

Alias:	None

Product:	valgrind
Classification:	Developer tools
Component:	memcheck (other bugs)
Version First Reported In:	3.13.0
Platform:	Android Android 5.x

Importance:	NOR major
Target Milestone:	---
Assignee:	Julian Seward

URL:
Keywords:

Depends on:
Blocks:

Reported:	2017-07-11 07:07 UTC by Amardeep
Modified:	2018-07-24 17:05 UTC (History)
CC List:	0 users

See Also:
Latest Commit:
Version Fixed/Implemented In:
Sentry Crash Report:

Attachments
Add an attachment

Note You need to log in before you can comment on or make changes to this bug.

Description Amardeep 2017-07-11 07:07:19 UTC

I am trying to use valgrind on Android 2.3 and 5.0. But on both platform when i launch android app, valgrind starts for sometime but crashes in few seconds and does not show any heap, error summary.

I have build the valgrind for arm architecture.and v is 3.13.0.

used following command :
setprop wrap.com.example.hellojni "/data/local/Inst/bin/valgrind -v --leak-check=full"


am start -a android.intent.action.MAIN -n com.example.hellojni/.HelloJni

just after issuing this command , app gest launched with valgrind and crashes in  few seconds. log output is below :



D/AndroidRuntime(22923): 
D/AndroidRuntime(22923): >>>>>> AndroidRuntime START com.android.internal.os.RuntimeInit <<<<<<
D/AndroidRuntime(22923): CheckJNI is OFF
D/AndroidRuntime(22923): Calling main entry com.android.commands.am.Am
I/ActivityManager(20315): Force stopping com.example.hellojni appid=10085 user=0: from pid 22923
I/ActivityManager(20315): Killing 22348:com.example.hellojni/u0a85 (adj 0): stop com.example.hellojni
I/WindowState(20315): WIN DEATH: Window{16ab94e2 u0 com.example.hellojni/com.example.hellojni.HelloJni}
I/WindowState(20315): WIN DEATH: Window{337483bd u0 com.example.hellojni/com.example.hellojni.HelloJni}
I/ActivityManager(20315):   Force finishing activity ActivityRecord{2850ad4d u0 com.example.hellojni/.HelloJni t29}
I/art     (22936): Late-enabling -Xcheck:jni
I/valgrind(22952): ==22953== Memcheck, a memory error detector
I/valgrind(22952): ==22953== Copyright (C) 2002-2017, and GNU GPL'd, by Julian Seward et al.
I/valgrind(22952): ==22953== Using Valgrind-3.13.0 and LibVEX; rerun with -h for copyright info
I/valgrind(22952): ==22953== Command: /system/bin/app_process /system/bin --application --nice-name=com.example.hellojni com.android.internal.os.WrapperInit 15 25 android.app.ActivityThread
I/valgrind(22952): ==22953== 
I/valgrind(22952): --22953-- Valgrind options:
I/valgrind(22952): --22953--    -v
I/valgrind(22952): --22953--    --leak-check=full
I/valgrind(22952): --22953-- Contents of /proc/version:
I/valgrind(22952): --22953--   Linux version 3.4.0-g154bef4 (android-build@vpbs1.mtv.corp.google.com) (gcc version 4.7 (GCC) ) #1 SMP PREEMPT Thu Nov 20 22:16:57 UTC 2014
I/valgrind(22952): --22953-- 
I/valgrind(22952): --22953-- Arch and hwcaps: ARM, LittleEndian, ARMv7-neon-vfp
I/valgrind(22952): --22953-- Page sizes: currently 4096, max supported 4096
I/valgrind(22952): --22953-- Valgrind library directory: /data/local/Inst/lib/valgrind
I/valgrind(22952): --22953-- Reading syms from /system/xbin/daemonsu
I/valgrind(22952): --22953--    object doesn't have a symbol table
I/valgrind(22952): --22953--   Reading EXIDX entries: 132 available
I/valgrind(22952): --22953--   Reading EXIDX entries: 128 attempted, 128 successful
I/valgrind(22952): --22953-- Reading syms from /system/bin/linker
I/valgrind(22952): --22953--   Considering /system/bin/linker ..
I/valgrind(22952): --22953--   .. CRC mismatch (computed 9b93b657 wanted 74bca3eb)
I/valgrind(22952): --22953-- Reading syms from /data/local/Inst/lib/valgrind/memcheck-arm-linux
I/valgrind(22952): --22953--    object doesn't have a dynamic symbol table
I/valgrind(22952): --22953-- Scheduler: using generic scheduler lock implementation.
I/valgrind(22952): --22953-- Reading suppressions file: /data/local/Inst/lib/valgrind/default.supp
I/valgrind(22952): --22953-- Reading syms from /data/local/Inst/lib/valgrind/vgpreload_core-arm-linux.so
I/valgrind(22952): --22953-- Reading syms from /data/local/Inst/lib/valgrind/vgpreload_memcheck-arm-linux.so
I/valgrind(22952): --22953-- REDIR: 0x4006b11 (NONE:__dl_strrchr) redirected to 0x481c5c0 (__dl_strrchr)
I/valgrind(22952): --22953-- REDIR: 0x4004ea5 (NONE:__dl_strlen) redirected to 0x481ca68 (__dl_strlen)
I/valgrind(22952): --22953-- REDIR: 0x4004848 (NONE:__dl_strcmp) redirected to 0x481cf58 (__dl_strcmp)
I/valgrind(22952): --22953-- Reading syms from /system/lib/libsigchain.so
I/valgrind(22952): --22953--   Considering /system/lib/libsigchain.so ..
I/valgrind(22952): --22953--   .. CRC mismatch (computed db2f7330 wanted aed0a46e)
I/valgrind(22952): --22953--    object doesn't have a symbol table
I/valgrind(22952): --22953--   Reading EXIDX entries: 12 available
I/valgrind(22952): --22953--   Reading EXIDX entries: 10 attempted, 10 successful
I/valgrind(22952): --22953-- Reading syms from /system/lib/libc.so
I/valgrind(22952): --22953--   Considering /system/lib/libc.so ..
I/valgrind(22952): --22953--   .. CRC mismatch (computed 7e56ad9f wanted e95601fd)
I/valgrind(22952): --22953-- Reading syms from /system/lib/liblog.so
I/valgrind(22952): --22953--   Considering /system/lib/liblog.so ..
I/valgrind(22952): --22953--   .. CRC mismatch (computed c7c5a33b wanted 62851943)
I/valgrind(22952): --22953--    object doesn't have a symbol table
I/valgrind(22952): --22953--   Reading EXIDX entries: 56 available
I/valgrind(22952): --22953--   Reading EXIDX entries: 55 attempted, 55 successful
I/valgrind(22952): --22953-- Reading syms from /system/lib/libm.so
I/valgrind(22952): --22953--   Considering /system/lib/libm.so ..
I/valgrind(22952): --22953--   .. CRC mismatch (computed bba75fa5 wanted 2c8c2cf7)
I/valgrind(22952): --22953--    object doesn't have a symbol table
I/valgrind(22952): --22953--   Reading EXIDX entries: 144 available
I/valgrind(22952): ==22953==   Warning: whilst reading EXIDX: ExtabEntryDecode: failed with error code: -10
I/valgrind(22952): ==22953==   Warning: whilst reading EXIDX: ExtabEntryDecode: failed with error code: -10
I/valgrind(22952): ==22953==   Warning: whilst reading EXIDX: ExtabEntryDecode: failed with error code: -10
I/valgrind(22952): --22953--   Reading EXIDX entries: 143 attempted, 140 successful
I/valgrind(22952): --22953-- Reading syms from /system/lib/libstdc++.so
I/valgrind(22952): --22953--   Considering /system/lib/libstdc++.so ..
I/valgrind(22952): --22953--   .. CRC mismatch (computed 749f212a wanted 832506e8)
I/valgrind(22952): --22953--    object doesn't have a symbol table
I/valgrind(22952): --22953--   Reading EXIDX entries: 17 available
I/valgrind(22952): --22953--   Reading EXIDX entries: 16 attempted, 16 successful
I/valgrind(22952): --22953-- REDIR: 0x483be65 (libc.so:strlen) redirected to 0x481c8e0 (strlen)
I/valgrind(22952): --22953-- REDIR: 0x48785b9 (libc.so:strchr) redirected to 0x481c5f8 (strchr)
I/valgrind(22952): --22953-- REDIR: 0x487ae8d (libc.so:strncmp) redirected to 0x481caa0 (strncmp)
I/valgrind(22952): --22953-- Reading syms from /system/lib/libnetd_client.so
I/valgrind(22952): --22953--   Considering /system/lib/libnetd_client.so ..
I/valgrind(22952): --22953--   .. CRC mismatch (computed de1e9189 wanted a5c85c09)
I/valgrind(22952): --22953--    object doesn't have a symbol table
I/valgrind(22952): --22953--   Reading EXIDX entries: 20 available
I/valgrind(22952): --22953--   Reading EXIDX entries: 19 attempted, 19 successful
I/valgrind(22952): --22953-- REDIR: 0x483afc4 (libc.so:memset) redirected to 0x481d4cc (memset)
I/valgrind(22952): --22953-- REDIR: 0x483a7c9 (libc.so:malloc) redirected to 0x481b150 (malloc)
I/valgrind(22952): --22953-- REDIR: 0x487ab45 (libc.so:memchr) redirected to 0x481cfb4 (memchr)
I/valgrind(22952): --22953-- REDIR: 0x483aeb5 (libc.so:memcpy) redirected to 0x48217f8 (memcpy)
I/valgrind(22952): --22953-- REDIR: 0x483a79d (libc.so:free) redirected to 0x481a978 (free)
I/valgrind(22952): --22953-- REDIR: 0x48785c1 (libc.so:strrchr) redirected to 0x481c438 (strrchr)
I/valgrind(22952): --22953-- REDIR: 0x483b020 (libc.so:strcmp) redirected to 0x481cd30 (strcmp)
I/valgrind(22952): --22953-- REDIR: 0x487aeb3 (libc.so:strncpy) redirected to 0x481f588 (strncpy)
I/valgrind(22952): --22953-- REDIR: 0x483bb1d (libc.so:strcpy) redirected to 0x481e49c (strcpy)
D/AndroidRuntime(22953): 
D/AndroidRuntime(22953): >>>>>> AndroidRuntime START com.android.internal.os.RuntimeInit <<<<<<
D/AndroidRuntime(22953): CheckJNI is OFF
D/AndroidRuntime(22953): Calling main entry com.android.internal.os.WrapperInit
W/main    (22953): type=1400 audit(0.0:26): avc: denied { write } for path="pipe:[102023]" dev="pipefs" ino=102023 scontext=u:r:untrusted_app:s0 tcontext=u:r:zygote:s0 tclass=fifo_file
D/AndroidRuntime(22953): Could not write pid of wrapped process to Zygote pipe.
D/AndroidRuntime(22953): java.io.IOException: write failed: EACCES (Permission denied)
D/AndroidRuntime(22953): 	at libcore.io.IoBridge.write(IoBridge.java:502)
D/AndroidRuntime(22953): 	at java.io.FileOutputStream.write(FileOutputStream.java:186)
D/AndroidRuntime(22953): 	at java.io.DataOutputStream.writeInt(DataOutputStream.java:180)
D/AndroidRuntime(22953): 	at com.android.internal.os.WrapperInit.main(WrapperInit.java:67)
D/AndroidRuntime(22953): 	at com.android.internal.os.RuntimeInit.nativeFinishInit(Native Method)
D/AndroidRuntime(22953): 	at com.android.internal.os.RuntimeInit.main(RuntimeInit.java:249)
D/AndroidRuntime(22953): Caused by: android.system.ErrnoException: write failed: EACCES (Permission denied)
D/AndroidRuntime(22953): 	at libcore.io.Posix.writeBytes(Native Method)
D/AndroidRuntime(22953): 	at libcore.io.Posix.write(Posix.java:223)
D/AndroidRuntime(22953): 	at libcore.io.BlockGuardOs.write(BlockGuardOs.java:313)
D/AndroidRuntime(22953): 	at libcore.io.IoBridge.write(IoBridge.java:497)
D/AndroidRuntime(22953): 	... 5 more
D/Zygote  (22953): begin preload
I/Zygote  (22953): Preloading classes...
E/Zygote  (22953): setregid() failed. errno: 1
E/Zygote  (22953): setreuid() failed. errno: 1
I/art     (22953): Explicit concurrent mark sweep GC freed 1600(143KB) AllocSpace objects, 1(16KB) LOS objects, 92% free, 86KB/1110KB, paused 1.678ms total 12.603ms
I/art     (22953): Counter: 1
I/art     (22953): Explicit concurrent mark sweep GC freed 599(37KB) AllocSpace objects, 0(0B) LOS objects, 91% free, 97KB/1121KB, paused 701us total 2.777ms
I/art     (22953): Explicit concurrent mark sweep GC freed 643(40KB) AllocSpace objects, 0(0B) LOS objects, 90% free, 106KB/1130KB, paused 732us total 6.683ms
I/art     (22953): Explicit concurrent mark sweep GC freed 304(22KB) AllocSpace objects, 2(63KB) LOS objects, 83% free, 208KB/1232KB, paused 640us total 4.821ms
E/Minikin (22953): addFont failed to create font /system/fonts/NanumGothic.ttf
E/Minikin (22953): addFont failed to create font /system/fonts/DroidSansFallback.ttf
I/art     (22953): Explicit concurrent mark sweep GC freed 1511(90KB) AllocSpace objects, 1(16KB) LOS objects, 82% free, 217KB/1241KB, paused 579us total 4.425ms
I/art     (22953): Explicit concurrent mark sweep GC freed 1949(93KB) AllocSpace objects, 0(0B) LOS objects, 79% free, 263KB/1287KB, paused 579us total 6.378ms
I/art     (22953): Explicit concurrent mark sweep GC freed 1984(88KB) AllocSpace objects, 0(0B) LOS objects, 77% free, 300KB/1324KB, paused 610us total 5.004ms
I/art     (22953): Explicit concurrent mark sweep GC freed 410(29KB) AllocSpace objects, 0(0B) LOS objects, 75% free, 336KB/1360KB, paused 610us total 4.974ms
I/valgrind(22952): referenceTable GDEF length=670 1
I/valgrind(22952): referenceTable GSUB length=7202 1
I/valgrind(22952): referenceTable GPOS length=24560 1
I/art     (22953): Thread[1,tid=22953,WaitingForJniOnLoad,Thread*=0xb4f07800,peer=0x72d7f0e0,"main"] recursive attempt to load library "/system/lib/libmedia_jni.so"
D/MtpDeviceJNI(22953): register_android_mtp_MtpDevice
I/art     (22953): Thread[1,tid=22953,WaitingForJniOnLoad,Thread*=0xb4f07800,peer=0x72d7f0e0,"main"] recursive attempt to load library "/system/lib/libmedia_jni.so"
I/art     (22953): Thread[1,tid=22953,WaitingForJniOnLoad,Thread*=0xb4f07800,peer=0x72d7f0e0,"main"] recursive attempt to load library "/system/lib/libmedia_jni.so"
I/art     (22953): Explicit concurrent mark sweep GC freed 645(37KB) AllocSpace objects, 0(0B) LOS objects, 74% free, 347KB/1371KB, paused 640us total 8.026ms
I/art     (22953): Explicit concurrent mark sweep GC freed 547(27KB) AllocSpace objects, 0(0B) LOS objects, 73% free, 375KB/1399KB, paused 610us total 7.690ms
I/art     (22953): Explicit concurrent mark sweep GC freed 546(31KB) AllocSpace objects, 0(0B) LOS objects, 71% free, 401KB/1425KB, paused 610us total 7.904ms
I/art     (22953): Explicit concurrent mark sweep GC freed 1858(109KB) AllocSpace objects, 0(0B) LOS objects, 64% free, 567KB/1591KB, paused 610us total 8.026ms
E/EmojiFactory_jni(22953): Failed to load libemoji.so: dlopen failed: library "libemoji.so" not found
I/art     (22953): Explicit concurrent mark sweep GC freed 365(46KB) AllocSpace objects, 0(0B) LOS objects, 54% free, 865KB/1889KB, paused 579us total 10.223ms
I/art     (22953): Explicit concurrent mark sweep GC freed 440(30KB) AllocSpace objects, 0(0B) LOS objects, 53% free, 884KB/1908KB, paused 640us total 7.354ms
I/art     (22953): Explicit concurrent mark sweep GC freed 778(49KB) AllocSpace objects, 0(0B) LOS objects, 53% free, 884KB/1908KB, paused 640us total 7.202ms
I/art     (22953): Explicit concurrent mark sweep GC freed 629(35KB) AllocSpace objects, 0(0B) LOS objects, 53% free, 906KB/1930KB, paused 701us total 11.383ms
I/SamplingProfilerIntegration(22953): Profiling disabled.
I/art     (22953): Explicit concurrent mark sweep GC freed 601(43KB) AllocSpace objects, 0(0B) LOS objects, 52% free, 912KB/1936KB, paused 701us total 9.246ms
I/art     (22953): Explicit concurrent mark sweep GC freed 263(21KB) AllocSpace objects, 0(0B) LOS objects, 52% free, 940KB/1964KB, paused 732us total 8.117ms
I/art     (22953): Explicit concurrent mark sweep GC freed 289(45KB) AllocSpace objects, 0(0B) LOS objects, 51% free, 949KB/1973KB, paused 610us total 8.605ms
I/art     (22953): Explicit concurrent mark sweep GC freed 10625(491KB) AllocSpace objects, 1(16KB) LOS objects, 43% free, 1327KB/2MB, paused 640us total 11.535ms
I/art     (22953): Explicit concurrent mark sweep GC freed 592(34KB) AllocSpace objects, 0(0B) LOS objects, 43% free, 1342KB/2MB, paused 671us total 10.467ms
I/art     (22953): Explicit concurrent mark sweep GC freed 744(44KB) AllocSpace objects, 0(0B) LOS objects, 43% free, 1346KB/2MB, paused 640us total 10.620ms
I/System  (22953): Loaded time zone names for "" in 74ms (70ms in ICU)
I/System  (22953): Loaded time zone names for "en_US" in 52ms (48ms in ICU)
I/art     (22953): Explicit concurrent mark sweep GC freed 2182(100KB) AllocSpace objects, 0(0B) LOS objects, 41% free, 1415KB/2MB, paused 732us total 12.359ms
I/art     (22953): Explicit concurrent mark sweep GC freed 136(12KB) AllocSpace objects, 0(0B) LOS objects, 40% free, 1480KB/2MB, paused 610us total 11.077ms
I/art     (22953): Explicit concurrent mark sweep GC freed 289(24KB) AllocSpace objects, 0(0B) LOS objects, 40% free, 1532KB/2MB, paused 640us total 10.650ms
I/art     (22953): Explicit concurrent mark sweep GC freed 678(47KB) AllocSpace objects, 0(0B) LOS objects, 40% free, 1533KB/2MB, paused 762us total 10.314ms
I/Zygote  (22953): ...preloaded 3005 classes in 1271ms.
I/art     (22953): VMRuntime.preloadDexCaches starting
I/art     (22953): VMRuntime.preloadDexCaches strings total=207505 before=38318 after=38318
I/art     (22953): VMRuntime.preloadDexCaches types total=18061 before=6313 after=6349
I/art     (22953): VMRuntime.preloadDexCaches fields total=84295 before=31902 after=32227
I/art     (22953): VMRuntime.preloadDexCaches methods total=149674 before=66306 after=67190
I/art     (22953): VMRuntime.preloadDexCaches finished
I/art     (22953): Counter: 0
E/Zygote  (22953): setreuid() failed. errno: 1
E/Zygote  (22953): setregid() failed. errno: 1
I/art     (22953): Counter: 1
I/art     (22953): Explicit concurrent mark sweep GC freed 82(16KB) AllocSpace objects, 0(0B) LOS objects, 40% free, 1518KB/2MB, paused 640us total 16.876ms
E/asset   (22966): setgid: Operation not permitted
I/Zygote  (22953): Preloading resources...
I/art     (22953): Explicit concurrent mark sweep GC freed 48(1760B) AllocSpace objects, 0(0B) LOS objects, 39% free, 1570KB/2MB, paused 793us total 11.169ms
I/art     (22953): Explicit concurrent mark sweep GC freed 28(1264B) AllocSpace objects, 0(0B) LOS objects, 39% free, 1620KB/2MB, paused 762us total 10.650ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 40% free, 1669KB/2MB, paused 701us total 11.230ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 1719KB/2MB, paused 701us total 9.765ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 40% free, 1768KB/2MB, paused 701us total 10.009ms
I/art     (22953): Explicit concurrent mark sweep GC freed 34(1584B) AllocSpace objects, 0(0B) LOS objects, 39% free, 1818KB/2MB, paused 640us total 10.192ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 1868KB/3MB, paused 671us total 9.887ms
I/art     (22953): Explicit concurrent mark sweep GC freed 68(3056B) AllocSpace objects, 0(0B) LOS objects, 39% free, 1925KB/3MB, paused 640us total 9.857ms
I/art     (22953): Explicit concurrent mark sweep GC freed 36(1648B) AllocSpace objects, 0(0B) LOS objects, 40% free, 1974KB/3MB, paused 640us total 10.620ms
I/art     (22953): Explicit concurrent mark sweep GC freed 36(1968B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2024KB/3MB, paused 640us total 9.826ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/3MB, paused 701us total 10.040ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 40% free, 2MB/3MB, paused 640us total 10.223ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/3MB, paused 610us total 9.948ms
I/art     (22953): Explicit concurrent mark sweep GC freed 34(2128B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/3MB, paused 640us total 9.826ms
I/art     (22953): Explicit concurrent mark sweep GC freed 36(1696B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/3MB, paused 732us total 10.070ms
I/art     (22953): Explicit concurrent mark sweep GC freed 37(1760B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/3MB, paused 671us total 10.833ms
I/art     (22953): Explicit concurrent mark sweep GC freed 54(2304B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/3MB, paused 610us total 11.016ms
I/art     (22953): Explicit concurrent mark sweep GC freed 38(1744B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/3MB, paused 610us total 10.833ms
I/art     (22953): Explicit concurrent mark sweep GC freed 36(2480B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/4MB, paused 610us total 10.955ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/4MB, paused 610us total 11.260ms
I/art     (22953): Explicit concurrent mark sweep GC freed 49(2080B) AllocSpace objects, 0(0B) LOS objects, 40% free, 2MB/4MB, paused 610us total 11.077ms
I/art     (22953): Explicit concurrent mark sweep GC freed 43(1920B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/4MB, paused 610us total 10.803ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/4MB, paused 610us total 11.444ms
I/art     (22953): Explicit concurrent mark sweep GC freed 47(2016B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/4MB, paused 640us total 10.192ms
I/art     (22953): Explicit concurrent mark sweep GC freed 46(2128B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/4MB, paused 610us total 10.253ms
I/art     (22953): Explicit concurrent mark sweep GC freed 37(1760B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/4MB, paused 640us total 9.979ms
I/art     (22953): Explicit concurrent mark sweep GC freed 37(1760B) AllocSpace objects, 0(0B) LOS objects, 39% free, 2MB/4MB, paused 640us total 10.192ms
I/art     (22953): Explicit concurrent mark sweep GC freed 37(1760B) AllocSpace objects, 0(0B) LOS objects, 40% free, 2MB/4MB, paused 640us total 9.887ms
I/art     (22953): Explicit concurrent mark sweep GC freed 116(4KB) AllocSpace objects, 0(0B) LOS objects, 40% free, 3MB/5MB, paused 640us total 11.138ms
I/art     (22953): Explicit concurrent mark sweep GC freed 119(5KB) AllocSpace objects, 0(0B) LOS objects, 40% free, 3MB/5MB, paused 671us total 10.162ms
I/art     (22953): Explicit concurrent mark sweep GC freed 62(2864B) AllocSpace objects, 0(0B) LOS objects, 39% free, 3MB/5MB, paused 640us total 10.345ms
I/art     (22953): Explicit concurrent mark sweep GC freed 37(5KB) AllocSpace objects, 0(0B) LOS objects, 40% free, 3MB/5MB, paused 610us total 10.070ms
I/art     (22953): Explicit concurrent mark sweep GC freed 50(2112B) AllocSpace objects, 0(0B) LOS objects, 39% free, 3MB/5MB, paused 610us total 11.779ms
I/art     (22953): Explicit concurrent mark sweep GC freed 100(4KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 3MB/5MB, paused 640us total 11.138ms
I/art     (22953): Explicit concurrent mark sweep GC freed 51(2240B) AllocSpace objects, 0(0B) LOS objects, 40% free, 3MB/5MB, paused 610us total 11.230ms
I/art     (22953): Explicit concurrent mark sweep GC freed 21(960B) AllocSpace objects, 0(0B) LOS objects, 39% free, 3MB/5MB, paused 610us total 11.535ms
I/art     (22953): Explicit concurrent mark sweep GC freed 21(960B) AllocSpace objects, 0(0B) LOS objects, 39% free, 3MB/6MB, paused 610us total 11.230ms
I/art     (22953): Explicit concurrent mark sweep GC freed 36(1552B) AllocSpace objects, 0(0B) LOS objects, 39% free, 3MB/6MB, paused 610us total 11.322ms
I/art     (22953): Explicit concurrent mark sweep GC freed 47(2032B) AllocSpace objects, 0(0B) LOS objects, 40% free, 3MB/6MB, paused 610us total 11.383ms
I/art     (22953): Explicit concurrent mark sweep GC freed 16(688B) AllocSpace objects, 0(0B) LOS objects, 39% free, 3MB/6MB, paused 671us total 10.986ms
I/art     (22953): Explicit concurrent mark sweep GC freed 108(4KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 5MB/8MB, paused 640us total 11.749ms
I/art     (22953): Explicit concurrent mark sweep GC freed 190(8KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 5MB/8MB, paused 640us total 10.559ms
I/art     (22953): Explicit concurrent mark sweep GC freed 242(10KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 5MB/8MB, paused 610us total 12.054ms
I/art     (22953): Explicit concurrent mark sweep GC freed 88(3KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 5MB/8MB, paused 640us total 11.260ms
I/art     (22953): Explicit concurrent mark sweep GC freed 77(3KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 5MB/8MB, paused 640us total 11.566ms
I/art     (22953): Explicit concurrent mark sweep GC freed 100(4KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 5MB/9MB, paused 610us total 11.291ms
I/art     (22953): Explicit concurrent mark sweep GC freed 95(4KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 5MB/9MB, paused 610us total 11.535ms
I/art     (22953): Explicit concurrent mark sweep GC freed 62(2800B) AllocSpace objects, 0(0B) LOS objects, 39% free, 5MB/9MB, paused 610us total 10.955ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1408B) AllocSpace objects, 0(0B) LOS objects, 40% free, 5MB/9MB, paused 610us total 10.681ms
I/art     (22953): Explicit concurrent mark sweep GC freed 45(3KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 5MB/9MB, paused 671us total 10.498ms
I/art     (22953): Explicit concurrent mark sweep GC freed 49(2128B) AllocSpace objects, 0(0B) LOS objects, 40% free, 5MB/9MB, paused 1.586ms total 12.329ms
I/art     (22953): Explicit concurrent mark sweep GC freed 56(2576B) AllocSpace objects, 0(0B) LOS objects, 39% free, 5MB/9MB, paused 640us total 11.566ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1488B) AllocSpace objects, 0(0B) LOS objects, 40% free, 5MB/9MB, paused 579us total 11.535ms
I/art     (22953): Explicit concurrent mark sweep GC freed 17(752B) AllocSpace objects, 0(0B) LOS objects, 39% free, 5MB/9MB, paused 671us total 11.444ms
I/art     (22953): Explicit concurrent mark sweep GC freed 13(560B) AllocSpace objects, 0(0B) LOS objects, 39% free, 5MB/9MB, paused 640us total 11.596ms
I/art     (22953): Explicit concurrent mark sweep GC freed 21(2912B) AllocSpace objects, 0(0B) LOS objects, 40% free, 5MB/9MB, paused 610us total 11.413ms
I/art     (22953): Explicit concurrent mark sweep GC freed 23(4KB) AllocSpace objects, 0(0B) LOS objects, 40% free, 6MB/10MB, paused 610us total 11.596ms
I/art     (22953): Explicit concurrent mark sweep GC freed 43(2048B) AllocSpace objects, 0(0B) LOS objects, 40% free, 6MB/10MB, paused 610us total 11.474ms
I/art     (22953): Explicit concurrent mark sweep GC freed 39(1856B) AllocSpace objects, 0(0B) LOS objects, 39% free, 6MB/10MB, paused 610us total 12.115ms
I/art     (22953): Explicit concurrent mark sweep GC freed 37(1760B) AllocSpace objects, 0(0B) LOS objects, 40% free, 6MB/10MB, paused 640us total 11.474ms
I/art     (22953): Explicit concurrent mark sweep GC freed 44(1984B) AllocSpace objects, 0(0B) LOS objects, 39% free, 6MB/10MB, paused 610us total 11.627ms
I/art     (22953): Explicit concurrent mark sweep GC freed 44(1776B) AllocSpace objects, 0(0B) LOS objects, 40% free, 6MB/10MB, paused 640us total 11.108ms
I/art     (22953): Explicit concurrent mark sweep GC freed 56(2480B) AllocSpace objects, 0(0B) LOS objects, 39% free, 6MB/10MB, paused 610us total 10.894ms
I/art     (22953): Explicit concurrent mark sweep GC freed 28(1200B) AllocSpace objects, 0(0B) LOS objects, 40% free, 6MB/10MB, paused 640us total 10.620ms
I/art     (22953): Explicit concurrent mark sweep GC freed 28(1264B) AllocSpace objects, 0(0B) LOS objects, 40% free, 6MB/10MB, paused 640us total 11.749ms
I/art     (22953): Explicit concurrent mark sweep GC freed 28(1200B) AllocSpace objects, 0(0B) LOS objects, 40% free, 6MB/11MB, paused 640us total 11.810ms
I/art     (22953): Explicit concurrent mark sweep GC freed 137(6KB) AllocSpace objects, 0(0B) LOS objects, 40% free, 6MB/11MB, paused 640us total 12.359ms
I/art     (22953): Explicit concurrent mark sweep GC freed 173(7KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 6MB/11MB, paused 640us total 10.803ms
I/art     (22953): Explicit concurrent mark sweep GC freed 84(3KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 6MB/11MB, paused 640us total 11.016ms
I/art     (22953): Explicit concurrent mark sweep GC freed 36(1648B) AllocSpace objects, 0(0B) LOS objects, 39% free, 6MB/11MB, paused 640us total 10.803ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 6MB/11MB, paused 640us total 11.871ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 6MB/11MB, paused 671us total 12.176ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/11MB, paused 610us total 11.932ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 40% free, 7MB/11MB, paused 640us total 11.718ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 40% free, 7MB/11MB, paused 640us total 12.359ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 40% free, 7MB/11MB, paused 640us total 11.718ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 40% free, 7MB/11MB, paused 610us total 11.871ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 40% free, 7MB/12MB, paused 610us total 12.084ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/12MB, paused 640us total 11.932ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/12MB, paused 640us total 11.077ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/12MB, paused 640us total 11.169ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/12MB, paused 640us total 10.864ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/12MB, paused 640us total 11.535ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/12MB, paused 671us total 11.749ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/12MB, paused 610us total 11.993ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/12MB, paused 640us total 12.207ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/12MB, paused 610us total 11.962ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/12MB, paused 610us total 11.779ms
I/art     (22953): Explicit concurrent mark sweep GC freed 32(1456B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/12MB, paused 610us total 11.901ms
I/art     (22953): Explicit concurrent mark sweep GC freed 25(1120B) AllocSpace objects, 0(0B) LOS objects, 40% free, 7MB/13MB, paused 640us total 11.779ms
I/art     (22953): Explicit concurrent mark sweep GC freed 23(1024B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/13MB, paused 640us total 11.871ms
I/art     (22953): Explicit concurrent mark sweep GC freed 33(1568B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/13MB, paused 640us total 10.864ms
I/art     (22953): Explicit concurrent mark sweep GC freed 37(1760B) AllocSpace objects, 0(0B) LOS objects, 39% free, 7MB/13MB, paused 640us total 11.077ms
I/art     (22953): Explicit concurrent mark sweep GC freed 37(1760B) AllocSpace objects, 0(0B) LOS objects, 39% free, 8MB/13MB, paused 610us total 11.383ms
I/art     (22953): Explicit concurrent mark sweep GC freed 37(1760B) AllocSpace objects, 0(0B) LOS objects, 39% free, 8MB/13MB, paused 640us total 11.169ms
I/art     (22953): Explicit concurrent mark sweep GC freed 37(1760B) AllocSpace objects, 0(0B) LOS objects, 39% free, 8MB/13MB, paused 1.556ms total 11.901ms
I/art     (22953): Explicit concurrent mark sweep GC freed 37(1760B) AllocSpace objects, 0(0B) LOS objects, 39% free, 8MB/13MB, paused 610us total 12.512ms
I/art     (22953): Explicit concurrent mark sweep GC freed 38(1792B) AllocSpace objects, 0(0B) LOS objects, 40% free, 8MB/13MB, paused 579us total 11.840ms
I/art     (22953): Explicit concurrent mark sweep GC freed 155(5KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 8MB/13MB, paused 640us total 12.145ms
I/art     (22953): Explicit concurrent mark sweep GC freed 250(8KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 8MB/13MB, paused 640us total 11.901ms
I/art     (22953): Explicit concurrent mark sweep GC freed 70(2928B) AllocSpace objects, 0(0B) LOS objects, 40% free, 8MB/13MB, paused 640us total 11.260ms
I/art     (22953): Explicit concurrent mark sweep GC freed 1076(36KB) AllocSpace objects, 0(0B) LOS objects, 40% free, 8MB/14MB, paused 640us total 12.573ms
I/art     (22953): Explicit concurrent mark sweep GC freed 771(26KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 8MB/14MB, paused 610us total 12.329ms
I/art     (22953): Explicit concurrent mark sweep GC freed 1358(46KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 8MB/14MB, paused 610us total 12.145ms
I/art     (22953): Explicit concurrent mark sweep GC freed 787(28KB) AllocSpace objects, 0(0B) LOS objects, 40% free, 8MB/14MB, paused 701us total 11.505ms
I/art     (22953): Explicit concurrent mark sweep GC freed 118(6KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 8MB/14MB, paused 640us total 11.199ms
I/art     (22953): Explicit concurrent mark sweep GC freed 65(3040B) AllocSpace objects, 0(0B) LOS objects, 39% free, 8MB/14MB, paused 610us total 12.329ms
I/art     (22953): Explicit concurrent mark sweep GC freed 62(2960B) AllocSpace objects, 0(0B) LOS objects, 39% free, 8MB/14MB, paused 610us total 12.298ms
I/Zygote  (22953): ...preloaded 430 resources in 2040ms.
I/art     (22953): Explicit concurrent mark sweep GC freed 75(3KB) AllocSpace objects, 0(0B) LOS objects, 39% free, 8MB/14MB, paused 671us total 14.495ms
I/Zygote  (22953): ...preloaded 41 resources in 29ms.
I/art     (22953): Counter: 0
D/libEGL  (22953): loaded /vendor/lib/egl/libEGL_adreno.so
D/libEGL  (22953): loaded /vendor/lib/egl/libGLESv1_CM_adreno.so
D/libEGL  (22953): loaded /vendor/lib/egl/libGLESv2_adreno.so
I/Zygote  (22953): Preloading shared libraries...
D/Zygote  (22953): end preload
I/PowerManagerService(20315): Waking up from sleep (uid 1000)...
V/KeyguardServiceDelegate(20315): onScreenTurnedOn(showListener = com.android.internal.policy.impl.PhoneWindowManager$2@9fe350e)
I/DisplayPowerController(20315): Blocking screen on until initial contents have been drawn.
D/SurfaceFlinger(  184): Set power mode=2, type=0 flinger=0xb6a62000
I/DisplayManagerService(20315): Display device changed: DisplayDeviceInfo{"Built-in Screen": 1200 x 1920, 60.0 fps, supportedRefreshRates [60.0], density 320, 320.842 x 322.966 dpi, appVsyncOff 0, presDeadline 17666667, touch INTERNAL, rotation 0, type BUILT_IN, state ON, FLAG_DEFAULT_DISPLAY, FLAG_ROTATES_WITH_CONTENT, FLAG_SECURE, FLAG_SUPPORTS_PROTECTED_BUFFERS}
V/KeyguardServiceDelegate(20315): **** SHOWN CALLED ****
I/DisplayPowerController(20315): Unblocked screen on after 115 ms
D/SurfaceControl(20315): Excessive delay in setPowerMode(): 246ms
I/PowerManagerService(20315): Going to sleep due to screen timeout (uid 1000)...
D/AndroidRuntime(22980): 
D/AndroidRuntime(22980): >>>>>> AndroidRuntime START com.android.internal.os.RuntimeInit <<<<<<
D/AndroidRuntime(22980): CheckJNI is OFF
D/AndroidRuntime(22980): Calling main entry com.android.commands.am.Am
D/AndroidRuntime(22980): Shutting down VM
I/art     (22980): Debugger is no longer active
E/art     (22980): Thread attaching while runtime is shutting down: Binder_1
I/AndroidRuntime(22980): NOTE: attach of thread 'Binder_1' failed
I/InputDispatcher(20315): Application is not responding: Window{32889ac6 u0 StatusBar}.  It has been 5004.1ms since event, 5003.8ms since wait started.  Reason: Waiting to send non-key event because the touched window has not finished processing certain input events that were delivered to it over 500.0ms ago.  Wait queue length: 8.  Wait queue head age: 5507.9ms.
I/WindowManager(20315): Input event dispatching timed out sending to StatusBar.  Reason: Waiting to send non-key event because the touched window has not finished processing certain input events that were delivered to it over 500.0ms ago.  Wait queue length: 8.  Wait queue head age: 5507.9ms.
I/PowerManagerService(20315): Waking up from dozing (uid 1000)...
I/Process (20315): Sending signal. PID: 20315 SIG: 3
I/art     (20315): Thread[5,tid=20324,WaitingInMainSignalCatcherLoop,Thread*=0xaf40e400,peer=0x12c02080,"Signal Catcher"]: reacting to signal 3
I/art     (20315): Wrote stack traces to '/data/anr/traces.txt'


Please check.

Comment 1 Julian Seward 2018-07-24 17:05:06 UTC

There's no evidence I can see in the log, that Valgrind actually crashed
or failed here.