zygote64 OutOfMemoryError on DEBUG = true in TvInputService.java

[Dean Wheatley]

Changing frameworks/base/media/java/android/media/tv/TvInputService.java

private static final boolean DEBUG = false;

to

private static final boolean DEBUG = true;

on a yukawa-userdebug AOSP master build causes zygote boot issues like those copied below:

1-01 00:00:22.711 2589 2822 W zygote64: Core platform API violation: Lsun/misc/Unsafe;->allocateInstance(Ljava/lang/Class;)Ljava/lang/Object; from /system/framework/framework.jar!classes3.dex using linking

01-01 00:00:22.848 2589 2826 I zygote64: Clamp target GC heap from 17MB to 16MB

01-01 00:00:22.848 2589 2826 I zygote64: Background concurrent copying GC freed 7581(403KB) AllocSpace objects, 0(0B) LOS objects, 26% free, 11MB/16MB, paused 126us total 116.938ms

01-01 00:00:23.029 2589 2589 I zygote64: Thread[1,tid=2589,Native,Thread*=0xb400efba46eaa380,peer=0x12c40078,"main"] recursive attempt to load library "libmedia_jni.so"

01-01 00:00:23.030 2589 2589 I zygote64: Thread[1,tid=2589,Native,Thread*=0xb400efba46eaa380,peer=0x12c40078,"main"] recursive attempt to load library "libmedia_jni.so"

01-01 00:00:23.031 2589 2589 D MtpDeviceJNI: register_android_mtp_MtpDevice

01-01 00:00:23.031 2589 2589 I zygote64: Thread[1,tid=2589,Native,Thread*=0xb400efba46eaa380,peer=0x12c40078,"main"] recursive attempt to load library "libmedia_jni.so"

01-01 00:00:23.032 2589 2589 I zygote64: Thread[1,tid=2589,Native,Thread*=0xb400efba46eaa380,peer=0x12c40078,"main"] recursive attempt to load library "libmedia_jni.so"

01-01 00:00:23.034 2589 2589 W MediaProfiles: Could not find a validated xml file. Using the default instance instead.

01-01 00:00:23.048 2589 2589 W Zygote : Class not found for preloading: android.media.MediaRouter$Static$Client$1

01-01 00:00:23.049 2589 2589 W Zygote : Class not found for preloading: android.media.MediaRouter$Static$Client$2

01-01 00:00:23.091 2589 2589 W Zygote : Class not found for preloading: android.net.shared.Inet4AddressUtils

01-01 00:00:23.091 2589 2589 W Zygote : Class not found for preloading: android.net.shared.InetAddressUtils

01-01 00:00:23.184 2589 2589 W Zygote : Class not found for preloading: android.service.carrier.CarrierMessagingServiceWrapper$CarrierMessagingCallbackWrapper

01-01 00:00:23.217 2589 2589 W Zygote : Class not found for preloading: android.system.suspend.ISuspendControlService$Stub$Proxy

01-01 00:00:23.217 2589 2589 W Zygote : Class not found for preloading: android.system.suspend.ISuspendControlService$Stub

01-01 00:00:23.218 2589 2589 W Zygote : Class not found for preloading: android.system.suspend.ISuspendControlService

01-01 00:00:23.218 2589 2589 W Zygote : Class not found for preloading: android.system.suspend.WakeLockInfo$1

01-01 00:00:23.218 2589 2589 W Zygote : Class not found for preloading: android.system.suspend.WakeLockInfo

01-01 00:00:23.245 2590 2820 W zygote : Core platform API violation: Lsun/misc/Unsafe;->allocateInstance(Ljava/lang/Class;)Ljava/lang/Object; from /system/framework/framework.jar!classes3.dex using linking

01-01 00:00:23.518 2589 2589 W Zygote : Class not found for preloading: android.view.contentcapture.MainContentCaptureSession$1

01-01 00:00:23.757 2589 2589 W Zygote : Class not found for preloading: com.android.ims.FeatureConnection$IFeatureUpdate

