USB Tethering cannot connect (can still ping device)

[C_B]

The usb tethering function for AudioRelay is not working on my Oneplus 7 pro running KOSP (Android 12 custom rom). My device is rooted (Magisk). The USB tethering function itself works well and when I ping my phones ip from my PC, the response time is 1ms. However, when I try to connect through Audio Relay when usb tethered, I keep getting “An error occurred when playing, please retry”.

I’ve tried USB tethering AudioRelay with my old S8 with zero problems. And with using the regular mode, I also experience zero issues on my OP7 Pro. Any suggestions would be much appreciated.

[Asapha]

Sorry about the generic error message.

If haven’t done so yet, could you please try with this version? (Install both the Android and Desktop versions)

AudioRelay 0.22

Changelog For changes between 0.12 to 0.21, see this post AudioRelay 0.21 (Windows, MacOS, Linux, Android). It mainly added Android to PC streaming PC to PC streaming Linux and MacOS versions 0.22.1 Android Fixed: In the server view, show all the connected devices instead of only 1 UI: Show errors when AudioRelay fails to capture or play audio. Previously, the stream simply stopped Desktop UI: Show errors when AudioRelay fails to capture or play audio. Previously, the errors were simp…

If it still doesn’t work

Getting some log would be necessary to troubleshoot what’s happening.
The Android app doesn’t store log messages itself so using ADB is necessary.
You would need to do these steps:

• Get ADB and enable USB debugging
• Open a terminal
• Confirm that your device is available via the command adb devices
• Type adb logcat --clear to remove all the previous logs
• Type adb logcat > logcat-output.txt to write the new logs to a file
• In the Android App, try to connect to the server via USB tethering
• In the terminal, stop the log collection by doing CTRL C
• Post the logcat-output.txt file here

There should be the real error message inside the log file.
Let me know if you need help with the instructions.

[C_B]

Here are the logs. I should have noted that I was on 0.21 already.

