Seemingly random 'process crashed' for the instrumentation runner

[tsaulic]

Description

What appears to randomly process crashes of the test runner. I understand this may not be the best example on how to present the issue (without the full code), but I was unable to reproduce this on a fresh project :(

Steps to Reproduce

In our app, the test is really simple. When I run it from the IDE it runs almost 100% correctly, from command line via
./gradlew connectedProductionDebugAndroidTest -i

it often fails with the error provided below.

class MyTest {

    private val mockViewModel = mockk<FeedViewModel>(relaxed = true, relaxUnitFun = true)

    @get:Rule
    val mockInjectionRule = MockInjectionRule {
        every { it.myViewModel() } returns mockViewModel
        every { mockViewModel.shouldDoSomething() } returns false
    }

    @Test
    fun myFragment_titleDisplays() {
        launchFragmentInContainer(themeResId = R.style.AppTheme) { MyFragment() }

        onView(withText(R.string.title))
                .check(ViewAssertions.matches(isDisplayed()))
                .check(matches(withParent(withId(R.id.toolbar))))
        onView(withId(R.id.action_settings))
                .check(matches(isDisplayed()))
    }
}

Expected Results

Tests run/pass

Actual Results

Tests pass on average 7/10 times, when they fail, this is logged in Logcat

        ActivityManager  I  Start proc 23437:com.android.chrome:sandboxed_process0/u0i50 for webview_service Ïd
                            ebug/org.chromium.content.app.SandboxedProcessService0
                         W  Slow operation: 57ms so far, now at startProcess: starting to update pids map
                         W  Slow operation: 57ms so far, now at startProcess: done updating pids map
         dboxed_process  W  Unexpected CPU variant for X86 using defaults: x86
         ercentral.debu  I  Background concurrent copying GC freed 161195(5MB) AllocSpace objects, 5(88KB) LOS objects, 36% free, 10MB/1
                            6MB, paused 1.468ms total 178.354ms
        ActivityManager  W  Slow operation: 70ms so far, now at attachApplicationLocked: after mServices.attachApplicationLocked
 cr_ChildProcessService  I  Creating new ChildProcessService pid=23437
        ActivityManager  I  Process com.examplepackage.debug (pid 23395) has died: fore TOP
                 Zygote  I  Process 23395 exited due to signal (11)
        InputDispatcher  W  channel '81514ff com.examplepackage.debug/androidx.fragment.app.testing.FragmentScenario$EmptyFragmen
                            tActivity (server)' ~ Consumer closed input channel or an error occurred.  events=0x9
                         E  channel '81514ff com.examplepackage.debug/androidx.fragment.app.testing.FragmentScenario$EmptyFragmen
                            tActivity (server)' ~ Channel is unrecoverably broken and will be disposed!
        libprocessgroup  W  kill(-23395, 9) failed: No such process
                         I  Successfully killed process cgroup uid 10097 pid 23395 in 4ms
 cr_ChildProcessService  I  Destroying ChildProcessService pid=23437
          WindowManager  I  WIN DEATH: Window{81514ff u0 com.examplepackage.debug/androidx.fragment.app.testing.FragmentScenario$
                            EmptyFragmentActivity}
                         D  HostConnection::get() New Host Connection established 0xe9c0a840, tid 1973
        InputDispatcher  W  Attempted to unregister already unregistered input channel '81514ff com.examplepackage.debug/androidx
                            .fragment.app.testing.FragmentScenario$EmptyFragmentActivity (server)'
         gralloc_ranchu  D  gralloc_unregister_buffer: exiting HostConnection (is buffer-handling thread)
         dboxed_process  I  System.exit called, status: 0
         AndroidRuntime  I  VM exiting with result code 0, cleanup skipped.
                         D  HostConnection::get() New Host Connection established 0xe9c0a840, tid 1973
         gralloc_ranchu  D  gralloc_unregister_buffer: exiting HostConnection (is buffer-handling thread)
                         D  HostConnection::get() New Host Connection established 0xe9c0a840, tid 1973
         gralloc_ranchu  D  gralloc_unregister_buffer: exiting HostConnection (is buffer-handling thread)
                 Zygote  I  Process 23437 exited cleanly (0)
                         D  HostConnection::get() New Host Connection established 0xe9c0a840, tid 1973
   GnssLocationProvider  I  WakeLock acquired by sendMessage(REPORT_SV_STATUS, 0, com.android.server.location.GnssLocationProvider$SvSta
                            tusInfo@453db8e)
         gralloc_ranchu  D  gralloc_unregister_buffer: exiting HostConnection (is buffer-handling thread)
   GnssLocationProvider  I  WakeLock released by handleMessage(REPORT_SV_STATUS, 0, com.android.server.location.GnssLocationProvider$SvS
                            tatusInfo@453db8e)
         SurfaceFlinger  W  couldn't log to binary event log: overflow.
        ActivityManager  W  Force removing ActivityRecord{82ebca5 u0 com.examplepackage.debug/androidx.fragment.app.testing.Fragm
                            entScenario$EmptyFragmentActivity t300}: app died, no saved state
                         W  Crash of app com.examplepackage.debug running instrumentation ComponentInfo{com.examplepackage
                            .debug.test/com.examplepackage.myapp.CustomTestRunner}
                         I  Force stopping com.examplepackage.debug appid=10097 user=0: finished inst

                            Process com.android.chrome:sandboxed_process0 (PID: 23437) ended

        ActivityManager  I  Killing 23437:com.android.chrome:sandboxed_process0/u0a97i50 (adj 0): isolated not needed
        libprocessgroup  W  Failed to open process cgroup uid 99050 pid 23437: No such file or directory
--
--
                         E  channel '81514ff com.examplepackage.debug/androidx.fragment.app.testing.FragmentScenario$EmptyFragmen
                            tActivity (server)' ~ Channel is unrecoverably broken and will be disposed!
        libprocessgroup  W  kill(-23395, 9) failed: No such process
                         I  Successfully killed process cgroup uid 10097 pid 23395 in 4ms
 cr_ChildProcessService  I  Destroying ChildProcessService pid=23437
          WindowManager  I  WIN DEATH: Window{81514ff u0 com.examplepackage.debug/androidx.fragment.app.testing.FragmentScenario$
                            EmptyFragmentActivity}

AndroidX Test and Android OS Versions

debugImplementation ("androidx.fragment:fragment-testing:1.1.0-alpha08") {
    exclude group: "androidx.test", module: "core"
}

androidTestImplementation 'androidx.test.ext:junit:1.1.1-beta01'
androidTestImplementation 'androidx.test:runner:1.1.1'
androidTestImplementation 'androidx.test:rules:1.1.1'
androidTestImplementation 'androidx.test.espresso:espresso-core:3.1.1'
androidTestImplementation 'androidx.test.espresso:espresso-contrib:3.1.1'
androidTestImplementation 'androidx.test.espresso:espresso-web:3.1.1'
androidTestImplementation 'androidx.test.espresso:espresso-intents:3.1.1'
androidTestImplementation "androidx.arch.core:core-testing:2.0.0"
androidTestUtil 'androidx.test:orchestrator:1.1.1'

Emulator is API 28 Nexus 5, animations turned off.

Link to a public git repo demonstrating the problem:

N/A

[brettchabot]

Strange. Nothing in the posted stack trace gives me any clue to what might be wrong.

Can you post a sample that reproduces this issue?

[tsaulic]

Strange. Nothing in the posted stack trace gives me any clue to what might be wrong.

Can you post a sample that reproduces this issue?

I'm trying to set up a minimal project where the same issue would occur consistently. Still no luck, but will post an update here if I succeed.

[navidRoohi]

Any update about this issue? I m getting Test instrumentation process crashed., randomly. Adding a bit wait before starting the Test process solved it. but, I don't think is the right way.

[ruXlab]

Not sure if it can be related but by creating TestSubscriber even without using it increases chances for process to crash by ~80%, without - ~20%

Same as topic starter I use mockk(1.9.3) and launchFragment(1.2.0)

[yuriykulikov]

Same here, also mockk 1.9.3 and launchFragment 1.2.0. Running clean before the test seems to solve the issue.

[tsaulic]

Same here, also mockk 1.9.3 and launchFragment 1.2.0. Running clean before the test seems to solve the issue.

Thank you @yuriykulikov, mockk 1.9.3 works with 1.2.0-beta02 (as in - the tests run now). Just had to force objenesis version:

    allprojects {
        configurations.all {
            resolutionStrategy {
                force("org.objenesis:objenesis:2.6")
            }
        }
    }

But I still get process crashed randomly, unfortunately.

[brettchabot]

closing due to lack of info

[joreilly]

Also seeing this. Getting random crashes that we've narrowed down to use of mockk. Tried various combinations of versions but still happening

[TWiStErRob]

We;re seeing this and the last erroring logcat is suggesting an interplay of Jacoco, Garbage Collector (finalizers) and native. It happens after ~10 minutes of successful test executions. Failure rate is about 3-5%, so not possible to repro.

02-11 09:58:09.992  2831  2842 I art     : Background partial concurrent mark sweep GC freed 15522(954KB) AllocSpace objects, 48(42MB) LOS objects, 5% free, 75MB/79MB, paused 3.303ms total 160.332ms
02-11 09:58:10.141  2831  2842 W art     : Suspending all threads took: 6.671ms
02-11 09:58:10.170  2831  2842 I art     : Background partial concurrent mark sweep GC freed 79588(5MB) AllocSpace objects, 59(91MB) LOS objects, 5% free, 70MB/74MB, paused 8.203ms total 155.791ms
02-11 09:58:10.357  2831  2842 W art     : Suspending all threads took: 33.815ms
02-11 09:58:10.375  2831  2839 W System.err: java.lang.Error: Cleaner terminated abnormally
02-11 09:58:10.376  2831  2839 W System.err: 	at sun.misc.Cleaner$1.run(Cleaner.java:147)
02-11 09:58:10.376  2831  2839 W System.err: 	at sun.misc.Cleaner$1.run(Cleaner.java:145)
02-11 09:58:10.376  2831  2839 W System.err: 	at java.security.AccessController.doPrivileged(AccessController.java:41)
02-11 09:58:10.376  2831  2839 W System.err: 	at sun.misc.Cleaner.clean(Cleaner.java:144)
02-11 09:58:10.376  2831  2839 W System.err: 	at java.lang.ref.ReferenceQueue.enqueueLocked(ReferenceQueue.java:75)
02-11 09:58:10.376  2831  2839 W System.err: 	at java.lang.ref.ReferenceQueue.enqueuePending(ReferenceQueue.java:243)
02-11 09:58:10.376  2831  2839 W System.err: 	at java.lang.Daemons$ReferenceQueueDaemon.run(Daemons.java:160)
02-11 09:58:10.376  2831  2839 W System.err: 	at java.lang.Thread.run(Thread.java:761)
02-11 09:58:10.376  2831  2842 I art     : Background partial concurrent mark sweep GC freed 24256(1469KB) AllocSpace objects, 63(92MB) LOS objects, 5% free, 63MB/67MB, paused 35.146ms total 191.839ms
02-11 09:58:10.376  2831  2839 W System.err: Caused by: java.lang.RuntimeException: Attempted to free 98 native bytes with only 80 native bytes registered as allocated
02-11 09:58:10.376  2831  2839 W System.err: 	at dalvik.system.VMRuntime.registerNativeFree(Native Method)
02-11 09:58:10.376  2831  2839 W System.err: 	at libcore.util.NativeAllocationRegistry.registerNativeFree(NativeAllocationRegistry.java:223)
02-11 09:58:10.376  2831  2839 W System.err: 	at libcore.util.NativeAllocationRegistry.-wrap0(NativeAllocationRegistry.java)
02-11 09:58:10.376  2831  2839 W System.err: 	at libcore.util.NativeAllocationRegistry$CleanerThunk.run(NativeAllocationRegistry.java:196)
02-11 09:58:10.376  2831  2839 W System.err: 	at sun.misc.Cleaner.clean(Cleaner.java:142)
02-11 09:58:10.376  2831  2839 W System.err: 	... 4 more
02-11 09:58:10.378  2831 11893 W System.err: java.io.FileNotFoundException: /jacoco.exec (Read-only file system)
02-11 09:58:10.378  2831 11893 W System.err: 	at java.io.FileOutputStream.open(Native Method)
02-11 09:58:10.378  2831 11893 W System.err: 	at java.io.FileOutputStream.<init>(FileOutputStream.java:221)
02-11 09:58:10.378  2831 11893 W System.err: 	at org.jacoco.agent.rt.internal_8ff85ea.output.FileOutput.openFile(FileOutput.java:67)
02-11 09:58:10.378  2831 11893 W System.err: 	at org.jacoco.agent.rt.internal_8ff85ea.output.FileOutput.writeExecutionData(FileOutput.java:53)
02-11 09:58:10.378  2831 11893 W System.err: 	at org.jacoco.agent.rt.internal_8ff85ea.Agent.shutdown(Agent.java:137)
02-11 09:58:10.378  2831 11893 W System.err: 	at org.jacoco.agent.rt.internal_8ff85ea.Agent$1.run(Agent.java:54)
02-11 09:58:10.387  2831  2839 I art     : System.exit called, status: 1

[gosr]

Did anyone find a proper fix for this?

[NitroG42]

Got exactly the same issue with multiple projects. I'm pretty sure this come from mockk from a way or another.
Is there any way to have more info about process crashes when there are absolutly no stack trace in the logcat @brettchabot ?
I'll try to work on a sample because after a few year I can't bear having my CI jobs failing each 1/2 time for no reason 😢

[ricard-v]

I have exactly the same issue and I also believe MockK could be behind it. I have posted the issue on SO: https://stackoverflow.com/questions/63059986/android-ui-tests-with-espresso-mockk-crash-with-sigsegv

Looking at OP crash log, I found one common error message:

channel '81514ff com.examplepackage.debug/androidx.fragment.app.testing.FragmentScenario$EmptyFragmentActivity (server)' ~ Channel is unrecoverably broken and will be disposed!

In my case, this log message is always right before the crash, usually alongside a SIGSEGV.

I am using Espresso to write a login + pwd to test a LoginActivity and I use MockK to mock all the Repositories / Services logic in a MVP architecture.
Also, none of this has happened on my physical devices.