01-01 00:00:23.760 2589 2589 W Zygote : Class not found for preloading: com.android.ims.IFeatureConnector

01-01 00:00:23.772 2589 2589 W Zygote : Class not found for preloading: com.android.ims.ImsManager$3

01-01 00:00:23.772 2589 2589 W Zygote : Class not found for preloading: com.android.ims.ImsManager$ExecutorFactory

01-01 00:00:23.773 2589 2589 W Zygote : Class not found for preloading: com.android.ims.ImsManager$ImsExecutorFactory

01-01 00:00:23.924 2589 2589 W Zygote : Class not found for preloading: com.android.internal.os.RuntimeInit$RuntimeThreadPrioritySetter

01-01 00:00:24.410 2589 2589 W Zygote : Class not found for preloading: com.android.internal.telephony.IccSmsInterfaceManager$2

01-01 00:00:24.766 2589 2589 W Zygote : Class not found for preloading: com.android.internal.telephony.dataconnection.DcController$1

01-01 00:00:24.766 2589 2589 W Zygote : Class not found for preloading: com.android.internal.telephony.dataconnection.DcController$DccDefaultState

01-01 00:00:24.776 2589 2589 W Zygote : Class not found for preloading: com.android.internal.telephony.dataconnection.DcTracker$5

01-01 00:00:24.867 2589 2589 W Zygote : Class not found for preloading: com.android.internal.telephony.ims.ImsServiceController$ImsFeatureContainer

01-01 00:00:24.908 2589 2589 W Zygote : Class not found for preloading: com.android.internal.telephony.imsphone.ImsPhoneCallTracker$PhoneNumberUtilsProxy

01-01 00:00:25.032 2723 2846 W ServiceManager: Service package_native didn't start. Returning NULL

01-01 00:00:25.032 2723 2846 E storaged: getService package_native failed

01-01 00:00:25.087 2589 2589 W Zygote : Class not found for preloading: com.android.internal.widget.LockPatternUtils$2

01-01 00:00:25.132 2589 2589 W Zygote : Class not found for preloading: dalvik.system.ThreadPrioritySetter

01-01 00:00:25.142 2589 2589 W Zygote : Class not found for preloading: java.math.BigInt

01-01 00:00:25.142 2589 2589 W Zygote : Class not found for preloading: java.math.Conversion

01-01 00:00:25.142 2589 2589 W Zygote : Class not found for preloading: java.math.Multiplication

01-01 00:00:25.142 2589 2589 W Zygote : Class not found for preloading: java.math.NativeBN

01-01 00:00:25.192 2589 2589 W Zygote : Class not found for preloading: libcore.icu.RelativeDateTimeFormatter$FormatterCache

01-01 00:00:25.192 2589 2589 W Zygote : Class not found for preloading: libcore.icu.RelativeDateTimeFormatter

01-01 00:00:25.194 2589 2589 W Zygote : Class not found for preloading: libcore.timezone.CountryTimeZones$OffsetResult

01-01 00:00:25.194 2589 2589 W Zygote : Class not found for preloading: libcore.timezone.CountryTimeZones$TimeZoneMapping

01-01 00:00:25.194 2589 2589 W Zygote : Class not found for preloading: libcore.timezone.CountryTimeZones

01-01 00:00:25.194 2589 2589 W Zygote : Class not found for preloading: libcore.timezone.TimeZoneDataFiles

01-01 00:00:25.194 2589 2589 W Zygote : Class not found for preloading: libcore.timezone.TimeZoneFinder$SelectiveCountryTimeZonesExtractor

01-01 00:00:25.195 2589 2589 W Zygote : Class not found for preloading: libcore.timezone.TimeZoneFinder$TimeZonesProcessor

01-01 00:00:25.195 2589 2589 W Zygote : Class not found for preloading: libcore.timezone.TimeZoneFinder

01-01 00:00:25.195 2589 2589 W Zygote : Class not found for preloading: libcore.timezone.ZoneInfoDb$1

01-01 00:00:25.195 2589 2589 W Zygote : Class not found for preloading: libcore.timezone.ZoneInfoDb