--------- beginning of main
01-15 13:12:37.972  2835  2835 D QtiCarrierConfigHelper: WARNING, no carrier configs on phone Id: 1
01-15 13:12:38.196   959  2471 I WifiHAL : event received NL80211_CMD_VENDOR, vendor_id = 0x1374, subcmd = 0xd
01-15 13:12:38.196  1278  1278 I cnss-daemon: nl80211 response handler invoked
01-15 13:12:38.196  1278  1278 I cnss-daemon: nl80211_response_handler: cmd 103, vendorID 4980, subcmd 13  received
01-15 13:12:39.168 31594 31594 D BoundBrokerSvc: onUnbind: Intent { act=com.google.android.gms.measurement.START pkg=com.google.android.gms }
01-15 13:12:40.200  1278  1278 I cnss-daemon: nl80211 response handler invoked
01-15 13:12:40.200  1278  1278 I cnss-daemon: nl80211_response_handler: cmd 103, vendorID 4980, subcmd 13  received
01-15 13:12:40.200   959  2471 I WifiHAL : event received NL80211_CMD_VENDOR, vendor_id = 0x1374, subcmd = 0xd
01-15 13:12:42.206  1278  1278 I cnss-daemon: nl80211 response handler invoked
01-15 13:12:42.206  1278  1278 I cnss-daemon: nl80211_response_handler: cmd 103, vendorID 4980, subcmd 13  received
01-15 13:12:42.206   959  2471 I WifiHAL : event received NL80211_CMD_VENDOR, vendor_id = 0x1374, subcmd = 0xd
--------- beginning of system
01-15 13:12:42.479  1588  1588 W WindowManager: removeWindowToken: Attempted to remove non-existing token: android.os.Binder@cd82d06
01-15 13:12:43.373  1588  2922 D ConnectivityService: requestNetwork for uid/pid:10351/1731 activeRequest: null callbackRequest: 13967 [NetworkRequest [ REQUEST id=13968, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10351 RequestorUid: 10351 RequestorPkg: com.azefsw.audioconnect] ]] callback flags: 0 priority: 2147483647
01-15 13:12:43.373  1588  2407 D ConnectivityService: NetReassign [13968 : null → 187]
01-15 13:12:43.374  1588  2400 D WifiNetworkFactory: got request NetworkRequest [ REQUEST id=13968, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10351 RequestorUid: 10351 RequestorPkg: com.azefsw.audioconnect] ]
01-15 13:12:43.374  1588  2400 D UntrustedWifiNetworkFactory: got request NetworkRequest [ REQUEST id=13968, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10351 RequestorUid: 10351 RequestorPkg: com.azefsw.audioconnect] ]
01-15 13:12:43.375  1588  2400 D OemPaidWifiNetworkFactory: got request NetworkRequest [ REQUEST id=13968, [ Capabilities: INTERNET&NOT_RESTRICTED&TRUSTED&NOT_VCN_MANAGED Uid: 10351 RequestorUid: 10351 RequestorPkg: com.azefsw.audioconnect] ]
01-15 13:12:43.380  1588  2922 W ProcessStats: Tracking association SourceState{472c838 system/1000 BTopFgs #890056} whose proc state 2 is better than process ProcessState{6d1e576 com.joaomgcd.autoinput/10299 pkg=com.joaomgcd.autoinput} proc state 3 (20 skipped)
01-15 13:12:43.381  4398  5432 V ACCESSIBILITY: CLICK - text: null; viewId: null
01-15 13:12:43.381  4398  5432 V JOAOMGCD: AutoInput-CLICK - text: null; viewId: null
01-15 13:12:43.384  4398  6732 D EventBus: No subscribers registered for event class com.joaomgcd.log.m
01-15 13:12:43.384  4398  6732 D EventBus: No subscribers registered for event class org.greenrobot.eventbus.NoSubscriberEvent
01-15 13:12:43.385 31594 31594 D BoundBrokerSvc: onBind: Intent { act=com.google.android.gms.measurement.START pkg=com.google.android.gms }
01-15 13:12:43.385 31594 31594 D BoundBrokerSvc: Loading bound service for intent: Intent { act=com.google.android.gms.measurement.START pkg=com.google.android.gms }
01-15 13:12:43.388  4398  5432 V LASTMESSAGEn: Received last update com.joaomgcd.accessibility.a.b@30baa2e6
01-15 13:12:43.388  1731  6631 I AR      : Remotely connecting to 192.168.192.18...
01-15 13:12:43.389  1731  1884 I AR      : Acquiring wakelocks...
01-15 13:12:43.389  1588  4640 I WifiService: acquireWifiLock uid=10351 lockMode=3
01-15 13:12:43.389  1588  2922 I ActivityManager: Background started FGS: Allowed [callingPackage: com.joaomgcd.autoinput; callingUid: 10299; uidState: FGS ; intent: Intent { act=com.twofortyfouram.locale.intent.action.REQUEST_QUERY flg=0x10000000 cmp=net.dinglisch.android.taskerm/com.joaomgcd.taskerm.plugin.ServiceRequestQuery (has extras) }; code:ACTIVITY_STARTER; tempAllowListReason:<,reasonCode:SYSTEM_ALLOW_LISTED,duration:9223372036854775807,callingUid:-1>; targetSdkVersion:29; callerTargetSdkVersion:28; startForegroundCount:0; bindFromPackage:null]
01-15 13:12:43.393  1731  6631 V AR      : Requesting audio focus...
01-15 13:12:43.393  1588  1667 I DropBoxManagerService: add tag=system_server_wtf isTagEnabled=true flags=0x2
01-15 13:12:43.395   918   978 D audio_hw_primary: adev_create_audio_patch: enter: num sources 1, num_sinks 1, handle 115
01-15 13:12:43.395   918   978 D audio_hw_primary: adev_create_audio_patch: Playback patch from mix handle 13 to device 2
01-15 13:12:43.395   918   978 D audio_hw_primary: update_patch: enter
01-15 13:12:43.395   918   978 D audio_hw_primary: route_output_stream: enter: usecase(1: low-latency-playback) devices 2
01-15 13:12:43.396  1588  1667 E NotificationVibratorHelper: Error creating vibration waveform with pattern: [0]
01-15 13:12:43.399   918  4197 D audio_hw_primary: start_output_stream: enter: stream(0xed753000)usecase(1: low-latency-playback) devices(0x2) is_haptic_usecase(0)
01-15 13:12:43.399   918  4197 E audio_hw_extn: audio_extn_perf_lock_acquire: Incorrect params, Failed to acquire perf lock, err 
01-15 13:12:43.399   918  4197 D audio_hw_primary: select_devices for use case (low-latency-playback)
01-15 13:12:43.399   918  4197 D msm8974_platform: platform_get_output_snd_device: enter: output devices(0x2)
01-15 13:12:43.399   918  4197 D msm8974_platform: platform_get_output_snd_device: exit: snd_device(speaker)
01-15 13:12:43.399   918  4197 D ultrasound_extn: ultrasound_select_devices_pre ultrasound_enabled:0 usecase(low-latency-playback), devices:2 in_snd_device(0, ), out_snd_device(10, speaker)
01-15 13:12:43.399   918  4197 D ultrasound_extn: dump_usecase_list: SDM660 enter sensor_activated:0 hw_port_enabled:0
01-15 13:12:43.399   918  4197 D ultrasound_extn: dump_usecase_list: SDM660 select_devices_pre usecase[#0]:(1, low-latency-playback) in_dev:(0, ) out_dv:(0, )
01-15 13:12:43.399   918  4197 I msm8974_platform: platform_check_and_set_codec_backend_cfg:becf: afe: bitwidth 24, samplerate 48000 channels 2, backend_idx 0 usecase = 1 device (speaker)
01-15 13:12:43.399   918  4197 D msm8974_platform: platform_split_snd_device: snd_device(10) num devices(1) new_snd_devices(0)
01-15 13:12:43.399   918  4197 I msm8974_platform: platform_check_and_set_codec_backend_cfg: new_snd_devices[0] is 10
01-15 13:12:43.399   918  4197 I msm8974_platform: platform_check_codec_backend_cfg:becf: afe: bitwidth 24, samplerate 48000 channels 2, backend_idx 0 usecase = 1 device (speaker)
01-15 13:12:43.400   918  4197 D audio_hw_extn: audio_extn_enable_hifi_filter: mixer_value set 0
01-15 13:12:43.400   918  4197 D msm8974_platform: platform_check_codec_backend_cfg:becf: updated afe: bitwidth 24, samplerate 48000 channels 2,backend_idx 0 usecase = 1 device (speaker)
01-15 13:12:43.400   918  4197 D msm8974_platform: platform_check_codec_backend_cfg:becf: afe: playback on codec device not supporting native playback set default Sample Rate(48k)
01-15 13:12:43.400   918  4197 I msm8974_platform: platform_check_codec_backend_cfg:becf: afe: Codec selected backend: 0 updated bit width: 24 and sample rate: 48000
01-15 13:12:43.400   918  4197 D audio_hw_primary: check_usecases_codec_backend:becf: force routing 0
01-15 13:12:43.400   918  4197 E msm8974_platform: platform_check_backends_match: Invalid snd_device = 
01-15 13:12:43.400   918  4197 D audio_hw_primary: check_usecases_codec_backend:becf: (102) check_usecases curr device: speaker, usecase device: backends match 0
01-15 13:12:43.400   918  4197 D audio_hw_primary: check_usecases_codec_backend:becf: check_usecases num.of Usecases to switch 0
01-15 13:12:43.400   918  4197 D msm8974_platform: platform_split_snd_device: snd_device(10) num devices(0) new_snd_devices(0)
01-15 13:12:43.400   918  4197 D hardware_info: hw_info_append_hw_type : device_name = speaker
01-15 13:12:43.400   918  4197 D msm8974_platform: platform_split_snd_device: snd_device(10) num devices(0) new_snd_devices(0)
01-15 13:12:43.400   918  4197 D audio_hw_primary: enable_snd_device: snd_device(10: speaker)
01-15 13:12:43.400   918  4197 D msm8974_platform: platform_get_island_cfg_on_device:island cfg status on snd_device = (speaker 0)
01-15 13:12:43.400   918  4197 I soundtrigger: audio_extn_sound_trigger_update_device_status: device 0xa of type 0 for Event 1, with Raise=0
01-15 13:12:43.400   918  4197 D audio_route: Apply path: speaker
01-15 13:12:43.400   918  4197 D hardware_info: hw_info_append_hw_type : device_name = vi-feedback
01-15 13:12:43.400   918  4197 D msm8974_platform: platform_split_snd_device: snd_device(248) num devices(0) new_snd_devices(0)
01-15 13:12:43.400   918  4197 D audio_hw_primary: enable_snd_device: snd_device(248: vi-feedback)
01-15 13:12:43.400   918  4197 D msm8974_platform: platform_get_island_cfg_on_device:island cfg status on snd_device = (vi-feedback 0)
01-15 13:12:43.400   918  4197 I soundtrigger: audio_extn_sound_trigger_update_device_status: device 0xf8 of type 1 for Event 1, with Raise=0
01-15 13:12:43.400   918  4197 D audio_route: Apply path: vi-feedback
01-15 13:12:43.400   918  4197 D soundtrigger: audio_extn_sound_trigger_update_stream_status: uc_info->id 53 of type 1 for Event 3, with Raise=0
01-15 13:12:43.400   918  4197 D audio_hw_utils: audio_extn_utils_send_app_type_cfg: usecase->in_snd_device vi-feedback
01-15 13:12:43.400   918  4197 E voice   : voice_is_in_call_rec_stream: input stream is NULL
01-15 13:12:43.400   918  4197 D msm8974_platform: platform_split_snd_device: snd_device(248) num devices(0) new_snd_devices(0)
01-15 13:12:43.400   918  4197 E audio_hw_utils: send_app_type_cfg_for_device: Could not get ctl for mixer cmd - Audio Stream Capture 35 App Type Cfg
01-15 13:12:43.400   918  4197 D msm8974_platform: platform_split_snd_device: snd_device(248) num devices(1) new_snd_devices(0)
01-15 13:12:43.400   918  4197 D ACDB-LOADER: ACDB -> send_audio_cal, acdb_id = 102, path = 1, app id = 0x11132, sample rate = 8000, afe_sample_rate = 8000
01-15 13:12:43.400   918  4197 D ACDB-LOADER: ACDB -> send_asm_topology
01-15 13:12:43.400   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID
01-15 13:12:43.400   918  4197 D ACDB-LOADER: ACDB -> send_adm_topology
01-15 13:12:43.400   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> send_audtable
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_COMMON_TABLE_SIZE
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_COMMON_TABLE
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> AUDIO_SET_AUDPROC_CAL cal_type[11] acdb_id[102] app_type[69938]
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_STREAM_TABLE_SIZE
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> send_audstrmtable
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_STREAM_TABLE
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 20
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> send_afe_topology
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 102, Topology Id 1000b909
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> send_afe_cal
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AFE_INSTANCE_COMMON_TABLE_SIZE
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AFE_INSTANCE_COMMON_TABLE
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> AUDIO_SET_AFE_CAL cal_type[17] acdb_id[102]
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> send_hw_delay : acdb_id = 102 path = 1
01-15 13:12:43.401   918  4197 D ACDB-LOADER: ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY
01-15 13:12:43.401   918  4197 D audio_hw_primary: enable_audio_route: apply mixer and update path: spkr-vi-record
01-15 13:12:43.401   918  4197 D audio_route: Apply path: spkr-vi-record
01-15 13:12:43.402   918  4197 E audio_hw_tfa98xx_feedback: audio_extn_tfa98xx_start_feedback: pcm device tx id=35
01-15 13:12:43.404  1588  1667 E NotificationVibratorHelper: Error creating vibration waveform with pattern: [0]
01-15 13:12:43.413 31782  6737 W NetworkScheduler: Error inserting flex_time=0 job_id=-1 period=2000 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=214815000 persistence_level=1 preferred_charging_state=1 required_network_type=0 runtime=1642277563412 retry_strategy={"maximum_backoff_seconds":{"3600":0},"initial_backoff_seconds":{"30":0},"retry_policy":{"0":0}} last_runtime=0, error message: UNIQUE constraint failed: pending_ops.tag, pending_ops.target_class, pending_ops.target_package, pending_ops.user_id (code 2067 SQLITE_CONSTRAINT_UNIQUE) [CONTEXT service_id=218 ]
01-15 13:12:43.417   918  4197 I audio_hw_utils: audio_extn_utils_update_stream_output_app_type_cfg Allowing 24 and above bits playback on speaker ONLY at default sampling rate
01-15 13:12:43.417   918  4197 E soundtrigger: audio_extn_sound_trigger_update_stream_status: invalid input device 0x0, for event 3
01-15 13:12:43.417   918  4197 D soundtrigger: audio_extn_sound_trigger_update_stream_status: uc_info->id 1 of type 0 for Event 3, with Raise=0
01-15 13:12:43.417   918  4197 D audio_hw_utils: audio_extn_utils_send_app_type_cfg: usecase->out_snd_device speaker
01-15 13:12:43.417   918  4197 D msm8974_platform: platform_split_snd_device: snd_device(10) num devices(0) new_snd_devices(0)
01-15 13:12:43.417   918  4197 D audio_hw_utils: audio_extn_btsco_get_sample_rate:Not a BT SCO device, need not update sampling rate
01-15 13:12:43.417   918  4197 I audio_hw_utils: send_app_type_cfg_for_device PLAYBACK app_type 69937, acdb_dev_id 15, sample_rate 48000, snd_device_be_idx 39
01-15 13:12:43.418   918  4197 D msm8974_platform: platform_split_snd_device: snd_device(10) num devices(1) new_snd_devices(0)
01-15 13:12:43.418   918  4197 D audio_hw_utils: audio_extn_btsco_get_sample_rate:Not a BT SCO device, need not update sampling rate
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> send_audio_cal, acdb_id = 15, path = 0, app id = 0x11131, sample rate = 48000, afe_sample_rate = 48000
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> send_asm_topology
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_STREAM_TOPOLOGY_ID
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> send_adm_topology
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> send_audtable
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_COMMON_TABLE_SIZE
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_COMMON_TABLE
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> AUDIO_SET_AUDPROC_CAL cal_type[11] acdb_id[15] app_type[69937]
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> send_audvoltable
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_GAIN_DEP_STEP_TABLE_SIZE
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_GAIN_DEP_STEP_TABLE, vol index 0
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> AUDIO_SET_VOL_CAL cal type = 12
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_STREAM_TABLE_SIZE
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> send_audstrmtable
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AUDPROC_INSTANCE_STREAM_TABLE
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> audstrm_cal->cal_type.cal_data.cal_size = 20
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> send_afe_topology
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AFE_TOPOLOGY_ID
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> GET_AFE_TOPOLOGY_ID for adcd_id 15, Topology Id 1000b910
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> send_afe_cal
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AFE_INSTANCE_COMMON_TABLE_SIZE
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> ACDB_CMD_GET_AFE_INSTANCE_COMMON_TABLE
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> AUDIO_SET_AFE_CAL cal_type[16] acdb_id[15]
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> send_hw_delay : acdb_id = 15 path = 0
01-15 13:12:43.418   918  4197 D ACDB-LOADER: ACDB -> ACDB_AVSYNC_INFO: ACDB_CMD_GET_DEVICE_PROPERTY
01-15 13:12:43.418   918  4197 D msm8974_platform: platform_split_snd_device: snd_device(10) num devices(0) new_snd_devices(0)
01-15 13:12:43.418   918  4197 I msm8974_platform: platform_get_custom_mtmx_params: no matching param with id 0 ip_ch 2 op_ch 2 uc_id 1 snd_dev 10
01-15 13:12:43.418   918  4197 D audio_hw_primary: enable_audio_route: apply mixer and update path: low-latency-playback quat_i2s
01-15 13:12:43.418   918  4197 D audio_route: Apply path: low-latency-playback quat_i2s
01-15 13:12:43.419   918  4197 D ultrasound_extn: ultrasound_select_devices_post: enter: engine_suspended: 0
01-15 13:12:43.419   918  4197 D ultrasound_extn: dump_usecase_list: SDM660 enter sensor_activated:0 hw_port_enabled:0
01-15 13:12:43.419   918  4197 D ultrasound_extn: dump_usecase_list: SDM660 select_devices_post usecase[#0]:(1, low-latency-playback) in_dev:(0, ) out_dv:(10, speaker)
01-15 13:12:43.420   918  4197 D ultrasound_extn: dump_usecase_list: SDM660 select_devices_post usecase[#1]:(53, spkr-vi-record) in_dev:(248, vi-feedback) out_dv:(0, )
01-15 13:12:43.420   918  4197 D audio_hw_primary: select_devices:FOUND ultrasound devices : snd_device(10: speaker)
01-15 13:12:43.420   918  4197 D audio_hw_notify_sensor: notify_audio_state: notify state(1) success
01-15 13:12:43.420   918  4197 D audio_hw_primary: select_devices:NotifyAudioState AUDIO_ULTRASOUND_ENABLE !
01-15 13:12:43.420   918  4197 D audio_hw_primary: select_devices: done
01-15 13:12:43.420   918  4197 E audio_ext_hw_plugin: [ext_hw_plugin_usecase_start] NULL input pointer
01-15 13:12:43.420   918  4197 E audio_hw_primary: start_output_stream: failed to start ext hw plugin
01-15 13:12:43.420   953  1814 I sensors-hal: AudioStateEventCallBack:1308, _audioState : 1, _preAudioState : 0
01-15 13:12:43.420   953  1814 I sensors-hal: AudioStateEventCallBack:1311, not active, return
01-15 13:12:43.458   918  4197 D msm8974_platform: platform_set_channel_map mixer_ctl_name:Playback Channel Map13
01-15 13:12:43.458   918  4197 D msm8974_platform: platform_set_channel_map: set mapping(1 2 0 0 0 0 0 0) for channel:2
01-15 13:12:43.459   918  4197 E audio_hw_extn: audio_extn_perf_lock_release: Perf lock release error 
01-15 13:12:43.459   918  4197 D audio_hw_primary: start_output_stream: exit
01-15 13:12:43.459   918  4197 D msm8974_platform: platform_split_snd_device: snd_device(10) num devices(0) new_snd_devices(0)
01-15 13:12:43.459   918  4197 I msm8974_platform: platform_get_custom_mtmx_params: no matching param with id 0 ip_ch 2 op_ch 2 uc_id 1 snd_dev 10
01-15 13:12:43.595  4494  4494 W Notification4R_GO: NotificationListener.onNotificationPosted-Socket is not available
01-15 13:12:43.603  4494  4494 W Notification4R_GO: NotificationListener.onNotificationPosted-Socket is not available
01-15 13:12:43.605  2495  2617 D PeopleSpaceWidgetMgr: Sbn doesn't contain valid PeopleTileKey: null/0/com.azefsw.audioconnect
01-15 13:12:43.616  2495  2617 D PeopleSpaceWidgetMgr: Sbn doesn't contain valid PeopleTileKey: null/0/com.azefsw.audioconnect
01-15 13:12:43.868  2835  2835 D QtiCarrierConfigHelper: WARNING, no carrier configs on phone Id: 1
01-15 13:12:44.052 31782  6634 W NetworkScheduler: Error inserting flex_time=9000 job_id=-1 period=20000 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=214815000 persistence_level=1 preferred_charging_state=1 required_network_type=0 runtime=1642277564051 retry_strategy={"maximum_backoff_seconds":{"3600":0},"initial_backoff_seconds":{"30":0},"retry_policy":{"0":0}} last_runtime=0, error message: UNIQUE constraint failed: pending_ops.tag, pending_ops.target_class, pending_ops.target_package, pending_ops.user_id (code 2067 SQLITE_CONSTRAINT_UNIQUE) [CONTEXT service_id=218 ]
01-15 13:12:44.199   959  2471 I WifiHAL : event received NL80211_CMD_VENDOR, vendor_id = 0x1374, subcmd = 0xd
01-15 13:12:44.199  1278  1278 I cnss-daemon: nl80211 response handler invoked
01-15 13:12:44.199  1278  1278 I cnss-daemon: nl80211_response_handler: cmd 103, vendorID 4980, subcmd 13  received
01-15 13:12:45.398  1588  1667 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
01-15 13:12:45.399  1588  1667 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
01-15 13:12:46.202   959  2471 I WifiHAL : event received NL80211_CMD_VENDOR, vendor_id = 0x1374, subcmd = 0xd
01-15 13:12:46.202  1278  1278 I cnss-daemon: nl80211 response handler invoked
01-15 13:12:46.202  1278  1278 I cnss-daemon: nl80211_response_handler: cmd 103, vendorID 4980, subcmd 13  received
01-15 13:12:46.350  5497  5704 W OIC_CA_TCP_SERVER_1023: poll() timedout
01-15 13:12:46.388  5497  5704 I OIC_CA_TCP_SERVER_1023: caglobals.tcp.connectionFds[0] = 97
01-15 13:12:46.388  5497  5704 I OIC_CA_TCP_SERVER_1023: readFd = 146
01-15 13:12:46.614   918   978 D audio_hw_primary: out_standby: enter: stream (0xed753000) usecase(1: low-latency-playback)
01-15 13:12:46.640   918   978 E audio_ext_hw_plugin: [ext_hw_plugin_usecase_stop] NULL input pointer
01-15 13:12:46.640   918   978 E audio_hw_primary: stop_output_stream: failed to stop ext hw plugin
01-15 13:12:46.640   918   978 D audio_hw_primary: disable_audio_route: reset and update mixer path: low-latency-playback quat_i2s
01-15 13:12:46.641   918   978 E soundtrigger: audio_extn_sound_trigger_update_stream_status: invalid input device 0x0, for event 2
01-15 13:12:46.641   918   978 D soundtrigger: audio_extn_sound_trigger_update_stream_status: uc_info->id 1 of type 0 for Event 2, with Raise=0
01-15 13:12:46.641   918   978 D msm8974_platform: platform_split_snd_device: snd_device(10) num devices(0) new_snd_devices(0)
01-15 13:12:46.641   918   978 I msm8974_platform: platform_get_custom_mtmx_params: no matching param with id 0 ip_ch 2 op_ch 2 uc_id 1 snd_dev 10
01-15 13:12:46.641   918   978 D hardware_info: hw_info_append_hw_type : device_name = speaker
01-15 13:12:46.641   918   978 D audio_hw_primary: disable_snd_device: snd_device(10: speaker)
01-15 13:12:46.641   918   978 D msm8974_platform: platform_split_snd_device: snd_device(10) num devices(0) new_snd_devices(0)
01-15 13:12:46.651   918   978 D hardware_info: hw_info_append_hw_type : device_name = vi-feedback
01-15 13:12:46.651   918   978 D audio_hw_primary: disable_snd_device: snd_device(248: vi-feedback)
01-15 13:12:46.651   918   978 D msm8974_platform: platform_split_snd_device: snd_device(248) num devices(0) new_snd_devices(0)
01-15 13:12:46.651   918   978 I soundtrigger: audio_extn_sound_trigger_update_device_status: device 0xf8 of type 1 for Event 0, with Raise=0
01-15 13:12:46.651   918   978 D audio_hw_primary: disable_audio_route: reset and update mixer path: spkr-vi-record
01-15 13:12:46.652   918   978 D soundtrigger: audio_extn_sound_trigger_update_stream_status: uc_info->id 53 of type 1 for Event 2, with Raise=0
01-15 13:12:46.652   918   978 I soundtrigger: audio_extn_sound_trigger_update_device_status: device 0xa of type 0 for Event 0, with Raise=0
01-15 13:12:46.652   918   978 D audio_hw_notify_sensor: notify_audio_state: notify state(0) success
01-15 13:12:46.652   918   978 D audio_hw_primary: disable_snd_device:NotifyAudioState AUDIO_ULTRASOUND_DISABLE !!
01-15 13:12:46.652   918   978 D audio_hw_primary: out_standby: exit
01-15 13:12:46.652   953  1814 I sensors-hal: AudioStateEventCallBack:1308, _audioState : 0, _preAudioState : 1
01-15 13:12:46.652   953  1814 I sensors-hal: AudioStateEventCallBack:1311, not active, return
01-15 13:12:48.204  1278  1278 I cnss-daemon: nl80211 response handler invoked
01-15 13:12:48.205  1278  1278 I cnss-daemon: nl80211_response_handler: cmd 103, vendorID 4980, subcmd 13  received
01-15 13:12:48.205   959  2471 I WifiHAL : event received NL80211_CMD_VENDOR, vendor_id = 0x1374, subcmd = 0xd
01-15 13:12:48.409  1731  6631 E AR      : Error on config exchange: Read timed out
01-15 13:12:48.411  1731  6631 E AR      : Stopping the player
01-15 13:12:48.411  1731  6631 E AR      : java.net.SocketTimeoutException: Read timed out
01-15 13:12:48.411  1731  6631 E AR      : 	at java.net.SocketInputStream.socketRead0(Native Method)
01-15 13:12:48.411  1731  6631 E AR      : 	at java.net.SocketInputStream.socketRead(SocketInputStream.java:119)
01-15 13:12:48.411  1731  6631 E AR      : 	at java.net.SocketInputStream.read(SocketInputStream.java:176)
01-15 13:12:48.411  1731  6631 E AR      : 	at java.net.SocketInputStream.read(SocketInputStream.java:144)
01-15 13:12:48.411  1731  6631 E AR      : 	at java.net.SocketInputStream.read(SocketInputStream.java:229)
01-15 13:12:48.411  1731  6631 E AR      : 	at com.google.protobuf.c.e(AbstractParser.java:1)
01-15 13:12:48.411  1731  6631 E AR      : 	at com.google.protobuf.o.G(GeneratedMessageV3.java:3)
01-15 13:12:48.411  1731  6631 E AR      : 	at m8.h.a(ConfigExchanger.kt:55)
01-15 13:12:48.411  1731  6631 E AR      : 	at n8.h.g(ConnectorImpl.kt:12)
01-15 13:12:48.411  1731  6631 E AR      : 	at n8.h.f(ConnectorImpl.kt:17)
01-15 13:12:48.411  1731  6631 E AR      : 	at r6.k.d(Unknown Source:3)
01-15 13:12:48.411  1731  6631 E AR      : 	at gk.b.o(SingleCreate.java:3)
01-15 13:12:48.411  1731  6631 E AR      : 	at sj.r.c(Single.java:2)
01-15 13:12:48.411  1731  6631 E AR      : 	at gk.g$a.onSuccess(SingleFlatMap.java:5)
01-15 13:12:48.411  1731  6631 E AR      : 	at fk.o$a.h(ObservableElementAtSingle.java:6)
01-15 13:12:48.411  1731  6631 E AR      : 	at fk.l$a.h(ObservableDoOnEach.java:3)
01-15 13:12:48.411  1731  6631 E AR      : 	at fk.d0$a.h(ObservableMap.java:6)
01-15 13:12:48.411  1731  6631 E AR      : 	at fk.d$b.d(ObservableCombineLatest.java:23)
01-15 13:12:48.411  1731  6631 E AR      : 	at fk.d$a.h(ObservableCombineLatest.java:12)
01-15 13:12:48.411  1731  6631 E AR      : 	at fk.d0$a.h(ObservableMap.java:6)
01-15 13:12:48.411  1731  6631 E AR      : 	at fk.j$a.h(ObservableDistinctUntilChanged.java:11)
01-15 13:12:48.411  1731  6631 E AR      : 	at fk.e0$a.run(ObservableObserveOn.java:23)
01-15 13:12:48.411  1731  6631 E AR      : 	at ik.l.run(ScheduledRunnable.java:2)
01-15 13:12:48.411  1731  6631 E AR      : 	at ik.l.call(ScheduledRunnable.java:1)
01-15 13:12:48.411  1731  6631 E AR      : 	at java.util.concurrent.FutureTask.run(FutureTask.java:266)
01-15 13:12:48.411  1731  6631 E AR      : 	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(ScheduledThreadPoolExecutor.java:301)
01-15 13:12:48.411  1731  6631 E AR      : 	at java.util.concurrent.ThreadPoolExecutor.runWorker(ThreadPoolExecutor.java:1167)
01-15 13:12:48.411  1731  6631 E AR      : 	at java.util.concurrent.ThreadPoolExecutor$Worker.run(ThreadPoolExecutor.java:641)
01-15 13:12:48.411  1731  6631 E AR      : 	at com.airbnb.epoxy.y.run(Unknown Source:17)
01-15 13:12:48.411  1731  6631 E AR      : 	at java.lang.Thread.run(Thread.java:920)
01-15 13:12:48.411  1731  6631 I player  : Stopping the player, reason: error
01-15 13:12:48.411  1731  1884 I AR      : Releasing wakelocks...
01-15 13:12:48.412  1588  2922 I WifiService: releaseWifiLock uid=10351
01-15 13:12:48.428  4494  4494 W Notification4R_GO: NotificationListener.onNotificationPosted-Socket is not available
01-15 13:12:48.436 31782  6634 W NetworkScheduler: Error inserting flex_time=3595000 job_id=-1 period=7192000 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=214815000 persistence_level=1 preferred_charging_state=1 required_network_type=0 runtime=1642277568435 retry_strategy={"maximum_backoff_seconds":{"3600":0},"initial_backoff_seconds":{"30":0},"retry_policy":{"0":0}} last_runtime=0, error message: UNIQUE constraint failed: pending_ops.tag, pending_ops.target_class, pending_ops.target_package, pending_ops.user_id (code 2067 SQLITE_CONSTRAINT_UNIQUE) [CONTEXT service_id=218 ]
01-15 13:12:48.442 31782  6634 W NetworkScheduler: Error inserting flex_time=3595000 job_id=-1 period=7192000 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=214815000 persistence_level=1 preferred_charging_state=1 required_network_type=0 runtime=1642277568440 retry_strategy={"maximum_backoff_seconds":{"3600":0},"initial_backoff_seconds":{"30":0},"retry_policy":{"0":0}} last_runtime=0, error message: UNIQUE constraint failed: pending_ops.tag, pending_ops.target_class, pending_ops.target_package, pending_ops.user_id (code 2067 SQLITE_CONSTRAINT_UNIQUE) [CONTEXT service_id=218 ]
01-15 13:12:48.460  2495  2617 D PeopleSpaceWidgetMgr: Sbn doesn't contain valid PeopleTileKey: null/0/com.azefsw.audioconnect
01-15 13:12:48.625  4494  4494 W Notification4R_GO: NotificationListener.onNotificationPosted-Socket is not available
01-15 13:12:48.633  2495  2617 D PeopleSpaceWidgetMgr: Sbn doesn't contain valid PeopleTileKey: null/0/com.azefsw.audioconnect
01-15 13:12:48.633  4494  4494 W Notification4R_GO: NotificationListener.onNotificationPosted-Socket is not available
01-15 13:12:48.638  4494  4494 W Notification4R_GO: NotificationListener.onNotificationPosted-Socket is not available
01-15 13:12:48.642  4494  4494 W Notification4R_GO: NotificationListener.onNotificationPosted-Socket is not available
01-15 13:12:48.648  2495  2617 D PeopleSpaceWidgetMgr: Sbn doesn't contain valid PeopleTileKey: null/0/com.azefsw.audioconnect
01-15 13:12:48.659  2495  2617 D PeopleSpaceWidgetMgr: Sbn doesn't contain valid PeopleTileKey: null/0/com.azefsw.audioconnect
01-15 13:12:48.671  2495  2617 D PeopleSpaceWidgetMgr: Sbn doesn't contain valid PeopleTileKey: null/0/com.azefsw.audioconnect
01-15 13:12:48.920  1588  4640 I WifiService: acquireMulticastLock uid=10351
01-15 13:12:49.075 31594  6749 W GAv4-SVC: Network compressed POST connection error: java.net.ConnectException: Failed to connect to ssl.google-analytics.com/0.0.0.0:443
01-15 13:12:50.006  2835  2835 D QtiCarrierConfigHelper: WARNING, no carrier configs on phone Id: 1

[Asapha]

Thanks for the logs!

The app reports that it doesn’t hear back from the server midway through the initial communication.
It’s possible that there’s an error server side which would close the connection.
But I don’t see why this would work on Wifi but not via USB tethering.

And with using the regular mode

Regular mode means via Wifi, right?

I was on 0.21 already

Could you please verify that you’re on 0.22.1 on both sides (not 0.21)?

Lastly, I think that we need to inspect the logs from the server side. You can get them through the server’s setting screen this time.

[C_B]

Whoops. Looks like I got confused between 0.22.1 and 0.21. Yeah I’m on 0.22.1 on both sides. Here’s the log from the host side

2022-01-16 14:02:15,679 INFO [IpcAudio] Audio socket connected
2022-01-16 14:02:15,726 INFO [IpcBackendStatusProbe] Backend is ready
2022-01-16 14:02:15,726 INFO [AR] Requesting startup settings...
2022-01-16 14:02:15,958 INFO [AR] Found no update
2022-01-16 14:02:16,210 INFO [AR] Windows' backend devices: [WindowsAudioDevice(id={0.0.0.00000000}.{3a07f29c-5861-410d-9db4-b28eabdde932}, type=Render, name=D50s (2- TOPPING USB DAC), isDefault=true, sampleRate=48000, channels=2), WindowsAudioDevice(id={0.0.0.00000000}.{92a70f85-82f6-4a5f-bce4-2b950e3092c2}, type=Render, name=DELL U2719DX (NVIDIA High Definition Audio), isDefault=false, sampleRate=48000, channels=2), WindowsAudioDevice(id={0.0.0.00000000}.{44c0cd22-3898-4b9e-8160-9c1cbe8578f0}, type=Render, name=DELL U2722D (NVIDIA High Definition Audio), isDefault=false, sampleRate=48000, channels=2)]
2022-01-16 14:02:17,944 INFO [AR] Network addresses: NetworkInfo(broadcastAddresses=[192.168.86.255], localAddresses=[192.168.86.196])
2022-01-16 14:21:50,462 INFO [AR] Showing main window...
2022-01-16 14:22:24,929 INFO [AR] Network addresses: NetworkInfo(broadcastAddresses=[192.168.126.255, 192.168.86.255], localAddresses=[192.168.126.131, 192.168.86.196])
2022-01-16 14:22:55,644 INFO [AR] Network addresses: NetworkInfo(broadcastAddresses=[192.168.126.255], localAddresses=[192.168.126.131])
2022-01-16 14:23:03,588 INFO [ClientListener] Remote device connected: 192.168.126.2
2022-01-16 14:23:13,595 ERROR [server_session_bootstrapper] Initial TCP communication failed
com.google.protobuf.InvalidProtocolBufferException: Read timed out
	at com.google.protobuf.AbstractParser.parsePartialDelimitedFrom(Unknown Source) ~[com.azefsw.merged.module:?]
	at com.google.protobuf.AbstractParser.parsePartialDelimitedFrom(Unknown Source) ~[com.azefsw.merged.module:?]
	at com.google.protobuf.AbstractParser.parsePartialDelimitedFrom(Unknown Source) ~[com.azefsw.merged.module:?]
	at com.azefsw.audioconnect.k.g.e.a(SourceFile:1062) ~[audiorelay.jar:?]
	at com.azefsw.audioconnect.k.k.a.a.a(SourceFile:81) ~[audiorelay.jar:?]
	at io.reactivex.internal.operators.completable.CompletableFromAction.subscribeActual(Unknown Source) [com.azefsw.merged.module:?]
	at io.reactivex.Completable.subscribe(Unknown Source) [com.azefsw.merged.module:?]
	at io.reactivex.internal.operators.observable.ObservableFlatMapCompletableCompletable$FlatMapCompletableMainObserver.onNext(Unknown Source) [com.azefsw.merged.module:?]
	at io.reactivex.internal.operators.observable.ObservableTake$TakeObserver.onNext(Unknown Source) [com.azefsw.merged.module:?]
	at io.reactivex.internal.operators.observable.ObservableObserveOn$ObserveOnObserver.drainNormal(Unknown Source) [com.azefsw.merged.module:?]
	at io.reactivex.internal.operators.observable.ObservableObserveOn$ObserveOnObserver.run(Unknown Source) [com.azefsw.merged.module:?]
	at io.reactivex.internal.schedulers.ScheduledRunnable.run(Unknown Source) [com.azefsw.merged.module:?]
	at io.reactivex.internal.schedulers.ScheduledRunnable.call(Unknown Source) [com.azefsw.merged.module:?]
	at java.util.concurrent.FutureTask.run(Unknown Source) [?:?]
	at java.util.concurrent.ScheduledThreadPoolExecutor$ScheduledFutureTask.run(Unknown Source) [?:?]
	at java.util.concurrent.ThreadPoolExecutor.runWorker(Unknown Source) [?:?]
	at java.util.concurrent.ThreadPoolExecutor$Worker.run(Unknown Source) [?:?]
	at java.lang.Thread.run(Unknown Source) [?:?]
Caused by: java.net.SocketTimeoutException: Read timed out
	at sun.nio.ch.NioSocketImpl.timedRead(Unknown Source) ~[?:?]
	at sun.nio.ch.NioSocketImpl.implRead(Unknown Source) ~[?:?]
	at sun.nio.ch.NioSocketImpl.read(Unknown Source) ~[?:?]
	at sun.nio.ch.NioSocketImpl$1.read(Unknown Source) ~[?:?]
	at java.net.Socket$SocketInputStream.read(Unknown Source) ~[?:?]
	at java.net.Socket$SocketInputStream.read(Unknown Source) ~[?:?]
	... 18 more

[Asapha]

So, the initial connection from the client to the server works (TCP on port 59100).
Then the PC successfully writes a message to your phone but when it’s time for the phone to respond, it stops working.

Besides a firewall, I’m not sure what would prevent the app from communicating back.
Could you try to temporarily switch off your firewall on PC? (And anything equivalent on your phone since you’re rooted.)
Or anything that does packet inspection which, maybe, doesn’t re-route packets correctly.

[C_B]

Sorry for disappearing for a few days but I’ve tried the solutions posted but still to no avail.