App starts with no sound

matsu · August 24, 2021, 4:19am

Hi all developers,
my app shows some issues:

the app does not start (screen is blackout and no response)
the app starts with no sound (runs correctly except for audio)
objects in the app fly forward far away

The issues happen at approximately 50% probability, i.e., it can run correctly at ~50%.

These issues are shown on dev-kit. Even if I update the firmwares, the issues still happen. I also have a Nreal glasses for consumer, but I do not see these issues on the consumer version. So, I guess there is a cause in a dev-kit device. However, demo apps in a dev-kit (butterfly, portal, cat, etc.) do not show these issues. So, it might be my app which is causing the issues, but I cannot guess any cause.

If you have the same/similar issues and any solution, I would like to know it.

Thank you.

matsu · August 28, 2021, 5:52am

08-27 11:36:24.536  1255  1562 E ANDR-PERF-JNI: com_qualcomm_qtiperformance_native_perf_io_prefetch_start
08-27 11:36:24.537   671   671 E ANDR-IOP: IOP HAL: Received pkg_name = com.Company.AppName pid = -1
08-27 11:36:24.541  1255  1562 E ActivityTrigger: activityStartTrigger: not whiteListedcom.Company.AppName/com.unity3d.player.UnityPlayerActivity/1
08-27 11:36:24.543  1255  1562 E ActivityTrigger: activityResumeTrigger: not whiteListedcom.Company.AppName/com.unity3d.player.UnityPlayerActivity/1
08-27 11:36:24.549   671   736 E ANDR-IOP: is_in_recent_list is TRUE
08-27 11:36:24.549   671   736 E ANDR-IOP: io prefetch is deactivate
08-27 11:36:24.553  1255  1562 E ActivityTrigger: activityResumeTrigger: not whiteListedcom.Company.AppName/com.unity3d.player.UnityPlayerActivity/1
08-27 11:36:24.610  1020  1020 E update_engine: [0827/113624:ERROR:update_attempter_android.cc(244)] CancelUpdate(...): Domain=update_engine, Code=generic_error, Message=No ongoing update to cancel.
08-27 11:36:24.610  1020  1020 E update_engine: [0827/113624:ERROR:update_attempter_android.cc(73)] Replying with failure: CancelUpdate@system/update_engine/update_attempter_android.cc:244: No ongoing update to cancel.
08-27 11:36:24.610  5540  5559 E UpdateHelper: Failed to suspend running update
08-27 11:36:24.610  5540  5559 E UpdateHelper: android.os.ServiceSpecificException: No ongoing update to cancel. (code 1)
08-27 11:36:24.610  5540  5559 E UpdateHelper:  at android.os.Parcel.readException(Parcel.java:2027)
08-27 11:36:24.610  5540  5559 E UpdateHelper:  at android.os.Parcel.readException(Parcel.java:1959)
08-27 11:36:24.610  5540  5559 E UpdateHelper:  at android.os.IUpdateEngine$Stub$Proxy.cancel(IUpdateEngine.java:222)
08-27 11:36:24.610  5540  5559 E UpdateHelper:  at android.os.UpdateEngine.cancel(UpdateEngine.java:206)
08-27 11:36:24.610  5540  5559 E UpdateHelper:  at ai.nreal.update.UpdateManager.suspend(UpdateManager.java:210)
08-27 11:36:24.610  5540  5559 E UpdateHelper:  at com.nreal.launcherupdate.UpdateHelper.pauseSystemUpdate(UpdateHelper.java:57)
08-27 11:36:24.610  5540  5559 E UpdateHelper:  at com.unity3d.player.UnityPlayer.nativePause(Native Method)
08-27 11:36:24.610  5540  5559 E UpdateHelper:  at com.unity3d.player.UnityPlayer.l(Unknown Source:0)
08-27 11:36:24.610  5540  5559 E UpdateHelper:  at com.unity3d.player.UnityPlayer$23.run(Unknown Source:2)
08-27 11:36:24.610  5540  5559 E UpdateHelper:  at android.os.Handler.handleCallback(Handler.java:790)
08-27 11:36:24.610  5540  5559 E UpdateHelper:  at android.os.Handler.dispatchMessage(Handler.java:99)
08-27 11:36:24.610  5540  5559 E UpdateHelper:  at android.os.Looper.loop(Looper.java:164)
08-27 11:36:24.610  5540  5559 E UpdateHelper:  at com.unity3d.player.UnityPlayer$e.run(Unknown Source:32)
08-27 11:36:24.612  1397  2274 E bt_btif : bta_gattc_cancel_bk_conn: failed
08-27 11:36:24.613  1397  2274 E bt_btif : No such connection need to be cancelled
08-27 11:36:24.613  1397  2274 E bt_btif : bta_gattc_cancel_bk_conn: failed
08-27 11:36:24.672  1255  4135 E ActivityTrigger: activityResumeTrigger: not whiteListedcom.Company.AppName/com.unity3d.player.UnityPlayerActivity/1
08-27 11:36:24.672  1255  4135 E ActivityManager: applyOptionsLocked: Unknown animationType=0
08-27 11:36:24.686   689  2167 E SurfaceFlinger: Failed to find layer (Background for - SurfaceView - Sys2037:ai.nreal.launcher/com.unity3d.player.UnityPlayerActivity#0) in layer parent (no-parent).
08-27 11:36:24.686   689  1356 E SurfaceFlinger: Failed to find layer (SurfaceView - Sys2037:ai.nreal.launcher/com.unity3d.player.UnityPlayerActivity#0) in layer parent (no-parent).
08-27 11:36:24.721  5540  5559 E ai.nreal.launcher: ACTION_PACKAGE_ADDED ,ACTION_PACKAGE_REMOVED unregistered
08-27 11:36:24.730   671   671 E ANDR-IOP: IOP HAL: Received pkg_name = com.Company.AppName pid = 0
08-27 11:36:24.804   671   671 E ANDR-IOP: IOP HAL: Received pkg_name = com.Company.AppName pid = 0
08-27 11:36:24.805  1255  1297 E ANDR-PERF-JNI: com_qualcomm_qtiperformance_native_perf_io_prefetch_start
08-27 11:36:24.805   671   671 E ANDR-IOP: IOP HAL: Received pkg_name = com.Company.AppName pid = 5948
08-27 11:36:24.816   671   736 E ANDR-IOP: io prefetch Capture is deactivated
08-27 11:36:24.843  5948  5966 E SchedPolicy: set_timerslack_ns write failed: Operation not permitted
08-27 11:36:24.844  5948  5966 E SchedPolicy: set_timerslack_ns write failed: Operation not permitted
08-27 11:36:24.901   647  3410 E msm8974_platform: platform_check_backends_match: Invalid snd_device =
08-27 11:36:24.901   647  3410 E ACDB-LOADER: Error: ACDB AudProc vol returned = -19
08-27 11:36:24.901   647  3410 E ACDB-LOADER: Error: ACDB_CMD_GET_AFE_COMMON_TABLE_SIZE Returned = -19
08-27 11:36:24.901   647  3410 E ACDB-LOADER: Error: ACDB AFE returned = -19
08-27 11:36:25.007   647  1658 E audio_hw_primary: out_set_volume: compress mute 0
08-27 11:36:25.007   647  1658 E audio_hw_primary: out_set_compr_volume:ctl for mixer cmd - Compress Playback 15 Volume, left 0.504081, right 0.504081
08-27 11:36:25.009   647  6005 E msm8974_platform: platform_check_backends_match: Invalid snd_device =
08-27 11:36:25.010   647  6005 E ACDB-LOADER: Error: ACDB_CMD_GET_AUDPROC_COMMON_TABLE_SIZE Returned = -19
08-27 11:36:25.010   647  6005 E ACDB-LOADER: Error: ACDB audproc returned = -19
08-27 11:36:25.011   647  6005 E ACDB-LOADER: Error: ACDB AudProc vol returned = -19
08-27 11:36:25.011   647  6005 E ACDB-LOADER: Error: ACDB_CMD_GET_AFE_COMMON_TABLE_SIZE Returned = -19
08-27 11:36:25.011   647  6005 E ACDB-LOADER: Error: ACDB AFE returned = -19
08-27 11:36:25.763   648  1739 E vendor.qti.bluetooth@1.0-wake_lock: Release wakelock is released
08-27 11:36:26.547   672   713 E ANDR-PERF-OPTSHANDLER: perf_lock_rel: updated /sys/class/scsi_host/host0/../../../clkscale_enable with 1
08-27 11:36:26.547   672   713 E ANDR-PERF-OPTSHANDLER:  return value 2
08-27 11:36:30.560  5948  6033 E Unity   : [Error][NativeConfigration] SetTrackableImageDataBase: InvalidArgument
08-27 11:36:30.560  5948  6033 E Unity   : NRKernal.DefaultLogHelper:Log(LogLevel, Object)
08-27 11:36:30.560  5948  6033 E Unity   : NRKernal.NativeErrorListener:Check(NativeResult, Object, String, Boolean)
08-27 11:36:30.560  5948  6033 E Unity   : NRKernal.NativeConfigration:SetTrackableImageDataBase(UInt64, UInt64)
08-27 11:36:30.560  5948  6033 E Unity   : NRKernal.<>c__DisplayClass10_0:<UpdateImageTrackingConfig>b__0()
08-27 11:36:30.560  5948  6033 E Unity   : System.Action:Invoke()
08-27 11:36:30.560  5948  6033 E Unity   : System.Threading.Tasks.Task:Execute()
08-27 11:36:30.560  5948  6033 E Unity   : System.Threading.ContextCallback:Invoke(Object)
08-27 11:36:30.560  5948  6033 E Unity   : System.Threading.ExecutionContext:RunInternal(ExecutionContext, ContextCallback, Object, Boolean)
08-27 11:36:30.560  5948  6033 E Unity   : System.Threading.Tasks.Task:ExecuteWithThreadLocal(Task&)
08-27 11:36:30.560  5948  6033 E Unity   : System.Threading.Tasks.Task:ExecuteEntry(Boolean)
08-27 11:36:30.560  5948  6033 E Unity   : System.Threading.ThreadPoolWorkQueue:Dispatch()
08-27 11:36:30.560  5948  6033 E Unity   :
08-27 11:36:31.125  5948  6031 E NREAL   : [2021-08-27 11:36:31.124] [ERROR] [NRSensor] Generic Camera data_bytes error, should be = 1843200, cur = 1445896
08-27 11:36:31.367  5948  6007 E NREAL   : [2021-08-27 11:36:31.367] [ERROR] [NRSDK] Generic Camera data_bytes error, should be = 615680, cur = 196536
08-27 11:36:31.372  5948  6007 E NREAL   : [2021-08-27 11:36:31.371] [ERROR] [NRSDK] Generic Camera data_bytes error, should be = 615680, cur = 65465
08-27 11:36:31.372  5948  6007 E NREAL   : [2021-08-27 11:36:31.371] [ERROR] [NRSDK] Generic Camera data_bytes error, should be = 615680, cur = 32687
08-27 11:36:31.372  5948  6007 E NREAL   : [2021-08-27 11:36:31.372] [ERROR] [NRSDK] Generic Camera data_bytes error, should be = 615680, cur = 32685
08-27 11:36:31.373  5948  6007 E NREAL   : [2021-08-27 11:36:31.373] [ERROR] [NRSDK] Generic Camera data_bytes error, should be = 615680, cur = 32684
08-27 11:36:31.373  5948  6007 E NREAL   : [2021-08-27 11:36:31.373] [ERROR] [NRSDK] Generic Camera data_bytes error, should be = 615680, cur = 32682
08-27 11:36:31.374  5948  6007 E NREAL   : [2021-08-27 11:36:31.374] [ERROR] [NRSDK] Generic Camera data_bytes error, should be = 615680, cur = 32680
08-27 11:36:31.375  5948  6007 E NREAL   : [2021-08-27 11:36:31.375] [ERROR] [NRSDK] Generic Camera data_bytes error, should be = 615680, cur = 32679
08-27 11:36:31.376  5948  6007 E NREAL   : [2021-08-27 11:36:31.376] [ERROR] [NRSDK] Generic Camera data_bytes error, should be = 615680, cur = 32677
08-27 11:36:31.376  5948  6007 E NREAL   : [2021-08-27 11:36:31.376] [ERROR] [NRSDK] Generic Camera data_bytes error, should be = 615680, cur = 32675
08-27 11:36:31.391  5948  6007 E NREAL   : [2021-08-27 11:36:31.391] [ERROR] [NRSDK] Generic Camera data_bytes error, should be = 615680, cur = 32641
08-27 11:36:31.419  5948  6007 E NREAL   : [2021-08-27 11:36:31.419] [ERROR] [NRSDK] Generic Camera data_bytes error, should be = 615680, cur = 582924
08-27 11:36:31.565  1397  2274 E bt_stack: [ERROR:gatt_main.cc(387)] gatt_act_connect
08-27 11:36:31.567  1397  1610 E bt_btif_gattc: btif_gattc_upstreams_evt: Unhandled event (7)!
08-27 11:36:31.568  1397  1610 E bt_btif_gattc: btif_gattc_upstreams_evt: Unhandled event (7)!
08-27 11:36:31.568  1397  1610 E bt_btif_gattc: btif_gattc_upstreams_evt: Unhandled event (7)!

matsu · August 28, 2021, 5:58am

I posted error messages by adb logcat -s *:E. I can see
[ERROR] [NRSDK] Generic Camera data_bytes error, should be = 615680, cur = 32687
which is not seen when the app starts correctly. So, I guess the RGB camera causes this issue, but I cannot guess any more. I am waiting for comments about it.

Version info:
Nreal system version: SDM 845-202106292240-837
Nreal controller version: Dec 25 2019 22:25:28 release
Glasses firmware version: 05.1.07.007_20210510
NRSDK: 1.5.7
Unity: 2020.3.9f1

matsu · August 30, 2021, 11:06pm

Update.
I have noticed that when I run my app along with scrcpy, it runs correctly at very high probability (99% or it does not show the issue so far).