01-01 00:00:25.196 2589 2589 W Zygote : Class not found for preloading: libcore.util.ZoneInfo$CheckedArithmeticException

01-01 00:00:25.196 2589 2589 W Zygote : Class not found for preloading: libcore.util.ZoneInfo$WallTime

01-01 00:00:25.239 2633 2737 W ServiceManager: Service media.audio_policy didn't start. Returning NULL

01-01 00:00:25.239 2633 2737 W AudioSystem: AudioPolicyService not published, waiting...

01-01 00:00:25.242 2589 2589 I Zygote : ...preloaded 11598 classes in 7101ms.

01-01 00:00:25.242 2589 2589 I zygote64: VMRuntime.preloadDexCaches starting

01-01 00:00:25.296 2589 2589 I zygote64: VMRuntime.preloadDexCaches strings total=367978 before=7281 after=7281

01-01 00:00:25.296 2589 2589 I zygote64: VMRuntime.preloadDexCaches types total=42008 before=7744 after=10013

01-01 00:00:25.296 2589 2589 I zygote64: VMRuntime.preloadDexCaches fields total=176639 before=8647 after=11975

01-01 00:00:25.296 2589 2589 I zygote64: VMRuntime.preloadDexCaches methods total=322679 before=10178 after=14620

01-01 00:00:25.296 2589 2589 I zygote64: VMRuntime.preloadDexCaches finished

01-01 00:00:25.298 2589 2589 D Zygote64Timing: PreloadClasses took to complete: 7158ms

01-01 00:00:25.312 2589 2589 D ApplicationLoaders: Created zygote-cached class loader: /system/framework/android.hidl.base-V1.0-java.jar

01-01 00:00:25.339 2589 2589 D ApplicationLoaders: Created zygote-cached class loader: /system/framework/android.hidl.manager-V1.0-java.jar

01-01 00:00:25.349 2589 2589 D ApplicationLoaders: Created zygote-cached class loader: /system/framework/android.test.base.jar

01-01 00:00:25.349 2589 2589 D Zygote64Timing: CacheNonBootClasspathClassLoaders took to complete: 51ms

01-01 00:00:25.350 2589 2589 I Zygote : Preloading resources...

01-01 00:00:25.369 2589 2589 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:25.379 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:25.472 2589 2826 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:25.472 2589 2826 I zygote64: Background concurrent copying GC freed 8531(561KB) AllocSpace objects, 0(0B) LOS objects, 3% free, 15MB/16MB, paused 83us total 228.822ms

01-01 00:00:25.472 2589 2589 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 102.900ms

01-01 00:00:25.472 2589 2589 I zygote64: Starting a blocking GC Alloc

01-01 00:00:25.472 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 92.444ms

01-01 00:00:25.472 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:25.503 2589 2589 W Resources: Preloaded drawable resource #0x108028b (android:drawable/dialog_background_material) that varies with configuration!!

01-01 00:00:25.583 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:25.583 2589 2589 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:25.586 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:25.587 2589 2589 I zygote64: Starting a blocking GC Alloc

01-01 00:00:25.609 2589 2589 I Zygote : ...preloaded 64 resources in 259ms.

01-01 00:00:25.646 2589 2589 I Zygote : ...preloaded 41 resources in 37ms.

01-01 00:00:25.646 2589 2589 D Zygote64Timing: PreloadResources took to complete: 297ms

01-01 00:00:25.686 2589 2589 D libEGL : loaded /vendor/lib64/egl/libGLES_mali.so

01-01 00:00:25.715 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:25.734 2589 2589 I Zygote : Preloading shared libraries...

01-01 00:00:25.738 2589 2589 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:25.739 2633 2737 I ServiceManager: Waiting for service 'media.audio_policy' on '/dev/binder'...

01-01 00:00:25.781 2589 2826 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:25.782 2589 2826 I zygote64: Background concurrent copying GC freed 1750(147KB) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 92us total 163.294ms

01-01 00:00:25.782 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 66.359ms

