Fenix: [Bug]Crash when disconnecting from sync

[AndiAJ]

Steps to reproduce

1. From the "Turn on sync" section , tap "Use email instead" and log in using valid credentials.
1.1 After successfully signing in, from the "Turn on sync" section tap "Sign out" and "Disconnect".

2. From the "Turn on sync" section , tap "Ready to scan" and log in using the QR pairing.
2.1 After successfully signing in using the QR pairing, from the "Turn on sync" sectiontap "Sign out" and "Disconnect".

3. From the "Passwords" section tap on "Sync logins", tap "Use email instead" and log in using valid credentials.
3.1 After successfully signing in, from the "Passwords" section tap on "Sync logins" =>"Sign out" and afterwards "Disconnect".

4. From the "Passwords" section tap on "Sync logins", tap "Ready to scan" and log in using the QR pairing.
4.1 After successfully signing in, from the "Passwords" section tap on "Sync logins" => "Sign out" and afterwards "Disconnect".

Expected behavior

In each of the above STR's the user is properly disconected

Actual behavior

Crash in each case

Device information

• Android device: Google Pixel 3a XL (Android 9)
• Fenix version: Nightly Build #13220606 from 11/18

Notes

Logs

1. Turn on sync section - Disconnect after sign in with email

 --------- beginning of crash
2019-11-18 11:50:55.720 18376-18439/? E/AndroidRuntime: FATAL EXCEPTION: pool-8-thread-1
    Process: org.mozilla.fenix.nightly, PID: 18376
    mozilla.appservices.push.StorageError: Error(

    Storage Error: "No record for uaid:chid \"d8f9c3cf2c2943b8a880de60bb15946b\":\"992a0f0542383f1ea5ef51b7cf4ae6c4\"")
        at mozilla.appservices.push.RustError.intoException(RustError.kt:8)
        at mozilla.appservices.push.PushManager.decrypt(PushManager.kt:16)
        at mozilla.components.feature.push.RustPushConnection.decrypt(Connection.kt:3)
        at mozilla.components.feature.push.AutoPushFeature$onMessageReceived$1.invokeSuspend(AutoPushFeature.kt:20)
        at mozilla.components.feature.push.AutoPushFeature$onMessageReceived$1.invoke(Unknown Source:10)
        at mozilla.components.feature.push.AutoPushFeatureKt$launchAndTry$1.invokeSuspend(AutoPushFeature.kt:5)
        at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(ContinuationImpl.kt:2)
        at kotlinx.coroutines.DispatchedTask.run(Dispatched.kt:15)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
        at java.lang.Thread.run(Thread.java:764)
2019-11-18 11:50:55.723 18349-18349/? W/ActivityThread: handleWindowVisibility: no activity for token android.os.BinderProxy@3ce93d3
2019-11-18 11:50:55.741 18376-18439/? I/Process: Sending signal. PID: 18376 SIG: 9
2019-11-18 11:50:55.748 1182-1242/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
2019-11-18 11:50:55.748 1182-1242/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
2019-11-18 11:50:55.761 571-1427/? D/SurfaceFlinger: duplicate layer name: changing com.google.android.apps.nexuslauncher/com.google.android.apps.nexuslauncher.NexusLauncherActivity to com.google.android.apps.nexuslauncher/com.google.android.apps.nexuslauncher.NexusLauncherActivity#1
2019-11-18 11:50:55.815 18414-18583/? I/Gecko: Exiting due to channel error.
2019-11-18 11:50:55.815 1182-9464/? W/InputMethodManagerService: Got RemoteException sending setActive(false) notification to pid 18376 uid 10188
2019-11-18 11:50:55.831 1182-1411/? W/InputDispatcher: channel '96d0c42 org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)' ~ Consumer closed input channel or an error occurred.  events=0x9
2019-11-18 11:50:55.831 1182-1411/? E/InputDispatcher: channel '96d0c42 org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)' ~ Channel is unrecoverably broken and will be disposed!

2. Turn on sync section - Disconnect after QR pairing**

  --------- beginning of crash
