Skip to content

Did not get confirmation UiAutomator2 deleteSession worked; Error was: UnknownError: An unknown server-side error occurred while processing the command. Original error: 'DELETE /' cannot be proxied to UiAutomator2 server because the instrumentation process is not running (probably crashed) #1670

New issue

Have a question about this project? # for a free GitHub account to open an issue and contact its maintainers and the community.

By clicking “#”, you agree to our terms of service and privacy statement. We’ll occasionally send you account related emails.

Already on GitHub? # to your account

Closed
Karthikvt143 opened this issue Mar 29, 2022 · 9 comments

Comments

@Karthikvt143
Copy link

The server is running
[HTTP] Waiting until the server is closed
[HTTP] Received server close event
[Appium] Welcome to Appium v1.20.2
[Appium] Non-default server args:
[Appium] relaxedSecurityEnabled: true
[Appium] allowInsecure: {
[Appium] }
[Appium] denyInsecure: {
[Appium] }
[Appium] Appium REST http interface listener started on 0.0.0.0:4723
[HTTP] --> POST /wd/hub/session
[HTTP] {"desiredCapabilities":{"VERSION":"10","appActivity":"com.pixelvide.fmsapp.activities.PreSplash","appPackage":"com.pixelvide.fmsapp","automationName ":"UiAutomator2","deviceName":"IFMIS","ensureWebviewsHavePages":true,"platformName":"Android","skipServerInstallation":"true","newCommandTimeout":0,"connectHardwareKeyboard":true}}
[MJSONWP] Calling AppiumDriver.createSession() with args: [{"VERSION":"10","appActivity":"com.pixelvide.fmsapp.activities.PreSplash","appPackage":"com.pixelvide.fmsapp","automationName ":"UiAutomator2","deviceName":"IFMIS","ensureWebviewsHavePages":true,"platformName":"Android","skipServerInstallation":"true","newCommandTimeout":0,"connectHardwareKeyboard":true},null,null]
[BaseDriver] Event 'newSessionRequested' logged at 1648550713430 (16:15:13 GMT+0530 (India Standard Time))
[Appium]
[Appium] ======================================================================
[Appium] DEPRECATION WARNING:
[Appium]
[Appium] The 'automationName' capability was not provided in the desired
[Appium] capabilities for this Android session
[Appium]
[Appium] Setting 'automationName=UiAutomator2' by default and using the
[Appium] UiAutomator2 Driver
[Appium]
[Appium] The next major version of Appium (2.x) will require the
[Appium] 'automationName' capability to be set for all sessions on all
[Appium] platforms
[Appium]
[Appium] In previous versions (Appium <= 1.13.x), the default was
[Appium] 'automationName=UiAutomator1'
[Appium]
[Appium] If you wish to use that automation instead of UiAutomator2, please
[Appium] add 'automationName=UiAutomator1' to your desired capabilities
[Appium]
[Appium] For more information about drivers, please visit
[Appium] http://appium.io/docs/en/about-appium/intro/ and explore the
[Appium] 'Drivers' menu
[Appium]
[Appium] ======================================================================
[Appium]
[Appium] Appium v1.20.2 creating new AndroidUiautomator2Driver (v1.61.2) session
[Appium] Applying relaxed security to 'AndroidUiautomator2Driver' as per server command line argument. All insecure features will be enabled unless explicitly disabled by --deny-insecure
[BaseDriver] Creating session with MJSONWP desired capabilities: {
[BaseDriver] "VERSION": "10",
[BaseDriver] "appActivity": "com.pixelvide.fmsapp.activities.PreSplash",
[BaseDriver] "appPackage": "com.pixelvide.fmsapp",
[BaseDriver] "automationName ": "UiAutomator2",
[BaseDriver] "deviceName": "IFMIS",
[BaseDriver] "ensureWebviewsHavePages": true,
[BaseDriver] "platformName": "Android",
[BaseDriver] "skipServerInstallation": "true",
[BaseDriver] "newCommandTimeout": 0,
[BaseDriver] "connectHardwareKeyboard": true
[BaseDriver] }
[BaseDriver] Capability 'skipServerInstallation' changed from string to boolean. This may cause unexpected behavior
[BaseDriver] The following capabilities were provided, but are not recognized by Appium:
[BaseDriver] VERSION
[BaseDriver] automationName
[BaseDriver] connectHardwareKeyboard
[BaseDriver] Session created with session id: fb8b12a4-93be-4107-9e41-1917a3cd059a
[UiAutomator2] Starting 'com.pixelvide.fmsapp' directly on the device
[ADB] Using 'adb' from '/Users/karthik/Library/Android/sdk/platform-tools/adb'
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 start-server'
[AndroidDriver] Retrieving device list
[ADB] Trying to find a connected android device
[ADB] Getting connected devices
[ADB] Connected devices: [{"udid":"emulator-5554","state":"device"}]
[AndroidDriver] Using device: emulator-5554
[ADB] Using 'adb' from '/Users/karthik/Library/Android/sdk/platform-tools/adb'
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 start-server'
[ADB] Setting device id to emulator-5554
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell getprop ro.build.version.sdk'
[ADB] Current device property 'ro.build.version.sdk': 29
[ADB] Getting device platform version
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell getprop ro.build.version.release'
[ADB] Current device property 'ro.build.version.release': 10
[ADB] Device API level: 29
[UiAutomator2] Relaxing hidden api policy
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell 'settings put global hidden_api_policy_pre_p_apps 1;settings put global hidden_api_policy_p_apps 1;settings put global hidden_api_policy 1''
[AndroidDriver] No app sent in, not parsing package/activity
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 wait-for-device'
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell echo ping'
[AndroidDriver] Pushing settings apk to device...
[ADB] Getting install status for io.appium.settings
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.settings'
[ADB] 'io.appium.settings' is installed
[ADB] Getting package info for 'io.appium.settings'
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package io.appium.settings'
[ADB] The version name of the installed 'io.appium.settings' is greater or equal to the application version name ('3.2.1' >= '3.2.1')
[ADB] There is no need to install/upgrade '/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/io.appium.settings/apks/settings_apk-debug.apk'
[ADB] Getting IDs of all 'io.appium.settings' processes
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell 'pgrep --help; echo $?''
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pgrep -f ([[:blank:]]|^)io.appium.settings([[:blank:]]|$)'
[AndroidDriver] io.appium.settings is already running. There is no need to reset its permissions.
[Logcat] Starting logs capture with command: /Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 logcat -v threadtime
[UiAutomator2] Forwarding UiAutomator2 Server port 6790 to local port 8200
[ADB] Forwarding system: 8200 to device: 6790
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 forward tcp:8200 tcp:6790'
[UiAutomator2] 'skipServerInstallation' is set. Skipping UIAutomator2 server installation.
[UiAutomator2] No app capability. Assuming it is already on the device
[ADB] Getting install status for com.pixelvide.fmsapp
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell dumpsys package com.pixelvide.fmsapp'
[ADB] 'com.pixelvide.fmsapp' is installed
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell am force-stop com.pixelvide.fmsapp'
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pm clear com.pixelvide.fmsapp'
[AndroidDriver] Performed fast reset on the installed 'com.pixelvide.fmsapp' application (stop and clear)
[UiAutomator2] Performing shallow cleanup of automation leftovers
[UiAutomator2] No obsolete sessions have been detected (socket hang up)
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell am force-stop io.appium.uiautomator2.server.test'
[UiAutomator2] 'skipServerInstallation' is set. Attempting to use UIAutomator2 server from the device
[UiAutomator2] Waiting up to 30000ms for UiAutomator2 to be online...
[ADB] Creating ADB subprocess with args: ["-P",5037,"-s","emulator-5554","shell","am","instrument","-w","io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner"]
[WD Proxy] Matched '/status' to command name 'getStatus'
[WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8200/wd/hub/status] with no body
[WD Proxy] socket hang up
[Instrumentation] android.util.AndroidException: INSTRUMENTATION_FAILED: io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner
[Instrumentation] at com.android.commands.am.Instrument.run(Instrument.java:514)
[Instrumentation] at com.android.commands.am.Am.runInstrument(Am.java:196)
[Instrumentation] at com.android.commands.am.Am.onRun(Am.java:80)
[Instrumentation] at com.android.internal.os.BaseCommand.run(BaseCommand.java:56)
[Instrumentation] at com.android.commands.am.Am.main(Am.java:50)
[Instrumentation] at com.android.internal.os.RuntimeInit.nativeFinishInit(Native Method)
[Instrumentation] at com.android.internal.os.RuntimeInit.main(RuntimeInit.java:338)
[Instrumentation] FORTIFY: pthread_mutex_lock called on a destroyed mutex (0x97dfd000)
[Instrumentation] The process has exited with code 1
[UiAutomator2] The instrumentation process has been unexpectedly terminated. Retrying UiAutomator2 startup (#1 of 1)
[UiAutomator2] Performing strict cleanup of automation leftovers
[UiAutomator2] No obsolete sessions have been detected (socket hang up)
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell am force-stop io.appium.uiautomator2.server.test'
[ADB] Attempting to kill all uiautomator processes
[ADB] Getting IDs of all 'uiautomator' processes
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell pgrep -f ([[:blank:]]|^)uiautomator([[:blank:]]|$)'
[ADB] No 'uiautomator' process has been found
[UiAutomator2] Waiting up to 30000ms for UiAutomator2 to be online...
[ADB] Creating ADB subprocess with args: ["-P",5037,"-s","emulator-5554","shell","am","instrument","-w","io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner"]
[WD Proxy] Matched '/status' to command name 'getStatus'
[WD Proxy] Proxying [GET /status] to [GET http://127.0.0.1:8200/wd/hub/status] with no body
[WD Proxy] socket hang up
[Instrumentation] android.util.AndroidException: INSTRUMENTATION_FAILED: io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner
[Instrumentation] at com.android.commands.am.Instrument.run(Instrument.java:514)
[Instrumentation] INSTRUMENTATION_STATUS: Error=Unable to find instrumentation info for: ComponentInfo{io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner}
[Instrumentation] INSTRUMENTATION_STATUS: id=ActivityManagerService
[Instrumentation] INSTRUMENTATION_STATUS_CODE: -1
[Instrumentation] at com.android.commands.am.Am.runInstrument(Am.java:196)
[Instrumentation] at com.android.commands.am.Am.onRun(Am.java:80)
[Instrumentation] at com.android.internal.os.BaseCommand.run(BaseCommand.java:56)
[Instrumentation] at com.android.commands.am.Am.main(Am.java:50)
[Instrumentation] at com.android.internal.os.RuntimeInit.nativeFinishInit(Native Method)
[Instrumentation] at com.android.internal.os.RuntimeInit.main(RuntimeInit.java:338)
[Instrumentation] The process has exited with code 1
[UiAutomator2] The instrumentation process cannot be initialized. Make sure the application under test does not crash and investigate the logcat output.
[UiAutomator2] Deleting UiAutomator2 session
[UiAutomator2] Deleting UiAutomator2 server session
[UiAutomator2] Did not get confirmation UiAutomator2 deleteSession worked; Error was: UnknownError: An unknown server-side error occurred while processing the command. Original error: 'DELETE /' cannot be proxied to UiAutomator2 server because the instrumentation process is not running (probably crashed). Check the server log and/or the logcat output for more details
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell am force-stop com.pixelvide.fmsapp'
[Logcat] Stopping logcat capture
[ADB] Removing forwarded port socket connection: 8200
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 forward --remove tcp:8200'
[UiAutomator2] Restoring hidden api policy to the device default configuration
[ADB] Running '/Users/karthik/Library/Android/sdk/platform-tools/adb -P 5037 -s emulator-5554 shell 'settings delete global hidden_api_policy_pre_p_apps;settings delete global hidden_api_policy_p_apps;settings delete global hidden_api_policy''
[BaseDriver] Event 'newSessionStarted' logged at 1648550721359 (16:15:21 GMT+0530 (India Standard Time))
[MJSONWP] Encountered internal error running command: Error: The instrumentation process cannot be initialized. Make sure the application under test does not crash and investigate the logcat output.
[MJSONWP] at Object.errorAndThrow (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-support/lib/logging.js:94:35)
[MJSONWP] at UiAutomator2Server.startSession (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-uiautomator2-driver/lib/uiautomator2.js:241:13)
[MJSONWP] at AndroidUiautomator2Driver.startUiAutomator2Session (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-uiautomator2-driver/lib/driver.js:407:5)
[MJSONWP] at AndroidUiautomator2Driver.createSession (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/node_modules/appium-uiautomator2-driver/lib/driver.js:224:7)
[MJSONWP] at AppiumDriver.createSession (/Applications/Appium.app/Contents/Resources/app/node_modules/appium/lib/appium.js:371:35)
[HTTP] <-- POST /wd/hub/session 500 7932 ms - 274
[HTTP]
[HTTP] --> DELETE /wd/hub/session
[HTTP] {}
[HTTP] No route found for /wd/hub/session
[HTTP] <-- DELETE /wd/hub/session 404 10 ms - 211
[HTTP]

@mykola-mokhnach
Copy link
Contributor

Please provide the logcat output as requested in the actual error message

@Karthikvt143
Copy link
Author

2022-03-29 17:37:30.313 11691-11691/? I/e.myapplicatio: Late-enabling -Xcheck:jni
2022-03-29 17:37:30.811 11691-11691/? E/e.myapplicatio: Unknown bits set in runtime_flags: 0x8000
2022-03-29 17:37:30.817 11691-11691/? W/e.myapplicatio: Unexpected CPU variant for X86 using defaults: x86
2022-03-29 17:37:32.681 11691-11723/com.example.myapplication D/libEGL: Emulator has host GPU support, qemu.gles is set to 1.
2022-03-29 17:37:32.705 11691-11723/com.example.myapplication W/libc: Unable to set property "qemu.gles" to "1": connection failed; errno=13 (Permission denied)
2022-03-29 17:37:32.820 11691-11723/com.example.myapplication D/libEGL: loaded /vendor/lib/egl/libEGL_emulation.so
2022-03-29 17:37:32.825 11691-11723/com.example.myapplication D/libEGL: loaded /vendor/lib/egl/libGLESv1_CM_emulation.so
2022-03-29 17:37:32.834 11691-11723/com.example.myapplication D/libEGL: loaded /vendor/lib/egl/libGLESv2_emulation.so
2022-03-29 17:37:32.665 11691-11691/com.example.myapplication W/RenderThread: type=1400 audit(0.0:151): avc: denied { write } for name="property_service" dev="tmpfs" ino=7423 scontext=u:r:untrusted_app:s0:c150,c256,c512,c768 tcontext=u:object_r:property_socket:s0 tclass=sock_file permissive=0 app=com.example.myapplication
at com.google.android.chimera.config.ModuleManager.getCurrentModule(:com.google.android.gms@202414022@20.24.14 (040700-319035315):2)
2022-03-29 15:51:29.263 1729-7221/? W/audio_hw_generic: Hardware backing HAL too slow, could only write 0 of 661 frames
2022-03-29 17:37:34.160 11691-11691/com.example.myapplication W/e.myapplicatio: Accessing hidden method Landroid/view/View;->computeFitSystemWindows(Landroid/graphics/Rect;Landroid/graphics/Rect;)Z (greylist, reflection, allowed)
2022-03-29 15:51:29.285 1991-2011/system_process D/AutofillManagerService: Close system dialogs
2022-03-29 17:37:34.160 11691-11691/com.example.myapplication W/e.myapplicatio: Accessing hidden method Landroid/view/ViewGroup;->makeOptionalFitsSystemWindows()V (greylist, reflection, allowed)
2022-03-29 17:37:34.406 11691-11691/com.example.myapplication W/e.myapplicatio: Verification of void androidx.appcompat.widget.AppCompatButton.onTextChanged(java.lang.CharSequence, int, int, int) took 152.474ms
2022-03-29 17:37:34.646 11691-11721/com.example.myapplication D/HostConnection: HostConnection::get() New Host Connection established 0x9643eff0, tid 11721
2022-03-29 17:37:34.662 11691-11721/com.example.myapplication D/HostConnection: HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_direct_mem ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_YUV_Cache ANDROID_EMU_sync_buffer_data GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_host_side_tracing ANDROID_EMU_gles_max_version_3_0
2022-03-29 17:37:34.688 11691-11721/com.example.myapplication W/OpenGLRenderer: Failed to choose config with EGL_SWAP_BEHAVIOR_PRESERVED, retrying without...
2022-03-29 15:51:29.368 1991-2012/system_process I/ActivityTaskManager: START u0 {act=android.intent.action.MAIN cat=[android.intent.category.HOME] flg=0x10000100 cmp=com.google.android.apps.nexuslauncher/.NexusLauncherActivity (has extras)} from uid 0
2022-03-29 15:51:29.403 1729-7221/? W/audio_hw_generic: Hardware backing HAL too slow, could only write 0 of 661 frames
2022-03-29 17:37:34.699 11691-11721/com.example.myapplication D/EGL_emulation: eglMakeCurrent: 0xa27af9e0: ver 3 0 (tinfo 0xa284ee20)
2022-03-29 17:37:34.727 11691-11721/com.example.myapplication W/Gralloc3: mapper 3.x is not supported
2022-03-29 15:51:29.419 1729-7221/? W/audio_hw_generic: Hardware backing HAL too slow, could only write 0 of 661 frames
2022-03-29 17:37:34.735 11691-11721/com.example.myapplication D/HostConnection: createUnique: call
2022-03-29 15:51:29.446 1729-7221/? W/audio_hw_generic: Not supplying enough data to HAL, expected position 2881802 , only wrote 2881299
2022-03-29 17:37:34.736 11691-11721/com.example.myapplication D/HostConnection: HostConnection::get() New Host Connection established 0x964407b0, tid 11721
2022-03-29 17:37:34.737 11691-11721/com.example.myapplication D/HostConnection: HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_direct_mem ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_YUV_Cache ANDROID_EMU_sync_buffer_data GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_host_side_tracing ANDROID_EMU_gles_max_version_3_0
2022-03-29 17:37:34.740 11691-11721/com.example.myapplication D/eglCodecCommon: allocate: Ask for block of size 0x1000
2022-03-29 17:37:34.740 11691-11721/com.example.myapplication D/eglCodecCommon: allocate: ioctl allocate returned offset 0x3ff7ff000 size 0x2000
2022-03-29 17:37:34.837 11691-11721/com.example.myapplication D/EGL_emulation: eglMakeCurrent: 0xa27af9e0: ver 3 0 (tinfo 0xa284ee20)
2022-03-29 17:37:35.382 11691-11721/com.example.myapplication I/OpenGLRenderer: Davey! duration=855ms; Flags=1, IntendedVsync=5372326756593, Vsync=5372343423259, OldestInputEvent=9223372036854775807, NewestInputEvent=0, HandleInputStart=5372353000322, AnimationStart=5372353095322, PerformTraversalsStart=5372353176322, DrawStart=5372551965322, SyncQueued=5372638757322, SyncStart=5372646504322, IssueDrawCommandsStart=5372647331322, SwapBuffers=5373175252322, FrameCompleted=5373189781322, DequeueBufferDuration=833000, QueueBufferDuration=2603000,
2022-03-29 17:37:35.661 11691-11691/com.example.myapplication W/e.myapplicatio: Verification of void androidx.appcompat.view.menu.MenuItemImpl.setSubMenu(androidx.appcompat.view.menu.SubMenuBuilder) took 137.171ms
2022-03-29 17:37:35.723 11691-11721/com.example.myapplication I/OpenGLRenderer: Davey! duration=1170ms; Flags=0, IntendedVsync=5372360113812, Vsync=5373460113768, OldestInputEvent=9223372036854775807, NewestInputEvent=0, HandleInputStart=5373476466322, AnimationStart=5373476541322, PerformTraversalsStart=5373477221322, DrawStart=5373495996322, SyncQueued=5373520400322, SyncStart=5373521313322, IssueDrawCommandsStart=5373521420322, SwapBuffers=5373524149322, FrameCompleted=5373531557322, DequeueBufferDuration=1763000, QueueBufferDuration=1802000,
2022-03-29 17:37:30.313 11691-11691/? I/e.myapplicatio: Late-enabling -Xcheck:jni
2022-03-29 17:37:32.681 11691-11723/com.example.myapplication D/libEGL: Emulator has host GPU support, qemu.gles is set to 1.
2022-03-29 17:37:32.705 11691-11723/com.example.myapplication W/libc: Unable to set property "qemu.gles" to "1": connection failed; errno=13 (Permission denied)
2022-03-29 17:37:32.820 11691-11723/com.example.myapplication D/libEGL: loaded /vendor/lib/egl/libEGL_emulation.so
2022-03-29 17:37:32.825 11691-11723/com.example.myapplication D/libEGL: loaded /vendor/lib/egl/libGLESv1_CM_emulation.so
2022-03-29 17:37:32.834 11691-11723/com.example.myapplication D/libEGL: loaded /vendor/lib/egl/libGLESv2_emulation.so
2022-03-29 17:37:32.665 11691-11691/com.example.myapplication W/RenderThread: type=1400 audit(0.0:151): avc: denied { write } for name="property_service" dev="tmpfs" ino=7423 scontext=u:r:untrusted_app:s0:c150,c256,c512,c768 tcontext=u:object_r:property_socket:s0 tclass=sock_file permissive=0 app=com.example.myapplication
2022-03-29 17:37:34.160 11691-11691/com.example.myapplication W/e.myapplicatio: Accessing hidden method Landroid/view/View;->computeFitSystemWindows(Landroid/graphics/Rect;Landroid/graphics/Rect;)Z (greylist, reflection, allowed)
2022-03-29 17:37:34.160 11691-11691/com.example.myapplication W/e.myapplicatio: Accessing hidden method Landroid/view/ViewGroup;->makeOptionalFitsSystemWindows()V (greylist, reflection, allowed)
2022-03-29 17:37:34.406 11691-11691/com.example.myapplication W/e.myapplicatio: Verification of void androidx.appcompat.widget.AppCompatButton.onTextChanged(java.lang.CharSequence, int, int, int) took 152.474ms
2022-03-29 17:37:34.646 11691-11721/com.example.myapplication D/HostConnection: HostConnection::get() New Host Connection established 0x9643eff0, tid 11721
2022-03-29 17:37:34.688 11691-11721/com.example.myapplication W/OpenGLRenderer: Failed to choose config with EGL_SWAP_BEHAVIOR_PRESERVED, retrying without...
2022-03-29 17:37:34.692 11691-11721/com.example.myapplication D/EGL_emulation: eglCreateContext: 0xa27af9e0: maj 3 min 0 rcv 3
2022-03-29 17:37:34.699 11691-11721/com.example.myapplication D/EGL_emulation: eglMakeCurrent: 0xa27af9e0: ver 3 0 (tinfo 0xa284ee20)
2022-03-29 17:37:34.723 11691-11703/com.example.myapplication I/e.myapplicatio: Background concurrent copying GC freed 3246(256KB) AllocSpace objects, 0(0B) LOS objects, 49% free, 1746KB/3492KB, paused 746us total 164.273ms
2022-03-29 17:37:34.727 11691-11721/com.example.myapplication W/Gralloc3: mapper 3.x is not supported
2022-03-29 17:37:34.735 11691-11721/com.example.myapplication D/HostConnection: createUnique: call
2022-03-29 17:37:34.736 11691-11721/com.example.myapplication D/HostConnection: HostConnection::get() New Host Connection established 0x964407b0, tid 11721
2022-03-29 17:37:34.737 11691-11721/com.example.myapplication D/HostConnection: HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_direct_mem ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_YUV_Cache ANDROID_EMU_sync_buffer_data GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_host_side_tracing ANDROID_EMU_gles_max_version_3_0
2022-03-29 17:37:34.740 11691-11721/com.example.myapplication D/eglCodecCommon: allocate: Ask for block of size 0x1000
2022-03-29 17:37:34.740 11691-11721/com.example.myapplication D/eglCodecCommon: allocate: ioctl allocate returned offset 0x3ff7ff000 size 0x2000
2022-03-29 15:52:28.200 3151-3388/com.google.android.googlequicksearchbox W/ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
2022-03-29 17:37:34.837 11691-11721/com.example.myapplication D/EGL_emulation: eglMakeCurrent: 0xa27af9e0: ver 3 0 (tinfo 0xa284ee20)
2022-03-29 17:37:35.382 11691-11721/com.example.myapplication I/OpenGLRenderer: Davey! duration=855ms; Flags=1, IntendedVsync=5372326756593, Vsync=5372343423259, OldestInputEvent=9223372036854775807, NewestInputEvent=0, HandleInputStart=5372353000322, AnimationStart=5372353095322, PerformTraversalsStart=5372353176322, DrawStart=5372551965322, SyncQueued=5372638757322, SyncStart=5372646504322, IssueDrawCommandsStart=5372647331322, SwapBuffers=5373175252322, FrameCompleted=5373189781322, DequeueBufferDuration=833000, QueueBufferDuration=2603000,
2022-03-29 17:37:35.661 11691-11691/com.example.myapplication W/e.myapplicatio: Verification of void androidx.appcompat.view.menu.MenuItemImpl.setSubMenu(androidx.appcompat.view.menu.SubMenuBuilder) took 137.171ms
2022-03-29 17:37:35.668 11691-11691/com.example.myapplication I/Choreographer: Skipped 66 frames! The application may be doing too much work on its main thread.
2022-03-29 17:37:35.723 11691-11721/com.example.myapplication I/OpenGLRenderer: Davey! duration=1170ms; Flags=0, IntendedVsync=5372360113812, Vsync=5373460113768, OldestInputEvent=9223372036854775807, NewestInputEvent=0, HandleInputStart=5373476466322, AnimationStart=5373476541322, PerformTraversalsStart=5373477221322, DrawStart=5373495996322, SyncQueued=5373520400322, SyncStart=5373521313322, IssueDrawCommandsStart=5373521420322, SwapBuffers=5373524149322, FrameCompleted=5373531557322, DequeueBufferDuration=1763000, QueueBufferDuration=1802000,
2022-03-29 17:37:30.313 11691-11691/? I/e.myapplicatio: Late-enabling -Xcheck:jni
2022-03-29 17:37:30.811 11691-11691/? E/e.myapplicatio: Unknown bits set in runtime_flags: 0x8000
2022-03-29 17:37:30.817 11691-11691/? W/e.myapplicatio: Unexpected CPU variant for X86 using defaults: x86
2022-03-29 17:37:32.681 11691-11723/com.example.myapplication D/libEGL: Emulator has host GPU support, qemu.gles is set to 1.
2022-03-29 17:37:32.705 11691-11723/com.example.myapplication W/libc: Unable to set property "qemu.gles" to "1": connection failed; errno=13 (Permission denied)
2022-03-29 17:37:32.820 11691-11723/com.example.myapplication D/libEGL: loaded /vendor/lib/egl/libEGL_emulation.so
2022-03-29 17:37:32.825 11691-11723/com.example.myapplication D/libEGL: loaded /vendor/lib/egl/libGLESv1_CM_emulation.so
2022-03-29 17:37:32.834 11691-11723/com.example.myapplication D/libEGL: loaded /vendor/lib/egl/libGLESv2_emulation.so
2022-03-29 17:37:32.665 11691-11691/com.example.myapplication W/RenderThread: type=1400 audit(0.0:151): avc: denied { write } for name="property_service" dev="tmpfs" ino=7423 scontext=u:r:untrusted_app:s0:c150,c256,c512,c768 tcontext=u:object_r:property_socket:s0 tclass=sock_file permissive=0 app=com.example.myapplication
2022-03-29 17:37:34.160 11691-11691/com.example.myapplication W/e.myapplicatio: Accessing hidden method Landroid/view/View;->computeFitSystemWindows(Landroid/graphics/Rect;Landroid/graphics/Rect;)Z (greylist, reflection, allowed)
2022-03-29 17:37:34.160 11691-11691/com.example.myapplication W/e.myapplicatio: Accessing hidden method Landroid/view/ViewGroup;->makeOptionalFitsSystemWindows()V (greylist, reflection, allowed)
2022-03-29 17:37:34.406 11691-11691/com.example.myapplication W/e.myapplicatio: Verification of void androidx.appcompat.widget.AppCompatButton.onTextChanged(java.lang.CharSequence, int, int, int) took 152.474ms
2022-03-29 17:37:34.646 11691-11721/com.example.myapplication D/HostConnection: HostConnection::get() New Host Connection established 0x9643eff0, tid 11721
2022-03-29 17:37:34.662 11691-11721/com.example.myapplication D/HostConnection: HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_direct_mem ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_YUV_Cache ANDROID_EMU_sync_buffer_data GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_host_side_tracing ANDROID_EMU_gles_max_version_3_0
2022-03-29 17:37:34.688 11691-11721/com.example.myapplication W/OpenGLRenderer: Failed to choose config with EGL_SWAP_BEHAVIOR_PRESERVED, retrying without...
2022-03-29 17:37:34.692 11691-11721/com.example.myapplication D/EGL_emulation: eglCreateContext: 0xa27af9e0: maj 3 min 0 rcv 3
2022-03-29 17:37:34.699 11691-11721/com.example.myapplication D/EGL_emulation: eglMakeCurrent: 0xa27af9e0: ver 3 0 (tinfo 0xa284ee20)
2022-03-29 17:37:34.723 11691-11703/com.example.myapplication I/e.myapplicatio: Background concurrent copying GC freed 3246(256KB) AllocSpace objects, 0(0B) LOS objects, 49% free, 1746KB/3492KB, paused 746us total 164.273ms
2022-03-29 17:37:34.727 11691-11721/com.example.myapplication W/Gralloc3: mapper 3.x is not supported
2022-03-29 17:37:34.735 11691-11721/com.example.myapplication D/HostConnection: createUnique: call
2022-03-29 17:37:34.736 11691-11721/com.example.myapplication D/HostConnection: HostConnection::get() New Host Connection established 0x964407b0, tid 11721
2022-03-29 17:37:34.737 11691-11721/com.example.myapplication D/HostConnection: HostComposition ext ANDROID_EMU_CHECKSUM_HELPER_v1 ANDROID_EMU_native_sync_v2 ANDROID_EMU_native_sync_v3 ANDROID_EMU_native_sync_v4 ANDROID_EMU_dma_v1 ANDROID_EMU_direct_mem ANDROID_EMU_host_composition_v1 ANDROID_EMU_host_composition_v2 ANDROID_EMU_YUV_Cache ANDROID_EMU_sync_buffer_data GL_OES_EGL_image_external_essl3 GL_OES_vertex_array_object GL_KHR_texture_compression_astc_ldr ANDROID_EMU_host_side_tracing ANDROID_EMU_gles_max_version_3_0
2022-03-29 17:37:34.740 11691-11721/com.example.myapplication D/eglCodecCommon: allocate: Ask for block of size 0x1000
2022-03-29 17:37:34.740 11691-11721/com.example.myapplication D/eglCodecCommon: allocate: ioctl allocate returned offset 0x3ff7ff000 size 0x2000
2022-03-29 17:37:34.837 11691-11721/com.example.myapplication D/EGL_emulation: eglMakeCurrent: 0xa27af9e0: ver 3 0 (tinfo 0xa284ee20)
2022-03-29 17:37:35.382 11691-11721/com.example.myapplication I/OpenGLRenderer: Davey! duration=855ms; Flags=1, IntendedVsync=5372326756593, Vsync=5372343423259, OldestInputEvent=9223372036854775807, NewestInputEvent=0, HandleInputStart=5372353000322, AnimationStart=5372353095322, PerformTraversalsStart=5372353176322, DrawStart=5372551965322, SyncQueued=5372638757322, SyncStart=5372646504322, IssueDrawCommandsStart=5372647331322, SwapBuffers=5373175252322, FrameCompleted=5373189781322, DequeueBufferDuration=833000, QueueBufferDuration=2603000,
2022-03-29 17:37:35.661 11691-11691/com.example.myapplication W/e.myapplicatio: Verification of void androidx.appcompat.view.menu.MenuItemImpl.setSubMenu(androidx.appcompat.view.menu.SubMenuBuilder) took 137.171ms
2022-03-29 17:37:35.668 11691-11691/com.example.myapplication I/Choreographer: Skipped 66 frames! The application may be doing too much work on its main thread.
2022-03-29 17:37:35.723 11691-11721/com.example.myapplication I/OpenGLRenderer: Davey! duration=1170ms; Flags=0, IntendedVsync=5372360113812, Vsync=5373460113768, OldestInputEvent=9223372036854775807, NewestInputEvent=0, HandleInputStart=5373476466322, AnimationStart=5373476541322, PerformTraversalsStart=5373477221322, DrawStart=5373495996322, SyncQueued=5373520400322, SyncStart=5373521313322, IssueDrawCommandsStart=5373521420322, SwapBuffers=5373524149322, FrameCompleted=5373531557322, DequeueBufferDuration=1763000, QueueBufferDuration=1802000,

@mykola-mokhnach
Copy link
Contributor

Are you sure it's the correct log snippet taken within the same time range the automation session has been starting?

@Karthikvt143
Copy link
Author

Yes...

@mykola-mokhnach
Copy link
Contributor

Then probably something is missing from there. I'd expect some exception stack traces to be present in the log

@Karthikvt143
Copy link
Author

can you tell where could I find the exception logs

@Karthikvt143
Copy link
Author

@mykola-mokhnach will this help you with something

--------- beginning of main
03-29 20:18:04.023 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:18:04.025 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:18:04.025 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:18:04.025 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:18:08.457 15544 15916 E memtrack: Couldn't load memtrack module
03-29 20:18:08.457 15544 15916 W android.os.Debug: failed to get memory consumption info: -1
03-29 20:18:09.030 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:18:09.031 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:18:09.033 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:18:09.034 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:18:14.038 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:18:14.041 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:18:14.041 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:18:14.041 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:18:19.046 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:18:19.049 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:18:19.049 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:18:19.049 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:18:24.054 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:18:24.059 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:18:24.060 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:18:24.060 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:18:24.291 1828 1828 E netmgr : Failed to open QEMU pipe 'qemud:network': Invalid argument
03-29 20:18:25.555 1834 1834 E wifi_forwarder: RemoteConnection failed to initialize: RemoteConnection failed to open pipe
03-29 20:18:29.066 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:18:29.068 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:18:29.069 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:18:29.069 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:18:34.074 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:18:34.077 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:18:34.078 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:18:34.078 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:18:39.082 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:18:39.084 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:18:39.085 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:18:39.085 3151 3388 I MicroDetectionWorker: #onError(false)
--------- beginning of system
03-29 20:18:40.163 1991 1991 V SettingsProvider: Notifying for 0: content://settings/global/hidden_api_policy_pre_p_apps
03-29 20:18:40.195 1991 1991 V SettingsProvider: Notifying for 0: content://settings/global/hidden_api_policy_p_apps
03-29 20:18:40.216 1991 1991 V SettingsProvider: Notifying for 0: content://settings/global/hidden_api_policy
03-29 20:18:40.732 1991 3318 I ActivityManager: Force stopping com.pixelvide.fmsapp appid=10148 user=0: from pid 15967
03-29 20:18:40.756 2438 2438 D CarrierSvcBindHelper: No carrier app for: 0
03-29 20:18:40.798 1991 2008 I ActivityManager: Force stopping com.pixelvide.fmsapp appid=10148 user=0: clear data
03-29 20:18:40.801 1991 2020 I ActivityManager: Force stopping com.pixelvide.fmsapp appid=10148 user=-1: clearApplicationUserData
03-29 20:18:40.805 1798 1798 I keystore: clear_uid 10148
03-29 20:18:40.813 1991 2008 I ConditionProviders: Disallowing condition provider com.pixelvide.fmsapp
03-29 20:18:40.816 1766 2204 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:40.823 1766 2590 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:40.823 1766 1959 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:40.828 1766 8817 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:40.831 1766 2204 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:40.834 1766 2590 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:40.841 1766 2204 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:40.841 1766 1959 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:40.849 2438 2438 D CarrierSvcBindHelper: No carrier app for: 0
03-29 20:18:40.849 1766 8817 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:40.851 1766 1959 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:40.852 1766 2590 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:40.857 1766 2204 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:40.860 1766 8817 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:40.860 2168 2168 I GeofencerStateMachine: removeGeofences: removeRequest=RemoveGeofencingRequest[REMOVE_ALL packageName=com.pixelvide.fmsapp]
03-29 20:18:40.862 1766 1959 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:40.863 4726 4726 I CarrierServices: [2] RcsAutoStartReceiver.a: enableRcs changed from true to true
03-29 20:18:40.868 1766 2204 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:40.869 1766 2590 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:40.871 1766 8817 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:40.881 1766 1959 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:40.883 1766 2590 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:40.887 1766 2204 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:40.895 1766 8817 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:40.900 1766 8817 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:40.904 1766 1766 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:40.906 1766 2590 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:40.906 1766 1958 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:40.913 1766 1958 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:40.914 1766 8817 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:40.935 1766 1958 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:40.941 1766 1766 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:40.946 1766 1959 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:40.952 1766 2590 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:40.952 1780 1780 E adbd : failed to connect to socket 'tcp:6790': Connection refused
03-29 20:18:40.966 1766 1766 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:40.972 1766 2590 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:40.979 1766 2590 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:41.000 1766 1958 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:41.003 1766 1959 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:41.012 1766 8817 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:41.022 8606 15976 I MediaProvider: Begin Intent { act=android.intent.action.PACKAGE_DATA_CLEARED dat=package:com.pixelvide.fmsapp flg=0x1000010 cmp=com.android.providers.media/.MediaService (has extras) }
03-29 20:18:41.028 1766 8817 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:41.031 1766 1959 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:41.033 1766 1766 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:41.050 1766 1766 I chatty : uid=1041(audioserver) /system/bin/audioserver identical 1 line
03-29 20:18:41.053 1766 1766 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:41.056 1766 8817 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:41.060 1766 1766 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:41.062 1766 8817 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:41.066 1766 1766 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:41.073 1766 2590 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:41.077 8606 15976 I MediaProvider: End Intent { act=android.intent.action.PACKAGE_DATA_CLEARED dat=package:com.pixelvide.fmsapp flg=0x1000010 cmp=com.android.providers.media/.MediaService (has extras) }
03-29 20:18:41.080 1766 1959 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:41.082 1766 1766 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:41.085 1766 1958 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:41.090 1766 1958 D AF::TrackHandle: OpPlayAudio: track:58 usage:13 not muted
03-29 20:18:41.102 1766 1766 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:41.114 1766 1959 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:41.117 1766 1766 D AF::TrackHandle: OpPlayAudio: track:56 usage:13 not muted
03-29 20:18:41.136 1766 2590 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:41.147 1766 1959 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:41.151 1766 1959 I chatty : uid=1041(audioserver) Binder:1766_2 identical 1 line
03-29 20:18:41.154 1766 1959 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:41.165 1766 2590 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:41.175 1766 2590 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:41.185 1766 1959 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:41.187 1766 2590 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:41.209 1766 1766 D AF::TrackHandle: OpPlayAudio: track:57 usage:13 not muted
03-29 20:18:41.209 1766 8817 D AF::TrackHandle: OpPlayAudio: track:55 usage:13 not muted
03-29 20:18:41.217 2168 15989 I Fitness : FitCleanupIntentOperation received Intent android.intent.action.PACKAGE_DATA_CLEARED [CONTEXT service_id=17 ]
03-29 20:18:41.235 1991 2405 W ActivityManager: Invalid packageName: io.appium.uiautomator2.server.test
03-29 20:18:41.258 2528 9837 I Icing : doRemovePackageData com.pixelvide.fmsapp
03-29 20:18:41.264 1780 1780 E adbd : failed to connect to socket 'tcp:6790': Connection refused
03-29 20:18:41.272 8579 15819 I ProvidersCache: Provider returned no roots. Possibly naughty: com.google.android.apps.docs.storage
03-29 20:18:41.557 15995 15995 D AndroidRuntime: >>>>>> START com.android.internal.os.RuntimeInit uid 2000 <<<<<<
03-29 20:18:41.549 15995 15995 W app_process: type=1400 audit(0.0:224): avc: denied { read } for name="u:object_r:device_config_runtime_native_boot_prop:s0" dev="tmpfs" ino=7329 scontext=u:r:shell:s0 tcontext=u:object_r:device_config_runtime_native_boot_prop:s0 tclass=file permissive=0
03-29 20:18:41.564 15995 15995 E libc : Access denied finding property "persist.device_config.runtime_native_boot.enable_apex_image"
03-29 20:18:41.564 15995 15995 I AndroidRuntime: Using default boot image
03-29 20:18:41.564 15995 15995 E libc : Access denied finding property "persist.device_config.runtime_native_boot.disable_lock_profiling"
03-29 20:18:41.564 15995 15995 I AndroidRuntime: Leaving lock profiling enabled
03-29 20:18:41.564 15995 15995 E libc : Access denied finding property "persist.device_config.runtime_native_boot.enable_generational_cc"
03-29 20:18:41.549 15995 15995 W app_process: type=1400 audit(0.0:225): avc: denied { read } for name="u:object_r:device_config_runtime_native_boot_prop:s0" dev="tmpfs" ino=7329 scontext=u:r:shell:s0 tcontext=u:object_r:device_config_runtime_native_boot_prop:s0 tclass=file permissive=0
03-29 20:18:41.549 15995 15995 W app_process: type=1400 audit(0.0:226): avc: denied { read } for name="u:object_r:device_config_runtime_native_boot_prop:s0" dev="tmpfs" ino=7329 scontext=u:r:shell:s0 tcontext=u:object_r:device_config_runtime_native_boot_prop:s0 tclass=file permissive=0
03-29 20:18:41.568 15995 15995 I app_process: Core platform API reporting enabled, enforcing=false
03-29 20:18:41.665 15995 15995 D ICU : Time zone APEX file found: /apex/com.android.tzdata/etc/icu/icu_tzdata.dat
03-29 20:18:41.672 15995 15995 W app_process: Unexpected CPU variant for X86 using defaults: x86
03-29 20:18:41.680 15995 15995 I app_process: The ClassLoaderContext is a special shared library.
03-29 20:18:41.697 15995 15995 W app_process: JNI RegisterNativeMethods: attempt to register 0 native methods for android.media.AudioAttributes
03-29 20:18:41.703 15995 15995 D AndroidRuntime: Calling main entry com.android.commands.am.Am
03-29 20:18:41.712 1991 2945 W ActivityManager: Unable to find instrumentation info for: ComponentInfo{io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner}
03-29 20:18:41.715 1991 16007 W Binder : Outgoing transactions from this process must be FLAG_ONEWAY
03-29 20:18:41.715 1991 16007 W Binder : java.lang.Throwable
03-29 20:18:41.715 1991 16007 W Binder : at android.os.BinderProxy.transact(BinderProxy.java:480)
03-29 20:18:41.715 1991 16007 W Binder : at android.app.IInstrumentationWatcher$Stub$Proxy.instrumentationStatus(IInstrumentationWatcher.java:172)
03-29 20:18:41.715 1991 16007 W Binder : at com.android.server.am.InstrumentationReporter$MyThread.run(InstrumentationReporter.java:80)
03-29 20:18:41.718 15995 15995 I app_process: System.exit called, status: 1
03-29 20:18:41.718 15995 15995 I AndroidRuntime: VM exiting with result code 1.
03-29 20:18:42.275 1780 1780 E adbd : failed to connect to socket 'tcp:6790': Connection refused
03-29 20:18:42.337 1991 2405 W ActivityManager: Invalid packageName: io.appium.uiautomator2.server.test
03-29 20:18:44.091 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:18:44.098 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:18:44.099 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:18:44.099 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:18:45.435 1780 1780 E adbd : failed to connect to socket 'tcp:6790': Connection refused
03-29 20:18:45.559 16018 16018 W app_process: type=1400 audit(0.0:227): avc: denied { read } for name="u:object_r:device_config_runtime_native_boot_prop:s0" dev="tmpfs" ino=7329 scontext=u:r:shell:s0 tcontext=u:object_r:device_config_runtime_native_boot_prop:s0 tclass=file permissive=0
03-29 20:18:45.559 16018 16018 W app_process: type=1400 audit(0.0:228): avc: denied { read } for name="u:object_r:device_config_runtime_native_boot_prop:s0" dev="tmpfs" ino=7329 scontext=u:r:shell:s0 tcontext=u:object_r:device_config_runtime_native_boot_prop:s0 tclass=file permissive=0
03-29 20:18:45.569 16018 16018 D AndroidRuntime: >>>>>> START com.android.internal.os.RuntimeInit uid 2000 <<<<<<
03-29 20:18:45.572 16018 16018 E libc : Access denied finding property "persist.device_config.runtime_native_boot.enable_apex_image"
03-29 20:18:45.572 16018 16018 I AndroidRuntime: Using default boot image
03-29 20:18:45.572 16018 16018 E libc : Access denied finding property "persist.device_config.runtime_native_boot.disable_lock_profiling"
03-29 20:18:45.559 16018 16018 W app_process: type=1400 audit(0.0:229): avc: denied { read } for name="u:object_r:device_config_runtime_native_boot_prop:s0" dev="tmpfs" ino=7329 scontext=u:r:shell:s0 tcontext=u:object_r:device_config_runtime_native_boot_prop:s0 tclass=file permissive=0
03-29 20:18:45.572 16018 16018 I AndroidRuntime: Leaving lock profiling enabled
03-29 20:18:45.572 16018 16018 E libc : Access denied finding property "persist.device_config.runtime_native_boot.enable_generational_cc"
03-29 20:18:45.579 16018 16018 I app_process: Core platform API reporting enabled, enforcing=false
03-29 20:18:45.642 16018 16018 D ICU : Time zone APEX file found: /apex/com.android.tzdata/etc/icu/icu_tzdata.dat
03-29 20:18:45.649 16018 16018 W app_process: Unexpected CPU variant for X86 using defaults: x86
03-29 20:18:45.650 16018 16018 I app_process: The ClassLoaderContext is a special shared library.
03-29 20:18:45.664 16018 16018 W app_process: JNI RegisterNativeMethods: attempt to register 0 native methods for android.media.AudioAttributes
03-29 20:18:45.668 16018 16018 D AndroidRuntime: Calling main entry com.android.commands.am.Am
03-29 20:18:45.676 1991 2945 W ActivityManager: Unable to find instrumentation info for: ComponentInfo{io.appium.uiautomator2.server.test/androidx.test.runner.AndroidJUnitRunner}
03-29 20:18:45.677 1991 16007 W Binder : Outgoing transactions from this process must be FLAG_ONEWAY
03-29 20:18:45.677 1991 16007 W Binder : java.lang.Throwable
03-29 20:18:45.677 1991 16007 W Binder : at android.os.BinderProxy.transact(BinderProxy.java:480)
03-29 20:18:45.677 1991 16007 W Binder : at android.app.IInstrumentationWatcher$Stub$Proxy.instrumentationStatus(IInstrumentationWatcher.java:172)
03-29 20:18:45.677 1991 16007 W Binder : at com.android.server.am.InstrumentationReporter$MyThread.run(InstrumentationReporter.java:80)
03-29 20:18:45.689 16018 16018 I app_process: System.exit called, status: 1
03-29 20:18:45.689 16018 16018 I AndroidRuntime: VM exiting with result code 1.
03-29 20:18:46.491 1991 2945 I ActivityManager: Force stopping com.pixelvide.fmsapp appid=10148 user=0: from pid 16033
03-29 20:18:46.505 2438 2438 D CarrierSvcBindHelper: No carrier app for: 0
03-29 20:18:46.590 1991 1991 V SettingsProvider: Notifying for 0: content://settings/global/hidden_api_policy_pre_p_apps
03-29 20:18:46.611 1991 1991 V SettingsProvider: Notifying for 0: content://settings/global/hidden_api_policy_p_apps
03-29 20:18:46.637 1991 1991 V SettingsProvider: Notifying for 0: content://settings/global/hidden_api_policy
03-29 20:18:49.110 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:18:49.114 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:18:49.115 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:18:49.115 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:18:50.828 1991 2013 I DropBoxManagerService: add tag=system_server_strictmode isTagEnabled=true flags=0x2
03-29 20:18:51.891 2168 15284 E WakeLock: GCM_HB_ALARM release without a matched acquire!
03-29 20:18:50.846 1991 2013 I chatty : uid=1000(system) android.io identical 6 lines
03-29 20:18:50.849 1991 2013 I DropBoxManagerService: add tag=system_server_strictmode isTagEnabled=true flags=0x2
03-29 20:18:52.839 1991 2020 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.stats.service.DropBoxEntryAddedReceiver
03-29 20:18:52.840 1991 2020 W BroadcastQueue: Background execution not allowed: receiving Intent { act=android.intent.action.DROPBOX_ENTRY_ADDED flg=0x10 (has extras) } to com.google.android.gms/.chimera.GmsIntentOperationService$PersistentTrustedReceiver
03-29 20:18:54.120 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:18:54.121 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:18:54.121 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:18:54.121 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:18:59.128 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:18:59.130 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:18:59.133 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:18:59.133 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:18:59.934 1991 2019 E memtrack: Couldn't load memtrack module
03-29 20:18:59.934 1991 2019 W android.os.Debug: failed to get memory consumption info: -1
03-29 20:19:00.010 2186 2186 D KeyguardClockSwitch: Updating clock: 819
03-29 20:19:04.139 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:19:04.141 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:19:04.146 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:19:04.146 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:19:09.152 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:19:09.154 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:19:09.159 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:19:09.160 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:19:14.164 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:19:14.165 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:19:14.167 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:19:14.167 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:19:19.172 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:19:19.173 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:19:19.174 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:19:19.174 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:19:24.177 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:19:24.178 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:19:24.180 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:19:24.180 3151 3388 I MicroDetectionWorker: #onError(false)
03-29 20:19:24.354 1828 1828 E netmgr : Failed to open QEMU pipe 'qemud:network': Invalid argument
03-29 20:19:25.617 1834 1834 E wifi_forwarder: RemoteConnection failed to initialize: RemoteConnection failed to open pipe
03-29 20:19:29.183 3151 3388 I MicroDetectionWorker: #startMicroDetector [speakerMode: 0]
03-29 20:19:29.186 3151 3388 W ErrorReporter: reportError [type: 211, code: 393244, bug: 0]: errorCode: 393244, engine: 0
03-29 20:19:29.186 3151 3388 I MicroDetector: Keeping mic open: false
03-29 20:19:29.187 3151 3388 I MicroDetectionWorker: #onError(false)

@Karthikvt143
Copy link
Author

can someone help in resolving the above issue, I have uninstalled everything and started installing from scratch but the same error reflecting again and again

@JonaDxc
Copy link

JonaDxc commented Aug 28, 2024

how you solve it finally? I'm facing the same

# for free to join this conversation on GitHub. Already have an account? # to comment
Labels
None yet
Projects
None yet
Development

No branches or pull requests

3 participants