01-01 00:00:25.782 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:25.782 2589 2589 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 43.425ms

01-01 00:00:25.782 2589 2589 I zygote64: Starting a blocking GC Alloc

01-01 00:00:25.800 2589 2589 I Zygote : Called ZygoteHooks.endPreload()

01-01 00:00:25.803 2589 2589 I Zygote : Installed AndroidKeyStoreProvider in 3ms.

01-01 00:00:25.810 2589 2589 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:25.810 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:25.842 2709 2709 W ServiceManager: Service package_native didn't start. Returning NULL

01-01 00:00:25.842 2709 2709 E iorapd : Cannot get package manager service!

01-01 00:00:25.942 2589 2826 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:25.942 2589 2826 I zygote64: Background concurrent copying GC freed 1697(94KB) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 21us total 139.510ms

01-01 00:00:25.942 2589 2589 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 132.342ms

01-01 00:00:25.942 2589 2589 I zygote64: Starting a blocking GC Alloc

01-01 00:00:25.943 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:25.948 2589 2589 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:25.952 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 141.838ms

01-01 00:00:25.952 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:25.952 2589 2589 I zygote64: Starting a blocking GC Alloc

01-01 00:00:25.955 2589 2589 I Zygote : Warmed up JCA providers in 151ms.

01-01 00:00:25.957 2589 2589 D Zygote : end preload

01-01 00:00:25.958 2589 2589 D Zygote64Timing: ZygotePreload took to complete: 8291ms

01-01 00:00:25.958 2589 2589 I zygote64: Waiting for a blocking GC Explicit

01-01 00:00:26.097 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:26.099 2589 2826 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:26.099 2589 2826 I zygote64: Background concurrent copying GC freed 1942(92KB) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 35us total 145.785ms

01-01 00:00:26.099 2589 2589 I zygote64: WaitForGcToComplete blocked Explicit on HeapTrim for 141.468ms

01-01 00:00:26.099 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:26.241 2589 2589 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:26.241 2589 2589 I zygote64: Explicit concurrent copying GC freed 259(28KB) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 20us total 141.962ms

01-01 00:00:26.242 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 144.750ms

01-01 00:00:26.242 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:26.244 2589 2822 W zygote64: Verification of void com.android.ims.rcs.uce.UceController.<init>(android.content.Context, int) took 146.951ms (367.47 bytecodes/s) (2744B approximate peak alloc)

01-01 00:00:26.360 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:26.400 2589 2589 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:26.400 2589 2589 I zygote64: Explicit concurrent copying GC freed 5(128B) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 35us total 157.995ms

01-01 00:00:26.400 2589 2826 I zygote64: WaitForGcToComplete blocked Background on HeapTrim for 158.247ms

01-01 00:00:26.400 2589 2589 I zygote64: Starting a blocking GC Alloc

01-01 00:00:26.400 2589 2589 I zygote64: Starting a blocking GC Alloc

01-01 00:00:26.400 2589 2828 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:26.400 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:26.410 2589 2589 I zygote64: Alloc young concurrent copying GC freed 397(63KB) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 24us total 9.543ms

01-01 00:00:26.410 2589 2828 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 9.746ms

01-01 00:00:26.410 2589 2828 I zygote64: Starting a blocking GC Alloc

01-01 00:00:26.410 2589 2589 I zygote64: Forcing collection of SoftReferences for 88B allocation

01-01 00:00:26.410 2589 2589 I zygote64: Starting a blocking GC Alloc

01-01 00:00:26.410 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:26.560 2589 2589 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:26.560 2589 2589 I zygote64: Alloc concurrent copying GC freed 5(328B) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 20us total 149.409ms

01-01 00:00:26.560 2589 2826 I zygote64: WaitForGcToComplete blocked Background on HeapTrim for 159.497ms

01-01 00:00:26.560 2589 2589 W zygote64: Throwing OutOfMemoryError "Failed to allocate a 88 byte allocation with 65440 free bytes and 63KB until OOM, target footprint 16777216, growth limit 16777216; failed due to fragmentation (largest possible contiguous allocation 7077888 bytes)" (VmSize 13117596 kB)