2019-11-18 11:53:32.870 18892-18960/? E/AndroidRuntime: FATAL EXCEPTION: pool-8-thread-1
    Process: org.mozilla.fenix.nightly, PID: 18892
    mozilla.appservices.push.StorageError: Error(

    Storage Error: "No record for uaid:chid \"d8f9c3cf2c2943b8a880de60bb15946b\":\"992a0f0542383f1ea5ef51b7cf4ae6c4\"")
        at mozilla.appservices.push.RustError.intoException(RustError.kt:8)
        at mozilla.appservices.push.PushManager.decrypt(PushManager.kt:16)
        at mozilla.components.feature.push.RustPushConnection.decrypt(Connection.kt:3)
        at mozilla.components.feature.push.AutoPushFeature$onMessageReceived$1.invokeSuspend(AutoPushFeature.kt:20)
        at mozilla.components.feature.push.AutoPushFeature$onMessageReceived$1.invoke(Unknown Source:10)
        at mozilla.components.feature.push.AutoPushFeatureKt$launchAndTry$1.invokeSuspend(AutoPushFeature.kt:5)
        at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(ContinuationImpl.kt:2)
        at kotlinx.coroutines.DispatchedTask.run(Dispatched.kt:15)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
        at java.lang.Thread.run(Thread.java:764)
