search

ryanheise / audio_service

Flutter plugin to play audio in the background while the screen is off.
806 stars 481 forks source link

firebase + audio_service: application launched when it should not #875

Open yanivshaked opened 3 years ago

yanivshaked commented 3 years ago

Documented behaviour

Expected behavior: During incoming push notification (firebase messaging), the application should NOT be launched.

Actual behaviour

The application is fully launched in the background when a push notification is received.

Minimal reproduction project

https://github.com/12Tech12/audio_service In other to make the example operational, you should:

  1. Put in your google-services.json file under android/app directory
  2. Search and replace all phrases com.twtech.twelveqa to the package name supported by your google-services.json (com.ryanheise.audioserviceexample or other)

    Reproduction steps

  3. Copy the firebase messaging token from the log printed, for example:
Cloud Messaging Token is c9NQO3mTRwqvbVKVqwlZi2:APA91bEMZHEN8kt083sfukOxTIFy-gq8RUen7TQy3Wgvw-7hwRCQyAGmaqdedFc6bThfBIij911p4V3snahxRjl13um4yF67Ywu7HtiycdxcjXweDgN0ANBjB1sVnhtKSjfvcat6WIEB
  1. Close the application (also from background).
  2. Use firebase console to send a push notification to the device (use the token copied on step 1).
  3. The log "Application fully loaded!" will be displayed, indicating the application was fully loaded on the background due to the push notification (and it should NOT).

    Output of flutter doctor

    
Doctor summary (to see all details, run flutter doctor -v):
[√] Flutter (Channel stable, 2.5.3, on Microsoft Windows [Version 10.0.19042.1348], locale he-IL)
[√] Android toolchain - develop for Android devices (Android SDK version 31.0.0-rc3)
[√] Chrome - develop for the web
[√] Android Studio (version 2020.3)
[√] VS Code (version 1.62.2)
[√] Connected device (3 available)