01-01 00:00:26.561 2589 2589 E Zygote : System zygote died with exception

01-01 00:00:26.561 2589 2589 E Zygote : java.lang.OutOfMemoryError: Failed to allocate a 88 byte allocation with 65440 free bytes and 63KB until OOM, target footprint 16777216, growth limit 16777216; failed due to fragmentation (largest possible contiguous allocation 7077888 bytes)

01-01 00:00:26.561 2589 2589 E Zygote : at java.util.Arrays.copyOf(Arrays.java:3257)

01-01 00:00:26.561 2589 2589 E Zygote : at java.lang.AbstractStringBuilder.ensureCapacityInternal(AbstractStringBuilder.java:124)

01-01 00:00:26.561 2589 2589 E Zygote : at java.lang.AbstractStringBuilder.append(AbstractStringBuilder.java:448)

01-01 00:00:26.561 2589 2589 E Zygote : at java.lang.StringBuilder.append(StringBuilder.java:137)

01-01 00:00:26.561 2589 2589 E Zygote : at android.util.TimingsTraceLog.logDuration(TimingsTraceLog.java:130)

01-01 00:00:26.561 2589 2589 E Zygote : at android.util.TimingsTraceLog.traceEnd(TimingsTraceLog.java:114)

01-01 00:00:26.561 2589 2589 E Zygote : at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:940)

01-01 00:00:26.561 2589 2589 D AndroidRuntime: Shutting down VM

01-01 00:00:26.562 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:26.572 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 211.854ms

01-01 00:00:26.572 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:26.574 2589 2822 W zygote64: Verification of com.android.org.conscrypt.NativeSslSession com.android.org.conscrypt.NativeSslSession.newInstance(com.android.org.conscrypt.AbstractSessionContext, byte[], java.lang.String, int) took 213.381ms (1134.12 bytecodes/s) (6152B approximate peak alloc)

01-01 00:00:26.600 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:26.717 2589 2826 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:26.717 2589 2826 I zygote64: Background concurrent copying GC freed 248(19KB) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 39us total 144.658ms

01-01 00:00:26.718 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 117.612ms

01-01 00:00:26.718 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:26.718 2589 2822 W zygote64: Verification of java.security.PublicKey com.android.org.conscrypt.OpenSSLECKeyFactory.engineGeneratePublic(java.security.spec.KeySpec) took 118.304ms (557.88 bytecodes/s) (3024B approximate peak alloc)

01-01 00:00:26.765 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:26.842 2709 2709 W iorapd : Reconnect to package manager service: 3 times

01-01 00:00:26.843 2709 2709 I ServiceManager: Waiting for service 'package_native' on '/dev/binder'...

01-01 00:00:26.867 2589 2826 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:26.867 2589 2826 I zygote64: Background concurrent copying GC freed 16(1592B) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 79us total 140.772ms

01-01 00:00:26.867 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 102.251ms

01-01 00:00:26.867 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:26.869 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:26.878 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 9.318ms

01-01 00:00:26.878 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:26.912 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:27.019 2589 2826 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:27.019 2589 2826 I zygote64: Background concurrent copying GC freed 9(528B) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 118us total 140.569ms

01-01 00:00:27.019 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 107.291ms

01-01 00:00:27.019 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:27.020 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:27.027 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 6.847ms

01-01 00:00:27.027 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:27.033 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:27.173 2589 2826 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:27.173 2589 2826 I zygote64: Background concurrent copying GC freed 8(384B) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 22us total 146.007ms

01-01 00:00:27.174 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 140.736ms

01-01 00:00:27.174 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:27.174 2589 2822 I zygote64: Forcing collection of SoftReferences for 520B allocation

01-01 00:00:27.174 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:27.333 2589 2822 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:27.333 2589 2822 I zygote64: Alloc concurrent copying GC freed 17(2504B) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 23us total 159.198ms

