No sound in voice calls after a few days

I’m using Droidian on a Vollaphone 22. After flashing the phone, normal audio calls work out of the box. But after a day or a few, I can’t hear anything while making a phone call, and the person on the other end likewise. The same goes if I receive a call. Only a faintly low frequent buzzing is noticeable on both ends. What I tried / checked to get it working again:

  • Sound works in general. I can test the sound, and I hear the phone ringing when Feedback settings for Calls and Profile is on Full
  • If the other person hangs up I hear three beep, beep, beep
  • VoIP accounts are affected too
  • Doesn’t matter if output is to earpiece or speaker, both don’t show any effect
  • pactl settings checked, and changed quite a few, but with no effect at all
  • Rebooted the modem: sudo mmcli -m any -r with no effect
  • Rebooted the phone
  • Turned the phone off and removed battery to be sure the modem doesn’t hang
  • Set modem specifically to 2G, 3G
  • Tried droidian version 101 and 102

Nothing had any effect. I’m always ending up flashing my phone again after a few days. Then I can make calls again. It’s frustrating, because I know in general it’s working, but the error here is none obvious.

Does anyone had similar issues? Or maybe just some ideas what could go possibly wrong? Sounds to me like a problem with pipewire routing, but that’s just a guess.

Are you sure its only after day or two? For me on different device only happens if I restart phosh without reboot.

1 Like

Could you post ofono logs when trying calling?

sudo journalctl --no-pager -xe -u ofono

(please check and scrub eventual sensitive data)

Here are my logs of ofono. The first four times I tried calling, while during the last two lines I received a call. Anything else you need?

Feb 04 21:48:22 VollaPhone22 ofonod[3381]: ofonod[3381]: dialing ""
Feb 04 21:48:22 VollaPhone22 ofonod[3381]: dialing ""
Feb 04 21:48:57 VollaPhone22 ofonod[3381]: ofonod[3381]: Call 1 ended with cause 16 -> ofono reason 2
Feb 04 21:48:57 VollaPhone22 ofonod[3381]: Call 1 ended with cause 16 -> ofono reason 2
Feb 04 21:50:45 VollaPhone22 ofonod[3381]: ofonod[3381]: Call 1 ended with cause 16 -> ofono reason 2
Feb 04 21:50:45 VollaPhone22 ofonod[3381]: Call 1 ended with cause 16 -> ofono reason 2

I haven’t found out what actually triggered it. Could likely be that it is a restart of phosh without reboot.
But do you recover it? Just simply by rebooting the phone completely?

Thank you. Sorry, I missed one crucial part, which is to enable Debug logging (apologies for that - I have it enabled on various devices)

sudo systemctl edit ofono

add

[Service]
ExecStart=
ExecStart=/usr/sbin/ofonod --nodetach -d *

save, then

sudo systemctl restart ofono

to disable:

sudo rm -f /etc/systemd/system/ofono.service.d/override.conf
sudo systemctl daemon-reload
sudo systemctl restart ofono

it will be rather verbose, though. And please double check the output as there might be sensible data indeed.

There are various layers on the Droidian telephony. Perhaps you might try restarting the ofono service and ofono2mm (which replaces the ModemManager service):

sudo systemctl restart ofono
sudo systemctl restart ModemManager

Got you. After enabling debug mode, I got the following logs. Now I understand what you mean by rather verbose. In total I’ve logs of three calls, coming and going. Just showing here one. Hope that helps:

Feb 09 21:20:55 VollaPhone22 ofonod[3393]: ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:20:55 VollaPhone22 ofonod[3393]: ofonod[3393]: plugins/upower.c:update_battery_level() last_battery_level: 0 battery_level: 4 (75.000000)
Feb 09 21:20:55 VollaPhone22 ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:20:55 VollaPhone22 ofonod[3393]: plugins/upower.c:update_battery_level() last_battery_level: 0 battery_level: 4 (75.000000)
Feb 09 21:21:00 VollaPhone22 ofonod[3393]: ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:21:00 VollaPhone22 ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:21:05 VollaPhone22 ofonod[3393]: ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:21:05 VollaPhone22 ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:21:10 VollaPhone22 ofonod[3393]: ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:21:10 VollaPhone22 ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:21:15 VollaPhone22 ofonod[3393]: ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:21:15 VollaPhone22 ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:21:45 VollaPhone22 ofonod[3393]: ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:21:45 VollaPhone22 ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:21:50 VollaPhone22 ofonod[3393]: ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:21:50 VollaPhone22 ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:21:55 VollaPhone22 ofonod[3393]: ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:21:55 VollaPhone22 ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:22:00 VollaPhone22 ofonod[3393]: ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:22:00 VollaPhone22 ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:  6f 00 00 9e 00 00 00                             io......
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: slot1 < 130 responseAcknowledgement
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: slot1 > [0000009e] 140 getCurrentCallsResponse_1_2
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 32 3a 3a 49 52 61 64    .radio@1 .2::IRad
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0020: 69 6f 52 65 73 70 6f 6e  73 65 00 00 85 2a 74 70    ioRespon se...*tp
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0030: 00 00 00 00 c0 20 14 9a  7f 00 00 00 0c 00 00 00    ..... .. ........
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0040: 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0050: 00 00 00 00 85 2a 74 70  00 00 00 00 d0 20 14 9a    .....*tp ..... ..
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0060: 7f 00 00 00 10 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0070: 00 00 00 00 00 00 00 00  00 00 00 00 85 2a 74 70    ........ .....*tp
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0080: 01 00 00 00 e0 20 14 9a  7f 00 00 00 00 00 00 00    ..... .. ........
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0090: 00 00 00 00 01 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   00A0: 00 00 00 00                                         ....
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: slot1 < [0000009f] 19 getLastCallFailCause
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0020: 69 6f 00 00 9f 00 00 00                             io......
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: slot1 > [0000009f] 18 getLastCallFailCauseResponse
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0020: 69 6f 52 65 73 70 6f 6e  73 65 00 00 85 2a 74 70    ioRespon se...*tp
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0030: 00 00 00 00 c0 20 14 9a  7f 00 00 00 0c 00 00 00    ..... .. ........
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0040: 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0050: 00 00 00 00 85 2a 74 70  00 00 00 00 d0 20 14 9a    .....*tp ..... ..
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0060: 7f 00 00 00 18 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0070: 00 00 00 00 00 00 00 00  00 00 00 00 85 2a 74 70    ........ .....*tp
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0080: 01 00 00 00 e8 20 14 9a  7f 00 00 00 13 00 00 00    ..... .. ........
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0090: 00 00 00 00 01 00 00 00  00 00 00 00 08 00 00 00    ........ ........
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   00A0: 00 00 00 00                                         ....
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: slot1 > 2 callStateChanged
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0020: 69 6f 49 6e 64 69 63 61  74 69 6f 6e 00 00 00 00    ioIndica tion....
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0030: 01 00 00 00                                         ....
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: slot1 < [000000a0] 10 getCurrentCalls
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0020: 69 6f 00 00 a0 00 00 00                             io......
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: slot1 < 130 responseAcknowledgement
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: slot1 > 18 callRing
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0020: 69 6f 49 6e 64 69 63 61  74 69 6f 6e 00 00 00 00    ioIndica tion....
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0030: 01 00 00 00 01 00 00 00  85 2a 74 70 00 00 00 00    ........ .*tp....
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0040: a0 20 14 9a 7f 00 00 00  04 00 00 00 00 00 00 00    . ...... ........
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0050: 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: slot1 < 130 responseAcknowledgement
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: slot1 > 2 callStateChanged
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0020: 69 6f 49 6e 64 69 63 61  74 69 6f 6e 00 00 00 00    ioIndica tion....
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0030: 01 00 00 00                                         ....
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: slot1 < 130 responseAcknowledgement
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: slot1 > [000000a0] 140 getCurrentCallsResponse_1_2
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 32 3a 3a 49 52 61 64    .radio@1 .2::IRad
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0020: 69 6f 52 65 73 70 6f 6e  73 65 00 00 85 2a 74 70    ioRespon se...*tp
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0030: 00 00 00 00 48 21 14 9a  7f 00 00 00 0c 00 00 00    ....H!.. ........
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: src/binder_voicecall.c:binder_voicecall_info_new() [id=1,status=4,type=0,number=,name=]
Feb 09 21:22:04 VollaPhone22 ofonod[3393]:   0040: 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00    .......ofonod[3393]: src/binder_voicecall.c:binder_voicecall_info_new() [id=1,status=4,type=0,number=,name=]
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: ofonod[3393]: src/voicecall.c:ofono_voicecall_notify() Got a voicecall event, status: incoming (4), id: 1, number:  called_number: , called_name
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: ofonod[3393]: src/voicecall.c:ofono_voicecall_notify() Did not find a call with id: 1
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: src/voicecall.c:ofono_voicecall_notify() Got a voicecall event, status: incoming (4), id: 1, number:  called_number: , called_name
Feb 09 21:22:04 VollaPhone22 ofonod[3393]: src/voicecall.c:ofono_voicecall_notify() Did not find a call with id: 1
Feb 09 21:22:05 VollaPhone22 ofonod[3393]: ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:22:05 VollaPhone22 ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:22:08 VollaPhone22 ofonod[3393]: ofonod[3393]: src/binder_voicecall.c:binder_voicecall_info_new() [id=1,status=4,type=0,number=,name=]
Feb 09 21:22:08 VollaPhone22 ofonod[3393]: src/binder_voicecall.c:binder_voicecall_info_new() [id=1,status=4,type=0,number=,name=]
Feb 09 21:22:10 VollaPhone22 ofonod[3393]: ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:22:10 VollaPhone22 ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:22:11 VollaPhone22 ofonod[3393]: ofonod[3393]: src/binder_voicecall.c:binder_voicecall_answer() slot1 answering current call
Feb 09 21:22:11 VollaPhone22 ofonod[3393]: src/binder_voicecall.c:binder_voicecall_answer() slot1 answering current call
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: . ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0050: 00 00 00 00 85 2a 74 70  00 00 00 00 58 21 14 9a    .....*tp ....X!..
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0060: 7f 00 00 00 10 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0070: 00 00 00 00 00 00 00 00  00 00 00 00 85 2a 74 70    ........ .....*tp
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0080: 01 00 00 00 68 21 14 9a  7f 00 00 00 60 00 00 00    ....h!.. ....`...
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0090: 00 00 00 00 01 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   00A0: 00 00 00 00 85 2a 74 70  01 00 00 00 c8 21 14 9a    .....*tp .....!..
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   00B0: 7f 00 00 00 0e 00 00 00  00 00 00 00 02 00 00 00    ........ ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   00C0: 00 00 00 00 18 00 00 00  00 00 00 00 85 2a 74 70    ........ .....*tp
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   00D0: 01 00 00 00 d8 21 14 9a  7f 00 00 00 01 00 00 00    .....!.. ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   00E0: 00 00 00 00 02 00 00 00  00 00 00 00 30 00 00 00    ........ ....0...
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   00F0: 00 00 00 00 85 2a 74 70  01 00 00 00 00 00 00 00    .....*tp ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0100: 00 00 00 00 00 00 00 00  00 00 00 00 02 00 00 00    ........ ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0110: 00 00 00 00 48 00 00 00  00 00 00 00                ....H... ....
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: slot1 > 2 callStateChanged
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0020: 69 6f 49 6e 64 69 63 61  74 69 6f 6e 00 00 00 00    ioIndica tion....
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0030: 01 00 00 00                                         ....
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: slot1 < [000000a1] 10 getCurrentCalls
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0020: 69 6f 00 00 a1 00 00 00                             io......
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: slot1 < 130 responseAcknowledgement
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: slot1 > 18 callRing
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0020: 69 6f 49 6e 64 69 63 61  74 69 6f 6e 00 00 00 00    ioIndica tion....
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0030: 01 00 00 00 01 00 00 00  85 2a 74 70 00 00 00 00    ........ .*tp....
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: src/binder_voicecall.c:binder_voicecall_info_new() [id=1,status=0,type=0,number=,name=]
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0040: 68 20 14 9a 7f 00 00 00  04 00 00 00 00 00 00 00    h ...... ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0050: 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: slot1 < 130 responseAcknowledgement
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: slot1 > [000000a1] 140 getCurrentCallsResponse_1_2
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 32 3a 3a 49 52 61 64    .radio@1 .2::IRad
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0020: 69 6f 52 65 73 70 6f 6e  73 65 00 00 85 2a 74 70    ioRespon se...*tp
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0030: 00 00 00 00 48 21 14 9a  7f 00 00 00 0c 00 00 00    ....H!.. ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0040: 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0050: 00 00 00 00 85 2a 74 70  00 00 00 00 58 21 14 9a    .....*tp ....X!..
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0060: 7f 00 00 00 10 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0070: 00 00 00 00 00 00 00 00  00 00 00 00 85 2a 74 70    ........ .....*tp
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0080: 01 00 00 00 68 21 14 9a  7f 00 00 00 60 00 00 00    ....h!.. ....`...
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0090: 00 00 00 00 01 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   00A0: 00 00 00 00 85 2a 74 70  01 00 00 00 c8 21 14 9a    .....*tp .....!..
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   00B0: 7f 00 00 00 0e 00 00 00  00 00 00 00 02 00 00 00    ........ ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   00C0: 00 00 00 00 18 00 00 00  00 00 00 00 85 2a 74 70    ........ .....*tp
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   00D0: 01 00 00 00 d8 21 14 9a  7f 00 00 00 01 00 00 00    .....!.. ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   00E0: 00 00 00 00 02 00 00 00  00 00 00 00 30 00 00 00    ........ ....0...
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   00F0: 00 00 00 00 85 2a 74 70  01 00 00 00 00 00 00 00    .....*tp ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0100: 00 00 00 00 00 00 00 00  00 00 00 00 02 00 00 00    ........ ........
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0110: 00 00 00 00 48 00 00 00  00 00 00 00                ....H... ....
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: slot1 < [000000a2] 39 acceptCall
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0020: 69 6f 00 00 a2 00 00 00                             io......
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: slot1 > [000000a2] 38 acceptCallResponse
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: src/voicecall.c:ofono_voicecall_notify() Got a voicecall event, status: active (0), id: 1, number:  called_number: , called_name
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:12 VollaPhone22 ofonod[3393]:   0020: 69 6f 52 65 ofonod[3393]: src/binder_voicecall.c:binder_voicecall_info_new() [id=1,status=0,type=0,number=,name=]
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: ofonod[3393]: src/voicecall.c:ofono_voicecall_notify() Got a voicecall event, status: active (0), id: 1, number:  called_number: , called_name
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: ofonod[3393]: src/voicecall.c:ofono_voicecall_notify() Found call with id: 1
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: src/voicecall.c:ofono_voicecall_notify() Found call with id: 1
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: ofonod[3393]: src/handsfree-audio.c:agent_disconnect() Agent :1.187 disconnected
Feb 09 21:22:12 VollaPhone22 ofonod[3393]: src/handsfree-audio.c:agent_disconnect() Agent :1.187 disconnected
Feb 09 21:22:13 VollaPhone22 ofonod[3393]: ofonod[3393]: src/handsfree-audio.c:am_agent_register() Agent :1.224 registered with the CODECs: CVSD
Feb 09 21:22:13 VollaPhone22 ofonod[3393]: ofonod[3393]: src/handsfree-audio.c:am_agent_register() Wideband speech disabled: no mSBC support
Feb 09 21:22:13 VollaPhone22 ofonod[3393]: src/handsfree-audio.c:am_agent_register() Agent :1.224 registered with the CODECs: CVSD
Feb 09 21:22:13 VollaPhone22 ofonod[3393]: src/handsfree-audio.c:am_agent_register() Wideband speech disabled: no mSBC support
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: ofonod[3393]: src/binder_voicecall.c:binder_voicecall_hangup() slot1 hanging up call with id 1
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: src/binder_voicecall.c:binder_voicecall_hangup() slot1 hanging up call with id 1
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: 73 70 6f 6e  73 65 00 00 85 2a 74 70    ioRespon se...*tp
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0030: 00 00 00 00 60 20 14 9a  7f 00 00 00 0c 00 00 00    ....` .. ........
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0040: 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0050: 00 00 00 00                                         ....
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: slot1 < [000000a3] 10 getCurrentCalls
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0020: 69 6f 00 00 a3 00 00 00                             io......
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: slot1 > 2 callStateChanged
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0020: 69 6f 49 6e 64 69 63 61  74 69 6f 6e 00 00 00 00    ioIndica tion....
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0030: 01 00 00 00                                         ....
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: slot1 < 130 responseAcknowledgement
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: slot1 > 2 callStateChanged
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0020: 69 6f 49 6e 64 69 63 61  74 69 6f 6e 00 00 00 00    ioIndica tion....
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0030: 01 00 00 00                                         ....
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: slot1 < 130 responseAcknowledgement
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: slot1 > [000000a3] 140 getCurrentCallsResponse_1_2
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 32 3a 3a 49 52 61 64    .radio@1 .2::IRad
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0020: 69 6f 52 65 73 70 6f 6e  73 65 00 00 85 2a 74 70    ioRespon se...*tp
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0030: 00 00 00 00 48 21 14 9a  7f 00 00 00 0c 00 00 00    ....H!.. ........
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0040: 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0050: 00 00 00 00 85 2a 74 70  00 00 00 00 58 21 14 9a    .....*tp ....X!..
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0060: 7f 00 00 00 10 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0070: 00 00 00 00 00 00 00 00  00 00 00 00 85 2a 74 70    ........ .....*tp
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0080: 01 00 00 00 68 21 14 9a  7f 00 00 00 60 00 00 00    ....h!.. ....`...
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0090: 00 00 00 00 01 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   00A0: 00 00 00 00 85 2a 74 70  01 00 00 00 c8 21 14 9a    .....*tp .....!..
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   00B0: 7f 00 00 00 0e 00 00 00  00 00 00 00 02 00 00 00    ........ ........
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   00C0: 00 00 00 00 18 00 00 00  00 00 00 00 85 2a 74 70    ........ .....*tp
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   00D0: 01 00 00 00 d8 21 14 9a  7f 00 00 00 01 00 00 00    .....!.. ........
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   00E0: 00 00 00 00 02 00 00 00  00 00 00 00 30 00 00 00    ........ ....0...
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   00F0: 00 00 00 00 85 2a 74 70  01 00 00 00 00 00 00 00    .....*tp ........
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0100: 00 00 00 00 00 00 00 00  00 00 00 00 02 00 00 00    ........ ........
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0110: 00 00 00 00 48 00 00 00  00 00 00 00                ....H... ....
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: slot1 < [000000a4] 13 hangup
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0020: 69 6f 00 00 a4 00 00 00  01 00 00 00                io...... ....
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: slot1 > [000000a4] 12 hangupResponse
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0020: 69 6f 52 65 73 70 6f 6e  73 65 00 00 85 2a 74 70    ioRespon se...*tp
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0030: 00 00 00 00 60 20 14 9a  7f 00 00 00 0c 00 00 00    ....` .. ........
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0040: 00 00 00 00 00 00 00 00  00 00 00 00 00 00 00 00    ........ ........
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0050: 00 00 00 00                                         ....
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: slot1 < [000000a5] 10 getCurrentCalls
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0020: 69 6f 00 00 a5 00 00 00                             io......
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: slot1 > 2 callStateChanged
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0000: 61 6e 64 72 6f 69 64 2e  68 61 72 64 77 61 72 65    android. hardware
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0010: 2e 72 61 64 69 6f 40 31  2e 30 3a 3a 49 52 61 64    .radio@1 .0::IRad
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0020: 69 6f 49 6e 64 69 63 61  74 69 6f 6e 00 00 00 00    ioIndica tion....
Feb 09 21:22:22 VollaPhone22 ofonod[3393]:   0030: 01 00 00 00                                         ....
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: src/voicecall.c:ofono_voicecall_disconnected() Got disconnection event for id: 1, reason: 1
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: slot1 < 130 responseAcknowledgement
Feb 09 21:22:22 VollaPhone22 ofonod[3393]: sloofonod[3393]: src/voicecall.c:ofono_voicecall_disconnected() Got disconnection event for id: 1, reason: 1
Feb 09 21:22:40 VollaPhone22 ofonod[3393]: ofonod[3393]: plugins/upower.c:battery_props_changed()
Feb 09 21:22:40 VollaPhone22 ofonod[3393]: plugins/upower.c:battery_props_changed()

I flashed my phone last weekend, made all the updates, and finally could make calls again…until next day. I didn’t change anything. There was no reboot, no freeze, nothing. I literally didn’t touch my phone, and still after ~24 hours of flashing, I can’t make any phone calls again.

Is it actually normal that my load average is close to 20 on droidian, even though the CPU usage is close to nothing?

top - 21:22:36 up 4 days,  1:11,  2 users,  load average: 19.20, 19.10, 19.06
Tasks: 563 total,   1 running, 562 sleeping,   0 stopped,   0 zombie
%Cpu0  :  0.3 us,  3.3 sy,  0.0 ni, 96.4 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st 
%Cpu1  :  1.0 us,  0.7 sy,  0.0 ni, 98.3 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st 
%Cpu2  :  1.3 us,  0.7 sy,  0.0 ni, 97.0 id,  1.0 wa,  0.0 hi,  0.0 si,  0.0 st 
%Cpu3  :  1.4 us,  1.0 sy,  0.0 ni, 97.6 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st 
%Cpu4  :  0.0 us,  1.0 sy,  0.0 ni, 99.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st 
%Cpu5  :  0.7 us,  0.3 sy,  0.0 ni, 99.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st 
%Cpu6  :  0.0 us,  0.0 sy,  0.0 ni,100.0 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st 
%Cpu7  :  0.3 us,  1.0 sy,  0.0 ni, 98.7 id,  0.0 wa,  0.0 hi,  0.0 si,  0.0 st 
MiB Mem :   3498.4 total,   1019.5 free,   1429.7 used,   1063.0 buff/cache     
MiB Swap:   1924.1 total,   1685.1 free,    239.0 used.   2068.7 avail Mem 
ps aux | awk '$8 ~ /D/ { print $0 }'
root         228  0.0  0.0      0     0 ?        D    May03   0:00 [ccci_poll1]
root         270  0.0  0.0      0     0 ?        D    May03   0:00 [ddp_irq_log_kth]
root         272  0.0  0.0      0     0 ?        D    May03   0:17 [disp_idlemgr]
root         273  0.0  0.0      0     0 ?        D    May03   0:02 [disp_check]
root         275  0.0  0.0      0     0 ?        D    May03   0:00 [decouple_trigge]
root         284  0.0  0.0      0     0 ?        D    May03   0:02 [wdtk-0]
root         285  0.0  0.0      0     0 ?        D    May03   0:02 [wdtk-1]
root         286  0.0  0.0      0     0 ?        D    May03   0:12 [wdtk-2]
root         287  0.0  0.0      0     0 ?        D    May03   0:29 [wdtk-3]
root         288  0.0  0.0      0     0 ?        D    May03   0:09 [wdtk-4]
root         289  0.0  0.0      0     0 ?        D    May03   0:28 [wdtk-5]
root         290  0.0  0.0      0     0 ?        D    May03   0:01 [wdtk-6]
root         291  0.0  0.0      0     0 ?        D    May03   0:01 [wdtk-7]
root         300  0.0  0.0      0     0 ?        D    May03   0:00 [hang_detect]
root         331  0.0  0.0      0     0 ?        D    May03   0:00 [rt9471_bc12_en_]
root         333  0.0  0.0      0     0 ?        D    May03   0:00 [gauge_timer_thr]
root         351  0.0  0.0      0     0 ?        D    May03   0:24 [ipi_cpu_dvfs_rt]
root         375  0.0  0.0      0     0 ?        D    May03   0:00 [qos_recv]

Voice calls work reliably after a fresh Droidian flash, then suddenly produce no audio on either side (call connects normally, no signaling issue) until the device is reflashed. This last occured directly after a ~2h+ call. Restarting the Android LXC container or the phone does not fix it, only a full reflash of userdata does.

Confirmed root mechanism

PulseAudio (which loads audio.primary.mt6768.so directly via libhybris rather than running a separate Android audio-HAL service) segfaults reliably on entering call mode:

kernel: Core dump to |/usr/lib/systemd/systemd-coredump ... pipe failed
systemd[...]: pulseaudio.service: Main process exited, code=killed, status=11/SEGV

The crash consistently happens right after:

AudioALSASpeechPhoneCallController: setMuteInfo(), mMuteInfo: 0x0
SpeechParserGen93: appOpsGetInstance(), init AppOps struct, lib is libaudio_param_parser-vnd.so
[SIGSEGV]

systemd auto-restarts pulseaudio.service (Restart=on-failure), so the service technically stays “up” while the call itself produces only a faint electrical squeal/click and no audio, matching the reported symptom.

Coredump not capturedsystemd-coredump fails with pipe failed, likely due to sandboxing in the shipped pulseaudio.service unit (NoNewPrivileges, RestrictNamespaces, SystemCallFilter=@system-service). No backtrace obtained yet.

Ruled out during investigation

  • VoLTE/AMR-WB/EVS codec: crash reproduces identically while modem is forced to 2G/3G (mmcli --set-allowed-modes=2g,3g), so it is not tied to VoLTE registration or wideband codec negotiation.
  • Thread scheduling priority: every audio thread fails setpriority(ANDROID_PRIORITY_AUDIO) with errno 13/EACCES in both working and broken states; adding LimitRTPRIO=95/LimitNICE=-20 to the systemd unit had no effect on crash frequency.
  • /data state divergence: diff -rq between a full tar of /data/vendor taken right after a working flash and one taken in the broken state showed no meaningful differences (only unrelated camera calibration dump files and an ipsec conf timestamp).
  • Runtime PM state of the AFE device (/sys/bus/platform/devices/11220000.mt6768-afe-pcm/power/{runtime_status,control}): inconclusive. Observed runtime_status: active (instead of the expected suspended) both immediately after a dropped call and, on a separate occasion, persistently after a ~2h call, with no active call in progress. Even after multiple reboots runtime_status is always active - and no sound is available in any voice calls anymore. However, forcing a driver rebind (echo <device> > .../unbind then bind) while the audio stack was live froze the entire device (black screen, unresponsive SSH), requiring a hard reset — this path should not be attempted again on a live system; it points at shared clock/power-domain dependencies with other hardware blocks (likely display) that aren’t safe to touch from userspace.

Working-state log reference (for comparison against future broken-state captures)

On a fresh flash, entering call mode logs, in order:

mt6768_afe_runtime_resume()
mt6768_afe_enable_clock()
mtk_dai_pcm_hw_params(...)
mtk_adda_ul_event(), name ADDA Capture Enable ...
mtk_stf_event(), name Sidetone Filter ...

In the broken state, the AFE resume/clock-enable steps and the ADDA/sidetone setup are consistently absent from the log — the flow jumps straight from hw_params to device turn-on sequences, suggesting the kernel’s runtime-PM bookkeeping believes the AFE block is already active/clocked when it may not correctly be, though this remains unconfirmed without a kernel-level trace.


Update, additional finding (most significant so far):

runtime_status of the AFE platform device persists as active indefinitely, confirmed to be a genuinely live/counting state, not a frozen sysfs read:

$ cat .../power/runtime_active_time
2141304
$ sleep 30
$ cat .../power/runtime_active_time
2171320

The delta (30016 ms) exactly matches the elapsed wall time, confirming the kernel’s runtime-PM active-time counter is actively incrementing for this device even with no audio activity and no call in progress, and this persists across a full device reboot (not just a service/container restart). This rules out a userspace/RAM-only state and points to something the kernel considers a legitimate, un-released active reference on this device, surviving reboot — likely either firmware/co-processor state (e.g. modem/DSP side) or a persistent driver-internal flag, not plain kernel memory.

No runtime_usage or debugfs pm_genpd interfaces are available on this kernel build to inspect the raw refcount directly.


Some ideas to go further - other thoughts?

  • A working coredump (fix the sandboxing/coredump pipe issue in pulseaudio.service, or run PulseAudio outside the restricted unit temporarily) to get an actual backtrace out of SpeechParserGen93/the vendor audio-HAL.
  • Ideally, someone with access to the MediaTek mt6768-audio kernel driver source and the vendor audio.primary.mt6768.so HAL source, since further blind experimentation on the live device carries real risk of hanging shared hardware blocks (as demonstrated above).