• No issues found!```

Devices exhibiting the bug

Device: Android OnePlus 8T OS Version: 11

ryanheise commented 3 years ago

Thank you for the report, I shall look into this tomorrow.

ryanheise commented 3 years ago

It may take me a while before I can set up an environment to test this, but in the meantime would you be willing to provide a stack trace inside of AudioServicePlugin.getFlutterEngine()?

The easiest way without tools would be to just insert this line inside of that method:

    public static synchronized FlutterEngine getFlutterEngine(Context context) {
        new Exception().printStackTrace(); // INSERT THIS LINE

This engine should only be used by the activity and by this plugin's own service (AudioService). firebase_messaging has these intent filters:

    <service
      android:name=".FlutterFirebaseMessagingBackgroundService"
      android:permission="android.permission.BIND_JOB_SERVICE"
      android:exported="false"/>
    <service android:name=".FlutterFirebaseMessagingService"
      android:exported="false">
      <intent-filter>
        <action android:name="com.google.firebase.MESSAGING_EVENT"/>
      </intent-filter>
    </service>
    <receiver
      android:name=".FlutterFirebaseMessagingReceiver"
      android:exported="true"
      android:permission="com.google.android.c2dm.permission.SEND">
      <intent-filter>
        <action android:name="com.google.android.c2dm.intent.RECEIVE" />
      </intent-filter>
    </receiver>

But looking at those, it seems firebase_messaging instantiates its own FlutterEngine so this should not happen.

The only thing I can think of is if the activity receives the intent, but according to your repro project's manifest that only happens if you click on the notification which is not the case here.

So a stack trace will help narrow down who is actually instantiating audio_service's own FlutterEngine.

yanivshaked commented 3 years ago

Sure, anything I can do to help find a solution to this issue ASAP. On it, will post shortly.

yanivshaked commented 3 years ago

In addition, I have provided you with maintenance permission to the fork, so in case you would like to add more logs / try out different solution, you can simply push the changes to the fork, I can quickly test them and report.

yanivshaked commented 3 years ago

Deleted my previous message, as it was incorrect. When starting the application regularly (flutter run or app icon click), I am getting the following log:


2021-11-17 08:08:33.813 31557-31557/com.twtech.twelveqa D/ApplicationLoaders: Returning zygote-cached class loader: /system/framework/android.test.base.jar
2021-11-17 08:08:33.769 31557-31557/? I/libc: SetHeapTaggingLevel: tag level set to 0
2021-11-17 08:08:33.771 31557-31557/? I/twtech.twelveq: Late-enabling -Xcheck:jni
2021-11-17 08:08:33.784 31557-31557/? I/twtech.twelveq: Unquickening 21 vdex files!
2021-11-17 08:08:29.047 2435-2435/? D/OpSWarpChargingView: level is same as latest one [95]
2021-11-17 08:08:33.769 31557-31557/? I/libc: SetHeapTaggingLevel: tag level set to 0
2021-11-17 08:08:33.784 31557-31557/? E/twtech.twelveq: Unknown bits set in runtime_flags: 0x800000
2021-11-17 08:08:33.771 31557-31557/? I/twtech.twelveq: Late-enabling -Xcheck:jni
2021-11-17 08:08:33.784 31557-31557/? I/twtech.twelveq: Unquickening 21 vdex files!
2021-11-17 08:08:33.784 31557-31557/? E/twtech.twelveq: Unknown bits set in runtime_flags: 0x800000
2021-11-17 08:08:33.769 31557-31557/? I/libc: SetHeapTaggingLevel: tag level set to 0
2021-11-17 08:08:33.771 31557-31557/? I/twtech.twelveq: Late-enabling -Xcheck:jni
2021-11-17 08:08:33.784 31557-31557/? I/twtech.twelveq: Unquickening 21 vdex files!
2021-11-17 08:08:33.784 31557-31557/? E/twtech.twelveq: Unknown bits set in runtime_flags: 0x800000
2021-11-17 08:08:33.824 31557-31557/com.twtech.twelveqa I/Perf: Connecting to perf service.
2021-11-17 08:08:33.828 31557-31557/com.twtech.twelveqa D/NetworkSecurityConfig: No Network Security Config specified, using platform default
2021-11-17 08:08:33.828 31557-31557/com.twtech.twelveqa D/NetworkSecurityConfig: No Network Security Config specified, using platform default
2021-11-17 08:08:33.834 31557-31557/com.twtech.twelveqa I/FirebaseApp: Device unlocked: initializing all Firebase APIs for app [DEFAULT]
2021-11-17 08:08:33.839 31557-31557/com.twtech.twelveqa I/FirebaseInitProvider: FirebaseApp initialization successful
2021-11-17 08:08:33.839 31557-31557/com.twtech.twelveqa D/FLTFireContextHolder: received application context.
2021-11-17 08:08:33.842 31557-27594/com.twtech.twelveqa E/sqlite3_android: ONEPLUS_NAME_PARTS_MATCH SQLITE_OK
2021-11-17 08:08:33.853 31557-31557/com.twtech.twelveqa E/libprocessgroup: set_timerslack_ns write failed: Operation not permitted
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err: java.lang.Exception
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at com.ryanheise.audioservice.AudioServicePlugin.getFlutterEngine(AudioServicePlugin.java:68)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at com.ryanheise.audioservice.AudioServiceActivity.provideFlutterEngine(AudioServiceActivity.java:12)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at io.flutter.embedding.android.FlutterActivityAndFragmentDelegate.setupFlutterEngine(FlutterActivityAndFragmentDelegate.java:235)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at io.flutter.embedding.android.FlutterActivityAndFragmentDelegate.onAttach(FlutterActivityAndFragmentDelegate.java:162)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at io.flutter.embedding.android.FlutterActivity.onCreate(FlutterActivity.java:430)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.Activity.performCreate(Activity.java:8006)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.Activity.performCreate(Activity.java:7990)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.Instrumentation.callActivityOnCreate(Instrumentation.java:1329)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:3595)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.handleLaunchActivity(ActivityThread.java:3786)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.servertransaction.LaunchActivityItem.execute(LaunchActivityItem.java:85)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.servertransaction.TransactionExecutor.executeCallbacks(TransactionExecutor.java:135)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.servertransaction.TransactionExecutor.execute(TransactionExecutor.java:95)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2257)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.os.Handler.dispatchMessage(Handler.java:106)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.os.Looper.loop(Looper.java:233)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.main(ActivityThread.java:8030)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at java.lang.reflect.Method.invoke(Native Method)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:631)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:978)
2021-11-17 08:08:33.869 31557-27608/com.twtech.twelveqa I/ResourceExtractor: Found extracted resources res_timestamp-1-1637128743812
2021-11-17 08:08:33.869 31557-31557/com.twtech.twelveqa W/twtech.twelveqa: type=1400 audit(0.0:19131): avc: denied { read } for name="max_map_count" dev="proc" ino=24053391 scontext=u:r:untrusted_app_29:s0:c160,c259,c512,c768 tcontext=u:object_r:proc_max_map_count:s0 tclass=file permissive=0 app=com.twtech.twelveqa
2021-11-17 08:08:33.878 31557-31557/com.twtech.twelveqa I/AdrenoGLES-0: QUALCOMM build                   : 3571974a10, I64bd74b5bd
    Build Date                       : 09/11/20
    OpenGL ES Shader Compiler Version: EV031.32.02.00
    Local Branch                     : 
    Remote Branch                    : refs/tags/AU_LINUX_ANDROID_LA.UM.9.12.10.00.00.582.274
    Remote Branch                    : NONE
    Reconstruct Branch               : NOTHING
2021-11-17 08:08:33.878 31557-31557/com.twtech.twelveqa I/AdrenoGLES-0: Build Config                     : S P 10.0.5 AArch64
2021-11-17 08:08:33.878 31557-31557/com.twtech.twelveqa I/AdrenoGLES-0: Driver Path                      : /vendor/lib64/egl/libGLESv2_adreno.so
2021-11-17 08:08:33.880 31557-31557/com.twtech.twelveqa I/AdrenoGLES-0: PFP: 0x016dd091, ME: 0x00000000
2021-11-17 08:08:33.894 31557-31557/com.twtech.twelveqa D/MediaBrowserCompat: Connecting to a MediaBrowserService.
2021-11-17 08:08:33.910 31557-31557/com.twtech.twelveqa I/TextToSpeech: Sucessfully bound to com.google.android.tts
2021-11-17 08:08:33.914 31557-31557/com.twtech.twelveqa D/PathProviderPlugin: Don't use TaskQueues.
2021-11-17 08:08:33.915 31557-31557/com.twtech.twelveqa W/System.err: java.lang.Exception
2021-11-17 08:08:33.915 31557-31557/com.twtech.twelveqa W/System.err:     at com.ryanheise.audioservice.AudioServicePlugin.getFlutterEngine(AudioServicePlugin.java:68)
2021-11-17 08:08:33.915 31557-31557/com.twtech.twelveqa W/System.err:     at com.ryanheise.audioservice.AudioServicePlugin.onAttachedToActivity(AudioServicePlugin.java:261)
2021-11-17 08:08:33.915 31557-31557/com.twtech.twelveqa W/System.err:     at io.flutter.embedding.engine.FlutterEngineConnectionRegistry.attachToActivityInternal(FlutterEngineConnectionRegistry.java:362)
2021-11-17 08:08:33.915 31557-31557/com.twtech.twelveqa W/System.err:     at io.flutter.embedding.engine.FlutterEngineConnectionRegistry.attachToActivity(FlutterEngineConnectionRegistry.java:344)
2021-11-17 08:08:33.915 31557-31557/com.twtech.twelveqa W/System.err:     at io.flutter.embedding.android.FlutterActivityAndFragmentDelegate.onAttach(FlutterActivityAndFragmentDelegate.java:176)
2021-11-17 08:08:33.915 31557-31557/com.twtech.twelveqa W/System.err:     at io.flutter.embedding.android.FlutterActivity.onCreate(FlutterActivity.java:430)
2021-11-17 08:08:33.915 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.Activity.performCreate(Activity.java:8006)
2021-11-17 08:08:33.915 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.Activity.performCreate(Activity.java:7990)
2021-11-17 08:08:33.916 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.Instrumentation.callActivityOnCreate(Instrumentation.java:1329)
2021-11-17 08:08:33.916 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:3595)
2021-11-17 08:08:33.916 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.handleLaunchActivity(ActivityThread.java:3786)
2021-11-17 08:08:33.916 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.servertransaction.LaunchActivityItem.execute(LaunchActivityItem.java:85)
2021-11-17 08:08:33.916 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.servertransaction.TransactionExecutor.executeCallbacks(TransactionExecutor.java:135)
2021-11-17 08:08:33.916 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.servertransaction.TransactionExecutor.execute(TransactionExecutor.java:95)
2021-11-17 08:08:33.916 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2257)
2021-11-17 08:08:33.916 31557-31557/com.twtech.twelveqa W/System.err:     at android.os.Handler.dispatchMessage(Handler.java:106)
2021-11-17 08:08:33.916 31557-31557/com.twtech.twelveqa W/System.err:     at android.os.Looper.loop(Looper.java:233)
2021-11-17 08:08:33.916 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.main(ActivityThread.java:8030)
2021-11-17 08:08:33.916 31557-31557/com.twtech.twelveqa W/System.err:     at java.lang.reflect.Method.invoke(Native Method)
2021-11-17 08:08:33.916 31557-31557/com.twtech.twelveqa W/System.err:     at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:631)
2021-11-17 08:08:33.916 31557-31557/com.twtech.twelveqa W/System.err:     at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:978)
2021-11-17 08:08:33.925 31557-31557/com.twtech.twelveqa W/twtech.twelveq: Accessing hidden method Landroid/view/accessibility/AccessibilityNodeInfo;->getSourceNodeId()J (greylist,test-api, reflection, allowed)
2021-11-17 08:08:33.925 31557-31557/com.twtech.twelveqa W/twtech.twelveq: Accessing hidden method Landroid/view/accessibility/AccessibilityRecord;->getSourceNodeId()J (greylist, reflection, allowed)
2021-11-17 08:08:33.925 31557-31557/com.twtech.twelveqa W/twtech.twelveq: Accessing hidden field Landroid/view/accessibility/AccessibilityNodeInfo;->mChildNodeIds:Landroid/util/LongArray; (greylist, reflection, allowed)
2021-11-17 08:08:33.925 31557-31557/com.twtech.twelveqa W/twtech.twelveq: Accessing hidden method Landroid/util/LongArray;->get(I)J (greylist, reflection, allowed)
2021-11-17 08:08:33.949 31557-31557/com.twtech.twelveqa V/ViewRootImpl: The specified message queue synchronization  barrier token has not been posted or has already been removed
2021-11-17 08:08:33.956 31557-31557/com.twtech.twelveqa D/OpScreenModeManager: setRefreshRate view io.flutter.embedding.android.FlutterSurfaceView{72be442 V.E...... ......I. 0,0-0,0} viewRate 0 width 0 height 0
2021-11-17 08:08:33.956 31557-31557/com.twtech.twelveqa D/OpScreenModeManager: create mRatio 1.2
2021-11-17 08:08:33.990 31557-27632/com.twtech.twelveqa I/flutter: Observatory listening on http://127.0.0.1:42807/EixeMaNGyjI=/
2021-11-17 08:08:34.072 31557-31557/com.twtech.twelveqa D/OpScreenModeManager: setRefreshRate parent io.flutter.embedding.android.FlutterView{a487c53 VFE...... ......ID 0,0-1080,2355 #1} parent width 1080 parent height 2355 mRatio 1.2
2021-11-17 08:08:34.072 31557-31557/com.twtech.twelveqa D/OpScreenModeManager: setRefreshRate view io.flutter.embedding.android.FlutterSurfaceView{72be442 V.E...... ......ID 0,0-1080,2355} viewRate 1 width 1080 height 2355
2021-11-17 08:08:34.072 31557-31557/com.twtech.twelveqa D/OpScreenModeManager: setRefreshRate token android.os.BinderProxy@7e99239 rate 1 mCurrentRate 0 tmpRate:2
2021-11-17 08:08:34.073 31557-31557/com.twtech.twelveqa D/OpScreenModeManager: updateScenario fullScreen 1
2021-11-17 08:08:34.097 31557-31557/com.twtech.twelveqa W/Choreographer: Already have a pending vsync event.  There should only be one at a time.
2021-11-17 08:08:34.099 31557-31557/com.twtech.twelveqa I/TextToSpeech: Connected to ComponentInfo{com.google.android.tts/com.google.android.apps.speech.tts.googletts.service.GoogleTTSService}
2021-11-17 08:08:34.102 31557-27608/com.twtech.twelveqa I/TextToSpeech: Set up connection to ComponentInfo{com.google.android.tts/com.google.android.apps.speech.tts.googletts.service.GoogleTTSService}
2021-11-17 08:08:34.117 31557-31557/com.twtech.twelveqa W/System.err: java.lang.Exception
2021-11-17 08:08:34.117 31557-31557/com.twtech.twelveqa W/System.err:     at com.ryanheise.audioservice.AudioServicePlugin.getFlutterEngine(AudioServicePlugin.java:68)
2021-11-17 08:08:34.117 31557-31557/com.twtech.twelveqa W/System.err:     at com.ryanheise.audioservice.AudioService.onCreate(AudioService.java:282)
2021-11-17 08:08:34.117 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.handleCreateService(ActivityThread.java:4390)
2021-11-17 08:08:34.117 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.access$2000(ActivityThread.java:274)
2021-11-17 08:08:34.117 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2123)
2021-11-17 08:08:34.117 31557-31557/com.twtech.twelveqa W/System.err:     at android.os.Handler.dispatchMessage(Handler.java:106)
2021-11-17 08:08:34.117 31557-31557/com.twtech.twelveqa W/System.err:     at android.os.Looper.loop(Looper.java:233)
2021-11-17 08:08:34.117 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.main(ActivityThread.java:8030)
2021-11-17 08:08:34.117 31557-31557/com.twtech.twelveqa W/System.err:     at java.lang.reflect.Method.invoke(Native Method)
2021-11-17 08:08:34.117 31557-31557/com.twtech.twelveqa W/System.err:     at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:631)
2021-11-17 08:08:34.117 31557-31557/com.twtech.twelveqa W/System.err:     at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:978)
2021-11-17 08:08:34.117 31557-31557/com.twtech.twelveqa I/System.out: flutterEngine warmed up
2021-11-17 08:08:34.119 31557-31557/com.twtech.twelveqa E/TTS: getDefaultLocale: Attempt to invoke virtual method 'java.util.Locale android.speech.tts.Voice.getLocale()' on a null object reference
2021-11-17 08:08:34.369 31557-27643/com.twtech.twelveqa D/Choreographer: initialValue myPid:31557 myTid:27643 choreographer:android.view.Choreographer@2d02b3e
2021-11-17 08:08:34.370 31557-31557/com.twtech.twelveqa W/twtech.twelveq: Accessing hidden method Landroid/media/AudioTrack;->getLatency()I (greylist, reflection, allowed)
2021-11-17 08:08:34.371 31557-31557/com.twtech.twelveqa I/ExoPlayerImpl: Init fc37d9f [ExoPlayerLib/2.15.0] [OnePlus8T, KB2003, OnePlus, 30]
2021-11-17 08:08:34.382 31557-27636/com.twtech.twelveqa I/FirebaseApp: Device unlocked: initializing all Firebase APIs for app Twelve
2021-11-17 08:08:34.388 31557-27648/com.twtech.twelveqa E/sqlite3_android: ONEPLUS_NAME_PARTS_MATCH SQLITE_OK
2021-11-17 08:08:34.390 31557-31557/com.twtech.twelveqa I/TetheringManager: registerTetheringEventCallback:com.twtech.twelveqa
2021-11-17 08:08:34.426 31557-27610/com.twtech.twelveqa I/flutter: Is notifications authorized? => true
2021-11-17 08:08:34.445 31557-27610/com.twtech.twelveqa I/flutter: Cloud Messaging Token is cvYJNS1zS4O_Ca6lCDfNsr:APA91bGdXwChgYtbpsFV99NMvaehdOIPHIFUsFSvLAseTCow8L0DPSfZu7hehLC9z_fMY8ypyD6MG-83PgUYVjGze0bLJC_mlJ6zVWOuenfyrGwC4HTMWU6iAv5wrC7YAuol3JQEJr4n
2021-11-17 08:08:34.447 31557-27610/com.twtech.twelveqa I/flutter: Application fully loaded!
2021-11-17 08:08:34.450 31557-27650/com.twtech.twelveqa I/DpmTcmClient: RegisterTcmMonitor from: $Proxy1
2021-11-17 08:08:34.452 31557-31557/com.twtech.twelveqa W/FlutterJNI: FlutterJNI.setRefreshRateFPS called more than once
2021-11-17 08:08:34.454 31557-27654/com.twtech.twelveqa W/FlutterJNI: FlutterJNI.loadLibrary called more than once
2021-11-17 08:08:34.454 31557-27658/com.twtech.twelveqa W/FlutterJNI: FlutterJNI.prefetchDefaultFontManager called more than once
2021-11-17 08:08:34.455 31557-27608/com.twtech.twelveqa I/ResourceExtractor: Found extracted resources res_timestamp-1-1637128743812
2021-11-17 08:08:34.461 31557-31557/com.twtech.twelveqa W/FlutterJNI: FlutterJNI.init called more than once
2021-11-17 08:08:34.467 31557-31557/com.twtech.twelveqa I/FLTFireBGExecutor: Creating background FlutterEngine instance, with args: [--enable-dart-profiling]
2021-11-17 08:08:34.473 31557-31557/com.twtech.twelveqa I/TextToSpeech: Sucessfully bound to com.google.android.tts
2021-11-17 08:08:34.474 31557-31557/com.twtech.twelveqa D/PathProviderPlugin: Don't use TaskQueues.
2021-11-17 08:08:34.475 31557-31557/com.twtech.twelveqa I/TextToSpeech: Connected to ComponentInfo{com.google.android.tts/com.google.android.apps.speech.tts.googletts.service.GoogleTTSService}
2021-11-17 08:08:34.480 31557-27608/com.twtech.twelveqa I/TextToSpeech: Set up connection to ComponentInfo{com.google.android.tts/com.google.android.apps.speech.tts.googletts.service.GoogleTTSService}
2021-11-17 08:08:34.484 31557-31557/com.twtech.twelveqa E/TTS: getDefaultLocale: Attempt to invoke virtual method 'java.util.Locale android.speech.tts.Voice.getLocale()' on a null object reference
2021-11-17 08:08:34.700 31557-31557/com.twtech.twelveqa I/FLTFireMsgService: FlutterFirebaseMessagingBackgroundService started!
2021-11-17 08:08:35.044 31557-27611/com.twtech.twelveqa D/twtech.twelveq: Successfully load libgui-plugin.so, this=0x7e57dbc298
2021-11-17 08:08:35.050 31557-31557/com.twtech.twelveqa D/OpScreenModeManager: setRefreshRate parent io.flutter.embedding.android.FlutterView{a487c53 VFE...... ........ 0,0-1080,2355 #1} parent width 1080 parent height 2355 mRatio 1.2
2021-11-17 08:08:35.050 31557-31557/com.twtech.twelveqa D/OpScreenModeManager: setRefreshRate view io.flutter.embedding.android.FlutterSurfaceView{72be442 V.E...... ........ 0,0-1080,2355} viewRate 1 width 1080 height 2355
2021-11-17 08:08:35.063 31557-31557/com.twtech.twelveqa D/DecorView: onWindowFocusChangedFromViewRoot hasFocus: true, DecorView@f2bc428[AudioServiceActivity]
2021-11-17 08:08:35.064 31557-31557/com.twtech.twelveqa D/ViewRootImpl[AudioServiceActivity]: windowFocusChanged hasFocus=true inTouchMode=true
2021-11-17 08:08:35.080 31557-27603/com.twtech.twelveqa W/DisplayEventDispatcher: dispatcher 0x7c03cea690 ~ ignoring unknown event type 0x6d746f6e
2021-11-17 08:08:35.080 31557-27643/com.twtech.twelveqa W/DisplayEventDispatcher: dispatcher 0x7bb3cd4fd0 ~ ignoring unknown event type 0x6d746f6e
2021-11-17 08:08:35.081 31557-31557/com.twtech.twelveqa W/DisplayEventDispatcher: dispatcher 0x7bb3ccb550 ~ ignoring unknown event type 0x6d746f6e
2021-11-17 08:08:35.564 31557-27644/com.twtech.twelveqa W/VideoCapabilities: Unsupported mime image/vnd.android.heic
2021-11-17 08:08:35.567 31557-27644/com.twtech.twelveqa W/VideoCapabilities: Unrecognized profile/level 0/3 for video/mpeg2
2021-11-17 08:08:35.567 31557-27644/com.twtech.twelveqa W/VideoCapabilities: Unrecognized profile/level 0/3 for video/mpeg2
2021-11-17 08:08:35.596 31557-27672/com.twtech.twelveqa D/CCodec: allocate(c2.android.mp3.decoder)
2021-11-17 08:08:35.597 31557-27672/com.twtech.twelveqa I/Codec2Client: Available Codec2 services: "software"
2021-11-17 08:08:35.599 31557-27672/com.twtech.twelveqa I/CCodec: Created component [c2.android.mp3.decoder]
2021-11-17 08:08:35.600 31557-27672/com.twtech.twelveqa D/CCodecConfig: read media type: audio/mpeg
2021-11-17 08:08:35.601 31557-27672/com.twtech.twelveqa D/ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.max-count.values
2021-11-17 08:08:35.601 31557-27672/com.twtech.twelveqa D/ReflectedParamUpdater: extent() != 1 for single value type: output.subscribed-indices.values
2021-11-17 08:08:35.601 31557-27672/com.twtech.twelveqa D/ReflectedParamUpdater: extent() != 1 for single value type: input.buffers.allocator-ids.values
2021-11-17 08:08:35.601 31557-27672/com.twtech.twelveqa D/ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.allocator-ids.values
2021-11-17 08:08:35.601 31557-27672/com.twtech.twelveqa D/ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.allocator-ids.values
2021-11-17 08:08:35.601 31557-27672/com.twtech.twelveqa D/ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.pool-ids.values
2021-11-17 08:08:35.601 31557-27672/com.twtech.twelveqa D/ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.pool-ids.values
2021-11-17 08:08:35.602 31557-27672/com.twtech.twelveqa I/CCodecConfig: query failed after returning 7 values (BAD_INDEX)
2021-11-17 08:08:35.602 31557-27672/com.twtech.twelveqa D/CCodecConfig: c2 config diff is Dict {
      c2::u32 coded.bitrate.value = 64000
      c2::u32 input.buffers.max-size.value = 8192
      c2::u32 input.delay.value = 0
      string input.media-type.value = "audio/mpeg"
      string output.media-type.value = "audio/raw"
      c2::u32 raw.channel-count.value = 2
      c2::u32 raw.sample-rate.value = 44100
    }
2021-11-17 08:08:35.602 31557-27672/com.twtech.twelveqa D/CCodec: [c2.android.mp3.decoder] buffers are bound to CCodec for this session
2021-11-17 08:08:35.602 31557-27672/com.twtech.twelveqa D/CCodecConfig: no c2 equivalents for flags
2021-11-17 08:08:35.602 31557-27672/com.twtech.twelveqa D/CCodecConfig: config failed => CORRUPTED
2021-11-17 08:08:35.602 31557-27672/com.twtech.twelveqa W/Codec2Client: query -- param skipped: index = 1107298332.
2021-11-17 08:08:35.602 31557-27672/com.twtech.twelveqa D/CCodec: client requested max input size 4096, which is smaller than what component recommended (8192); overriding with component recommendation.
2021-11-17 08:08:35.602 31557-27672/com.twtech.twelveqa W/CCodec: This behavior is subject to change. It is recommended that app developers double check whether the requested max input size is in reasonable range.
2021-11-17 08:08:35.602 31557-27672/com.twtech.twelveqa D/CCodec: setup formats input: AMessage(what = 0x00000000) = {
      int32_t channel-count = 2
      int32_t max-input-size = 8192
      string mime = "audio/mpeg"
      int32_t sample-rate = 44100
    } and output: AMessage(what = 0x00000000) = {
      int32_t channel-count = 2
      string mime = "audio/raw"
      int32_t sample-rate = 44100
    }
2021-11-17 08:08:35.603 31557-27672/com.twtech.twelveqa W/Codec2Client: query -- param skipped: index = 1342179345.
2021-11-17 08:08:35.603 31557-27672/com.twtech.twelveqa W/Codec2Client: query -- param skipped: index = 2415921170.
2021-11-17 08:08:35.603 31557-27672/com.twtech.twelveqa E/FMQ: grantorIdx must be less than 3
2021-11-17 08:08:35.603 31557-27672/com.twtech.twelveqa E/FMQ: grantorIdx must be less than 3
2021-11-17 08:08:35.603 31557-27672/com.twtech.twelveqa D/CCodecBufferChannel: [c2.android.mp3.decoder#94] Created input block pool with allocatorID 16 => poolID 17 - OK (0)
2021-11-17 08:08:35.604 31557-27672/com.twtech.twelveqa I/CCodecBufferChannel: [c2.android.mp3.decoder#94] Created output block pool with allocatorID 16 => poolID 1099 - OK
2021-11-17 08:08:35.604 31557-27672/com.twtech.twelveqa D/CCodecBufferChannel: [c2.android.mp3.decoder#94] Configured output block pool ids 1099 => OK
2021-11-17 08:08:35.604 31557-27672/com.twtech.twelveqa E/ion: ioctl c0044901 failed with code -1: Inappropriate ioctl for device
2021-11-17 08:08:35.608 31557-27672/com.twtech.twelveqa E/FMQ: grantorIdx must be less than 3
2021-11-17 08:08:35.610 31557-27676/com.twtech.twelveqa E/FMQ: grantorIdx must be less than 3
2021-11-17 08:08:35.611 31557-27644/com.twtech.twelveqa D/AudioTrack: set(): streamType -1, sampleRate 44100, format 0x1, channelMask 0x3, frameCount 14144, flags #0, notificationFrames 0, sessionId 4337, transferType 3, uid -1, pid -1
2021-11-17 08:08:35.616 31557-27644/com.twtech.twelveqa D/AudioTrack: Uid 10928 AudioTrack::setVolume left 1.000000 right 1.000000
2021-11-17 08:08:40.610 31557-27675/com.twtech.twelveqa D/BufferPoolAccessor2.0: bufferpool2 0x7c43d420c8 : 5(40960 size) total buffers - 4(32768 size) used buffers - 0/5 (recycle/alloc) - 4/36 (fetch/transfer)
2021-11-17 08:08:40.610 31557-27675/com.twtech.twelveqa D/BufferPoolAccessor2.0: evictor expired: 1, evicted: 1

Note that the callstack is printed 3 times.

When application is started in the background due to the push notification (After fully terminating the app), I am getting the following log:


2021-11-17 08:11:11.556 27921-27921/? I/libc: SetHeapTaggingLevel: tag level set to 0
2021-11-17 08:11:11.558 27921-27921/? I/twtech.twelveq: Late-enabling -Xcheck:jni
2021-11-17 08:11:10.846 2435-2435/? E/NavigationButtonUtils: res.getInteger(resId) = 2
2021-11-17 08:11:11.569 27921-27921/? I/twtech.twelveq: Unquickening 21 vdex files!
2021-11-17 08:11:11.569 27921-27921/? E/twtech.twelveq: Unknown bits set in runtime_flags: 0x800000
2021-11-17 08:11:11.556 27921-27921/? I/libc: SetHeapTaggingLevel: tag level set to 0
2021-11-17 08:11:11.558 27921-27921/? I/twtech.twelveq: Late-enabling -Xcheck:jni
2021-11-17 08:11:11.569 27921-27921/? I/twtech.twelveq: Unquickening 21 vdex files!
2021-11-17 08:11:11.569 27921-27921/? E/twtech.twelveq: Unknown bits set in runtime_flags: 0x800000
2021-11-17 08:11:11.556 27921-27921/? I/libc: SetHeapTaggingLevel: tag level set to 0
2021-11-17 08:11:11.558 27921-27921/? I/twtech.twelveq: Late-enabling -Xcheck:jni
2021-11-17 08:11:11.569 27921-27921/? I/twtech.twelveq: Unquickening 21 vdex files!
2021-11-17 08:11:11.569 27921-27921/? E/twtech.twelveq: Unknown bits set in runtime_flags: 0x800000
2021-11-17 08:11:11.646 27921-27921/com.twtech.twelveqa D/ApplicationLoaders: Returning zygote-cached class loader: /system/framework/android.test.base.jar
2021-11-17 08:11:11.684 27921-27921/com.twtech.twelveqa I/Perf: Connecting to perf service.
2021-11-17 08:11:11.694 27921-27921/com.twtech.twelveqa D/NetworkSecurityConfig: No Network Security Config specified, using platform default
2021-11-17 08:11:11.695 27921-27921/com.twtech.twelveqa D/NetworkSecurityConfig: No Network Security Config specified, using platform default
2021-11-17 08:11:11.714 27921-27921/com.twtech.twelveqa I/FirebaseApp: Device unlocked: initializing all Firebase APIs for app [DEFAULT]
2021-11-17 08:11:11.731 27921-27921/com.twtech.twelveqa I/FirebaseInitProvider: FirebaseApp initialization successful
2021-11-17 08:11:11.732 27921-27921/com.twtech.twelveqa D/FLTFireContextHolder: received application context.
2021-11-17 08:11:11.734 27921-27945/com.twtech.twelveqa E/sqlite3_android: ONEPLUS_NAME_PARTS_MATCH SQLITE_OK
2021-11-17 08:11:11.747 27921-27921/com.twtech.twelveqa D/FLTFireMsgReceiver: broadcast received for message
2021-11-17 08:11:11.771 27921-27967/com.twtech.twelveqa I/FLTFireMsgService: Service has not yet started, messages will be queued.
2021-11-17 08:11:11.775 27921-27968/com.twtech.twelveqa I/ResourceExtractor: Found extracted resources res_timestamp-1-1637128743812
2021-11-17 08:11:11.792 27921-27921/com.twtech.twelveqa I/FLTFireBGExecutor: Creating background FlutterEngine instance.
2021-11-17 08:11:11.802 27921-27921/com.twtech.twelveqa W/FlutterJNI: FlutterJNI.setRefreshRateFPS called more than once
2021-11-17 08:11:11.804 27921-27985/com.twtech.twelveqa W/FlutterJNI: FlutterJNI.loadLibrary called more than once
2021-11-17 08:11:11.804 27921-27968/com.twtech.twelveqa I/ResourceExtractor: Found extracted resources res_timestamp-1-1637128743812
2021-11-17 08:11:11.806 27921-27921/com.twtech.twelveqa W/FlutterJNI: FlutterJNI.init called more than once
2021-11-17 08:11:11.809 27921-27921/com.twtech.twelveqa W/twtech.twelveqa: type=1400 audit(0.0:19140): avc: denied { read } for name="max_map_count" dev="proc" ino=24054458 scontext=u:r:untrusted_app_29:s0:c160,c259,c512,c768 tcontext=u:object_r:proc_max_map_count:s0 tclass=file permissive=0 app=com.twtech.twelveqa
2021-11-17 08:11:11.832 27921-27921/com.twtech.twelveqa I/AdrenoGLES-0: QUALCOMM build                   : 3571974a10, I64bd74b5bd
    Build Date                       : 09/11/20
    OpenGL ES Shader Compiler Version: EV031.32.02.00
    Local Branch                     : 
    Remote Branch                    : refs/tags/AU_LINUX_ANDROID_LA.UM.9.12.10.00.00.582.274
    Remote Branch                    : NONE
    Reconstruct Branch               : NOTHING
2021-11-17 08:11:11.832 27921-27921/com.twtech.twelveqa I/AdrenoGLES-0: Build Config                     : S P 10.0.5 AArch64
2021-11-17 08:11:11.832 27921-27921/com.twtech.twelveqa I/AdrenoGLES-0: Driver Path                      : /vendor/lib64/egl/libGLESv2_adreno.so
2021-11-17 08:11:11.845 27921-27921/com.twtech.twelveqa I/AdrenoGLES-0: PFP: 0x016dd091, ME: 0x00000000
2021-11-17 08:11:11.876 27921-27921/com.twtech.twelveqa D/MediaBrowserCompat: Connecting to a MediaBrowserService.
2021-11-17 08:11:11.929 27921-27921/com.twtech.twelveqa I/TextToSpeech: Sucessfully bound to com.google.android.tts
2021-11-17 08:11:11.945 27921-27921/com.twtech.twelveqa D/PathProviderPlugin: Don't use TaskQueues.
2021-11-17 08:11:11.957 27921-27921/com.twtech.twelveqa I/TextToSpeech: Connected to ComponentInfo{com.google.android.tts/com.google.android.apps.speech.tts.googletts.service.GoogleTTSService}
2021-11-17 08:11:11.965 27921-27968/com.twtech.twelveqa I/TextToSpeech: Set up connection to ComponentInfo{com.google.android.tts/com.google.android.apps.speech.tts.googletts.service.GoogleTTSService}
2021-11-17 08:11:11.980 27921-27921/com.twtech.twelveqa W/System.err: java.lang.Exception
2021-11-17 08:11:11.980 27921-27921/com.twtech.twelveqa W/System.err:     at com.ryanheise.audioservice.AudioServicePlugin.getFlutterEngine(AudioServicePlugin.java:68)
2021-11-17 08:11:11.981 27921-27921/com.twtech.twelveqa W/System.err:     at com.ryanheise.audioservice.AudioService.onCreate(AudioService.java:282)
2021-11-17 08:11:11.981 27921-27921/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.handleCreateService(ActivityThread.java:4390)
2021-11-17 08:11:11.981 27921-27921/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.access$2000(ActivityThread.java:274)
2021-11-17 08:11:11.981 27921-27921/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2123)
2021-11-17 08:11:11.981 27921-27921/com.twtech.twelveqa W/System.err:     at android.os.Handler.dispatchMessage(Handler.java:106)
2021-11-17 08:11:11.981 27921-27921/com.twtech.twelveqa W/System.err:     at android.os.Looper.loop(Looper.java:233)
2021-11-17 08:11:11.981 27921-27921/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.main(ActivityThread.java:8030)
2021-11-17 08:11:11.981 27921-27921/com.twtech.twelveqa W/System.err:     at java.lang.reflect.Method.invoke(Native Method)
2021-11-17 08:11:11.981 27921-27921/com.twtech.twelveqa W/System.err:     at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:631)
2021-11-17 08:11:11.981 27921-27921/com.twtech.twelveqa W/System.err:     at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:978)
2021-11-17 08:11:12.001 27921-27921/com.twtech.twelveqa I/TextToSpeech: Sucessfully bound to com.google.android.tts
2021-11-17 08:11:12.003 27921-27921/com.twtech.twelveqa D/PathProviderPlugin: Don't use TaskQueues.
2021-11-17 08:11:12.004 27921-27921/com.twtech.twelveqa I/System.out: flutterEngine warmed up
2021-11-17 08:11:12.010 27921-27921/com.twtech.twelveqa E/TTS: getDefaultLocale: Attempt to invoke virtual method 'java.util.Locale android.speech.tts.Voice.getLocale()' on a null object reference
2021-11-17 08:11:12.010 27921-27921/com.twtech.twelveqa I/TextToSpeech: Connected to ComponentInfo{com.google.android.tts/com.google.android.apps.speech.tts.googletts.service.GoogleTTSService}
2021-11-17 08:11:12.024 27921-27968/com.twtech.twelveqa I/TextToSpeech: Set up connection to ComponentInfo{com.google.android.tts/com.google.android.apps.speech.tts.googletts.service.GoogleTTSService}
2021-11-17 08:11:12.030 27921-27921/com.twtech.twelveqa E/TTS: getDefaultLocale: Attempt to invoke virtual method 'java.util.Locale android.speech.tts.Voice.getLocale()' on a null object reference
2021-11-17 08:11:12.138 27921-28006/com.twtech.twelveqa I/flutter: Observatory listening on http://127.0.0.1:42937/wP8DT2U-Uns=/
2021-11-17 08:11:12.945 27921-27921/com.twtech.twelveqa I/FLTFireMsgService: FlutterFirebaseMessagingBackgroundService started!
2021-11-17 08:11:13.007 27921-27987/com.twtech.twelveqa I/flutter: MessageHandler got: {authorProfileImageUrl: users/1094/profile/dee5cf5d-f7cc-4aaa-b058-61ffbcde1cd5.jpg, authorUserId: 1094, notificationContext: ForYouFeed, feedId: 68830, text: Is this working?, title: testing this one}
2021-11-17 08:11:13.601 27921-28018/com.twtech.twelveqa D/Choreographer: initialValue myPid:27921 myTid:28018 choreographer:android.view.Choreographer@eea5f84
2021-11-17 08:11:13.603 27921-27921/com.twtech.twelveqa W/twtech.twelveq: Accessing hidden method Landroid/media/AudioTrack;->getLatency()I (greylist, reflection, allowed)
2021-11-17 08:11:13.608 27921-27921/com.twtech.twelveqa I/ExoPlayerImpl: Init 6fd136d [ExoPlayerLib/2.15.0] [OnePlus8T, KB2003, OnePlus, 30]
2021-11-17 08:11:13.630 27921-27921/com.twtech.twelveqa I/TetheringManager: registerTetheringEventCallback:com.twtech.twelveqa
2021-11-17 08:11:13.632 27921-28011/com.twtech.twelveqa I/FirebaseApp: Device unlocked: initializing all Firebase APIs for app Twelve
2021-11-17 08:11:13.655 27921-28026/com.twtech.twelveqa E/sqlite3_android: ONEPLUS_NAME_PARTS_MATCH SQLITE_OK
2021-11-17 08:11:13.706 27921-28007/com.twtech.twelveqa I/flutter: FirebaseMessaging requestPermission error: [firebase_messaging/unknown] Attempt to invoke virtual method 'java.lang.Object android.content.Context.getSystemService(java.lang.String)' on a null object reference
2021-11-17 08:11:13.753 27921-28007/com.twtech.twelveqa I/flutter: Cloud Messaging Token is cvYJNS1zS4O_Ca6lCDfNsr:APA91bGdXwChgYtbpsFV99NMvaehdOIPHIFUsFSvLAseTCow8L0DPSfZu7hehLC9z_fMY8ypyD6MG-83PgUYVjGze0bLJC_mlJ6zVWOuenfyrGwC4HTMWU6iAv5wrC7YAuol3JQEJr4n
2021-11-17 08:11:13.759 27921-28007/com.twtech.twelveqa I/flutter: Application fully loaded!
2021-11-17 08:11:13.760 27921-27921/com.twtech.twelveqa W/FLTFireMsgService: Attempted to start a duplicate background isolate. Returning...
2021-11-17 08:11:16.031 27921-28027/com.twtech.twelveqa I/DpmTcmClient: RegisterTcmMonitor from: $Proxy1
2021-11-17 08:11:17.362 27921-28021/com.twtech.twelveqa W/VideoCapabilities: Unsupported mime image/vnd.android.heic
2021-11-17 08:11:17.374 27921-28021/com.twtech.twelveqa W/VideoCapabilities: Unrecognized profile/level 0/3 for video/mpeg2
2021-11-17 08:11:17.376 27921-28021/com.twtech.twelveqa W/VideoCapabilities: Unrecognized profile/level 0/3 for video/mpeg2
2021-11-17 08:11:17.508 27921-28034/com.twtech.twelveqa D/CCodec: allocate(c2.android.mp3.decoder)
2021-11-17 08:11:17.510 27921-28034/com.twtech.twelveqa I/Codec2Client: Available Codec2 services: "software"
2021-11-17 08:11:17.515 27921-28034/com.twtech.twelveqa I/CCodec: Created component [c2.android.mp3.decoder]
2021-11-17 08:11:17.516 27921-28034/com.twtech.twelveqa D/CCodecConfig: read media type: audio/mpeg
2021-11-17 08:11:17.518 27921-28034/com.twtech.twelveqa D/ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.max-count.values
2021-11-17 08:11:17.518 27921-28034/com.twtech.twelveqa D/ReflectedParamUpdater: extent() != 1 for single value type: output.subscribed-indices.values
2021-11-17 08:11:17.518 27921-28034/com.twtech.twelveqa D/ReflectedParamUpdater: extent() != 1 for single value type: input.buffers.allocator-ids.values
2021-11-17 08:11:17.518 27921-28034/com.twtech.twelveqa D/ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.allocator-ids.values
2021-11-17 08:11:17.519 27921-28034/com.twtech.twelveqa D/ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.allocator-ids.values
2021-11-17 08:11:17.519 27921-28034/com.twtech.twelveqa D/ReflectedParamUpdater: extent() != 1 for single value type: output.buffers.pool-ids.values
2021-11-17 08:11:17.519 27921-28034/com.twtech.twelveqa D/ReflectedParamUpdater: extent() != 1 for single value type: algo.buffers.pool-ids.values
2021-11-17 08:11:17.520 27921-28034/com.twtech.twelveqa I/CCodecConfig: query failed after returning 7 values (BAD_INDEX)
2021-11-17 08:11:17.520 27921-28034/com.twtech.twelveqa D/CCodecConfig: c2 config diff is Dict {
      c2::u32 coded.bitrate.value = 64000
      c2::u32 input.buffers.max-size.value = 8192
      c2::u32 input.delay.value = 0
      string input.media-type.value = "audio/mpeg"
      string output.media-type.value = "audio/raw"
      c2::u32 raw.channel-count.value = 2
      c2::u32 raw.sample-rate.value = 44100
    }
2021-11-17 08:11:17.521 27921-28034/com.twtech.twelveqa D/CCodec: [c2.android.mp3.decoder] buffers are bound to CCodec for this session
2021-11-17 08:11:17.521 27921-28034/com.twtech.twelveqa D/CCodecConfig: no c2 equivalents for flags
2021-11-17 08:11:17.522 27921-28034/com.twtech.twelveqa D/CCodecConfig: config failed => CORRUPTED
2021-11-17 08:11:17.522 27921-28034/com.twtech.twelveqa W/Codec2Client: query -- param skipped: index = 1107298332.
2021-11-17 08:11:17.522 27921-28034/com.twtech.twelveqa D/CCodec: client requested max input size 4096, which is smaller than what component recommended (8192); overriding with component recommendation.
2021-11-17 08:11:17.522 27921-28034/com.twtech.twelveqa W/CCodec: This behavior is subject to change. It is recommended that app developers double check whether the requested max input size is in reasonable range.
2021-11-17 08:11:17.522 27921-28034/com.twtech.twelveqa D/CCodec: setup formats input: AMessage(what = 0x00000000) = {
      int32_t channel-count = 2
      int32_t max-input-size = 8192
      string mime = "audio/mpeg"
      int32_t sample-rate = 44100
    } and output: AMessage(what = 0x00000000) = {
      int32_t channel-count = 2
      string mime = "audio/raw"
      int32_t sample-rate = 44100
    }
2021-11-17 08:11:17.523 27921-28034/com.twtech.twelveqa W/Codec2Client: query -- param skipped: index = 1342179345.
2021-11-17 08:11:17.523 27921-28034/com.twtech.twelveqa W/Codec2Client: query -- param skipped: index = 2415921170.
2021-11-17 08:11:17.524 27921-28034/com.twtech.twelveqa E/FMQ: grantorIdx must be less than 3
2021-11-17 08:11:17.524 27921-28034/com.twtech.twelveqa E/FMQ: grantorIdx must be less than 3
2021-11-17 08:11:17.524 27921-28034/com.twtech.twelveqa D/CCodecBufferChannel: [c2.android.mp3.decoder#509] Created input block pool with allocatorID 16 => poolID 17 - OK (0)
2021-11-17 08:11:17.525 27921-28034/com.twtech.twelveqa I/CCodecBufferChannel: [c2.android.mp3.decoder#509] Created output block pool with allocatorID 16 => poolID 1100 - OK
2021-11-17 08:11:17.526 27921-28034/com.twtech.twelveqa D/CCodecBufferChannel: [c2.android.mp3.decoder#509] Configured output block pool ids 1100 => OK
2021-11-17 08:11:17.526 27921-28034/com.twtech.twelveqa E/ion: ioctl c0044901 failed with code -1: Inappropriate ioctl for device
2021-11-17 08:11:17.536 27921-28034/com.twtech.twelveqa E/FMQ: grantorIdx must be less than 3
2021-11-17 08:11:17.539 27921-28038/com.twtech.twelveqa E/FMQ: grantorIdx must be less than 3
2021-11-17 08:11:18.553 27921-28021/com.twtech.twelveqa D/AudioTrack: set(): streamType -1, sampleRate 44100, format 0x1, channelMask 0x3, frameCount 14144, flags #0, notificationFrames 0, sessionId 4345, transferType 3, uid -1, pid -1
2021-11-17 08:11:18.566 27921-28021/com.twtech.twelveqa D/AudioTrack: Uid 10928 AudioTrack::setVolume left 1.000000 right 1.000000
2021-11-17 08:11:22.692 27921-28037/com.twtech.twelveqa D/BufferPoolAccessor2.0: bufferpool2 0x7c43d17698 : 5(40960 size) total buffers - 4(32768 size) used buffers - 0/5 (recycle/alloc) - 7/38 (fetch/transfer)
2021-11-17 08:11:22.692 27921-28037/com.twtech.twelveqa D/BufferPoolAccessor2.0: evictor expired: 1, evicted: 1

Note that the callstack appears once.

ryanheise commented 3 years ago

Sorry I understand what is going on now, and this is by design, not contrary to the API documentation. Still, your minimal reproduction project was helpful.

There are two things going on here:

  1. The audio handler is warming up so that it is ready to take requests from your background isolate (in this case the one created by firebase_messaging).
  2. The audio handler is hosted by the main isolate.

I take it that you don't want your app to perform some kind of complete initialisation in that warm up process. I think what happens in this situation is that the app widget is laid out with an effective size of 0x0 so it would be possible to wrap your app in a widget that waits until the dimension is greater than 0x0 before triggering the initialisation.

Down the track I might consider configuration options to run the audio handler in a separate isolate (again) or provide an option to prevent audio service loading in a particular background isolate but most people will not want that, and I think for now you can make your app work within the current model.

yanivshaked commented 3 years ago

@ryanheise, thank you for your quick response.

The issue is not only that the application is initialized in the warm up process; Its initialized incorrectly, ending up to be stuck on the application splash screen. (This is not reproduced by the example).

Is it possible (for us, in a forked version of audio_service), to recognize that the warm up process is originated from a push notification and not run the warm up process? Alternatively, if you can guide me, I am willing to submit a PR for providing an option to prevent the audio service from loading in a particular background isolate.

This is quite critical for us, as we are on the verge of releasing a new version of our application (pending this issue to be solved).

ryanheise commented 3 years ago

Is it possible (for us, in a forked version of audio_service), to recognize that the warm up process is originated from a push notification and not run the warm up process?

Yes, I suggested the solution in my previous comment which is to look at the widget dimensions.

yanivshaked commented 3 years ago

I believe this solution is not sufficient for us, since when started in the background our application is stuck on the splash screen.

We need to recognize this situation before flutter UI is started, and prevent main activity from running.

ryanheise commented 3 years ago

Do you mean the splash screen in your Flutter UI or in the Android XML layout?

yanivshaked commented 3 years ago

Splash screen of Android XML layout

ryanheise commented 3 years ago

I'll need a repro project for that.

yanivshaked commented 3 years ago

Unfortunately this issue isn't reproduced on the example application, only on our application. I can try to assemble a repro for that, but I don't think this is the correct way to solve our issue.

ryanheise commented 3 years ago

I would be interested in whatever is the quickest solution at this stage, whatever that turns out to be, but first I will need the repro case to work it out.

yanivshaked commented 3 years ago

I would like to contact you on your email to further discuss. If possible, please provide your email.

ryanheise commented 3 years ago

My email is linked from my pub page.

yanivshaked commented 3 years ago

I have managed to find a working hack:

  1. Added a new method to AudioServicePlugin called setArmStartup, to control a private boolean flag: 
    private static boolean armStartup = false;
public static synchronized void setArmStartup(boolean enabled) {
    armStartup = enabled;
}
  2. I have conditioned the operation of creating a new FlutterEngine by that flag: 
    public static synchronized FlutterEngine getFlutterEngine(Context context) {
    if (armStartup == false) {
        return null;
    }
    FlutterEngine flutterEngine = FlutterEngineCache.getInstance().get(flutterEngineId);
    if (flutterEngine == null) {
        // XXX: The constructor triggers onAttachedToEngine so this variable doesn't help us.
        // Maybe need a boolean flag to tell us we're currently loading the main flutter engine.
        flutterEngine = new FlutterEngine(context.getApplicationContext());
        flutterEngine.getDartExecutor().executeDartEntrypoint(DartExecutor.DartEntrypoint.createDefault());
        FlutterEngineCache.getInstance().put(flutterEngineId, flutterEngine);
    }
    return flutterEngine;
}
  3. I have added a call to setArmFlag under AudioServiceActivity.provideFlutterEngine: 
    public class AudioServiceActivity extends FlutterActivity {
@Override
public FlutterEngine provideFlutterEngine(Context context) {
    AudioServicePlugin.setArmStartup(true);
    return AudioServicePlugin.getFlutterEngine(context);
}
}

In other words, I am not allowing the initialization of the flutter engine if application was not started from its main activity. You can view the code changed here.

What do you think?

nt4f04uNd commented 3 years ago

This would make the FlutterActivity to create its own engine which would break the plugin.

I tried reproducing the bug with the example you provided, but it didn't happen. It just received the notification regularly, and I was able to launch the app from it.

Also I would be interested to know how are you getting logs from a fully terminated app? I tried logcatting it, but it shows nothing.

yanivshaked commented 3 years ago

This would make the FlutterActivity to create its own engine which would break the plugin.

I have just retested the behavior of the application (with those changes incorporated into the audio_service plugin), and it seems to be working fine. Able to play from background, and application doesn't start on incoming FCM push notification.

I tried reproducing the bug with the example you provided, but it didn't happen. It just received the notification regularly, and I was able to launch the app from it.

The problem presented in the example is that after terminating the application, an incoming FCM push notification causes the application to fully launch in the background (which is NOT the wanted behavior). So when you say you were able to launch it - it was already running and you just put it into foreground.

Also I would be interested to know how are you getting logs from a fully terminated app? I tried logcatting it, but it shows nothing.

I am using Android Studio Logcat terminal while setting the debug com package to my own package: image

nt4f04uNd commented 3 years ago

Ok thanks.

This would make the FlutterActivity to create its own engine which would break the plugin.

Actually, I misread what this was doing, sorry, it should work fine however other apps also won't be able to run the service without an activity.

ryanheise commented 3 years ago

Just to add my thoughts here for now, the main place to look is inside AudioServicePlugin.onAttachedToEngine which gets called on each plugin to install that plugin into each new FlutterEngine. Since firebase_messaging creates its own background FlutterEngine, this method will be called here to install audio_service into that engine. Now in audio_service, each FlutterEngine is set up to be a client of AudioService so that it can communicate with your audio handler. You don't want that, so you would need to edit this method to avoid connecting to the service in your specific case. If you want to do it with a boolean flag, the safer way to do it is not to set a flag for when you want to connect to the AudioService, but rather set a flag for when you "don't" want to connect to the AudioService. That is because there are more cases where the AudioService can and should be started from the background and you don't want to break those - there's only "one" situation where you actually want to disable the normal behaviour, so I would suggest setting your flag to handle that special case.

yanivshaked commented 3 years ago

Just to add my thoughts here for now, the main place to look is inside AudioServicePlugin.onAttachedToEngine which gets called on each plugin to install that plugin into each new FlutterEngine. Since firebase_messaging creates its own background FlutterEngine, this method will be called here to install audio_service into that engine. Now in audio_service, each FlutterEngine is set up to be a client of AudioService so that it can communicate with your audio handler. You don't want that, so you would need to edit this method to avoid connecting to the service in your specific case. If you want to do it with a boolean flag, the safer way to do it is not to set a flag for when you want to connect to the AudioService, but rather set a flag for when you "don't" want to connect to the AudioService. That is because there are more cases where the AudioService can and should be started from the background and you don't want to break those - there's only "one" situation where you actually want to disable the normal behaviour, so I would suggest setting your flag to handle that special case.

Understood. I will try going in that direction. In any case, this type of solution is a kind of hack (which will not be incorporated into the audio_service plugin), and I would like to pursue a proper solution that can be merged to the upstream.

tinted-knight commented 2 years ago

Hi! Just wanted to mention, that this conversation was useful when we were analyzing issues in our app.

What we have in our case is: install the app with Bluetooth turned off, then turn BT on without running the app (AudioService.onCreate calls AudioServicePlugin.getFlutterEngine with flutterEngine warmed up log message) and starting app after that causes it to stuck on splash screen.

I wonder if it may be caused by not calling onAttachedToEngine during the Activity startup (maybe because it was called on service onCreate when BT was turned on). May it be cached or something like that or maybe wrong flutter engine was initialized as you discussed above? Or because system keeps service alive?

Because on the second run app works fine and according to logs onAttachedToEngine is called during startup.

First I ended up with not calling AudioServicePlugin.getFlutterEngine in service onCreate, but saw this solutions, which is probably more elegant although in our case does pretty much the same.

In addition, I should mention that this behavior happens only on some devices (Samsung and OnePlus) and OS version (e.g. LineageOS 17.1) and may be related to some kind of vendor specific implementation of Android APIs.

ryanheise commented 2 years ago

@tinted-knight is it feasible to provide a reproduction project for your particular case?

tinted-knight commented 2 years ago

I may have time to work on it during weekend. Also I made fixes that mentioned above based on yours from this issue that are already in minor (it looks important in our case not to have those crashes).

ryanheise commented 2 years ago

The minor release has also been published.

mayurdhurpate commented 2 years ago

Hi,

We were facing the exact same issue as @yanivshaked and the dimension check with LayoutBuilder in main function seems to fix this for us. Thanks for suggesting this @ryanheise! I think FCM and audio_service issue should have a better fix for the long term though. Will the recommendation suggested #845 resolve the incorrect warming up issue as well?

Adamteh commented 2 years ago

@mayurdhurpate Can you please share a code snippet of how you achieved that with LayoutBuilder in the main function

mayurdhurpate commented 2 years ago

I've added this in our main.dart:

void main() async {
  // ...some initialization code
  await runZonedGuarded<Future<void>>(() async {
    runApp(
      LayoutBuilder(builder: (context, constraints) {
        if (constraints.maxHeight == 0.0 && constraints.maxWidth == 0.0) {
          return Container();
        } else {
          return MyApp();
        }
      }),
    );
  }, (dynamic e, StackTrace st) {
    print(e);
    print(st);
    FirebaseCrashlytics.instance.recordError(e, st);
  });
}

In our case, we just wanted to prevent triggering of many analytics functions, and this solved the problem for us.

AliShehab93 commented 2 years ago

Hi folks, Actually, I spent about 2 weeks trying on this issue, I wasted a lot of my time trying to figure out a solution. Today I find out that the problem is between the firebase messaging and audio service plugins. I tried the layout builder hack and it works. Thanks for adding this hack solution. But there is still an error when receiving the notification in the background, and the app is terminated, I hope for the coming days to have the solution added in the plugin concerning the layout builder hack but in other solution as checking the background service type running and also avoid having such an error in the background.

The error is as: E/flutter (20457): [ERROR:flutter/lib/ui/ui_dart_state.cc(198)] Unhandled Exception: [firebase_messaging/unknown] Attempt to invoke virtual method 'java.lang.Object android.content.Context.getSystemService(java.lang.String)' on a null object reference E/flutter (20457): E/flutter (20457): #0 StandardMethodCodec.decodeEnvelope (package:flutter/src/services/message_codecs.dart:607) E/flutter (20457): #1 MethodChannel._invokeMethod (package:flutter/src/services/platform_channel.dart:167) E/flutter (20457): E/flutter (20457): #2 MethodChannel.invokeMapMethod (package:flutter/src/services/platform_channel.dart:367) E/flutter (20457): E/flutter (20457): #3 MethodChannelFirebaseMessaging.requestPermission (package:firebase_messaging_platform_interface/src/method_channel/method_channel_messaging.dart:271) E/flutter (20457): E/flutter (20457): #4 main.. (package:gradeheroapp/main.dart:82) E/flutter (20457): E/flutter (

Thanks in advance,

adrianvintu commented 1 year ago

Adding to Home Widget https://pub.dev/packages/home_widget this minimal code

  public FlutterEngine provideFlutterEngine(Context context) {
    return AudioServicePlugin.getFlutterEngine(context);
  }

breaks the Home Widget plugin in that some code is not executed. Also, clicking on the home widget the log does not show a new isolate being created.

Sorry for the crappy description, I have no idea how isolates work, and I also could not create a minimal reproducible example - it's not that easy.

I leave this comment for other people who have the same trouble with Home Widget https://pub.dev/packages/home_widget, and subscribe to the thread.

ryanheise commented 1 year ago

@adrianvintu that sounds like an interesting use case which I'd like to see working, however how is your comment related to this issue? Home widgets have nothing to do with firebase.

adrianvintu commented 1 year ago

It think it's related to how the app is revived.

I managed to get a running sample jut for you :)

Setting up the files:

  1. I made the example from https://pub.dev/packages/home_widget compile, attached here home_widget-main.zip

  2. added

    dependencies:
just_audio: ^0.9.29
just_audio_background: ^0.0.1-beta.7

  3. AndroidManifest.xml Activity android:name=".MainActivity" <- unchanged

  4. this is where the magic happens: home_widget-main\example\android\app\src\main\kotlin\es\antonborri\home_widget_example\MainActivity.kt

    //class MainActivity : FlutterActivity() {
class MainActivity : com.ryanheise.audioservice.AudioServiceActivity() {
}

Settings up the test:

  1. add the home widget on the device home screen

  2. run the /example/ with class MainActivity : FlutterActivity()

  3. add some data to the widget body field, like here Screenshot_1672671221

  4. minimize the app and click on body. you will get an alert with the text from the body, like here Screenshot_1672671228

  5. run the /example/ with class MainActivity : com.ryanheise.audioservice.AudioServiceActivity()

  6. add some data to the widget body field

  7. minimize the app and click on body. you will NOT get an alert with the text from the body.

Can you please investigate this? It's way over my knowledge of how Flutter/Dart works.

ryanheise commented 1 year ago

@adrianvintu , the reason I said that is that it is difficult for me to address two different issues with two different reproduction projects etc. in one GitHub issue. Even if they are related issues, you can simply create a new issue and reference this issue as a related one.

idrats commented 1 year ago

I suppose this issue is linked with https://github.com/ryanheise/audio_service/issues/929

When we get push at terminated mode the first point to launch is firebase background handler, but after it main.dart starts to launch

djsjr commented 1 year ago

Since this issue has several different conversations going on, I want to summarize that the original issue is the flutter app using audio_services and firebase_messaging (FCM) gets stuck on the splash screen when attempting to open the app after receiving a push notification in a terminated state on Android. (App starts prematurely when a push notification received.) I am experiencing this issue now as well.

Getting stuck on the splash screen is the part that makes this a priority for myself to find a way around, since I can't send notifs to android users without getting them stuck.

@yanivshaked Wondering if you've found a better solution in the meantime?

ryanheise commented 1 year ago

Does anyone have an updated stacktrace?

Szymon-Gesicki commented 1 year ago

Has anyone fixed this or dealt with it somehow? My application keeps stopping in splash screen when I open it from firebase notification?

ryanheise commented 1 year ago

Do YOU have an updated stack trace? (See my previous comment.)

Szymon-Gesicki commented 1 year ago

I do not know what you mean by "updated straktrace", if you expect something else then write right away I will send you. I am anxious to fix it that is why I would like to help

FLUTTER_APP_LOGGER -> this is my logger in the flutter application these are the logs from logcat

simply opening the application:

https://binshare.net/DuEGc8H6DeRP0XVdq3DT

opening from firebase notification:

https://binshare.net/7hE4fEygZjQRBOt2TgZ1

ryanheise commented 1 year ago

The first StackTrace above was this part of the log which mentions the type of the exception and then a series of "at" lines:

2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err: java.lang.Exception
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at com.ryanheise.audioservice.AudioServicePlugin.getFlutterEngine(AudioServicePlugin.java:68)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at com.ryanheise.audioservice.AudioServiceActivity.provideFlutterEngine(AudioServiceActivity.java:12)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at io.flutter.embedding.android.FlutterActivityAndFragmentDelegate.setupFlutterEngine(FlutterActivityAndFragmentDelegate.java:235)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at io.flutter.embedding.android.FlutterActivityAndFragmentDelegate.onAttach(FlutterActivityAndFragmentDelegate.java:162)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at io.flutter.embedding.android.FlutterActivity.onCreate(FlutterActivity.java:430)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.Activity.performCreate(Activity.java:8006)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.Activity.performCreate(Activity.java:7990)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.Instrumentation.callActivityOnCreate(Instrumentation.java:1329)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:3595)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.handleLaunchActivity(ActivityThread.java:3786)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.servertransaction.LaunchActivityItem.execute(LaunchActivityItem.java:85)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.servertransaction.TransactionExecutor.executeCallbacks(TransactionExecutor.java:135)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.servertransaction.TransactionExecutor.execute(TransactionExecutor.java:95)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2257)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.os.Handler.dispatchMessage(Handler.java:106)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.os.Looper.loop(Looper.java:233)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at android.app.ActivityThread.main(ActivityThread.java:8030)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at java.lang.reflect.Method.invoke(Native Method)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:631)
2021-11-17 08:08:33.863 31557-31557/com.twtech.twelveqa W/System.err:     at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:978)

The exception for this bug originates at:

at com.ryanheise.audioservice.AudioServicePlugin.getFlutterEngine(AudioServicePlugin.java:68)

I was asking for a more recent stack trace.

I have looked at your logs and they contain a different exception, so I don't think your bug is the same bug as this one.

Szymon-Gesicki commented 1 year ago

I disagree. I have a problem that the app stops on splash screen after opening from firebase notification. When I throw out the "audio_service" plugin (changing the manifest and init "AudioService") everything works fine. Can't find any solution to make it work. Is there anything else I can send you to help find the problem? Unless there is another issue about it in which case I'm very sorry but I couldn't find anything similar.

ryanheise commented 1 year ago

Even if the result is similar to this bug, your logs show a different cause and hence a different bug. So, a fix that solves THIS bug is unlikely to also solve your bug, because they have different causes.

Unless there is another issue about it in which case I'm very sorry but I couldn't find anything similar.

What you're doing is posting your problem on an existing bug when what you should be doing is submitting a separate bug report with your new stack trace since your bug has different characteristics and would require a separate investigation.

djsjr commented 1 year ago

@ryanheise Here's a stacktrace from when I receive a notification and then try to open the app by tapping the app logo. (stuck on splash screen). I added the line of code you asked for with the new Exception(). I can also provide a stacktrace from normal openings (no notification, not stuck on splash screen) should it help.

2023-03-17 21:22:39.026  3598-4575  NetlinkTracker/wlan0    pid-3598                             D  addressUpdated: 2601:18e:c280:8780:eb7e:2c11:68d2:ed0/64 on wlan0 flags 2304 scope 0
2023-03-17 21:22:39.031  3598-4748  NetlinkTracker/wlan0    pid-3598                             D  addressUpdated: 2601:18e:c280:8780:f447:d66c:aabc:dbd5/64 on wlan0 flags 1 scope 0
2023-03-17 21:22:40.443 22100-22119 omain.app          pid-22100                            I  Waiting for a blocking GC ProfileSaver
2023-03-17 21:22:40.478 22100-22119 omain.app          pid-22100                            I  WaitForGcToComplete blocked ProfileSaver on RunEmptyCheckpoint for 35.004ms
2023-03-17 21:22:42.106  3598-4748  NetlinkTracker/wlan0    pid-3598                             D  addressUpdated: 2601:18e:c280:8780:eb7e:2c11:68d2:ed0/64 on wlan0 flags 2304 scope 0
2023-03-17 21:22:42.112  3598-4748  NetlinkTracker/wlan0    pid-3598                             D  addressUpdated: 2601:18e:c280:8780:f447:d66c:aabc:dbd5/64 on wlan0 flags 1 scope 0
2023-03-17 21:22:42.534 23490-21745 ChimeraUtils            pid-23490                            W  Module com.google.android.gms.gcm has empty metadata display_name_string_id
2023-03-17 21:22:42.563 22100-22100 FLTFireMsgReceiver      pid-22100                            D  broadcast received for message
2023-03-17 21:22:42.600 22100-22164 FLTFireMsgService       pid-22100                            I  Service has not yet started, messages will be queued.
2023-03-17 21:22:42.630 22100-22100 FLTFireBGExecutor       pid-22100                            I  Creating background FlutterEngine instance.
2023-03-17 21:22:42.643 22100-22166 FlutterJNI              pid-22100                            W  FlutterJNI.loadLibrary called more than once
2023-03-17 21:22:42.645 22100-22100 FlutterJNI              pid-22100                            W  FlutterJNI.init called more than once
2023-03-17 21:22:42.655 22100-22100 omain.appp         pid-22100                            W  type=1400 audit(0.0:599): avc: denied { read } for name="max_map_count" dev="proc" ino=1241145 scontext=u:r:untrusted_app:s0:c77,c257,c512,c768 tcontext=u:object_r:proc_max_map_count:s0 tclass=file permissive=0 app=com.domain.app
2023-03-17 21:22:42.677 22100-22100 AdrenoGLES-0            pid-22100                            I  QUALCOMM build                   : 704ecd9a2b, Ib3f3e69395
                                                                                                    Build Date                       : 12/29/20
                                                                                                    OpenGL ES Shader Compiler Version: EV031.32.02.06
                                                                                                    Local Branch                     : 
                                                                                                    Remote Branch                    : 
                                                                                                    Remote Branch                    : 
                                                                                                    Reconstruct Branch               : 
2023-03-17 21:22:42.677 22100-22100 AdrenoGLES-0            pid-22100                            I  Build Config                     : S P 10.0.7 AArch64
2023-03-17 21:22:42.677 22100-22100 AdrenoGLES-0            pid-22100                            I  Driver Path                      : /vendor/lib64/egl/libGLESv2_adreno.so
2023-03-17 21:22:42.688 22100-22100 AdrenoGLES-0            pid-22100                            I  PFP: 0x016ee190, ME: 0x00000000
2023-03-17 21:22:42.740 22100-22100 Amplify Flutter         pid-22100                            I  Added Core plugin
2023-03-17 21:22:42.742 22100-22100 MediaBrowserCompat      pid-22100                            D  Connecting to a MediaBrowserService.
2023-03-17 21:22:42.808 22100-22147 FA                      pid-22100                            I  Tag Manager is not found and thus will not be used
2023-03-17 21:22:42.812 22100-22186 FirebaseMessaging       pid-22100                            W  Missing Default Notification Channel metadata in AndroidManifest. Default value will be used.
2023-03-17 21:22:42.840  3370-4008  Avrcp_ext               pid-3370                             V  updateCurrentMediaState: mMediaController: null
2023-03-17 21:22:42.844  3370-4008  Avrcp_ext               pid-3370                             V  isMusicActive: false getBluetoothPlayState: 2
2023-03-17 21:22:42.844  3370-4008  Avrcp_ext               pid-3370                             V  updateCurrentMediaState: isPlaying = false
2023-03-17 21:22:42.844  3370-4008  Avrcp_ext               pid-3370                             V  Media update: id -1➡-1? [MediaAttributes: none] : [MediaAttributes: none]
2023-03-17 21:22:42.844  3370-4008  Avrcp_ext               pid-3370                             D  isPackageNameValid: browsedPackage = com.spotify.musicisValid = true
2023-03-17 21:22:42.844  3370-4008  Avrcp_ext               pid-3370                             V  isBrowseSupported for com.spotify.music: false
2023-03-17 21:22:42.844  3370-4008  Avrcp_ext               pid-3370                             V  newPlayStatus:2mReportedPlayStatus:-1
2023-03-17 21:22:42.844  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState, state: PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null} device: null
2023-03-17 21:22:42.844  3370-4008  Avrcp_ext               pid-3370                             V  Device: no name: 
2023-03-17 21:22:42.844  3370-4008  chatty                  pid-3370                             I  uid=1002(bluetooth) BluetoothAvrcpH identical 3 lines
2023-03-17 21:22:42.844  3370-4008  Avrcp_ext               pid-3370                             V  Device: no name: 
2023-03-17 21:22:42.844  3370-4008  Avrcp_ext               pid-3370                             V  updatePlayerStateAndPosition, old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332143510, actions=0, custom actions=[], active item id=-1, error=null}, state=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null}
2023-03-17 21:22:42.844  3370-4008  Avrcp_ext               pid-3370                             V  old state = PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332143510, actions=0, custom actions=[], active item id=-1, error=null}, new state= PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null}
2023-03-17 21:22:42.844  3370-4008  HeadsetService          pid-3370                             D  getHeadsetService(): returning com.android.bluetooth.hfp.HeadsetService@ab117d1
2023-03-17 21:22:42.844  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:42.844  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:42.844  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:42.844  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:42.845  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332143510, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:42.845  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:42.845  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:42.845  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:42.845  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:42.845  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:42.845  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332143510, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:42.845  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:42.845  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:42.845  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:42.845  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:42.845  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:42.845  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332143510, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:42.845  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:42.845  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:42.845  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:42.845  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:42.845  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:42.845  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332143510, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:42.845  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:42.845  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:42.845  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:42.845  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:42.845  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:42.846  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332143510, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:42.846  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:42.846  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:42.846  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:42.846  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:42.846  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:42.846  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:42.846  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:42.846  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:42.846  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:42.846  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:42.846  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:42.846  3370-4008  Avrcp_ext               pid-3370                             V  Exit updatePlayerStateAndPosition
2023-03-17 21:22:42.855 22100-22100 System.err              pid-22100                            W  java.lang.Exception
2023-03-17 21:22:42.855 22100-22100 System.err              pid-22100                            W      at com.ryanheise.audioservice.a.B(AudioServicePlugin.java:1)
2023-03-17 21:22:42.855 22100-22100 System.err              pid-22100                            W      at com.ryanheise.audioservice.AudioService.onCreate(AudioService.java:22)
2023-03-17 21:22:42.855 22100-22100 System.err              pid-22100                            W      at android.app.ActivityThread.handleCreateService(ActivityThread.java:4308)
2023-03-17 21:22:42.855 22100-22100 System.err              pid-22100                            W      at android.app.ActivityThread.access$1700(ActivityThread.java:252)
2023-03-17 21:22:42.855 22100-22100 System.err              pid-22100                            W      at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2018)
2023-03-17 21:22:42.855 22100-22100 System.err              pid-22100                            W      at android.os.Handler.dispatchMessage(Handler.java:106)
2023-03-17 21:22:42.855 22100-22100 System.err              pid-22100                            W      at android.os.Looper.loop(Looper.java:250)
2023-03-17 21:22:42.855 22100-22100 System.err              pid-22100                            W      at android.app.ActivityThread.main(ActivityThread.java:7877)
2023-03-17 21:22:42.855 22100-22100 System.err              pid-22100                            W      at java.lang.reflect.Method.invoke(Native Method)
2023-03-17 21:22:42.855 22100-22100 System.err              pid-22100                            W      at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:592)
2023-03-17 21:22:42.855 22100-22100 System.err              pid-22100                            W      at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:958)
2023-03-17 21:22:42.872 22100-22100 Amplify Flutter         pid-22100                            I  Added Core plugin
2023-03-17 21:22:42.881 22100-22100 System.out              pid-22100                            I  flutterEngine warmed up
2023-03-17 21:22:42.884 22100-22100 FLTFireMsgService       pid-22100                            I  FlutterFirebaseMessagingBackgroundService started!
2023-03-17 21:22:42.888 22100-22172 flutter                 pid-22100                            I  Handling a background message
2023-03-17 21:22:42.888 22100-22172 flutter                 pid-22100                            I  testing
2023-03-17 21:22:42.945 22100-22188 flutter                 pid-22100                            I  running main
2023-03-17 21:22:42.986 23490-22161 NetworkScheduler        pid-23490                            W  Error inserting flex_time=3277000 job_id=-1 period=6556000 source=16 requires_charging=0 preferred_network_type=1 target_class=com.google.android.gms.measurement.PackageMeasurementTaskService user_id=0 target_package=com.google.android.gms tag=Measurement.PackageMeasurementTaskService.UPLOAD_TASK_TAG task_type=0 required_idleness_state=0 service_kind=0 source_version=223616000 persistence_level=1 preferred_charging_state=1 required_network_type=0 runtime=1679102562979 retry_strategy={"maximum_backoff_seconds":{"3600":0},"initial_backoff_seconds":{"30":0},"retry_policy":{"0":0}} last_runtime=0 [CONTEXT service_id=218 ]
                                                                                                    android.database.sqlite.SQLiteConstraintException: UNIQUE constraint failed: pending_ops.tag, pending_ops.target_class, pending_ops.target_package, pending_ops.user_id (code 2067 SQLITE_CONSTRAINT_UNIQUE)
                                                                                                        at android.database.sqlite.SQLiteConnection.nativeExecuteForLastInsertedRowId(Native Method)
                                                                                                        at android.database.sqlite.SQLiteConnection.executeForLastInsertedRowId(SQLiteConnection.java:938)
                                                                                                        at android.database.sqlite.SQLiteSession.executeForLastInsertedRowId(SQLiteSession.java:790)
                                                                                                        at android.database.sqlite.SQLiteStatement.executeInsert(SQLiteStatement.java:88)
                                                                                                        at android.database.sqlite.SQLiteDatabase.insertWithOnConflict(SQLiteDatabase.java:1701)
                                                                                                        at android.database.sqlite.SQLiteDatabase.insertOrThrow(SQLiteDatabase.java:1596)
                                                                                                        at bhmj.g(:com.google.android.gms@223616037@22.36.16 (150400-476895098):52)
                                                                                                        at bhlc.n(:com.google.android.gms@223616037@22.36.16 (150400-476895098):3)
                                                                                                        at bhlc.u(:com.google.android.gms@223616037@22.36.16 (150400-476895098):20)
                                                                                                        at bhlc.h(:com.google.android.gms@223616037@22.36.16 (150400-476895098):3)
                                                                                                        at bhgt.run(:com.google.android.gms@223616037@22.36.16 (150400-476895098):9)
                                                                                                        at zby.c(:com.google.android.gms@223616037@22.36.16 (150400-476895098):6)
                                                                                                        at zby.run(:com.google.android.gms@223616037@22.36.16 (150400-476895098):7)
                                                                                                        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
                                                                                                        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
                                                                                                        at zhe.run(:com.google.android.gms@223616037@22.36.16 (150400-476895098):0)
                                                                                                        at java.lang.Thread.run(Thread.java:923)
2023-03-17 21:22:43.002 22100-22100 pool-33-thread-         pid-22100                            W  type=1400 audit(0.0:600): avc: denied { read } for name="version" dev="proc" ino=4026532039 scontext=u:r:untrusted_app:s0:c77,c257,c512,c768 tcontext=u:object_r:proc_version:s0 tclass=file permissive=0 app=com.domain.app
2023-03-17 21:22:43.055 22100-22188 flutter                 pid-22100                            I  HASh CODE
2023-03-17 21:22:43.056 22100-22188 flutter                 pid-22100                            I  142514211
2023-03-17 21:22:43.056 22100-22188 flutter                 pid-22100                            I  initialized audio handler
2023-03-17 21:22:43.091  1822-1822  NotificationService     pid-1822                             D  0|com.domain.app|0|campaign_collapse_key_4460241692937559571|10333: granting content://settings/system/notification_sound
2023-03-17 21:22:43.094  1822-1822  chatty                  pid-1822                             I  uid=1000 system_server identical 2 lines
2023-03-17 21:22:43.096  1822-1822  NotificationService     pid-1822                             D  0|com.domain.app|0|campaign_collapse_key_4460241692937559571|10333: granting content://settings/system/notification_sound
2023-03-17 21:22:43.099  1822-1822  NotificationHistory     pid-1822                             W  Attempted to add notif for locked/gone/disabled user 0
2023-03-17 21:22:43.101  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:23 type:android.media.SoundPool u/pid:10289/3388 state:idle attr:AudioAttributes: usage=USAGE_ASSISTANCE_SONIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x800 tags= bundle=null
2023-03-17 21:22:43.101  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:39 type:android.media.SoundPool u/pid:10200/4472 state:idle attr:AudioAttributes: usage=USAGE_ASSISTANCE_SONIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x801 tags= bundle=null
2023-03-17 21:22:43.101  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:15 type:android.media.SoundPool u/pid:1000/1822 state:idle attr:AudioAttributes: usage=USAGE_ASSISTANCE_SONIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x800 tags= bundle=null
2023-03-17 21:22:43.101  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:223 type:android.media.MediaPlayer u/pid:10289/3388 state:idle attr:AudioAttributes: usage=USAGE_NOTIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x800 tags= bundle=null
2023-03-17 21:22:43.101  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager isPlaying: false
2023-03-17 21:22:43.121 22100-22188 flutter                 pid-22100                            I  great success
2023-03-17 21:22:43.122 22100-22100 FLTFireMsgService       pid-22100                            W  Attempted to start a duplicate background isolate. Returning...
2023-03-17 21:22:43.136 22100-22100 amplify:fl...th_cognito pid-22100                            I  Added Auth plugin
2023-03-17 21:22:43.139  3388-3388  Interrupti...teProvider pid-3388                             D  No bubble up: not allowed to bubble: 0|com.domain.app|0|campaign_collapse_key_4460241692937559571|10333
2023-03-17 21:22:43.140  3388-3388  Interrupti...teProvider pid-3388                             D  No heads up: unimportant notification: 0|com.domain.app|0|campaign_collapse_key_4460241692937559571|10333
2023-03-17 21:22:43.144 22100-22100 amplify:fl...s_pinpoint pid-22100                            I  Added AnalyticsPinpoint plugin
2023-03-17 21:22:43.178 22100-22216 AWSMobileClient         pid-22100                            D  Using the SignInProviderConfig from `awsconfiguration.json`.
2023-03-17 21:22:43.187  1313-20788 NuPlayerDriver          pid-1313                             D  NuPlayerDriver(0xf1541f50) created, clientPid(3388)
2023-03-17 21:22:43.323  1313-22225 GenericSource           pid-1313                             D  FileSource remote
2023-03-17 21:22:43.426  1305-1740  Utils                   pid-1305                             E  did not find channel-count and/or sample-rate
2023-03-17 21:22:43.427  1305-19285 Utils                   pid-1305                             E  did not find channel-count and/or sample-rate
2023-03-17 21:22:43.441 22100-22216 AWSMobileClient         pid-22100                            W  Failed to parse HostedUI settings from store
                                                                                                    java.lang.NullPointerException: Attempt to invoke virtual method 'int java.lang.String.length()' on a null object reference
                                                                                                        at org.json.JSONTokener.nextCleanInternal(JSONTokener.java:121)
                                                                                                        at org.json.JSONTokener.nextValue(JSONTokener.java:98)
                                                                                                        at org.json.JSONObject.<init>(JSONObject.java:165)
                                                                                                        at org.json.JSONObject.<init>(JSONObject.java:182)
                                                                                                        at com.amazonaws.mobile.client.AWSMobileClient.getHostedUIJSON(AWSMobileClient.java:4)
                                                                                                        at com.amazonaws.mobile.client.AWSMobileClient$2.run(AWSMobileClient.java:60)
                                                                                                        at com.amazonaws.mobile.client.internal.InternalCallback$1.run(InternalCallback.java:1)
                                                                                                        at java.lang.Thread.run(Thread.java:923)
2023-03-17 21:22:43.467  1822-17677 MediaFocusControl       pid-1822                             I  requestAudioFocus() from uid/pid 10289/3388 clientId=android.media.AudioManager@9062a73 callingPack=com.android.systemui req=3 flags=0x0 sdk=30
2023-03-17 21:22:43.470  1822-17677 MediaFocusControl       pid-1822                             D  mMultiAudioFocusEnabled: false, aa.mContentType: 4, aa.mUsage: 5, aa.mFlags: 0, callingPackageName: com.android.systemui, isDesktopConnected: false
2023-03-17 21:22:43.487 22100-22216 AWSMobileClient         pid-22100                            D  Inspecting user state details
2023-03-17 21:22:43.537 22100-22100 MethodChan...s_pinpoint pid-22100                            E  Failed to handle method call
                                                                                                    java.lang.NullPointerException: Attempt to invoke virtual method 'java.lang.String android.content.Context.getPackageName()' on a null object reference
                                                                                                        at android.content.ComponentName.<init>(ComponentName.java:131)
                                                                                                        at android.content.Intent.<init>(Intent.java:6879)
                                                                                                        at com.amazonaws.amplify.amplify_analytics_pinpoint.AnalyticsPinpoint.onMethodCall(AnalyticsPinpoint.kt:3)
                                                                                                        at p9.j$a.a(MethodChannel.java:2)
                                                                                                        at e9.c.l(DartMessenger.java:2)
                                                                                                        at e9.c.m(DartMessenger.java:3)
                                                                                                        at e9.c.i(Unknown Source:0)
                                                                                                        at e9.b.run(Unknown Source:12)
                                                                                                        at android.os.Handler.handleCallback(Handler.java:938)
                                                                                                        at android.os.Handler.dispatchMessage(Handler.java:99)
                                                                                                        at android.os.Looper.loop(Looper.java:250)
                                                                                                        at android.app.ActivityThread.main(ActivityThread.java:7877)
                                                                                                        at java.lang.reflect.Method.invoke(Native Method)
                                                                                                        at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:592)
                                                                                                        at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:958)
2023-03-17 21:22:43.546 22100-22188 flutter                 pid-22100                            I  signed in? false
2023-03-17 21:22:43.561 22100-22188 flutter                 pid-22100                            I  connected to wifi network
2023-03-17 21:22:43.561 22100-22188 flutter                 pid-22100                            I  checking for unrecorded listenUnit and event
2023-03-17 21:22:43.979  1313-22222 GenericSource           pid-1313                             I  start
2023-03-17 21:22:43.979  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:23 type:android.media.SoundPool u/pid:10289/3388 state:idle attr:AudioAttributes: usage=USAGE_ASSISTANCE_SONIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x800 tags= bundle=null
2023-03-17 21:22:43.979  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:39 type:android.media.SoundPool u/pid:10200/4472 state:idle attr:AudioAttributes: usage=USAGE_ASSISTANCE_SONIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x801 tags= bundle=null
2023-03-17 21:22:43.979  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:15 type:android.media.SoundPool u/pid:1000/1822 state:idle attr:AudioAttributes: usage=USAGE_ASSISTANCE_SONIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x800 tags= bundle=null
2023-03-17 21:22:43.980  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:223 type:android.media.MediaPlayer u/pid:10289/3388 state:started attr:AudioAttributes: usage=USAGE_NOTIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x800 tags= bundle=null
2023-03-17 21:22:43.980  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager isPlaying: true
2023-03-17 21:22:43.980  3370-4008  Avrcp_ext               pid-3370                             V  updateCurrentMediaState: mMediaController: null
2023-03-17 21:22:43.982  3370-4008  Avrcp_ext               pid-3370                             V  isMusicActive: false getBluetoothPlayState: 2
2023-03-17 21:22:43.982  3370-4008  Avrcp_ext               pid-3370                             V  updateCurrentMediaState: isPlaying = false
2023-03-17 21:22:43.982  3370-4008  Avrcp_ext               pid-3370                             V  Media update: id -1➡-1? [MediaAttributes: none] : [MediaAttributes: none]
2023-03-17 21:22:43.982  3370-4008  Avrcp_ext               pid-3370                             D  isPackageNameValid: browsedPackage = com.spotify.musicisValid = true
2023-03-17 21:22:43.982  3370-4008  Avrcp_ext               pid-3370                             V  isBrowseSupported for com.spotify.music: false
2023-03-17 21:22:43.982  3370-4008  Avrcp_ext               pid-3370                             V  newPlayStatus:2mReportedPlayStatus:-1
2023-03-17 21:22:43.983  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState, state: PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null} device: null
2023-03-17 21:22:43.983  3370-4008  Avrcp_ext               pid-3370                             V  Device: no name: 
2023-03-17 21:22:43.983  3370-4008  chatty                  pid-3370                             I  uid=1002(bluetooth) BluetoothAvrcpH identical 3 lines
2023-03-17 21:22:43.983  3370-4008  Avrcp_ext               pid-3370                             V  Device: no name: 
2023-03-17 21:22:43.983  3370-4008  Avrcp_ext               pid-3370                             V  updatePlayerStateAndPosition, old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null}, state=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null}
2023-03-17 21:22:43.984  3370-4008  Avrcp_ext               pid-3370                             V  old state = PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null}, new state= PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null}
2023-03-17 21:22:43.984  3370-4008  HeadsetService          pid-3370                             D  getHeadsetService(): returning com.android.bluetooth.hfp.HeadsetService@ab117d1
2023-03-17 21:22:43.984  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:43.984  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:43.984  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:43.984  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:43.985  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:43.985  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:43.985  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:43.985  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:43.985  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:43.985  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:43.985  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:43.985  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:43.985  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:43.985  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:43.985  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:43.985  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:43.986  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:43.986  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:43.986  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:43.986  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:43.986  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:43.986  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:43.986  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:43.987  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:43.987  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:43.987  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332180563, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:43.987  3370-4008  Avrcp_ext               pid-3370                             V  Exit updatePlayerStateAndPosition
2023-03-17 21:22:43.994  3388-3388  MediaRouter             pid-3388                             V  Selecting route: RouteInfo{ name=Phone, description=null, status=null, category=RouteCategory{ name=System types=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO  groupable=false }, supportedTypes=ROUTE_TYPE_LIVE_AUDIO ROUTE_TYPE_LIVE_VIDEO , presentationDisplay=null }
2023-03-17 21:22:43.994  3388-3388  MediaRouter             pid-3388                             V  Selecting route ignored,not new route
2023-03-17 21:22:44.073   938-938   audio_hw_primary        pid-938                              D  adev_get_parameters: exit: returns - 
2023-03-17 21:22:44.076  1019-1391  hash_map_utils          pid-1019                             I  key: 'is_hw_dec_session_available' value: 'audio/vorbis'
2023-03-17 21:22:44.087  1313-22238 MediaCodec              pid-1313                             I  (0xee8025f0) init name(c2.android.vorbis.decoder)
2023-03-17 21:22:44.093  1313-22239 CCodec                  pid-1313                             D  allocate(c2.android.vorbis.decoder)
2023-03-17 21:22:44.106  1364-2423  C2Store                 pid-1364                             V  in init
2023-03-17 21:22:44.106  1364-2423  C2Store                 pid-1364                             V  loading dll
2023-03-17 21:22:44.265  1313-22239 CCodec                  pid-1313                             I  Created component [c2.android.vorbis.decoder]
2023-03-17 21:22:44.269  1313-22239 CCodecConfig            pid-1313                             D  read media type: audio/vorbis
2023-03-17 21:22:44.275  1313-22239 ReflectedParamUpdater   pid-1313                             D  extent() != 1 for single value type: algo.buffers.max-count.values
2023-03-17 21:22:44.276  1313-22239 ReflectedParamUpdater   pid-1313                             D  extent() != 1 for single value type: output.subscribed-indices.values
2023-03-17 21:22:44.277  1313-22239 ReflectedParamUpdater   pid-1313                             D  extent() != 1 for single value type: input.buffers.allocator-ids.values
2023-03-17 21:22:44.277  1313-22239 ReflectedParamUpdater   pid-1313                             D  extent() != 1 for single value type: output.buffers.allocator-ids.values
2023-03-17 21:22:44.277  1313-22239 ReflectedParamUpdater   pid-1313                             D  extent() != 1 for single value type: algo.buffers.allocator-ids.values
2023-03-17 21:22:44.278  1313-22239 ReflectedParamUpdater   pid-1313                             D  extent() != 1 for single value type: output.buffers.pool-ids.values
2023-03-17 21:22:44.278  1313-22239 ReflectedParamUpdater   pid-1313                             D  extent() != 1 for single value type: algo.buffers.pool-ids.values
2023-03-17 21:22:44.285  1313-22239 CCodecConfig            pid-1313                             I  query failed after returning 7 values (BAD_INDEX)
2023-03-17 21:22:44.288  1313-22239 CCodecConfig            pid-1313                             D  c2 config diff is Dict {
                                                                                                      c2::u32 coded.bitrate.value = 64000
                                                                                                      c2::u32 input.buffers.max-size.value = 32768
                                                                                                      c2::u32 input.delay.value = 0
                                                                                                      string input.media-type.value = "audio/vorbis"
                                                                                                      string output.media-type.value = "audio/raw"
                                                                                                      c2::u32 raw.channel-count.value = 1
                                                                                                      c2::u32 raw.sample-rate.value = 48000
                                                                                                    }
2023-03-17 21:22:44.290  1313-22239 MediaCodec              pid-1313                             I  (0xee8025f0) Component Allocated (c2.android.vorbis.decoder)
2023-03-17 21:22:44.290  1313-22238 MediaCodec              pid-1313                             I  (0xee8025f0) configure surface(0x0) crypto(0x0) flags(0)
2023-03-17 21:22:44.291  1313-22238 MediaCodec              pid-1313                             D  (0xee8025f0) configure format: AMessage(what = 0x00000000) = {
                                                                                                          string mime = "audio/vorbis"
                                                                                                          int64_t durationUs = 1360317
                                                                                                          Buffer csd-0 = {
                                                                                                            00000000:  01 76 6f 72 62 69 73 00  00 00 00 02 44 ac 00 00  .vorbis.....D...
                                                                                                            00000010:  00 00 00 00 6d a0 07 00  00 00 00 00 b8 01        ....m.........
                                                                                                          }
                                                                                                          Buffer *csd-1 = 0xf184a350
                                                                                                          int32_t bitrate = 499821
                                                                                                          int32_t channel-count = 2
                                                                                                          int32_t sample-rate = 44100
                                                                                                          int32_t channel-mask = 3
                                                                                                          int32_t priority = 0
                                                                                                          int32_t pcm-encoding = 2
                                                                                                        }
2023-03-17 21:22:44.291  1313-22239 MediaCodec              pid-1313                             D  flushMediametrics
2023-03-17 21:22:44.291  1313-22239 CCodec                  pid-1313                             D  [c2.android.vorbis.decoder] buffers are bound to CCodec for this session
2023-03-17 21:22:44.291  1313-22239 CCodecConfig            pid-1313                             D  no c2 equivalents for durationUs
2023-03-17 21:22:44.291  1313-22239 CCodecConfig            pid-1313                             D  no c2 equivalents for csd-1
2023-03-17 21:22:44.291  1313-22239 CCodecConfig            pid-1313                             D  no c2 equivalents for channel-mask
2023-03-17 21:22:44.292  1313-22239 CCodecConfig            pid-1313                             D  no c2 equivalents for flags
2023-03-17 21:22:44.293  1313-22239 CCodecConfig            pid-1313                             D  c2 config diff is   c2::u32 raw.channel-count.value = 2
                                                                                                      c2::u32 raw.sample-rate.value = 44100
2023-03-17 21:22:44.296  1313-22239 Codec2Client            pid-1313                             W  query -- param skipped: index = 1107298332.
2023-03-17 21:22:44.296  1313-22239 CCodec                  pid-1313                             D  setup formats input: AMessage(what = 0x00000000) = {
                                                                                                      int32_t channel-count = 2
                                                                                                      int32_t max-input-size = 32768
                                                                                                      string mime = "audio/vorbis"
                                                                                                      int32_t sample-rate = 44100
                                                                                                    } and output: AMessage(what = 0x00000000) = {
                                                                                                      int32_t channel-count = 2
                                                                                                      string mime = "audio/raw"
                                                                                                      int32_t sample-rate = 44100
                                                                                                      int32_t channel-mask = 3
                                                                                                    }
2023-03-17 21:22:44.296  1313-22239 MediaCodec              pid-1313                             I  MediaCodec will operate in async mode
2023-03-17 21:22:44.297  1313-22238 MediaCodec              pid-1313                             I  (0xee8025f0) start
2023-03-17 21:22:44.297  1313-22239 Codec2Client            pid-1313                             W  query -- param skipped: index = 1342179345.
2023-03-17 21:22:44.298  1313-22239 Codec2Client            pid-1313                             W  query -- param skipped: index = 2415921170.
2023-03-17 21:22:44.300  1313-22239 FMQ                     pid-1313                             E  grantorIdx must be less than 3
2023-03-17 21:22:44.300  1313-22239 FMQ                     pid-1313                             E  grantorIdx must be less than 3
2023-03-17 21:22:44.301  1313-22239 CCodecBufferChannel     pid-1313                             D  [c2.android.vorbis.decoder#84] Created input block pool with allocatorID 16 => poolID 26 - OK (0)
2023-03-17 21:22:44.302  1364-2421  BufferPoolAccessor2.0   pid-1364                             D  bufferpool2 0xb400007733587b58 : 0(0 size) total buffers - 0(0 size) used buffers - 118/124 (recycle/alloc) - 6/123 (fetch/transfer)
2023-03-17 21:22:44.303  1364-2421  BufferPoolAccessor2.0   pid-1364                             D  Destruction - bufferpool2 0xb400007733587b58 cached: 0/0M, 0/0% in use; allocs: 124, 95% recycled; transfers: 123, 95% unfetched
2023-03-17 21:22:44.304  1313-1715  BufferPoolAccessor2.0   pid-1313                             D  bufferpool2 0xee4c0c88 : 0(0 size) total buffers - 0(0 size) used buffers - 120/131 (recycle/alloc) - 11/252 (fetch/transfer)
2023-03-17 21:22:44.304  1313-1715  BufferPoolAccessor2.0   pid-1313                             D  Destruction - bufferpool2 0xee4c0c88 cached: 0/0M, 0/0% in use; allocs: 131, 92% recycled; transfers: 252, 96% unfetched
2023-03-17 21:22:44.305  1313-22239 CCodecBufferChannel     pid-1313                             I  [c2.android.vorbis.decoder#84] Created output block pool with allocatorID 16 => poolID 41 - OK
2023-03-17 21:22:44.305  1313-22239 CCodecBufferChannel     pid-1313                             D  [c2.android.vorbis.decoder#84] Configured output block pool ids 41 => OK
2023-03-17 21:22:44.319  1364-22240 SimpleC2Component       pid-1364                             D  Using output block pool with poolID 41 => got 41 - 0
2023-03-17 21:22:44.321  1313-22239 MediaCodec              pid-1313                             I  (0xee8025f0) kWhatStartCompleted
2023-03-17 21:22:44.323  1313-22239 FMQ                     pid-1313                             E  grantorIdx must be less than 3
2023-03-17 21:22:44.339  1313-1715  FMQ                     pid-1313                             E  grantorIdx must be less than 3
2023-03-17 21:22:44.342  1313-22239 CCodecBuffers           pid-1313                             D  [c2.android.vorbis.decoder#84:Output[N]] popFromStashAndRegister: output format changed to AMessage(what = 0x00000000) = {
                                                                                                      int32_t channel-count = 2
                                                                                                      string mime = "audio/raw"
                                                                                                      int32_t sample-rate = 44100
                                                                                                      int32_t channel-mask = 3
                                                                                                    }
2023-03-17 21:22:44.361  1019-1391  APM_AudioPolicyManager  pid-1019                             D  getOutputForAttrInt() attributes={ Content type: AUDIO_CONTENT_TYPE_SONIFICATION Usage: AUDIO_USAGE_NOTIFICATION Source: -1 Flags: 0xc00 Tags:  } stream=AUDIO_STREAM_NOTIFICATION session 305 selectedDeviceId 0
2023-03-17 21:22:44.363  1019-1391  AudioFlinger            pid-1019                             D  Client defaulted notificationFrames to 11025 for frameCount 22050
2023-03-17 21:22:44.369  1019-1391  AF::TrackHandle         pid-1019                             D  OpPlayAudio: track:70 usage:5 not muted
2023-03-17 21:22:44.383  1313-22237 AudioTrack              pid-1313                             D  createTrack_l: RelativeVolumeFeature, mRelativePercentage:1.000000
2023-03-17 21:22:44.387  1313-22237 AudioTrack              pid-1313                             D  setVolume: RelativeVolumeFeature, mRelativePercentage:1.000000, left:1.000000, right:1.000000
2023-03-17 21:22:44.387  1313-22237 AudioTrack              pid-1313                             D  setVolume: RelativeVolumeFeature, mRelativePercentage:1.000000, left:1.000000, right:1.000000
2023-03-17 21:22:44.425   938-1671  EffectsFactory          pid-938                              I  EffectCreate() create_effect
2023-03-17 21:22:44.434  1019-1391  APM_AudioPolicyManager  pid-1019                             D  startOutput() output 13, stream 5, session 305
2023-03-17 21:22:44.446  1822-17691 system_server           pid-1822                             I  oneway function results will be dropped but finished with status OK and parcel size 4
2023-03-17 21:22:44.451  1822-17691 system_server           pid-1822                             I  oneway function results will be dropped but finished with status OK and parcel size 4
2023-03-17 21:22:44.455   959-959   QTI PowerHAL            pid-959                              I  Power setBoost: 3, duration: 2000
2023-03-17 21:22:44.463   938-16811 msm8974_platform        pid-938                              V  platform_get_output_snd_device: enter: output devices(0x2)
2023-03-17 21:22:44.463   938-16811 msm8974_platform        pid-938                              V  platform_get_output_snd_device: exit: snd_device(speaker)
2023-03-17 21:22:44.464   938-16811 msm8974_platform        pid-938                              V  platform_get_backend_index:napb: backend port - 0 device - 2 
2023-03-17 21:22:44.464   938-16811 msm8974_platform        pid-938                              V  platform_check_and_set_playback_backend_cfg: usecase->stream.out->config.format (0)
2023-03-17 21:22:44.464   938-16811 msm8974_platform        pid-938                              V  platform_check_and_set_playback_backend_cfg:becf: afe: bitwidth 16, samplerate 48000 channels 2, backend_idx 0 usecase = 1 device (speaker)
2023-03-17 21:22:44.464   938-16811 msm8974_platform        pid-938                              V  platform_check_and_set_playback_backend_cfg: new_snd_devices[0] is 2
2023-03-17 21:22:44.464   938-16811 msm8974_platform        pid-938                              V  platform_get_backend_index:napb: backend port - 0 device - 2 
2023-03-17 21:22:44.464   938-16811 msm8974_platform        pid-938                              V  platform_check_playback_backend_cfg:becf: afe: bitwidth 16, samplerate 48000 channels 2, backend_idx 0 usecase = 1 device (speaker)
2023-03-17 21:22:44.464   938-16811 msm8974_platform        pid-938                              V  platform_check_playback_backend_cfg:becf: afe: Codec selected backend: 0 updated bit width: 16 andsample rate: 48000
2023-03-17 21:22:44.464   938-16811 msm8974_platform        pid-938                              V  platform_send_audio_calibration: sending audio calibration for snd_device(2) acdb_id(14)
2023-03-17 21:22:44.466   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_audio_cal, acdb_id = 14, path = 0, app id = 0x11130, sample rate = 48000, afe_sample_rate = 48000
2023-03-17 21:22:44.466   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_asm_topology
2023-03-17 21:22:44.466   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID
2023-03-17 21:22:44.469   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_adm_topology
2023-03-17 21:22:44.469   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_audtable
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_COMMON_TABLE_SIZE
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_COMMON_TABLE
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> AUDIO_SET_AUDPROC_CAL cal_type[11] acdb_id[14] app_type[69936]
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_audvoltable
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_GAIN_DEP_STEP_TABLE_SIZE
2023-03-17 21:22:44.470   938-16811 android.ha...io.service pid-938                              D  Failed to fetch the lookup information of the device 0000000E 
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  Error: ACDB_CMD_GET_AUDPROC_INSTANCE_GAIN_DEP_STEP_TABLE_SIZE Returned = -19
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_GAIN_DEP_STEP_TABLE, vol index 0
2023-03-17 21:22:44.470   938-16811 android.ha...io.service pid-938                              D  Failed to fetch the lookup information of the device 0000000E 
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  Error: ACDB AudProc vol returned = -19
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> AUDIO_SET_VOL_CAL cal type = 12
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_STREAM_TABLE_SIZE
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_audstrmtable
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_STREAM_TABLE
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 20
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_afe_topology
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 14, Topology Id 1000ff01
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_afe_cal
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AFE_INSTANCE_COMMON_TABLE_SIZE
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AFE_INSTANCE_COMMON_TABLE
2023-03-17 21:22:44.470   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> AUDIO_SET_AFE_CAL cal_type[16] acdb_id[14]
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_hw_delay : acdb_id = 14 path = 0
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY
2023-03-17 21:22:44.471   938-16811 msm8974_platform        pid-938                              V  platform_send_audio_calibration: sending audio calibration for snd_device(176) acdb_id(102)
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_audio_cal, acdb_id = 102, path = 1, app id = 0x11130, sample rate = 48000, afe_sample_rate = 48000
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_asm_topology
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_adm_topology
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_audtable
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_COMMON_TABLE_SIZE
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_COMMON_TABLE
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> AUDIO_SET_AUDPROC_CAL cal_type[11] acdb_id[102] app_type[69936]
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  check_fnn_usecase_status: fnn usecase active 0
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_PERSISTENT_AUDPROC_INSTANCE_COMMON_TABLE_SIZE
2023-03-17 21:22:44.471   938-16811 android.ha...io.service pid-938                              D  ACDBFILE_MGR: Property for the pid 000133B7 not found
2023-03-17 21:22:44.471   938-16811 chatty                  pid-938                              I  uid=1041(audioserver) writer identical 12 lines
2023-03-17 21:22:44.471   938-16811 android.ha...io.service pid-938                              D  ACDBFILE_MGR: Property for the pid 000133B7 not found
2023-03-17 21:22:44.471   938-16811 android.ha...io.service pid-938                              D  [Data Manager]-> No data found
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  Error: ACDB_CMD_GET_PERSISTENT_AUDPROC_INSTANCE_COMMON_TABLE_SIZE returned -18
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_audproc_persist_table -18 
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  Error: Invalid Audproc persist size = -18
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_STREAM_TABLE_SIZE
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_audstrmtable
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_STREAM_TABLE
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 20
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_afe_topology
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 102, Topology Id 1000ff00
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_afe_cal
2023-03-17 21:22:44.471   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AFE_INSTANCE_COMMON_TABLE_SIZE
2023-03-17 21:22:44.472   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_CMD_GET_AFE_INSTANCE_COMMON_TABLE
2023-03-17 21:22:44.472   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> AUDIO_SET_AFE_CAL cal_type[17] acdb_id[102]
2023-03-17 21:22:44.472   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> send_hw_delay : acdb_id = 102 path = 1
2023-03-17 21:22:44.472   938-16811 ACDB-LOADER             pid-938                              D  ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY
2023-03-17 21:22:44.472   938-16811 audio_hw_primary        pid-938                              D  enable_snd_device: snd_device(176: vi-feedback)
2023-03-17 21:22:44.472   938-16811 audio_route             pid-938                              D  Apply path: vi-feedback
2023-03-17 21:22:44.473   938-16811 audio_hw_primary        pid-938                              D  enable_audio_route: usecase(26) apply and update mixer path: spkr-vi-record
2023-03-17 21:22:44.473   938-16811 audio_route             pid-938                              D  Apply path: spkr-vi-record
2023-03-17 21:22:44.475   938-16811 audio_hw_dsm_feedback   pid-938                              E  start_dsm_feedback_processing: cannot open device '/dev/snd/pcmC0D59c': No such file or directory
2023-03-17 21:22:44.475   938-16811 audio_hw_primary        pid-938                              D  disable_snd_device: snd_device(176: vi-feedback)
2023-03-17 21:22:44.475   938-16811 audio_hw_primary        pid-938                              D  disable_audio_route: usecase(26) reset and update mixer path: spkr-vi-record
2023-03-17 21:22:44.476   938-16811 audio_hw_primary        pid-938                              D  enable_snd_device: snd_device(2: speaker)
2023-03-17 21:22:44.477   938-16811 audio_route             pid-938                              D  Apply path: speaker
2023-03-17 21:22:44.479   938-16811 msm8974_platform        pid-938                              V  platform_get_snd_device_backend_index: enter with device 2
2023-03-17 21:22:44.479   938-16811 msm8974_platform        pid-938                              V  platform_get_backend_index:napb: backend port - 0 device - 2 
2023-03-17 21:22:44.479   938-16811 audio_hw_utils          pid-938                              I  Set Audio Stream 9 App Type Cfg: app_type 69936, acdb_dev_id 14, sample_rate 48000, snd_device_be_idx 43
2023-03-17 21:22:44.480   938-16811 audio_hw_primary        pid-938                              D  enable_audio_route: usecase(1) apply and update mixer path: low-latency-playback speaker
2023-03-17 21:22:44.480   938-16811 audio_route             pid-938                              D  Apply path: low-latency-playback speaker
2023-03-17 21:22:44.483   938-1667  MotSpeakerHelper        pid-938                              D  Speaker ON: volume 0.500000 (step 12), usecase SAFE
2023-03-17 21:22:44.531   959-959   QTI PowerHAL            pid-959                              I  Power setBoost: 3, duration: -1
2023-03-17 21:22:44.531   938-16811 audio_hw_primary        pid-938                              D  out_write: retry previous failed cal level set
2023-03-17 21:22:44.552   938-1671  MotSpeakerWrapper       pid-938                              I  EFFECT_CMD_SET_VOLUME: 0.071497, 0.071497 on ID 305 (Notification)
2023-03-17 21:22:44.553   938-1667  MotSpeakerHelper        pid-938                              D  Speaker ON: volume 0.071497 (step 6), usecase SONIFICATION
2023-03-17 21:22:44.866  3598-4748  NetlinkTracker/wlan0    pid-3598                             D  addressUpdated: 2601:18e:c280:8780:eb7e:2c11:68d2:ed0/64 on wlan0 flags 2304 scope 0
2023-03-17 21:22:44.870  3598-4748  NetlinkTracker/wlan0    pid-3598                             D  addressUpdated: 2601:18e:c280:8780:f447:d66c:aabc:dbd5/64 on wlan0 flags 1 scope 0
2023-03-17 21:22:45.361  1313-22239 CCodecBufferChannel     pid-1313                             D  [c2.android.vorbis.decoder#84] buffers after EOS ignored (0 us)
2023-03-17 21:22:45.362  1313-22239 chatty                  pid-1313                             I  uid=1013(media) NPDecoder-CL identical 1 line
2023-03-17 21:22:45.362  1313-22239 CCodecBufferChannel     pid-1313                             D  [c2.android.vorbis.decoder#84] buffers after EOS ignored (0 us)
2023-03-17 21:22:45.616  1313-22237 AudioTrack              pid-1313                             D  stop(43): called with 59990 frames delivered
2023-03-17 21:22:45.971  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:23 type:android.media.SoundPool u/pid:10289/3388 state:idle attr:AudioAttributes: usage=USAGE_ASSISTANCE_SONIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x800 tags= bundle=null
2023-03-17 21:22:45.971  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:39 type:android.media.SoundPool u/pid:10200/4472 state:idle attr:AudioAttributes: usage=USAGE_ASSISTANCE_SONIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x801 tags= bundle=null
2023-03-17 21:22:45.971  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:15 type:android.media.SoundPool u/pid:1000/1822 state:idle attr:AudioAttributes: usage=USAGE_ASSISTANCE_SONIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x800 tags= bundle=null
2023-03-17 21:22:45.972  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:223 type:android.media.MediaPlayer u/pid:10289/3388 state:stopped attr:AudioAttributes: usage=USAGE_NOTIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x800 tags= bundle=null
2023-03-17 21:22:45.972  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager isPlaying: false
2023-03-17 21:22:45.972  3370-4008  Avrcp_ext               pid-3370                             V  updateCurrentMediaState: mMediaController: null
2023-03-17 21:22:45.974  3370-4008  Avrcp_ext               pid-3370                             V  isMusicActive: false getBluetoothPlayState: 2
2023-03-17 21:22:45.974  3370-4008  Avrcp_ext               pid-3370                             V  updateCurrentMediaState: isPlaying = false
2023-03-17 21:22:45.974  3370-4008  Avrcp_ext               pid-3370                             V  Media update: id -1➡-1? [MediaAttributes: none] : [MediaAttributes: none]
2023-03-17 21:22:45.974  3370-4008  Avrcp_ext               pid-3370                             D  isPackageNameValid: browsedPackage = com.spotify.musicisValid = true
2023-03-17 21:22:45.974  3370-4008  Avrcp_ext               pid-3370                             V  isBrowseSupported for com.spotify.music: false
2023-03-17 21:22:45.974  3370-4008  Avrcp_ext               pid-3370                             V  newPlayStatus:2mReportedPlayStatus:-1
2023-03-17 21:22:45.974  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState, state: PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332183693, actions=0, custom actions=[], active item id=-1, error=null} device: null
2023-03-17 21:22:45.974  3370-4008  Avrcp_ext               pid-3370                             V  Device: no name: 
2023-03-17 21:22:45.974  3370-4008  chatty                  pid-3370                             I  uid=1002(bluetooth) BluetoothAvrcpH identical 3 lines
2023-03-17 21:22:45.974  3370-4008  Avrcp_ext               pid-3370                             V  Device: no name: 
2023-03-17 21:22:45.975  3370-4008  Avrcp_ext               pid-3370                             V  updatePlayerStateAndPosition, old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null}, state=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332183693, actions=0, custom actions=[], active item id=-1, error=null}
2023-03-17 21:22:45.975  3370-4008  Avrcp_ext               pid-3370                             V  old state = PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null}, new state= PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332183693, actions=0, custom actions=[], active item id=-1, error=null}
2023-03-17 21:22:45.975  3370-4008  HeadsetService          pid-3370                             D  getHeadsetService(): returning com.android.bluetooth.hfp.HeadsetService@ab117d1
2023-03-17 21:22:45.975  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:45.976  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:45.976  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:45.976  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:45.976  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332183693, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:45.976  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:45.976  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:45.976  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:45.976  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:45.977  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:45.977  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332183693, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:45.977  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:45.977  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:45.977  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:45.977  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:45.977  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:45.977  1822-17677 MediaFocusControl       pid-1822                             I  abandonAudioFocus() from uid/pid 10289/3388 clientId=android.media.AudioManager@9062a73
2023-03-17 21:22:45.977  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332183693, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:45.977  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:45.978  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:45.978  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:45.978  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:45.978  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:45.978  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332183693, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:45.978  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:45.978  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:45.978  3370-4008  HeadsetService          pid-3370                             D  isScoOrCallActive(): Call Active:falseCall is Ringing:falseSCO is Active:false
2023-03-17 21:22:45.978  3370-4008  HeadsetService          pid-3370                             D   isAudioOn: The number of audio connected devices 0
2023-03-17 21:22:45.978  3370-4008  Avrcp_ext               pid-3370                             I  updatePlayStatusForDevice: device: null
2023-03-17 21:22:45.978  3370-4008  Avrcp_ext               pid-3370                             V  updatePlaybackState (1): old=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332181702, actions=0, custom actions=[], active item id=-1, error=null}(2), new=PlaybackState {state=2, position=-1, buffered position=0, speed=0.0, updated=332183693, actions=0, custom actions=[], active item id=-1, error=null}(2)
2023-03-17 21:22:45.979  3370-4008  Avrcp_ext               pid-3370                             I  Exit updatePlayStatusForDevice
2023-03-17 21:22:45.979  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:45.979  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:45.979  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:45.979  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:45.979  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:45.979  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:45.979  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:45.979  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:45.979  3370-4008  Avrcp_ext               pid-3370                             D  Enter sendPlayPosNotificationRsp
2023-03-17 21:22:45.979  3370-4008  Avrcp_ext               pid-3370                             D  sendPlayPosNotificationRsp: Not registered or requesting.
2023-03-17 21:22:45.979  3370-4008  Avrcp_ext               pid-3370                             V  Exit updatePlayerStateAndPosition
2023-03-17 21:22:45.979  3388-22212 MediaPlayer             pid-3388                             V  resetDrmState:  mDrmInfo=null mDrmProvisioningThread=null mPrepareDrmInProgress=false mActiveDrmScheme=false
2023-03-17 21:22:45.979  3388-22212 MediaPlayer             pid-3388                             V  cleanDrmObj: mDrmObj=null mDrmSessionId=null
2023-03-17 21:22:45.980  1313-20788 NuPlayerDriver          pid-1313                             D  reset(0xf1541f50) at state 6
2023-03-17 21:22:45.984  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:23 type:android.media.SoundPool u/pid:10289/3388 state:idle attr:AudioAttributes: usage=USAGE_ASSISTANCE_SONIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x800 tags= bundle=null
2023-03-17 21:22:45.984  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:39 type:android.media.SoundPool u/pid:10200/4472 state:idle attr:AudioAttributes: usage=USAGE_ASSISTANCE_SONIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x801 tags= bundle=null
2023-03-17 21:22:45.984  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager Player: AudioPlaybackConfiguration piid:15 type:android.media.SoundPool u/pid:1000/1822 state:idle attr:AudioAttributes: usage=USAGE_ASSISTANCE_SONIFICATION content=CONTENT_TYPE_SONIFICATION flags=0x800 tags= bundle=null
2023-03-17 21:22:45.984  3370-4008  Avrcp_ext               pid-3370                             D  AudioManager isPlaying: false
2023-03-17 21:22:45.995  1822-17677 system_server           pid-1822                             I  oneway function results will be dropped but finished with status OK and parcel size 4
2023-03-17 21:22:46.084   938-938   audio_hw_primary        pid-938                              D  disable_audio_route: usecase(1) reset and update mixer path: low-latency-playback speaker
2023-03-17 21:22:46.087   938-938   audio_hw_primary        pid-938                              D  disable_snd_device: snd_device(2: speaker)
2023-03-17 21:22:46.088   938-1667  MotSpeakerHelper        pid-938                              D  Speaker ON: volume 0.071497 (step 6), usecase SONIFICATION
2023-03-17 21:22:46.091  1313-22238 MediaCodec              pid-1313                             I  (0xee8025f0) flush
2023-03-17 21:22:46.093  1313-22238 MediaCodec              pid-1313                             I  (0xee8025f0) release
2023-03-17 21:22:46.094  1822-17677 system_server           pid-1822                             I  oneway function results will be dropped but finished with status OK and parcel size 4
2023-03-17 21:22:46.097  1019-1373  APM_AudioPolicyManager  pid-1019                             D  stopOutput() output 13, stream 5, session 305
2023-03-17 21:22:46.098  1313-22239 hw-BpHwBinder           pid-1313                             I  onLastStrongRef automatically unlinking death recipients
2023-03-17 21:22:46.098  1364-3576  hw-BpHwBinder           pid-1364                             I  onLastStrongRef automatically unlinking death recipients
2023-03-17 21:22:46.098  1364-3576  hw-BpHwBinder           pid-1364                             I  onLastStrongRef automatically unlinking death recipients
2023-03-17 21:22:46.100  1364-3576  C2Store                 pid-1364                             V  in ~ComponentModule
2023-03-17 21:22:46.100  1364-3576  C2Store                 pid-1364                             V  unloading dll
2023-03-17 21:22:46.103  1019-1373  APM::AudioPolicyEngine  pid-1019                             W  getDevicesForStrategy() unknown strategy: -1
2023-03-17 21:22:46.110  1313-22238 MediaCodec              pid-1313                             D  flushMediametrics
2023-03-17 21:22:46.111  1822-3487  system_server           pid-1822                             I  oneway function results will be dropped but finished with status OK and parcel size 4
2023-03-17 21:22:46.112  1822-3487  system_server           pid-1822                             I  oneway function results will be dropped but finished with status OK and parcel size 4
2023-03-17 21:22:46.121  1313-22222 NuPlayerDriver          pid-1313                             D  notifyResetComplete(0xf1541f50)
2023-03-17 21:22:46.125  1313-20788 AMessage                pid-1313                             W  failed to post message as target looper for handler 0 is gone.
2023-03-17 21:22:46.646   939-2254  slpi                    pid-939                              D  tmd2755 ALS_OC lux: 24.60, ch0: 542, ch1: 766, atime:50.04, again: 1024, scale: 1.67, pdata 13, poff:-103, CT: 376, NearBy: 0,Lth:50.81, Hth:92.54,Stu 10,Ccfg 20
2023-03-17 21:22:46.835   939-2254  slpi                    pid-939                              D  tmd2755 ALS_OC lux: 20.90, ch0: 454, ch1: 603, atime:50.04, again: 1024, scale: 1.67, pdata 13, poff:-103, CT: 376, NearBy: 0,Lth:50.81, Hth:92.54,Stu 10,Ccfg 20
2023-03-17 21:22:47.026   939-2254  slpi                    pid-939                              D  tmd2755 ALS_OC lux: 16.90, ch0: 391, ch1: 504, atime:50.04, again: 1024, scale: 1.67, pdata 13, poff:-103, CT: 376, NearBy: 0,Lth:50.81, Hth:92.54,Stu 10,Ccfg 20
2023-03-17 21:22:47.276   959-959   QTI PowerHAL            pid-959                              I  Power setBoost: 0, duration: 0
2023-03-17 21:22:47.280   959-959   QTI PowerHAL            pid-959                              I  Power setBoost: 0, duration: 0
2023-03-17 21:22:47.369  3618-3618  BaseSwipeDetector       pid-3618                             D  setState:IDLE->IDLE
2023-03-17 21:22:47.380  1822-17691 ActivityTaskManager     pid-1822                             I  START u0 {act=android.intent.action.MAIN cat=[android.intent.category.LAUNCHER] flg=0x10200000 cmp=com.domain.app/com.ryanheise.audioservice.AudioServiceActivity bnds=[24,223][158,335]} from uid 10287
2023-03-17 21:22:47.382  1822-17691 ActivityTaskManager     pid-1822                             D  getPreferredLaunchDisplay userset_displayid false current displayId -1
2023-03-17 21:22:47.400   959-959   QTI PowerHAL            pid-959                              I  Power setMode: 5 to: 1
2023-03-17 21:22:47.383  1822-17691 chatty                  pid-1822                             I  uid=1000(system) Binder:1822_17 identical 1 line
2023-03-17 21:22:47.391  1822-17691 ActivityTaskManager     pid-1822                             D  getPreferredLaunchDisplay userset_displayid false current displayId -1
2023-03-17 21:22:47.416  1822-1863  WindowManager           pid-1822                             V  Changing focus of displayId=0 to null from Window{681f024 u0 com.motorola.launcher3/com.android.launcher3.CustomizationPanelLauncher}
2023-03-17 21:22:47.422  4472-4472  GameMode                pid-4472                             D  isScreenUnlocked: true
2023-03-17 21:22:47.424  4472-4472  GameMode                pid-4472                             D  exiting game mode
2023-03-17 21:22:47.424  1822-1867  Compatibil...geReporter pid-1822                             D  Compat change id reported: 136274596; UID 10333; state: ENABLED
2023-03-17 21:22:47.430  1024-1107  DisplayIdentification   pid-1024                             W  Invalid EDID: falling back to serial number due to missing display name.
2023-03-17 21:22:47.430  1024-1107  DisplayIdentification   pid-1024                             W  Invalid EDID: falling back to ASCII text due to missing serial number.
2023-03-17 21:22:47.448 17164-7714  DynamicVol...umeService pid-17164                            W  onForegroundActivitiesChanged, but feature not opened, ignore! 
2023-03-17 21:22:47.450  4472-4472  GameMode                pid-4472                             D  reset : false
2023-03-17 21:22:47.450  4472-4472  GameMode                pid-4472                             D  reset: 
2023-03-17 21:22:47.450  4472-4472  GameMode                pid-4472                             D  isFeatureSupported: false
2023-03-17 21:22:47.453  4472-17472 MFL                     pid-4472                             D  getFreeFormPackages: is blank
2023-03-17 21:22:47.455 22100-22100 AutoSessionTracker      pid-22100                            D  Activity created: com.ryanheise.audioservice.AudioServiceActivity
2023-03-17 21:22:47.455 22100-22100 AutoSessionTracker      pid-22100                            D  Activity created: com.ryanheise.audioservice.AudioServiceActivity
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W  java.lang.Exception
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at com.ryanheise.audioservice.a.B(AudioServicePlugin.java:1)
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at com.ryanheise.audioservice.AudioServiceActivity.l(AudioServiceActivity.java:1)
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at io.flutter.embedding.android.e.G(FlutterActivityAndFragmentDelegate.java:6)
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at io.flutter.embedding.android.e.p(FlutterActivityAndFragmentDelegate.java:3)
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at io.flutter.embedding.android.d.onCreate(FlutterActivity.java:4)
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at android.app.Activity.performCreate(Activity.java:8108)
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at android.app.Activity.performCreate(Activity.java:8092)
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at android.app.Instrumentation.callActivityOnCreate(Instrumentation.java:1309)
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:3501)
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at android.app.ActivityThread.handleLaunchActivity(ActivityThread.java:3703)
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at android.app.servertransaction.LaunchActivityItem.execute(LaunchActivityItem.java:85)
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at android.app.servertransaction.TransactionExecutor.executeCallbacks(TransactionExecutor.java:135)
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at android.app.servertransaction.TransactionExecutor.execute(TransactionExecutor.java:95)
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2152)
2023-03-17 21:22:47.456 22100-22100 System.err              pid-22100                            W      at android.os.Handler.dispatchMessage(Handler.java:106)
2023-03-17 21:22:47.457 22100-22100 System.err              pid-22100                            W      at android.os.Looper.loop(Looper.java:250)
2023-03-17 21:22:47.457 22100-22100 System.err              pid-22100                            W      at android.app.ActivityThread.main(ActivityThread.java:7877)
2023-03-17 21:22:47.457 22100-22100 System.err              pid-22100                            W      at java.lang.reflect.Method.invoke(Native Method)
2023-03-17 21:22:47.457 22100-22100 System.err              pid-22100                            W      at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:592)
2023-03-17 21:22:47.457 22100-22100 System.err              pid-22100                            W      at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:958)
2023-03-17 21:22:47.459  1822-1868  WindowManager           pid-1822                             I  SURFACE show Surface(name=Splash Screen com.domain.app)/@0x6e278bd on display:0
2023-03-17 21:22:47.462  4472-17472 MFL                     pid-4472                             D  getDensityIndependentPixel: smallest width = 411
2023-03-17 21:22:47.462  4472-17472 MFL                     pid-4472                             D  getLaunchRect: margin = 0
2023-03-17 21:22:47.472 22100-22100 com.llfbandit.app_links pid-22100                            D  handleIntent: (Action) android.intent.action.MAIN
2023-03-17 21:22:47.472 22100-22100 com.llfbandit.app_links pid-22100                            D  handleIntent: (Data) null
2023-03-17 21:22:47.472 22100-22100 System.err              pid-22100                            W  java.lang.Exception
2023-03-17 21:22:47.472 22100-22100 System.err              pid-22100                            W      at com.ryanheise.audioservice.a.B(AudioServicePlugin.java:1)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at com.ryanheise.audioservice.a.onAttachedToActivity(AudioServicePlugin.java:4)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at io.flutter.embedding.engine.c.i(FlutterEngineConnectionRegistry.java:12)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at io.flutter.embedding.engine.c.e(FlutterEngineConnectionRegistry.java:6)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at io.flutter.embedding.android.e.p(FlutterActivityAndFragmentDelegate.java:6)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at io.flutter.embedding.android.d.onCreate(FlutterActivity.java:4)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at android.app.Activity.performCreate(Activity.java:8108)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at android.app.Activity.performCreate(Activity.java:8092)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at android.app.Instrumentation.callActivityOnCreate(Instrumentation.java:1309)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at android.app.ActivityThread.performLaunchActivity(ActivityThread.java:3501)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at android.app.ActivityThread.handleLaunchActivity(ActivityThread.java:3703)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at android.app.servertransaction.LaunchActivityItem.execute(LaunchActivityItem.java:85)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at android.app.servertransaction.TransactionExecutor.executeCallbacks(TransactionExecutor.java:135)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at android.app.servertransaction.TransactionExecutor.execute(TransactionExecutor.java:95)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at android.app.ActivityThread$H.handleMessage(ActivityThread.java:2152)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at android.os.Handler.dispatchMessage(Handler.java:106)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at android.os.Looper.loop(Looper.java:250)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at android.app.ActivityThread.main(ActivityThread.java:7877)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at java.lang.reflect.Method.invoke(Native Method)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at com.android.internal.os.RuntimeInit$MethodAndArgsCaller.run(RuntimeInit.java:592)
2023-03-17 21:22:47.473 22100-22100 System.err              pid-22100                            W      at com.android.internal.os.ZygoteInit.main(ZygoteInit.java:958)
2023-03-17 21:22:47.507 22100-22100 AutoSessionTracker      pid-22100                            D  Activity started: com.ryanheise.audioservice.AudioServiceActivity
2023-03-17 21:22:47.507 22100-22100 AutoSessionTracker      pid-22100                            D  Activity started: com.ryanheise.audioservice.AudioServiceActivity
2023-03-17 21:22:47.513 22100-22100 AutoSessionTracker      pid-22100                            D  Activity resumed: com.ryanheise.audioservice.AudioServiceActivity
2023-03-17 21:22:47.513 22100-22100 AutoSessionTracker      pid-22100                            D  Application entered the foreground.
2023-03-17 21:22:47.518 16860-16860 GsaVoiceInteractionSrv  pid-16860                            I  Handling ACTION_STOP_HOTWORD
2023-03-17 21:22:47.529 22100-22100 AutoSessionTracker      pid-22100                            D  Activity resumed: com.ryanheise.audioservice.AudioServiceActivity
2023-03-17 21:22:47.549 22100-22252 AWSMobileClient         pid-22100                            D  Inspecting user state details
2023-03-17 21:22:47.557 22100-22252 AWSMobileClient         pid-22100                            D  waitForSignIn: userState:GUEST
2023-03-17 21:22:47.573  1822-3461  WindowManager           pid-1822                             V  Changing focus of displayId=0 to Window{14214d6 u0 com.domain.app/com.ryanheise.audioservice.AudioServiceActivity} from null
2023-03-17 21:22:47.585 13769-22155 AdvertisingIdSettings   pid-13769                            W  Package com.domain.app failed Ad Id permission check. Apps that target Android SDK 33 or higher should declare com.google.android.gms.permission.AD_ID in the app manifest to access Ad Id.
2023-03-17 21:22:47.592   939-2254  slpi                    pid-939                              D  tmd2755 ALS_OC lux: 21.90, ch0: 480, ch1: 661, atime:50.04, again: 1024, scale: 1.67, pdata 13, poff:-103, CT: 376, NearBy: 0,Lth:50.81, Hth:92.54,Stu 10,Ccfg 20
2023-03-17 21:22:47.608 23490-22160 NetworkScheduler        pid-23490                            W  Error inserting flex_time=3273000 job_id=-1 period=6547000 source=16 requires_charging=0 preferred_network_type=1 target_class=com.google.android.gms.measurement.PackageMeasurementTaskService user_id=0 target_package=com.google.android.gms tag=Measurement.PackageMeasurementTaskService.UPLOAD_TASK_TAG task_type=0 required_idleness_state=0 service_kind=0 source_version=223616000 persistence_level=1 preferred_charging_state=1 required_network_type=0 runtime=1679102567605 retry_strategy={"maximum_backoff_seconds":{"3600":0},"initial_backoff_seconds":{"30":0},"retry_policy":{"0":0}} last_runtime=0 [CONTEXT service_id=218 ]
                                                                                                    android.database.sqlite.SQLiteConstraintException: UNIQUE constraint failed: pending_ops.tag, pending_ops.target_class, pending_ops.target_package, pending_ops.user_id (code 2067 SQLITE_CONSTRAINT_UNIQUE)
                                                                                                        at android.database.sqlite.SQLiteConnection.nativeExecuteForLastInsertedRowId(Native Method)
                                                                                                        at android.database.sqlite.SQLiteConnection.executeForLastInsertedRowId(SQLiteConnection.java:938)
                                                                                                        at android.database.sqlite.SQLiteSession.executeForLastInsertedRowId(SQLiteSession.java:790)
                                                                                                        at android.database.sqlite.SQLiteStatement.executeInsert(SQLiteStatement.java:88)
                                                                                                        at android.database.sqlite.SQLiteDatabase.insertWithOnConflict(SQLiteDatabase.java:1701)
                                                                                                        at android.database.sqlite.SQLiteDatabase.insertOrThrow(SQLiteDatabase.java:1596)
                                                                                                        at bhmj.g(:com.google.android.gms@223616037@22.36.16 (150400-476895098):52)
                                                                                                        at bhlc.n(:com.google.android.gms@223616037@22.36.16 (150400-476895098):3)
                                                                                                        at bhlc.u(:com.google.android.gms@223616037@22.36.16 (150400-476895098):20)
                                                                                                        at bhlc.h(:com.google.android.gms@223616037@22.36.16 (150400-476895098):3)
                                                                                                        at bhgt.run(:com.google.android.gms@223616037@22.36.16 (150400-476895098):9)
                                                                                                        at zby.c(:com.google.android.gms@223616037@22.36.16 (150400-476895098):6)
                                                                                                        at zby.run(:com.google.android.gms@223616037@22.36.16 (150400-476895098):7)
                                                                                                        at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
                                                                                                        at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
                                                                                                        at zhe.run(:com.google.android.gms@223616037@22.36.16 (150400-476895098):0)
                                                                                                        at java.lang.Thread.run(Thread.java:923)
2023-03-17 21:22:47.729  1822-3461  UserManagerService      pid-1822                             W  Do not support app clone feature.
2023-03-17 21:22:47.780  1822-3487  UserManagerService      pid-1822                             W  Do not support app clone feature.
2023-03-17 21:22:47.782   939-2254  slpi                    pid-939                              D  tmd2755 ALS_OC lux: 26.30, ch0: 582, ch1: 841, atime:50.04, again: 1024, scale: 1.67, pdata 13, poff:-103, CT: 376, NearBy: 0,Lth:50.81, Hth:92.54,Stu 10,Ccfg 20
2023-03-17 21:22:47.799 16836-16921 ogle.android.a          pid-16836                            I  Waiting for a blocking GC ProfileSaver
2023-03-17 21:22:47.844   939-2254  slpi                    pid-939                              D  tmd2755 ALS_OC lux: 30.00, ch0: 667, ch1: 976, atime:50.04, again: 1024, scale: 1.67, pdata 13, poff:-103, CT: 376, NearBy: 0,Lth:50.81, Hth:92.54,Stu 10,Ccfg 20
2023-03-17 21:22:47.850 16836-16868 ogle.android.a          pid-16836                            W  Reducing the number of considered missed Gc histogram windows from 740 to 100
2023-03-17 21:22:47.850 16836-16921 ogle.android.a          pid-16836                            I  WaitForGcToComplete blocked ProfileSaver on ProfileSaver for 51.500ms
2023-03-17 21:22:47.917  1822-1868  WindowManager           pid-1822                             I  SURFACE hide Surface(name=com.android.systemui.ImageWallpaper)/@0x1c276bf on display:0
2023-03-17 21:22:47.917  1822-1868  WindowManager           pid-1822                             I  SURFACE hide Surface(name=com.motorola.launcher3/com.android.launcher3.CustomizationPanelLauncher)/@0x8387d7a on display:0
2023-03-17 21:22:47.917  1822-1868  WindowManager           pid-1822                             I  SURFACE hide Surface(name=com.motorola.launcher3/com.android.launcher3.CustomizationPanelLauncher)/@0xca4c92b on display:0
2023-03-17 21:22:47.934  3598-4748  NetlinkTracker/wlan0    pid-3598                             D  addressUpdated: 2601:18e:c280:8780:eb7e:2c11:68d2:ed0/64 on wlan0 flags 2304 scope 0
2023-03-17 21:22:47.935  3598-4575  NetlinkTracker/wlan0    pid-3598                             D  addressUpdated: 2601:18e:c280:8780:f447:d66c:aabc:dbd5/64 on wlan0 flags 1 scope 0
2023-03-17 21:22:49.094  1822-3487  system_server           pid-1822                             I  oneway function results will be dropped but finished with status OK and parcel size 4
2023-03-17 21:22:49.305  1364-1720  BufferPoolAccessor2.0   pid-1364                             D  evictor expired: 1, evicted: 0
2023-03-17 21:22:50.302  1313-2428  BufferPoolAccessor2.0   pid-1313                             D  evictor expired: 1, evicted: 0
2023-03-17 21:22:51.017  3598-4575  NetlinkTracker/wlan0    pid-3598                             D  addressUpdated: 2601:18e:c280:8780:eb7e:2c11:68d2:ed0/64 on wlan0 flags 2304 scope 0
2023-03-17 21:22:51.023  3598-4748  NetlinkTracker/wlan0    pid-3598                             D  addressUpdated: 2601:18e:c280:8780:f447:d66c:aabc:dbd5/64 on wlan0 flags 1 scope 0
2023-03-17 21:22:51.303  1313-2428  BufferPoolAccessor2.0   pid-1313                             D  bufferpool2 0xee4c0268 : 0(0 size) total buffers - 0(0 size) used buffers - 120/131 (recycle/alloc) - 11/252 (fetch/transfer)
2023-03-17 21:22:51.303  1313-2428  BufferPoolAccessor2.0   pid-1313                             D  evictor expired: 1, evicted: 1
2023-03-17 21:22:51.307  1364-1720  BufferPoolAccessor2.0   pid-1364                             D  bufferpool2 0xb40000773358b528 : 0(0 size) total buffers - 0(0 size) used buffers - 117/124 (recycle/alloc) - 7/123 (fetch/transfer)
2023-03-17 21:22:51.307  1364-1720  BufferPoolAccessor2.0   pid-1364                             D  evictor expired: 1, evicted: 1
2023-03-17 21:22:54.087  3598-4575  NetlinkTracker/wlan0    pid-3598                             D  addressUpdated: 2601:18e:c280:8780:eb7e:2c11:68d2:ed0/64 on wlan0 flags 2304 scope 0
2023-03-17 21:22:54.089  3598-4748  NetlinkTracker/wlan0    pid-3598                             D  addressUpdated: 2601:18e:c280:8780:f447:d66c:aabc:dbd5/64 on wlan0 flags 1 scope 0
djsjr commented 1 year ago

@ryanheise Was this helpful? If not, let me know what else I can do

djsjr commented 1 year ago

Any update on this or possible PR to fix?

pmmdz commented 11 months ago

We debugged similar issue, where our app tried to fully start upon receiving background messages and couldn't find our own method channels defined in Main Activity. Our app also started doing its usual periodic staff in background and so, basically, we had a "zombie" app which awakens from a data push and lives in background. Some users reported that Android showed alerts about the app resources consumption in this state.

Here is our assumption. FirebaseMessaging creates separate Activity and FlutterEngine. It also initializes plug-ins, including AudioService, which is fine. AudioService in turn "warms up" it's own flutter engine, which calls main function in dart code. So, the main function is actually run from different activity (not MainActivity) which results in odd behavior both in background and later in foreground.

We ended up with similar solution to discussed above. However, we just removed "warm up" flutter engine code from AudioService startUp method.

This solution allows app to just run background message handler without spinning up main function.

We haven't found any issues with audio yet.

pmmdz commented 11 months ago

Here are our changes: https://github.com/meduza-corp/audio_service/commit/aba1cf0bcd59d397b1d2b82982ab4676cdae6956

jaskiratAtNexG commented 10 months ago

Since this issue has several different conversations going on, I want to summarize that the original issue is the flutter app using audio_services and firebase_messaging (FCM) gets stuck on the splash screen when attempting to open the app after receiving a push notification in a terminated state on Android. (App starts prematurely when a push notification received.) I am experiencing this issue now as well.

Getting stuck on the splash screen is the part that makes this a priority for myself to find a way around, since I can't send notifs to android users without getting them stuck.

@yanivshaked Wondering if you've found a better solution in the meantime?

Experiencing this exact same issue in our app is there any solution to this??

shivkumarordrio commented 7 months ago

We are facing same issue. Is there any solution for this?