01-01 00:00:27.333 2589 2822 W zygote64: Throwing OutOfMemoryError "Failed to allocate a 520 byte allocation with 2880 free bytes and 2880B until OOM, target footprint 16777216, growth limit 16777216; failed due to fragmentation (largest possible contiguous allocation 7602176 bytes)" (VmSize 13117724 kB)

01-01 00:00:27.334 2589 2822 W zygote64: Could not find Lcom/android/org/conscrypt/OpenSSLCipherChaCha20;

01-01 00:00:27.336 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:27.341 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 5.368ms

01-01 00:00:27.341 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:27.341 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:27.585 2589 2826 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:27.585 2589 2826 I zygote64: Background concurrent copying GC freed 10(1088B) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 18us total 243.199ms

01-01 00:00:27.585 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 243.438ms

01-01 00:00:27.585 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:27.589 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:27.593 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:27.594 2589 2822 I zygote64: Forcing collection of SoftReferences for 432B allocation

01-01 00:00:27.594 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:27.818 2589 2826 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:27.818 2589 2826 I zygote64: Background concurrent copying GC freed 7(264B) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 25us total 224.074ms

01-01 00:00:27.818 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 224.323ms

01-01 00:00:27.818 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:27.825 2590 2820 I zygote : Verified 24960 classes from mainline modules in 9.832s

01-01 00:00:28.067 2589 2822 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:28.067 2589 2822 I zygote64: Alloc concurrent copying GC freed 3(72B) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 25us total 248.321ms

01-01 00:00:28.067 2589 2826 I zygote64: WaitForGcToComplete blocked Background on HeapTrim for 248.528ms

01-01 00:00:28.067 2589 2822 W zygote64: Throwing OutOfMemoryError "Failed to allocate a 432 byte allocation with 648 free bytes and 648B until OOM, target footprint 16777216, growth limit 16777216; failed due to fragmentation (largest possible contiguous allocation 3488 bytes)" (VmSize 13117724 kB)

01-01 00:00:28.067 2589 2822 W zygote64: Could not find Lcom/android/org/conscrypt/OpenSSLEvpCipher;

01-01 00:00:28.068 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:28.074 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 5.965ms

01-01 00:00:28.074 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:28.074 2589 2822 I zygote64: Forcing collection of SoftReferences for 280B allocation

01-01 00:00:28.074 2589 2822 I zygote64: Waiting for a blocking GC Alloc

01-01 00:00:28.309 2589 2826 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:28.310 2589 2826 I zygote64: Background concurrent copying GC freed 6(144B) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 21us total 235.646ms

01-01 00:00:28.310 2589 2822 I zygote64: WaitForGcToComplete blocked Alloc on HeapTrim for 235.888ms

01-01 00:00:28.310 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:28.563 2589 2822 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:28.563 2589 2822 I zygote64: Alloc concurrent copying GC freed 3(72B) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 23us total 253.177ms

01-01 00:00:28.563 2589 2826 I zygote64: WaitForGcToComplete blocked Background on HeapTrim for 253.351ms

01-01 00:00:28.563 2589 2822 W zygote64: Throwing OutOfMemoryError "Failed to allocate a 280 byte allocation with 176 free bytes and 176B until OOM, target footprint 16777216, growth limit 16777216" (VmSize 13117724 kB)

01-01 00:00:28.564 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:28.564 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:28.570 2589 2822 I zygote64: Alloc young concurrent copying GC freed 0(0B) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 24us total 6.214ms

01-01 00:00:28.570 2589 2826 I zygote64: WaitForGcToComplete blocked HeapTrim on HeapTrim for 6.412ms

01-01 00:00:28.570 2589 2822 I zygote64: Forcing collection of SoftReferences for 152B allocation

01-01 00:00:28.570 2589 2822 I zygote64: Starting a blocking GC Alloc

01-01 00:00:28.877 2589 2822 I zygote64: Clamp target GC heap from 21MB to 16MB

01-01 00:00:28.878 2589 2822 I zygote64: Alloc concurrent copying GC freed 6(144B) AllocSpace objects, 0(0B) LOS objects, 0% free, 15MB/16MB, paused 21us total 307.242ms