2019-11-18 11:53:32.886 19521-19521/? E/h.CrashReporte: Not starting debugger since process cannot load the jdwp agent.
2019-11-18 11:53:32.891 1182-1244/? I/ActivityManager: Start proc 19521:org.mozilla.fenix.nightly:mozilla.components.lib.crash.CrashReporter/u0a188 for activity org.mozilla.fenix.nightly/mozilla.components.lib.crash.prompt.CrashReporterActivity
2019-11-18 11:53:32.892 1182-1242/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
2019-11-18 11:53:32.892 1182-1242/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
2019-11-18 11:53:32.896 18892-18960/? I/Process: Sending signal. PID: 18892 SIG: 9
2019-11-18 11:53:32.920 571-2056/? D/SurfaceFlinger: duplicate layer name: changing com.google.android.apps.nexuslauncher/com.google.android.apps.nexuslauncher.NexusLauncherActivity to com.google.android.apps.nexuslauncher/com.google.android.apps.nexuslauncher.NexusLauncherActivity#1
2019-11-18 11:53:32.928 19521-19521/? I/h.CrashReporte: The ClassLoaderContext is a special shared library.
2019-11-18 11:53:32.946 19521-19521/? W/System.err: SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
2019-11-18 11:53:32.946 19521-19521/? W/System.err: SLF4J: Defaulting to no-operation (NOP) logger implementation
2019-11-18 11:53:32.946 19521-19521/? W/System.err: SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
2019-11-18 11:53:32.947 19521-19521/? D/io.sentry.android.AndroidSentryClientFactory: Construction of Android Sentry.
2019-11-18 11:53:32.955 19521-19521/? D/RustNativeSupport: findMegazordLibraryName(viaduct, 0.42.2
2019-11-18 11:53:32.955 19521-19521/? D/RustNativeSupport: lib in use: none
2019-11-18 11:53:32.955 19521-19521/? D/RustNativeSupport: lib configured: fenix
2019-11-18 11:53:32.955 19521-19521/? D/RustNativeSupport: lib version configured: 0.42.2
2019-11-18 11:53:32.955 19521-19521/? D/RustNativeSupport: settled on fenix
2019-11-18 11:53:32.968 19521-19521/? D/RustNativeSupport: findMegazordLibraryName(rustlog, 0.42.2
2019-11-18 11:53:32.968 19521-19521/? D/RustNativeSupport: lib in use: none
2019-11-18 11:53:32.968 19521-19521/? D/RustNativeSupport: lib configured: fenix
2019-11-18 11:53:32.968 19521-19521/? D/RustNativeSupport: lib version configured: 0.42.2
2019-11-18 11:53:32.968 19521-19521/? D/RustNativeSupport: settled on fenix
2019-11-18 11:53:32.969 19521-19521/? I/rc_log_ffi::ios: rc_log adapter initialized!
2019-11-18 11:53:32.974 18936-19378/? I/Gecko: Exiting due to channel error.
2019-11-18 11:53:32.976 19521-19521/? W/org.mozilla.fenix.nightly:mozilla.components.lib.crash.CrashReporter: type=1400 audit(0.0:3276): avc: denied { read } for comm=45474C20496E6974 name="u:object_r:vendor_default_prop:s0" dev="tmpfs" ino=23114 scontext=u:r:untrusted_app:s0:c188,c256,c512,c768 tcontext=u:object_r:vendor_default_prop:s0 tclass=file permissive=0
2019-11-18 11:53:32.980 19521-19541/? E/libc: Access denied finding property "vendor.debug.egl.profiler"
2019-11-18 11:53:32.995 1182-1411/? W/InputDispatcher: channel '4e96fc1 org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)' ~ Consumer closed input channel or an error occurred.  events=0x9
2019-11-18 11:53:32.995 1182-1411/? E/InputDispatcher: channel '4e96fc1 org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)' ~ Channel is unrecoverably broken and will be disposed!
2019-11-18 11:53:32.997 1182-3250/? I/WindowManager: WIN DEATH: Window{4e96fc1 u0 org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity}
2019-11-18 11:53:32.997 1182-3250/? W/InputDispatcher: Attempted to unregister already unregistered input channel '4e96fc1 org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)'
2019-11-18 11:53:32.997 1182-9937/? I/ActivityManager: Process org.mozilla.fenix.nightly (pid 18892) has died: vis  TOP 

3. Passwords section - Disconnect after sign in with email

--------- beginning of crash
2019-11-18 12:06:28.938 21644-21800/? E/AndroidRuntime: FATAL EXCEPTION: pool-8-thread-1
    Process: org.mozilla.fenix.nightly, PID: 21644
    mozilla.appservices.push.StorageError: Error(

    Storage Error: "No record for uaid:chid \"b8331f36b2c34ef5ae61ddcfb7f8177a\":\"992a0f0542383f1ea5ef51b7cf4ae6c4\"")
        at mozilla.appservices.push.RustError.intoException(RustError.kt:8)
        at mozilla.appservices.push.PushManager.decrypt(PushManager.kt:16)
        at mozilla.components.feature.push.RustPushConnection.decrypt(Connection.kt:3)
        at mozilla.components.feature.push.AutoPushFeature$onMessageReceived$1.invokeSuspend(AutoPushFeature.kt:20)
        at mozilla.components.feature.push.AutoPushFeature$onMessageReceived$1.invoke(Unknown Source:10)
        at mozilla.components.feature.push.AutoPushFeatureKt$launchAndTry$1.invokeSuspend(AutoPushFeature.kt:5)
        at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(ContinuationImpl.kt:2)
        at kotlinx.coroutines.DispatchedTask.run(Dispatched.kt:15)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
        at java.lang.Thread.run(Thread.java:764)
2019-11-18 12:06:28.944 21644-21800/? I/Process: Sending signal. PID: 21644 SIG: 9
2019-11-18 12:06:28.955 1182-1242/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
2019-11-18 12:06:28.955 1182-1242/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
2019-11-18 12:06:28.958 1182-1244/? I/ActivityManager: Start proc 22267:org.mozilla.fenix.nightly:mozilla.components.lib.crash.CrashReporter/u0a189 for activity org.mozilla.fenix.nightly/mozilla.components.lib.crash.prompt.CrashReporterActivity
2019-11-18 12:06:28.964 22267-22267/? E/h.CrashReporte: Not starting debugger since process cannot load the jdwp agent.
2019-11-18 12:06:28.973 571-1427/? D/SurfaceFlinger: duplicate layer name: changing com.google.android.apps.nexuslauncher/com.google.android.apps.nexuslauncher.NexusLauncherActivity to com.google.android.apps.nexuslauncher/com.google.android.apps.nexuslauncher.NexusLauncherActivity#1
2019-11-18 12:06:28.996 22267-22267/? I/h.CrashReporte: The ClassLoaderContext is a special shared library.
2019-11-18 12:06:29.011 768-1037/? I/CHRE: @ 9030.716: [AR_CHRE] still: 100
2019-11-18 12:06:29.014 22267-22267/? W/System.err: SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
2019-11-18 12:06:29.014 22267-22267/? W/System.err: SLF4J: Defaulting to no-operation (NOP) logger implementation
2019-11-18 12:06:29.014 22267-22267/? W/System.err: SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
2019-11-18 12:06:29.015 22267-22267/? D/io.sentry.android.AndroidSentryClientFactory: Construction of Android Sentry.
2019-11-18 12:06:29.020 21692-21897/? I/Gecko: Exiting due to channel error.
2019-11-18 12:06:29.029 22267-22267/? D/RustNativeSupport: findMegazordLibraryName(viaduct, 0.42.2
2019-11-18 12:06:29.029 22267-22267/? D/RustNativeSupport: lib in use: none
2019-11-18 12:06:29.029 22267-22267/? D/RustNativeSupport: lib configured: fenix
2019-11-18 12:06:29.029 22267-22267/? D/RustNativeSupport: lib version configured: 0.42.2
2019-11-18 12:06:29.029 22267-22267/? D/RustNativeSupport: settled on fenix
2019-11-18 12:06:29.033 1182-1411/? W/InputDispatcher: channel '500454e org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)' ~ Consumer closed input channel or an error occurred.  events=0x9
2019-11-18 12:06:29.033 1182-1411/? E/InputDispatcher: channel '500454e org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)' ~ Channel is unrecoverably broken and will be disposed!
2019-11-18 12:06:29.034 2283-2297/? E/JavaBinder: *** Uncaught remote exception!  (Exceptions are not yet supported across processes.)
    java.lang.RuntimeException: android.os.DeadObjectException
        at android.os.Parcel.writeException(Parcel.java:1779)
        at android.os.Binder.execTransact(Binder.java:744)
     Caused by: android.os.DeadObjectException
        at android.os.BinderProxy.transactNative(Native Method)
        at android.os.BinderProxy.transact(Binder.java:1127)
        at cnk.b(:com.google.android.gms@[email protected] (100400-278422107):2)
        at skt.a(:com.google.android.gms@[email protected] (100400-278422107):3)
        at sju.a(:com.google.android.gms@[email protected] (100400-278422107):12)
        at sju.a(:com.google.android.gms@[email protected] (100400-278422107):4)
        at ahfe.a(:com.google.android.gms@[email protected] (100400-278422107):3)
        at sie.a(:com.google.android.gms@[email protected] (100400-278422107):35)
        at skx.onTransact(:com.google.android.gms@[email protected] (100400-278422107):17)
        at android.os.Binder.transact(Binder.java:667)
        at duv.onTransact(:com.google.android.gms@[email protected] (100400-278422107):2)
        at android.os.Binder.transact(Binder.java:667)
        at aaop.onTransact(:com.google.android.gms@[email protected] (100400-278422107):29)
        at android.os.Binder.execTransact(Binder.java:731)
2019-11-18 12:06:29.035 1182-1450/? I/ActivityManager: Process org.mozilla.fenix.nightly (pid 21644) has died: vis  TOP

4. Passwords section - Disconnect after QR pairing

    --------- beginning of crash
2019-11-18 11:57:12.327 19758-19826/? E/AndroidRuntime: FATAL EXCEPTION: pool-8-thread-1
    Process: org.mozilla.fenix.nightly, PID: 19758
    mozilla.appservices.push.StorageError: Error(

    Storage Error: "No record for uaid:chid \"d8f9c3cf2c2943b8a880de60bb15946b\":\"992a0f0542383f1ea5ef51b7cf4ae6c4\"")
        at mozilla.appservices.push.RustError.intoException(RustError.kt:8)
        at mozilla.appservices.push.PushManager.decrypt(PushManager.kt:16)
        at mozilla.components.feature.push.RustPushConnection.decrypt(Connection.kt:3)
        at mozilla.components.feature.push.AutoPushFeature$onMessageReceived$1.invokeSuspend(AutoPushFeature.kt:20)
        at mozilla.components.feature.push.AutoPushFeature$onMessageReceived$1.invoke(Unknown Source:10)
        at mozilla.components.feature.push.AutoPushFeatureKt$launchAndTry$1.invokeSuspend(AutoPushFeature.kt:5)
        at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(ContinuationImpl.kt:2)
        at kotlinx.coroutines.DispatchedTask.run(Dispatched.kt:15)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
        at java.lang.Thread.run(Thread.java:764)
2019-11-18 11:57:12.335 1182-1242/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
2019-11-18 11:57:12.335 1182-1242/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
2019-11-18 11:57:12.339 19758-19826/? I/Process: Sending signal. PID: 19758 SIG: 9
2019-11-18 11:57:12.354 20134-20134/? E/h.CrashReporte: Not starting debugger since process cannot load the jdwp agent.
2019-11-18 11:57:12.357 1182-1244/? I/ActivityManager: Start proc 20134:org.mozilla.fenix.nightly:mozilla.components.lib.crash.CrashReporter/u0a188 for activity org.mozilla.fenix.nightly/mozilla.components.lib.crash.prompt.CrashReporterActivity
2019-11-18 11:57:12.386 6682-6682/? W/ThreadPoolDumper: Queue length for executor EventBus is now 11. Perhaps some tasks are too long, or the pool is too small.
2019-11-18 11:57:12.392 571-1073/? D/SurfaceFlinger: duplicate layer name: changing com.google.android.apps.nexuslauncher/com.google.android.apps.nexuslauncher.NexusLauncherActivity to com.google.android.apps.nexuslauncher/com.google.android.apps.nexuslauncher.NexusLauncherActivity#1
2019-11-18 11:57:12.402 20134-20134/? I/h.CrashReporte: The ClassLoaderContext is a special shared library.
2019-11-18 11:57:12.415 6682-6705/? I/MicroDataManager: isInitializing-false locale not changed-true model not changed-true
2019-11-18 11:57:12.424 20134-20134/? W/System.err: SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
2019-11-18 11:57:12.424 20134-20134/? W/System.err: SLF4J: Defaulting to no-operation (NOP) logger implementation
2019-11-18 11:57:12.424 20134-20134/? W/System.err: SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
2019-11-18 11:57:12.424 19802-20003/? I/Gecko: Exiting due to channel error.
2019-11-18 11:57:12.424 20134-20134/? D/io.sentry.android.AndroidSentryClientFactory: Construction of Android Sentry.
2019-11-18 11:57:12.428 6682-6705/? I/StreamController: registerScope: nowcards-15-7f85e0-8353
2019-11-18 11:57:12.438 1182-1411/? W/InputDispatcher: channel '7d8224a org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)' ~ Consumer closed input channel or an error occurred.  events=0x9
2019-11-18 11:57:12.438 1182-1411/? E/InputDispatcher: channel '7d8224a org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)' ~ Channel is unrecoverably broken and will be disposed!
2019-11-18 11:57:12.439 1182-1791/? I/ActivityManager: Process org.mozilla.fenix.nightly (pid 19758) has died: vis  TOP 

[jonalmeida]

Looking into it this week. Seems like it's push related and the same crash in #6626 .

[jonalmeida]

Off the bat, it seems like we aren't cleaning up push registrations correctly on sign out.

[jonalmeida]

@AndiAJ are you still able to reproduce this? I've tried with no luck.

[AndiAJ]

Hi @jonalmeida , I've re-check this matter on the latest Nightly Build #13300628 from 11/26 and it is still reproducible, but not 100% of the time.
In each of the above mentioned cases it crashed one out of three attempts.

Managed to catch the logs for :

## Turn on sync section - Disconnect after QR pairing

Crashlog

--------- beginning of crash
2019-11-26 10:58:01.120 14037-14096/? E/AndroidRuntime: FATAL EXCEPTION: pool-8-thread-1
    Process: org.mozilla.fenix.nightly, PID: 14037
    mozilla.appservices.push.StorageError: Error(

    Storage Error: "No record for uaid:chid \"2ffdae40016e4993a1f55a21bdb39740\":\"992a0f0542383f1ea5ef51b7cf4ae6c4\"")
        at mozilla.appservices.push.RustError.intoException(RustError.kt:8)
        at mozilla.appservices.push.PushManager.decrypt(PushManager.kt:16)
        at mozilla.components.feature.push.RustPushConnection.decrypt(Connection.kt:3)
        at mozilla.components.feature.push.AutoPushFeature$onMessageReceived$1.invokeSuspend(AutoPushFeature.kt:20)
        at mozilla.components.feature.push.AutoPushFeature$onMessageReceived$1.invoke(Unknown Source:10)
        at mozilla.components.feature.push.AutoPushFeatureKt$launchAndTry$1.invokeSuspend(AutoPushFeature.kt:5)
        at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(ContinuationImpl.kt:2)
        at kotlinx.coroutines.DispatchedTask.run(Dispatched.kt:15)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
        at java.lang.Thread.run(Thread.java:764)
2019-11-26 10:58:01.123 14015-14015/? W/ActivityThread: handleWindowVisibility: no activity for token android.os.BinderProxy@1ceec0d
2019-11-26 10:58:01.138 14037-14096/? I/Process: Sending signal. PID: 14037 SIG: 9
2019-11-26 10:58:01.142 1182-1196/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
2019-11-26 10:58:01.143 1182-1196/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
2019-11-26 10:58:01.158 569-609/? D/SurfaceFlinger: duplicate layer name: changing com.google.android.apps.nexuslauncher/com.google.android.apps.nexuslauncher.NexusLauncherActivity to com.google.android.apps.nexuslauncher/com.google.android.apps.nexuslauncher.NexusLauncherActivity#1
2019-11-26 10:58:01.215 1182-4899/? W/InputMethodManagerService: Got RemoteException sending setActive(false) notification to pid 14037 uid 10224
2019-11-26 10:58:01.217 14089-14231/? I/Gecko: Exiting due to channel error.
2019-11-26 10:58:01.238 1182-1377/? W/InputDispatcher: channel '2a0d7cc org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)' ~ Consumer closed input channel or an error occurred.  events=0x9
2019-11-26 10:58:01.238 1182-1377/? E/InputDispatcher: channel '2a0d7cc org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)' ~ Channel is unrecoverably broken and will be disposed!
2019-11-26 10:58:01.241 693-693/? I/Zygote: Process 14037 exited due to signal (9)
2019-11-26 10:58:01.242 2245-2260/? E/JavaBinder: *** Uncaught remote exception!  (Exceptions are not yet supported across processes.)
    java.lang.RuntimeException: android.os.DeadObjectException
        at android.os.Parcel.writeException(Parcel.java:1779)
        at android.os.Binder.execTransact(Binder.java:744)
     Caused by: android.os.DeadObjectException
        at android.os.BinderProxy.transactNative(Native Method)
        at android.os.BinderProxy.transact(Binder.java:1127)
        at cnk.b(:com.google.android.gms@[email protected] (100400-278422107):2)
        at skt.a(:com.google.android.gms@[email protected] (100400-278422107):3)
        at sju.a(:com.google.android.gms@[email protected] (100400-278422107):12)
        at sju.a(:com.google.android.gms@[email protected] (100400-278422107):4)
        at ahfe.a(:com.google.android.gms@[email protected] (100400-278422107):3)
        at sie.a(:com.google.android.gms@[email protected] (100400-278422107):35)
        at skx.onTransact(:com.google.android.gms@[email protected] (100400-278422107):17)
        at android.os.Binder.transact(Binder.java:667)
        at duv.onTransact(:com.google.android.gms@[email protected] (100400-278422107):2)
        at android.os.Binder.transact(Binder.java:667)
        at aaop.onTransact(:com.google.android.gms@[email protected] (100400-278422107):29)
        at android.os.Binder.execTransact(Binder.java:731)
2019-11-26 10:58:01.244 1182-4724/? I/WindowManager: WIN DEATH: Window{2a0d7cc u0 org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity}
2019-11-26 10:58:01.244 1182-4724/? W/InputDispatcher: Attempted to unregister already unregistered input channel '2a0d7cc org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)'
2019-11-26 10:58:01.251 693-693/? I/Zygote: Process 14089 exited cleanly (0)
2019-11-26 10:58:01.251 787-787/? E/lowmemorykiller: Error opening /proc/14089/oom_score_adj; errno=2
2019-11-26 10:58:01.251 787-787/? E/lowmemorykiller: Error opening /proc/14037/oom_score_adj; errno=2
2019-11-26 10:58:01.252 1182-1195/? I/ActivityManager: Displayed org.mozilla.fenix.nightly/mozilla.components.lib.crash.prompt.CrashReporterActivity: +77ms
2019-11-26 10:58:01.254 1182-4724/? I/ActivityManager: Process org.mozilla.fenix.nightly:tab (pid 14089) has died: cch  CEM 
2019-11-26 10:58:01.254 1182-4724/? W/ActivityManager: Scheduling restart of crashed service org.mozilla.fenix.nightly/org.mozilla.gecko.process.GeckoServiceChildProcess$tab in 1000ms

## Passwords section - Disconnect after sign in with email

Crashlog

   --------- beginning of crash
2019-11-26 11:02:21.843 15993-16060/? E/AndroidRuntime: FATAL EXCEPTION: pool-8-thread-1
    Process: org.mozilla.fenix.nightly, PID: 15993
    mozilla.appservices.push.StorageError: Error(

    Storage Error: "No record for uaid:chid \"2ffdae40016e4993a1f55a21bdb39740\":\"992a0f0542383f1ea5ef51b7cf4ae6c4\"")
        at mozilla.appservices.push.RustError.intoException(RustError.kt:8)
        at mozilla.appservices.push.PushManager.decrypt(PushManager.kt:16)
        at mozilla.components.feature.push.RustPushConnection.decrypt(Connection.kt:3)
        at mozilla.components.feature.push.AutoPushFeature$onMessageReceived$1.invokeSuspend(AutoPushFeature.kt:20)
        at mozilla.components.feature.push.AutoPushFeature$onMessageReceived$1.invoke(Unknown Source:10)
        at mozilla.components.feature.push.AutoPushFeatureKt$launchAndTry$1.invokeSuspend(AutoPushFeature.kt:5)
        at kotlin.coroutines.jvm.internal.BaseContinuationImpl.resumeWith(ContinuationImpl.kt:2)
        at kotlinx.coroutines.DispatchedTask.run(Dispatched.kt:15)
        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
        at java.lang.Thread.run(Thread.java:764)
2019-11-26 11:02:21.851 15993-16060/? I/Process: Sending signal. PID: 15993 SIG: 9
2019-11-26 11:02:21.853 1182-1196/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
2019-11-26 11:02:21.853 1182-1196/? W/BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
2019-11-26 11:02:21.864 1182-1198/? I/ActivityManager: Start proc 16348:org.mozilla.fenix.nightly:mozilla.components.lib.crash.CrashReporter/u0a224 for activity org.mozilla.fenix.nightly/mozilla.components.lib.crash.prompt.CrashReporterActivity
2019-11-26 11:02:21.869 16348-16348/? E/h.CrashReporte: Not starting debugger since process cannot load the jdwp agent.
2019-11-26 11:02:21.900 569-1837/? D/SurfaceFlinger: duplicate layer name: changing com.google.android.apps.nexuslauncher/com.google.android.apps.nexuslauncher.NexusLauncherActivity to com.google.android.apps.nexuslauncher/com.google.android.apps.nexuslauncher.NexusLauncherActivity#1
2019-11-26 11:02:21.903 16348-16348/? I/h.CrashReporte: The ClassLoaderContext is a special shared library.
2019-11-26 11:02:21.920 16348-16348/? W/System.err: SLF4J: Failed to load class "org.slf4j.impl.StaticLoggerBinder".
2019-11-26 11:02:21.920 16348-16348/? W/System.err: SLF4J: Defaulting to no-operation (NOP) logger implementation
2019-11-26 11:02:21.920 16348-16348/? W/System.err: SLF4J: See http://www.slf4j.org/codes.html#StaticLoggerBinder for further details.
2019-11-26 11:02:21.920 16348-16348/? D/io.sentry.android.AndroidSentryClientFactory: Construction of Android Sentry.
2019-11-26 11:02:21.921 16039-16238/? I/Gecko: Exiting due to channel error.
2019-11-26 11:02:21.938 16348-16363/? E/libc: Access denied finding property "vendor.debug.egl.profiler"
2019-11-26 11:02:21.935 16348-16348/? W/org.mozilla.fenix.nightly:mozilla.components.lib.crash.CrashReporter: type=1400 audit(0.0:850): avc: denied { read } for comm=45474C20496E6974 name="u:object_r:vendor_default_prop:s0" dev="tmpfs" ino=16639 scontext=u:r:untrusted_app:s0:c224,c256,c512,c768 tcontext=u:object_r:vendor_default_prop:s0 tclass=file permissive=0
2019-11-26 11:02:21.938 1182-1377/? W/InputDispatcher: channel 'daf7d1f org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)' ~ Consumer closed input channel or an error occurred.  events=0x9
2019-11-26 11:02:21.938 1182-1377/? E/InputDispatcher: channel 'daf7d1f org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)' ~ Channel is unrecoverably broken and will be disposed!
2019-11-26 11:02:21.939 1182-1848/? I/WindowManager: WIN DEATH: Window{daf7d1f u0 org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity}
2019-11-26 11:02:21.939 1182-1848/? W/InputDispatcher: Attempted to unregister already unregistered input channel 'daf7d1f org.mozilla.fenix.nightly/org.mozilla.fenix.HomeActivity (server)'
2019-11-26 11:02:21.939 1182-7386/? I/ActivityManager: Process org.mozilla.fenix.nightly (pid 15993) has died: vis  TOP 
2019-11-26 11:02:21.940 1182-1199/? W/libprocessgroup: kill(-15993, 9) failed: No such process
2019-11-26 11:02:21.943 693-693/? I/Zygote: Process 15993 exited due to signal (9)
2019-11-26 11:02:21.956 1182-7386/? I/ActivityManager: Process org.mozilla.fenix.nightly:tab (pid 16039) has died: fore SVC 

[jonalmeida]

Thanks @AndiAJ that was super helpful!

This seems like we're receiving a push message right after we disconnect from FxA. What happens then, is that we destroy the push subscription for FxA immediately.

On the FxA server side, they emit a push notification to all clients including the device itself, that this device has signed out so that those clients can remove the record of the newly removed device.

So we receive the message from FxA notifying ourselves, and when we try to decrypt it, but since we have already unsubscribed we crash at the decryption.

Bringing this up in appservices to consider a solution.

[rfk]

On the FxA server side, they emit a push notification to all clients including the device itself.

Just for context sharing, I believe this is done because the device could be disconnected remotely (e.g. from the FxA settings page on the web) and so we want to immediately tell that device that its sessionToken has been revoked, allowing it to transition to some sort of "reconnect" state straight away.

[jonalmeida]

The dependent a-s bug has a fix that we can use to avoid this crash. Next, a-c needs to update to the version of a-s with that fix.

[softvision-miralobontiu]

Hi all!
I was able to reproduce the crash on RC 3.0.0 (#13431956), with Samsung Galaxy Note 8 (Android 9), with the first scenario: sign in via email, then sign out.

[jonalmeida]

Hi all!
I was able to reproduce the crash on RC 3.0.0 (#13431956), with Samsung Galaxy Note 8 (Android 9), with the first scenario: sign in via email, then sign out.

This issue hasn't been fixed in the 3.0 RC, requires updating app-services version.

[AndiAJ]

Hi, verified as fixed on the latest Nightly Build #13520613 from 12/18 using a Google Pixel 3a (Android 9).

No crash encountered while signing out 36 times. (signed in and out 9 times from each section) :relaxed:

[AndiAJ]

Reproducible on Fenix 3.0.2 Build #20081833 from 1/8 using a OnePlus A3 (Android 6)

[jonalmeida]

Reproducible on Fenix 3.0.2 Build #20081833 from 1/8 using a OnePlus A3 (Android 6)

Were you able to confirm the stack trace is similar to the one in https://github.com/mozilla-mobile/fenix/issues/6663#issuecomment-558534622 above?

[AndiAJ]

Hi @jonalmeida , re-tried approx. 12 times and wasn't able to reproduce it.

[jonalmeida]

@AndiAJ no worries, in the consistent case, I think we were see https://github.com/mozilla-mobile/android-components/issues/5044#issuecomment-554098962.

In these rare instances, is what this bug is for, where a fix hasn't gone up yet.