[OnePlus 3 & 3T Cross Device Development] 0xC0ncord:
Hello,

First off thank you very much for your hard work.

I have a strange but reproducible issue here. Whenever I place or receive a SIP call, my device hard freezes. The UI stops responding for about 5 seconds before going black, and the phone stays in this state until it is hard reset. I have gotten this crash on both the native Android SIP client in the Dialer and also with Linphone. I am running microG and Magisk. I had this exact same setup before on NLOS without issues.

Original kernel: Yes

Build date: Fri Feb 7 17:49:21 CET 2020

Logcat (taken right before launching Linphone and trying to place a call):

02-13 17:33:44.139 961 2935 I MediaFocusControl: requestAudioFocus() from uid/pid 10140/4058 clientId=android.media.AudioManager@e2bf95 callingPack=org.linphone req=4 flags=0x0 sdk=28
02-13 17:33:44.140 1879 2098 I vol.Events: writeEvent level_changed STREAM_RING 5
02-13 17:33:44.144 609 609 D AudioPolicyManagerCustom: setForceUse() usage 7, config 0, mPhoneState 3
02-13 17:33:44.148 1879 2098 I vol.Events: writeEvent level_changed STREAM_MUSIC 8
02-13 17:33:44.151 1879 2098 I vol.Events: writeEvent level_changed STREAM_ALARM 6
02-13 17:33:44.189 961 1069 W BatteryExternalStatsWorker: modem info is invalid: ModemActivityInfo{ mTimestamp=0 mSleepTimeMs=0 mIdleTimeMs=0 mTxTimeMs[]=[0, 0, 0, 0, 0] mRxTimeMs=0 mEnergyUsed=0}
02-13 17:33:44.208 961 4489 I ActivityManager: START u0 {cmp=org.linphone/.call.CallOutgoingActivity} from uid 10140
02-13 17:33:44.228 961 2935 I ActivityManager: START u0 {cmp=org.linphone/.call.CallOutgoingActivity} from uid 10140
02-13 17:33:44.242 4058 4058 W ActivityThread: handleWindowVisibility: no activity for token android.os.BinderProxy@d492576
02-13 17:33:44.461 961 4489 I ActivityManager: START u0 {act=android.content.pm.action.REQUEST_PERMISSIONS pkg=com.android.packageinstaller cmp=com.android.packageinstaller/.permission.ui.GrantPermissionsActivity (has extras)} from uid 10140
02-13 17:33:44.483 3534 3534 W ActivityThread: handleWindowVisibility: no activity for token android.os.BinderProxy@cf962fc
02-13 17:33:44.564 3534 3534 D OpenGLRenderer: Skia GL Pipeline
02-13 17:33:44.597 3534 4540 I Adreno : QUALCOMM build : 365e321, I294de8bafa
02-13 17:33:44.597 3534 4540 I Adreno : Build Date : 01/08/19
02-13 17:33:44.597 3534 4540 I Adreno : OpenGL ES Shader Compiler Version: EV031.25.03.02
02-13 17:33:44.597 3534 4540 I Adreno : Local Branch :
02-13 17:33:44.597 3534 4540 I Adreno : Remote Branch : refs/tags/AU_LINUX_ANDROID_LA.UM.7.5.R1.09.00.00.464.040
02-13 17:33:44.597 3534 4540 I Adreno : Remote Branch : NONE
02-13 17:33:44.597 3534 4540 I Adreno : Reconstruct Branch : NOTHING
02-13 17:33:44.597 3534 4540 I Adreno : Build Config : S L 6.0.7 AArch64
02-13 17:33:44.598 3534 4540 D vndksupport: Loading /vendor/lib64/hw/gralloc.msm8996.so from current namespace instead of sphal namespace.
02-13 17:33:44.602 3534 4540 I Adreno : PFP: 0x005ff110, ME: 0x005ff066
02-13 17:33:44.606 3534 4540 I ConfigStore: android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasWideColorDisplay retrieved: 0
02-13 17:33:44.606 3534 4540 I ConfigStore: android::hardware::configstore::V1_0::ISurfaceFlingerConfigs::hasHDRDisplay retrieved: 0
02-13 17:33:44.607 3534 4540 I OpenGLRenderer: Initialized EGL, version 1.4
02-13 17:33:44.607 3534 4540 D OpenGLRenderer: Swap behavior 2
02-13 17:33:44.618 3534 4540 D vndksupport: Loading /vendor/lib64/hw/android.hardware.graphics.mapper@2.0-impl.so from current namespace instead of sphal namespace.
02-13 17:33:44.619 3534 4540 D vndksupport: Loading /vendor/lib64/hw/gralloc.msm8996.so from current namespace instead of sphal namespace.
02-13 17:33:45.489 4058 4058 W libOpenSLES: Conversion from OpenSL ES positional channel mask 0x4 to Android mask 0 loses channels
02-13 17:33:45.489 4058 4058 I libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x10 based on channel count of 1)
02-13 17:33:45.500 609 1154 E : Request requires android.permission.RECORD_AUDIO
02-13 17:33:45.500 609 1154 E AudioPolicyIntefaceImpl: getInputForAttr permission denied: recording not allowed for uid 10140 pid 4058
02-13 17:33:45.500 609 1154 E AudioFlinger: createRecord() checkRecordThread_l failed
02-13 17:33:45.501 4058 4058 E IAudioFlinger: createRecord returned error -22
02-13 17:33:45.501 4058 4058 E AudioRecord: AudioFlinger could not create record track, status: -22
02-13 17:33:45.502 4058 4058 E libOpenSLES: android_audioRecorder_realize(0x73b5fff800) error creating AudioRecord object; status -22
02-13 17:33:45.512 4058 4058 W libOpenSLES: Leaving Object::Realize (SL_RESULT_CONTENT_UNSUPPORTED)
02-13 17:33:45.513 4058 4058 D : PlayerBase::PlayerBase()
02-13 17:33:45.513 4058 4058 D : TrackPlayerBase::TrackPlayerBase()
02-13 17:33:45.513 4058 4058 I libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x1 based on channel count of 1)
02-13 17:33:45.514 552 1458 D audio_hw_extn: audio_extn_get_parameters: returns
02-13 17:33:45.515 609 1154 I hash_map_utils: key: 'voip_out_stream_count' value: ''
02-13 17:33:45.516 552 1458 D audio_hw_extn: audio_extn_get_parameters: returns
02-13 17:33:45.517 609 1154 I hash_map_utils: key: 'voip_sample_rate' value: ''
02-13 17:33:45.517 609 1154 D AudioPolicyManagerCustom: Set VoIP and Direct output flags for PCM format
02-13 17:33:45.518 552 1458 I audio_hw_primary: getting vr mode to 0
02-13 17:33:45.518 609 1154 I hash_map_utils: key: 'vr_audio_mode_on' value: ''
02-13 17:33:45.519 609 1154 I AudioPolicyManagerCustom: VR mode is 0, switch to primary output if request is for fast|raw
02-13 17:33:45.519 609 1154 W AudioFlinger: createTrack_l(): mismatch between requested flags (00000104) and output flags (00000006)
02-13 17:33:45.519 609 1154 D AudioFlinger: Client defaulted notificationFrames to 192 for frameCount 384
02-13 17:33:45.521 4058 4058 I AudioTrack: AUDIO_OUTPUT_FLAG_FAST successful; frameCount 0 -> 384
02-13 17:33:45.526 553 553 W AshmemAllocator: ashmem_create_region(7680) returning hidl_memory(0x723ea2c100, 7680)
02-13 17:33:45.529 553 553 W AshmemAllocator: ashmem_create_region(7680) returning hidl_memory(0x723ea2c100, 7680)
02-13 17:33:45.754 961 961 I GnssLocationProvider: WakeLock acquired by sendMessage(SET_REQUEST, 0, com.android.server.location.GnssLocationProvider$GpsRequest@ef4492)
02-13 17:33:45.756 2968 2986 V NlpLocationProvider: onSetRequest: ProviderRequest[ON interval=+1d0h0m0s0ms lowPowerMode=false] by WorkSource{1000 android}
02-13 17:33:45.756 2968 2986 V NlpLocationProvider: using autoUpdate=true autoTime=86400000
02-13 17:33:45.770 961 1987 I WifiService: startScan uid=10052
02-13 17:33:45.822 961 1056 E AppOps : Creating dialog box
02-13 17:33:45.872 961 2929 I OpenGLRenderer: Initialized EGL, version 1.4
02-13 17:33:45.873 961 2929 D OpenGLRenderer: Swap behavior 2
02-13 17:33:45.955 3534 4540 D OpenGLRenderer: endAllActiveAnimators on 0x73bcbf2700 (RippleDrawable) with handle 0x73bcbff1e0
02-13 17:33:46.221 4058 4536 W libOpenSLES: Conversion from OpenSL ES positional channel mask 0x4 to Android mask 0 loses channels
02-13 17:33:46.221 4058 4536 I libOpenSLES: Emulating old channel mask behavior (ignoring positional mask 0x4, using default mask 0x10 based on channel count of 1)
02-13 17:33:46.225 552 1138 D audio_hw_primary: adev_open_input_stream: enter: sample_rate(48000) channel_mask(0x10) devices(0x80000004) stream_handle(0xe7c29380) io_handle(54) source(7) format 1
02-13 17:33:46.225 552 1138 D compress_voip: voice_extn_compress_voip_pcm_prop_check: VoIP PCM property is enabled
02-13 17:33:46.225 552 1138 D compress_voip: voice_extn_compress_voip_open_input_stream: enter
02-13 17:33:46.225 552 1138 D compress_voip: voip_set_mode: enter, format=1
02-13 17:33:46.225 552 1138 D compress_voip: voip_set_mode: Derived mode = 12
02-13 17:33:46.225 552 1138 W audio_hw_utils: audio_extn_utils_update_stream_input_app_type_cfg: App type could not be selected. Falling back to default
02-13 17:33:46.229 609 4558 I AudioFlinger: AudioFlinger's thread 0xed603400 tid=4558 ready to run
02-13 17:33:46.229 552 1138 D audio_hw_primary: in_standby: enter: stream (0xe7c29380) usecase(38: compress-voip-call)
02-13 17:33:46.230 552 4186 D audio_hw_primary: in_standby: enter: stream (0xe7c29380) usecase(38: compress-voip-call)
02-13 17:33:46.233 552 1138 W EffectHAL: Effect 0xe67fe1f0 command SET_CONFIG returned status: Invalid argument
02-13 17:33:46.237 552 1138 W EffectHAL: Effect 0xe67fe1f0 command SET_CONFIG returned status: Invalid argument
02-13 17:33:46.238 552 1458 W EffectHAL: Effect 0xe739c840 command SET_CONFIG returned status: Invalid argument
02-13 17:33:46.238 609 1154 W AudioFlinger: createRecordTrack_l(): mismatch between requested flags (00000005) and input flags (00000000)
02-13 17:33:46.239 609 1154 W AudioFlinger: session id 89 not found for pid 4058
02-13 17:33:46.240 609 1154 W AudioFlinger: session id 89 not found for pid 4058
02-13 17:33:46.244 552 4186 D audio_hw_primary: adev_close_input_stream: enter:stream_handle(0xe7c29380)
02-13 17:33:46.244 552 4186 D compress_voip: voice_extn_compress_voip_close_input_stream: enter
02-13 17:33:46.244 552 4186 D compress_voip: voip_stop_call: enter, out_stream_count=0, in_stream_count=0
02-13 17:33:46.244 552 4186 E compress_voip: voip_stop_call: Could not find the usecase (38) in the list
02-13 17:33:46.244 552 4186 E audio_hw_primary: adev_close_input_stream: Compress voip input cannot be closed, error:-22
02-13 17:33:46.246 552 552 D audio_hw_primary: adev_open_input_stream: enter: sample_rate(48000) channel_mask(0x10) devices(0x80000004) stream_handle(0xe8056e00) io_handle(62) source(7) format 1
02-13 17:33:46.246 552 552 D compress_voip: voice_extn_compress_voip_pcm_prop_check: VoIP PCM property is enabled
02-13 17:33:46.246 552 552 D compress_voip: voice_extn_compress_voip_open_input_stream: enter
02-13 17:33:46.246 552 552 D compress_voip: voip_set_mode: enter, format=1
02-13 17:33:46.246 552 552 D compress_voip: voip_set_mode: Derived mode = 12
02-13 17:33:46.246 552 552 W audio_hw_utils: audio_extn_utils_update_stream_input_app_type_cfg: App type could not be selected. Falling back to default
02-13 17:33:46.253 552 1458 W EffectHAL: Effect 0xe67fe230 command SET_CONFIG returned status: Invalid argument
02-13 17:33:46.255 609 4559 I AudioFlinger: AudioFlinger's thread 0xed603b80 tid=4559 ready to run
02-13 17:33:46.256 552 3182 D audio_hw_primary: in_standby: enter: stream (0xe8056e00) usecase(38: compress-voip-call)
02-13 17:33:46.256 552 1458 W EffectHAL: Effect 0xe67fe230 command SET_CONFIG returned status: Invalid argument
02-13 17:33:46.257 552 3182 W EffectHAL: Effect 0xe67fe170 command SET_CONFIG returned status: Invalid argument
02-13 17:33:46.274 552 1470 D audio_hw_primary: in_set_parameters: enter: kvpairs=bottom=;input_source=7;routing=-2147483644
02-13 17:33:46.277 609 1526 I SoundTriggerHwService::Module: onCallbackEvent no clients
02-13 17:33:46.284 552 4561 D compress_voip: voice_extn_compress_voip_start_input_stream: enter
02-13 17:33:46.284 552 4561 D compress_voip: voip_start_call: enter
02-13 17:33:46.284 552 4561 D audio_hw_primary: select_devices for use case (compress-voip-call)
02-13 17:33:46.285 552 4561 D audio_hw_primary: select_devices: out_snd_device(15: voice-handset) in_snd_device(99: voice-dmic-ef)
02-13 17:33:46.285 552 4561 I msm8974_platform: platform_check_and_set_codec_backend_cfg:becf: afe: bitwidth 16, samplerate 48000 channels 2, backend_idx 0 usecase = 38 device (voice-handset)
02-13 17:33:46.285 552 4561 D msm8974_platform: platform_split_snd_device: snd_device(15) num devices(1) new_snd_devices(0)
02-13 17:33:46.285 552 4561 I msm8974_platform: platform_check_and_set_codec_backend_cfg: new_snd_devices[0] is 15
02-13 17:33:46.285 552 4561 I msm8974_platform: platform_check_codec_backend_cfg:becf: afe: bitwidth 16, samplerate 48000 channels 2, backend_idx 0 usecase = 38 device (voice-handset)
02-13 17:33:46.285 552 4561 W msm8974_platform: platform_check_codec_backend_cfg:becf: afe:Use default bw and sr for voice/voip calls
02-13 17:33:46.285 552 4561 D msm8974_platform: platform_check_codec_backend_cfg:becf: afe: playback on codec device not supporting native playback set default Sample Rate(48k)
02-13 17:33:46.285 552 4561 I msm8974_platform: platform_check_codec_backend_cfg:becf: afe: Codec selected backend: 0 updated bit width: 16 and sample rate: 48000
02-13 17:33:46.285 552 4561 D audio_hw_primary: check_usecases_codec_backend:becf: force routing 0
02-13 17:33:46.285 552 4561 D audio_hw_primary: check_usecases_codec_backend:becf: (65) check_usecases curr device: voice-handset, usecase device:handset backends match 1
02-13 17:33:46.285 552 4561 D audio_hw_primary: check_usecases_codec_backend:becf: check_usecases (low-latency-playback) is active on (handset) - disabling ..
02-13 17:33:46.285 552 4561 D audio_hw_primary: disable_audio_route: reset and update mixer path: low-latency-playback
02-13 17:33:46.323 552 4561 D soundtrigger: audio_extn_sound_trigger_update_stream_status: uc_info->id 1 of type 0 for Event 2, with Raise=0
02-13 17:33:46.323 552 4561 E msm8974_platform: platform_check_backends_match: Invalid snd_device =
02-13 17:33:46.323 552 4561 D audio_hw_primary: check_usecases_codec_backend:becf: (65) check_usecases curr device: voice-handset, usecase device: backends match 0
02-13 17:33:46.323 552 4561 D audio_hw_primary: check_usecases_codec_backend:becf: check_usecases num.of Usecases to switch 1
02-13 17:33:46.323 552 4561 D msm8974_platform: platform_split_snd_device: snd_device(1) num devices(0) new_snd_devices(0)
02-13 17:33:46.323 552 4561 D hardware_info: hw_info_append_hw_type : device_name = handset
02-13 17:33:46.324 552 4561 D audio_hw_primary: disable_snd_device: snd_device(1: handset)
02-13 17:33:46.324 552 4561 D msm8974_platform: platform_split_snd_device: snd_device(1) num devices(0) new_snd_devices(0)
02-13 17:33:46.328 552 4561 I soundtrigger: audio_extn_sound_trigger_update_device_status: device 0x1 of type 0 for Event 0, with Raise=0
02-13 17:33:46.328 552 4561 D msm8974_platform: platform_split_snd_device: snd_device(1) num devices(0) new_snd_devices(0)
02-13 17:33:46.328 552 4561 D hardware_info: hw_info_append_hw_type : device_name = voice-handset
02-13 17:33:46.328 552 4561 D msm8974_platform: platform_split_snd_device: snd_device(15) num devices(0) new_snd_devices(0)
02-13 17:33:46.328 552 4561 D audio_hw_primary: enable_snd_device: snd_device(15: voice-handset)
02-13 17:33:46.328 552 4561 I soundtrigger: audio_extn_sound_trigger_update_device_status: device 0xf of type 0 for Event 1, with Raise=0
02-13 17:33:46.328 552 4561 D audio_route: Apply path: voice-handset
02-13 17:33:46.332 552 4561 D audio_hw_primary: check_usecases_codec_backend:becf: enabling usecase (low-latency-playback) on (voice-handset)
02-13 17:33:46.332 552 4561 D soundtrigger: audio_extn_sound_trigger_update_stream_status: uc_info->id 1 of type 0 for Event 3, with Raise=0
02-13 17:33:46.332 552 4561 D audio_hw_utils: audio_extn_utils_send_app_type_cfg: usecase->out_snd_device voice-handset
02-13 17:33:46.332 552 4561 D msm8974_platform: platform_split_snd_device: snd_device(15) num devices(0) new_snd_devices(0)
02-13 17:33:46.333 552 4561 E msm8974_platform: platform_get_snd_device_backend_index: BE DAI Name Table is not present
02-13 17:33:46.333 552 4561 E audio_hw_utils: send_app_type_cfg_for_device: Couldn't get the backend index for snd device voice-handset ret=-14
02-13 17:33:46.333 552 4561 D audio_hw_utils: audio_extn_btsco_get_sample_rate:Not a BT SCO device, need not update sampling rate
02-13 17:33:46.333 552 4561 I audio_hw_utils: send_app_type_cfg_for_device PLAYBACK app_type 69937, acdb_dev_id 7, sample_rate 48000, snd_device_be_idx -14
02-13 17:33:46.333 552 4561 D msm8974_platform: platform_split_snd_device: snd_device(15) num devices(1) new_snd_devices(0)
02-13 17:33:46.333 552 4561 D ACDB-LOADER: ACDB -> send_audio_cal, acdb_id = 7, path = 0, app id = 0x11131, sample rate = 48000
02-13 17:33:46.333 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_GAIN_DEP_STEP_TABLE, vol index 0
02-13 17:33:46.333 552 4561 D ACDB-LOADER: ACDB -> AUDIO_SET_VOL_CAL cal type = 12
02-13 17:33:46.333 552 4561 D ACDB-LOADER: ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 16
02-13 17:33:46.334 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID
02-13 17:33:46.334 552 4561 D ACDB-LOADER: ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 7, Topology Id 112fc
02-13 17:33:46.334 552 4561 D : Failed to fetch the lookup information of the device 00000007
02-13 17:33:46.334 552 4561 E ACDB-LOADER: Error: ACDB_CMD_GET_AFE_COMMON_TABLE_SIZE Returned = -19
02-13 17:33:46.334 552 4561 D : Failed to fetch the lookup information of the device 00000007
02-13 17:33:46.334 552 4561 E ACDB-LOADER: Error: ACDB AFE returned = -19
02-13 17:33:46.334 552 4561 D ACDB-LOADER: ACDB -> send_hw_delay : acdb_id = 7 path = 0
02-13 17:33:46.334 552 4561 D audio_hw_primary: enable_audio_route: apply mixer and update path: low-latency-playback
02-13 17:33:46.334 552 4561 D audio_route: Apply path: low-latency-playback
02-13 17:33:46.365 552 4561 D hardware_info: hw_info_append_hw_type : device_name = voice-handset
02-13 17:33:46.365 552 4561 I msm8974_platform: platform_check_and_set_capture_codec_backend_cfg:txbecf: afe: bitwidth 16, samplerate 48000, channel 1 format 1, backend_idx 7 usecase = 38 device (voice-dmic-ef)
02-13 17:33:46.365 552 4561 I msm8974_platform: platform_check_capture_codec_backend_cfg:txbecf: afe: Codec selected backend: 7 current bit width: 16 and sample rate: 48000, channels 1 format 1
02-13 17:33:46.365 552 4561 W msm8974_platform: platform_check_capture_codec_backend_cfg:txbecf: afe: Use default bw and sr for voice/voip calls and for unprocessed/camera source
02-13 17:33:46.365 552 4561 I msm8974_platform: platform_check_capture_codec_backend_cfg:txbecf: afe: Codec selected backend: 7 updated bit width: 16 and sample rate: 48000
02-13 17:33:46.365 552 4561 D audio_hw_primary: check_usecases_capture_codec_backend:becf: force routing 0
02-13 17:33:46.365 552 4561 D hardware_info: hw_info_append_hw_type : device_name = voice-dmic-ef
02-13 17:33:46.365 552 4561 D msm8974_platform: platform_split_snd_device: snd_device(99) num devices(0) new_snd_devices(0)
02-13 17:33:46.365 552 4561 D audio_hw_primary: enable_snd_device: snd_device(99: voice-dmic-ef)
02-13 17:33:46.366 552 4561 I soundtrigger: audio_extn_sound_trigger_update_device_status: device 0x63 of type 1 for Event 1, with Raise=1
02-13 17:33:46.366 552 4561 D sound_trigger_platform: platform_stdev_check_and_update_concurrency: concurrency active 0, tx 1, rx 0, concurrency session_allowed 0
02-13 17:33:46.366 552 4561 D audio_route: Apply path: voice-dmic-ef
02-13 17:33:46.380 552 4561 D ACDB-LOADER: ACDB -> send_voice_cal, acdb_rx = 7, acdb_tx = 41, feature_set = 0
02-13 17:33:46.380 552 4561 D ACDB-LOADER: ACDB -> send_voice_rx_topology
02-13 17:33:46.380 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_VOCPROC_COMMON_TOPOLOGY_ID
02-13 17:33:46.380 552 4561 D ACDB-LOADER: ACDB -> send_voice_tx_topology
02-13 17:33:46.380 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_VOCPROC_COMMON_TOPOLOGY_ID
02-13 17:33:46.380 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AFE_DATA
02-13 17:33:46.380 552 4561 D ACDB-LOADER: ACDB -> AUDIO_SET_SIDETONE_CAL
02-13 17:33:46.380 552 4561 D ACDB-LOADER: ACDB -> send_voice_columns, rxacdb_id 7, txacdb_id 41, table 5
02-13 17:33:46.380 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_VOC_COLUMNS_INFO_V2
02-13 17:33:46.380 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_VOC_PROC_STATIC_TABLE_SIZE
02-13 17:33:46.382 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_VOC_PROC_STATIC_TABLE
02-13 17:33:46.384 552 4561 D ACDB-LOADER: ACDB -> AUDIO_SET_VOCPROC_CAL
02-13 17:33:46.384 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_VOC_PROC_DEVICE_CFG
02-13 17:33:46.384 552 4561 D ACDB-LOADER: ACDB -> AUDIO_SET_VOCPROC_DEV_CFG_CAL
02-13 17:33:46.384 552 4561 D ACDB-LOADER: ACDB -> send_voice_columns, rxacdb_id 7, txacdb_id 41, table 4
02-13 17:33:46.384 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_VOC_COLUMNS_INFO_V2
02-13 17:33:46.384 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_VOC_PROC_DYNAMIC_TABLE_SIZE
02-13 17:33:46.388 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_VOC_PROC_DYNAMIC_TABLE
02-13 17:33:46.394 552 4561 D ACDB-LOADER: ACDB -> AUDIO_SET_VOCPROC_VOL_CAL
02-13 17:33:46.394 552 4561 D ACDB-LOADER: ACDB -> send_voice_columns, rxacdb_id 7, txacdb_id 41, table 6
02-13 17:33:46.394 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_VOC_COLUMNS_INFO_V2
02-13 17:33:46.394 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_VOC_STREAM_STATIC_TABLE_SIZE
02-13 17:33:46.395 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_VOC_STREAM_STATIC_TABLE
02-13 17:33:46.395 552 4561 D ACDB-LOADER: ACDB -> AUDIO_SET_VOCPROC_STREAM_CAL
02-13 17:33:46.395 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID
02-13 17:33:46.395 552 4561 D ACDB-LOADER: ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 41, Topology Id 112fb
02-13 17:33:46.395 552 4561 D : Failed to fetch the lookup information of the device 00000029
02-13 17:33:46.395 552 4561 E ACDB-LOADER: Error: ACDB_CMD_GET_AFE_COMMON_TABLE_SIZE Returned = -19
02-13 17:33:46.395 552 4561 D : Failed to fetch the lookup information of the device 00000029
02-13 17:33:46.395 552 4561 E ACDB-LOADER: Error: ACDB AFE returned = -19
02-13 17:33:46.395 552 4561 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID
02-13 17:33:46.395 552 4561 D ACDB-LOADER: ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 7, Topology Id 112fc
02-13 17:33:46.395 552 4561 D : Failed to fetch the lookup information of the device 00000007
02-13 17:33:46.395 552 4561 E ACDB-LOADER: Error: ACDB_CMD_GET_AFE_COMMON_TABLE_SIZE Returned = -19
02-13 17:33:46.395 552 4561 D : Failed to fetch the lookup information of the device 00000007
02-13 17:33:46.395 552 4561 E ACDB-LOADER: Error: ACDB AFE returned = -19
02-13 17:33:46.395 552 4561 D ACDB-LOADER: ACDB -> send_hw_delay : acdb_id = 41 path = 1
02-13 17:33:46.395 552 4561 D ACDB-LOADER: ACDB -> send_hw_delay : acdb_id = 7 path = 0
02-13 17:33:46.395 552 4561 D ACDB-LOADER: ACDB -> Sent VocProc Cal!
02-13 17:33:46.395 552 4561 E audio_hw_utils: audio_extn_utils_update_stream_app_type_cfg_for_usecase: app type cfg not supported for usecase type (3)
02-13 17:33:46.395 552 4561 I audio_hw_utils: audio_extn_utils_send_app_type_cfg: not a playback/capture path, no need to cfg app type
02-13 17:33:46.395 552 4561 D audio_hw_primary: enable_audio_route: apply mixer and update path: compress-voip-call
02-13 17:33:46.395 552 4561 D audio_route: Apply path: compress-voip-call
02-13 17:33:46.397 552 4561 D audio_hw_primary: select_devices: done
02-13 17:33:46.397 552 4561 D compress_voip: voip_start_call: Opening PCM capture device card_id(0) device_id(3)
02-13 17:33:46.402 552 4561 D compress_voip: voip_start_call: Opening PCM playback device card_id(0) device_id(3)
02-13 17:33:46.455 552 4561 D voice : voice_set_sidetone: enable, out_snd_device: 15
02-13 17:33:46.455 552 4561 D audio_route: Apply path: sidetone-handset

Thank you!