01-01 00:00:28.878 2589 2826 I zygote64: WaitForGcToComplete blocked Background on HeapTrim for 307.503ms

01-01 00:00:28.878 2589 2822 W zygote64: Throwing OutOfMemoryError "Failed to allocate a 152 byte allocation with 144 free bytes and 144B until OOM, target footprint 16777216, growth limit 16777216" (VmSize 13117724 kB, recursive case)

01-01 00:00:28.911 2589 2822 W zygote64: "Jit thread pool worker thread 0" daemon prio=5 tid=2 Runnable

01-01 00:00:28.911 2589 2822 W zygote64: | group="system" sCount=0 ucsCount=0 flags=0 obj=0x12d40260 self=0xb400efba46ea87b0

01-01 00:00:28.911 2589 2822 W zygote64: | sysTid=2822 nice=9 cgrp=system sched=0/0 handle=0xefb8c59fdcb0

01-01 00:00:28.911 2589 2822 W zygote64: | state=R schedstat=( 8560755130 1142710761 1968 ) utm=837 stm=18 core=2 HZ=100

01-01 00:00:28.911 2589 2822 W zygote64: | stack=0xefb8c5906000-0xefb8c5908000 stackSize=995KB

01-01 00:00:28.911 2589 2822 W zygote64: | held mutexes= "mutator lock"(shared held)

01-01 00:00:28.911 2589 2822 W zygote64: native: #00 pc 00000000004ae49c /apex/com.android.art/lib64/libart.so (art::DumpNativeStack(std::__1::basic_ostream<char, std::__1::char_traits<char> >&, int, BacktraceMap*, char const*, art::ArtMethod*, void*, bool)+140)

01-01 00:00:28.911 2589 2822 W zygote64: native: #01 pc 00000000005c57b4 /apex/com.android.art/lib64/libart.so (art::Thread::DumpStack(std::__1::basic_ostream<char, std::__1::char_traits<char> >&, bool, BacktraceMap*, bool) const+372)

01-01 00:00:28.911 2589 2822 W zygote64: native: #02 pc 00000000005bfe7c /apex/com.android.art/lib64/libart.so (art::Thread::ThrowOutOfMemoryError(char const*)+148)

01-01 00:00:28.911 2589 2822 W zygote64: native: #03 pc 00000000002aa704 /apex/com.android.art/lib64/libart.so (art::gc::Heap::ThrowOutOfMemoryError(art::Thread*, unsigned long, art::gc::AllocatorType)+828)

01-01 00:00:28.911 2589 2822 W zygote64: native: #04 pc 00000000002b2114 /apex/com.android.art/lib64/libart.so (art::gc::Heap::AllocateInternalWithGc(art::Thread*, art::gc::AllocatorType, bool, unsigned long, unsigned long*, unsigned long*, unsigned long*, art::ObjPtr<art::mirror::Class>*)+4532)

01-01 00:00:28.911 2589 2822 W zygote64: native: #05 pc 000000000049a22c /apex/com.android.art/lib64/libart.so (art::mirror::Object* art::gc::Heap::AllocObjectWithAllocator<true, true, art::mirror::String::AllocFromModifiedUtf8(art::Thread*, int, char const*, int)::$_3>(art::Thread*, art::ObjPtr<art::mirror::Class>, unsigned long, art::gc::AllocatorType, art::mirror::String::AllocFromModifiedUtf8(art::Thread*, int, char const*, int)::$_3 const&)+1564)

01-01 00:00:28.911 2589 2822 W zygote64: native: #06 pc 0000000000495fe0 /apex/com.android.art/lib64/libart.so (art::mirror::String::AllocFromModifiedUtf8(art::Thread*, int, char const*, int)+256)

01-01 00:00:28.911 2589 2822 W zygote64: native: #07 pc 00000000005d176c /apex/com.android.art/lib64/libart.so (art::Thread::ThrowNewWrappedException(char const*, char const*)+1092)

