[AT&T Samsung Galaxy Note I717] chn110:
Debugging VoIP - logs included

Every single recent i717 ROM has a broken VoIP dialer. When dialing a VoIP call, the call will connect, but there is no audio, and you can't hangup the phone. To help debug the problem, I am attaching two log files, one from a working ROM and one from the broken one. Hope someone here may be able to see what is causing the problem.

VoIPWorking.txt - this is from BeanStalk-4.4015-20131204-quincyatt ROM. VoIP works fine in this ROM

VoIPBroken.txt - this is from the most recent BeanStalk ROM. VoIP is broken.

Looking at the section of log when the phone started dialing (search for "Routing audio to VOIP handset"), I noticed that in the working ROM, it seems to be dealing with ALSA device, whereas in the broken one, no ALSA device can be found and it seems to be dealing with AudioGroup. See below:

Working:

03-04 23:19:30.160 D/AudioHardwareMSM8660( 328): Routing audio to VOIP handset

03-04 23:19:30.160 V/AudioHardwareMSM8660( 328): doAudioRouteOrMute() device 32, mMode 3, mMicMute 1

03-04 23:19:30.160 V/AudioHardwareMSM8660( 328): do_route_audio_rpc(50, 3, 1)

03-04 23:19:30.160 D/AudioHardwareMSM8660( 328): In VOIP HANDSET

03-04 23:19:30.160 D/AudioHardwareMSM8660( 328): new_rx = 14

03-04 23:19:30.160 D/AudioHardwareMSM8660( 328): new_tx = 15

03-04 23:19:30.160 D/AudioHardwareMSM8660( 328): updateDeviceInfo() called for default case

03-04 23:19:30.160 D/AudioHardwareMSM8660( 328): updateDeviceInfo: E

03-04 23:19:30.160 D/AudioHardwareMSM8660( 328): The node type is 1

03-04 23:19:30.160 V/AudioHardwareMSM8660( 328): rx_device = 40,temp_ptr->dev_id = 2

03-04 23:19:30.160 V/AudioHardwareMSM8660( 328): value of device and enable is 2 0 ALSA dev id:2

03-04 23:19:30.180 E/BluetoothPhoneService( 1067): Could not get a handle on Connection object for the call

03-04 23:19:30.210 V/AudioHardwareMSM8660( 328): value of device and enable is 40 1 ALSA dev id:14

03-04 23:19:30.260 D/ACDB-LOADER( 328): ACDB -> send_audio_cal, acdb_id = 40, path = 0

03-04 23:19:30.260 D/ACDB-LOADER( 328): ACDB -> send_adm_topology

03-04 23:19:30.260 D/ACDB-LOADER( 328): ACDB -> ACDB_CMD_GET_AUDPROC_COMMON_TOPOLOGY_ID

Broken:

03-01 23:08:03.216 D/AudioHardwareMSM8660( 339): Routing audio to VOIP handset

03-01 23:08:03.216 D/AudioHardwareMSM8660( 339): In VOIP HANDSET

03-01 23:08:03.216 D/AudioHardwareMSM8660( 339): new_rx = 14

03-01 23:08:03.216 D/AudioHardwareMSM8660( 339): new_tx = 15

03-01 23:08:03.216 D/AudioHardwareMSM8660( 339): updateDeviceInfo() called for default case

03-01 23:08:03.216 D/AudioHardwareMSM8660( 339): updateDeviceInfo: E

03-01 23:08:03.216 D/AudioHardwareMSM8660( 339): simple device switch

03-01 23:08:03.366 D/AudioGroup( 8665): stream[115] is configured as GSM 8kHz 20ms mode 0

03-01 23:08:03.366 D/AudioGroup( 8665): stream[118] is configured as RAW 8kHz 32ms mode 0

03-01 23:08:03.366 D/AudioGroup( 8665): stream[118] joins group[117]

03-01 23:08:03.366 D/AudioGroup( 8665): stream[115] joins group[117]

03-01 23:08:03.366 D/AudioGroup( 8665): group[117] switches from mode 0 to 2

03-01 23:08:03.366 D/AudioHardwareMSM8660( 339): Bufsize 4800

03-01 23:08:03.366 D/AudioHardwareMSM8660( 339): SampleRate 44.1k

03-01 23:08:03.366 D/AudioHardwareMSM8660( 339): AudioHardware::getInputBufferSize sampleRate 8000 format 1 channelCount 1

03-01 23:08:03.366 D/AudioHardwareMSM8660( 339): getInputBufferSize: sampleRate: 8000 channelCount: 1 bufferSize: 320

03-01 23:08:03.376 D/AudioGroup( 8665): reported frame count: output 435, input 320

03-01 23:08:03.376 D/AudioGroup( 8665): adjusted frame count: output 512, input 512

03-01 23:08:03.376 D/AudioTrack( 8665): Turn on Direct Output for VOIP RX

03-01 23:08:03.376 D/AudioPolicyManager( 339): profile found: device 1, flags 4001, samplingrate 8000, format 1, channelMask 1

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioHardware::openOutputStream devices 1 format 1 channels 1 samplerate 8000 flags 16385

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect::set lFormat = 1 lChannels= 1 lRate = 8000

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: format 1

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: channels 1

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: SampleRate 8000

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: SampleRate 8000

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: channels 1

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: format 1

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: format 1

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: channels 1

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: channels 1

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: bufferSize 320

03-01 23:08:03.376 I/AudioFlinger( 339): HAL output buffer size 160 frames, normal mix buffer size 160 frames

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: bufferSize 320

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: channels 1

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: format 1

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: SampleRate 8000

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: bufferSize 320

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: channels 1

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: format 1

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: SampleRate 8000

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: bufferSize 320

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: channels 1

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: format 1

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamOutDirect: SampleRate 8000

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioHardware::openInputStream devices 100000 format 1 channels 16 samplerate 8000 in_p=80000040 lin_p=4000000 in_v=80000001 lin_v=100000

03-01 23:08:03.376 E/AudioHardwareMSM8660( 339): Create Audio stream Voip

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamInVoip::set devices = 1048576 format = 1 pChannels = 16 Rate = 8000

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): AudioStreamInVoip::set(1, 16, 8000)

03-01 23:08:03.376 I/AudioFlinger( 339): AudioFlinger's thread 0x400b0d38 ready to run

03-01 23:08:03.376 D/AudioHardwareMSM8660( 339): Voipin 0 driver fd -1

03-01 23:08:03.376 E/AudioHardwareMSM8660( 339): Not closing MVS driver

03-01 23:08:03.447 D/ACDB-LOADER( 339): ACDB -> send_voice_cal, acdb_rx = 40, acdb_tx = 41

03-01 23:08:03.447 D/ACDB-LOADER( 339): ACDB -> send_voice_rx_topology

03-01 23:08:03.447 D/ACDB-LOADER( 339): ACDB -> ACDB_CMD_GET_DEVICE_INFO

03-01 23:08:03.447 D/ACDB-LOADER( 339): ACDB ->