01-01 00:00:28.911 2589 2822 W zygote64: native: #08 pc 00000000005bffe4 /apex/com.android.art/lib64/libart.so (art::Thread::ThrowOutOfMemoryError(char const*)+508)

01-01 00:00:28.911 2589 2822 W zygote64: native: #09 pc 00000000002aa704 /apex/com.android.art/lib64/libart.so (art::gc::Heap::ThrowOutOfMemoryError(art::Thread*, unsigned long, art::gc::AllocatorType)+828)

01-01 00:00:28.911 2589 2822 W zygote64: native: #10 pc 00000000002b2114 /apex/com.android.art/lib64/libart.so (art::gc::Heap::AllocateInternalWithGc(art::Thread*, art::gc::AllocatorType, bool, unsigned long, unsigned long*, unsigned long*, unsigned long*, art::ObjPtr<art::mirror::Class>*)+4532)

01-01 00:00:28.911 2589 2822 W zygote64: native: #11 pc 0000000000488dd0 /apex/com.android.art/lib64/libart.so (art::mirror::Object* art::gc::Heap::AllocObject<true, art::mirror::CopyClassVisitor>(art::Thread*, art::ObjPtr<art::mirror::Class>, unsigned long, art::mirror::CopyClassVisitor const&)+1432)

01-01 00:00:28.911 2589 2822 W zygote64: native: #12 pc 00000000004887f0 /apex/com.android.art/lib64/libart.so (art::mirror::Class::CopyOf(art::Handle<art::mirror::Class>, art::Thread*, int, art::ImTable*, art::PointerSize)+176)

01-01 00:00:28.911 2589 2822 W zygote64: native: #13 pc 00000000001ea124 /apex/com.android.art/lib64/libart.so (art::ClassLinker::LinkClass(art::Thread*, char const*, art::Handle<art::mirror::Class>, art::Handle<art::mirror::ObjectArray<art::mirror::Class> >, art::MutableHandle<art::mirror::Class>*)+1300)

01-01 00:00:28.911 2589 2822 W zygote64: native: #14 pc 00000000001e5be4 /apex/com.android.art/lib64/libart.so (art::ClassLinker::DefineClass(art::Thread*, char const*, unsigned long, art::Handle<art::mirror::ClassLoader>, art::DexFile const&, art::dex::ClassDef const&)+1188)

01-01 00:00:28.911 2589 2822 W zygote64: native: #15 pc 00000000001e65a8 /apex/com.android.art/lib64/libart.so (art::ClassLinker::FindClass(art::Thread*, char const*, art::Handle<art::mirror::ClassLoader>)+992)

01-01 00:00:28.911 2589 2822 W zygote64: native: #16 pc 0000000000355564 /apex/com.android.art/lib64/libart.so (art::jit::ZygoteVerificationTask::Run(art::Thread*)+940)

01-01 00:00:28.911 2589 2822 W zygote64: native: #17 pc 00000000005e4204 /apex/com.android.art/lib64/libart.so (art::ThreadPoolWorker::Run()+108)

01-01 00:00:28.911 2589 2822 W zygote64: native: #18 pc 00000000005e3cc8 /apex/com.android.art/lib64/libart.so (art::ThreadPoolWorker::Callback(void*)+192)

01-01 00:00:28.911 2589 2822 W zygote64: native: #19 pc 00000000000af660 /apex/com.android.runtime/lib64/bionic/libc.so (__pthread_start(void*)+64)

01-01 00:00:28.911 2589 2822 W zygote64: native: #20 pc 000000000004dc98 /apex/com.android.runtime/lib64/bionic/libc.so (__start_thread+64)

01-01 00:00:28.911 2589 2822 W zygote64: (no managed stack frames)

01-01 00:00:28.911 2589 2822 W zygote64: Could not find Lcom/android/org/conscrypt/OpenSSLMac$HmacMD5;

Is resetting DEBUG to true expected to work with userdebug builds, or is something else required?

[Dean Wheatley]

Interestingly, these zygote crash logs end after about 15 minutes, after which the system boots and operates seemingly normally.