PINE64
Still No Calls or SMS - Printable Version

+- PINE64 (https://forum.pine64.org)
+-- Forum: PinePhone (https://forum.pine64.org/forumdisplay.php?fid=120)
+--- Forum: PinePhone Software (https://forum.pine64.org/forumdisplay.php?fid=121)
+---- Forum: Arch Linux on PinePhone (https://forum.pine64.org/forumdisplay.php?fid=159)
+---- Thread: Still No Calls or SMS (/showthread.php?tid=17609)

Still No Calls or SMS - AndyM - 11-14-2022

Sorry, but I have no idea how to fix this.

If some kind person could guide me through it....?

Here is the result from journalctl when trying to place a call:
Code:
$ sudo journalctl --follow

Nov 14 09:08:16 pinephone dbus-daemon[3779]: [session uid=1000 pid=3779] Activating service name='org.gnome.Contacts' requested by ':1.90' (uid=1000 pid=13275 comm="gnome-calls")
Nov 14 09:08:16 pinephone dbus-daemon[3779]: [session uid=1000 pid=3779] Successfully activated service 'org.gnome.Contacts'
Nov 14 09:08:18 pinephone gnome-calls[4149]: Unexpected error response to netlink request while trying to fetch local IP address
Nov 14 09:08:28 pinephone gsd-media-keys[4005]: Unable to get default sink
Nov 14 09:08:28 pinephone gsd-media-keys[4005]: Unable to get default source
Nov 14 09:08:28 pinephone ModemManager[12626]: <info>  [modem0/call0] user request to start call
Nov 14 09:08:28 pinephone ModemManager[12626]: <info>  [modem0/call0] call state changed: unknown -> dialing (outgoing-started)
Nov 14 09:08:28 pinephone gsd-media-keys[4005]: Unable to get default source
Nov 14 09:08:28 pinephone ModemManager[12626]: <info>  [modem0/call0] call is started
Nov 14 09:08:33 pinephone gnome-calls[4149]: Unexpected error response to netlink request while trying to fetch local IP address
Nov 14 09:08:48 pinephone gnome-calls[4149]: Unexpected error response to netlink request while trying to fetch local IP address
Nov 14 09:08:53 pinephone nmbd[3306]: [2022/11/14 09:08:53.565089,  0] ../../source3/nmbd/nmbd_become_lmb.c:398(become_local_master_stage2)
Nov 14 09:08:53 pinephone nmbd[3306]:   *****
Nov 14 09:08:53 pinephone nmbd[3306]:
Nov 14 09:08:53 pinephone nmbd[3306]:   Samba name server PINEPHONE is now a local master browser for workgroup FDSERVICES on subnet 192.168.1.22
Nov 14 09:08:53 pinephone nmbd[3306]:
Nov 14 09:08:53 pinephone nmbd[3306]:   *****
Nov 14 09:09:03 pinephone gnome-calls[4149]: Unexpected error response to netlink request while trying to fetch local IP address
Nov 14 09:09:07 pinephone ModemManager[12626]: <info>  [modem0/call0] call state changed: dialing -> terminated (unknown)
Nov 14 09:09:07 pinephone gsd-media-keys[4005]: Unable to get default sink
Nov 14 09:09:07 pinephone gsd-media-keys[4005]: Unable to get default source
Nov 14 09:09:07 pinephone gnome-calls[4149]: Error deleting call `/org/freedesktop/ModemManager1/Call/0' on MMModemVoice `QUECTEL Mobile Broadband Module': GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.27 was not provided by any .service files
Nov 14 09:09:07 pinephone gnome-calls[4149]: Error deleting call `/org/freedesktop/ModemManager1/Call/0' on MMModemVoice `QUECTEL Mobile Broadband Module': GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.106 was not provided by any .service files
Nov 14 09:09:07 pinephone gsd-media-keys[4005]: Unable to get default source
Nov 14 09:09:18 pinephone gnome-calls[4149]: Unexpected error response to netlink request while trying to fetch local IP address
Nov 14 09:09:26 pinephone ModemManager[12626]: <info>  [modem0] 3GPP registration state changed (home -> searching)
Nov 14 09:09:26 pinephone ModemManager[12626]: <info>  [modem0] state changed (registered -> searching)
Nov 14 09:09:26 pinephone ModemManager[12626]: <warn>  [modem0] couldn't load operator code: Current operator MCC/MNC is still unknown
Nov 14 09:09:26 pinephone ModemManager[12626]: <warn>  [modem0] couldn't load operator name: Current operator id is still unknown
Nov 14 09:09:26 pinephone mmsdtng[3770]: ../mmsd-1.12.1/plugins/modemmanager.c:modem_state_changed_cb() State Change: Old State: 8 New State: 7, Reason: 0
Nov 14 09:09:26 pinephone mmsdtng[3770]: ../mmsd-1.12.1/plugins/modemmanager.c:mmsd_mm_get_modem_state() MM_MODEM_GOOD_STATE: 7
Nov 14 09:09:26 pinephone mmsdtng[3770]: ../mmsd-1.12.1/plugins/modemmanager.c:mmsd_mm_state() MMSD_MM_STATE_READY

I don't know what to do about these errors:
Code:
gnome-calls[4149]: Unexpected error response to netlink request while trying to fetch local IP address
Code:
gsd-media-keys[4005]: Unable to get default sink/source
Code:
gnome-calls[4149]: Error deleting call `/org/freedesktop/ModemManager1/Call/0' on MMModemVoice `QUECTEL Mobile Broadband Module': GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.27 was not provided by any .service files
gnome-calls[4149]: Error deleting call `/org/freedesktop/ModemManager1/Call/0' on MMModemVoice `QUECTEL Mobile Broadband Module': GDBus.Error:org.freedesktop.DBus.Error.ServiceUnknown: The name :1.106 was not provided by any .service files
Code:
ModemManager[12626]: <warn>  [modem0] couldn't load operator code: Current operator MCC/MNC is still unknown
ModemManager[12626]: <warn>  [modem0] couldn't load operator name: Current operator id is still unknown

I have googled all of them endlessly but I still have no idea how to fix then. I have updated geoclue2 to make sure that the polkit rules are up to date (I hope)

If I check the modem state I can see the Operator id and name:
Code:
$ mmcli -m 0
  -----------------------------------
  General  |                    path: /org/freedesktop/ModemManager1/Modem/0
           |               device id: b526365d8a8c8ebb23bbb0995d36796c19ab76a3
  -----------------------------------
  Hardware |            manufacturer: QUALCOMM INCORPORATED
           |                   model: QUECTEL Mobile Broadband Module
           |       firmware revision: EG25GGBR07A08M2G
           |          carrier config: ROW_Generic_3GPP
           | carrier config revision: 05010820
           |            h/w revision: 10000
           |               supported: gsm-umts, lte
           |                 current: gsm-umts, lte
           |            equipment id: 867698046452572
  -----------------------------------
  System   |                  device: /sys/devices/platform/soc/1c1b000.usb/usb2/2-1
           |                 drivers: option, qmi_wwan
           |                  plugin: quectel
           |            primary port: cdc-wdm0
           |                   ports: cdc-wdm0 (qmi), ttyUSB0 (qcdm), ttyUSB1 (gps),
           |                          ttyUSB2 (at), ttyUSB3 (at), wwan0 (net)
  -----------------------------------
  Status   |                    lock: sim-pin2
           |          unlock retries: sim-pin (3), sim-puk (10), sim-pin2 (3), sim-puk2 (10)
           |                   state: registered
           |             power state: on
           |             access tech: umts
           |          signal quality: 4% (cached)
  -----------------------------------
  Modes    |               supported: allowed: 2g; preferred: none
           |                          allowed: 3g; preferred: none
           |                          allowed: 4g; preferred: none
           |                          allowed: 2g, 3g; preferred: 3g
           |                          allowed: 2g, 3g; preferred: 2g
           |                          allowed: 2g, 4g; preferred: 4g
           |                          allowed: 2g, 4g; preferred: 2g
           |                          allowed: 3g, 4g; preferred: 4g
           |                          allowed: 3g, 4g; preferred: 3g
           |                          allowed: 2g, 3g, 4g; preferred: 4g
           |                          allowed: 2g, 3g, 4g; preferred: 3g
           |                          allowed: 2g, 3g, 4g; preferred: 2g
           |                 current: allowed: 2g, 3g, 4g; preferred: 4g
  -----------------------------------
  Bands    |               supported: egsm, dcs, pcs, g850, utran-1, utran-4, utran-6, utran-5,
           |                          utran-8, utran-2, eutran-1, eutran-2, eutran-3, eutran-4, eutran-5,
           |                          eutran-7, eutran-8, eutran-12, eutran-13, eutran-18, eutran-19,
           |                          eutran-20, eutran-25, eutran-26, eutran-28, eutran-38, eutran-39,
           |                          eutran-40, eutran-41, utran-19
           |                 current: egsm, dcs, pcs, g850, utran-1, utran-4, utran-6, utran-5,
           |                          utran-8, utran-2, eutran-1, eutran-2, eutran-3, eutran-4, eutran-5,
           |                          eutran-7, eutran-8, eutran-12, eutran-13, eutran-18, eutran-19,
           |                          eutran-20, eutran-25, eutran-26, eutran-28, eutran-38, eutran-39,
           |                          eutran-40, eutran-41, utran-19
  -----------------------------------
  IP       |               supported: ipv4, ipv6, ipv4v6
  -----------------------------------
  3GPP     |                    imei: 867698046452572
           |           enabled locks: fixed-dialing
           |             operator id: 20810
           |           operator name: SYMA
           |            registration: home
  -----------------------------------
  3GPP EPS |    ue mode of operation: csps-2
           |     initial bearer path: /org/freedesktop/ModemManager1/Bearer/0
           |  initial bearer ip type: ipv4v6
  -----------------------------------
  SIM      |        primary sim path: /org/freedesktop/ModemManager1/SIM/0

and yet if I look at the ModemManager Service I see this:

Code:
$ sudo systemctl status ModemManager
● ModemManager.service - Modem Manager
     Loaded: loaded (/usr/lib/systemd/system/ModemManager.service; enabled; preset: disabled)
     Active: active (running) since Mon 2022-11-14 07:19:32 CET; 3h 33min ago
   Main PID: 12626 (ModemManager)
      Tasks: 5 (limit: 3273)
     Memory: 6.1M
        CPU: 2.877s
     CGroup: /system.slice/ModemManager.service
             ├─12626 /usr/bin/ModemManager --test-quick-suspend-resume
             └─12639 /usr/lib/qmi-proxy

Nov 14 09:13:35 pinephone ModemManager[12626]: <info>  [modem0] state changed (enabled -> registered)
Nov 14 09:29:32 pinephone ModemManager[12626]: <info>  [modem0] 3GPP registration state changed (home -> searching)
Nov 14 09:29:32 pinephone ModemManager[12626]: <info>  [modem0] state changed (registered -> searching)
Nov 14 09:29:32 pinephone ModemManager[12626]: <warn>  [modem0] couldn't load operator code: Current operator MCC/MNC is still unkn>
Nov 14 09:29:32 pinephone ModemManager[12626]: <warn>  [modem0] couldn't load operator name: Current operator id is still unknown
Nov 14 09:30:05 pinephone ModemManager[12626]: <info>  [modem0] 3GPP registration state changed (searching -> idle)
Nov 14 09:30:05 pinephone ModemManager[12626]: <info>  [modem0] state changed (searching -> enabled)
Nov 14 09:30:06 pinephone ModemManager[12626]: <info>  [modem0] 3GPP registration state changed (idle -> registering)
Nov 14 09:30:06 pinephone ModemManager[12626]: <info>  [modem0] 3GPP registration state changed (registering -> home)
Nov 14 09:30:06 pinephone ModemManager[12626]: <info>  [modem0] state changed (enabled -> registered)
lines 1-21/21 (END)

as you can see, I have no idea what I am doing, but some advice would be really helpful now  Huh

Thanks

RE: Still No Calls or SMS - AndyM - 11-19-2022

I have continued to fiddle with this, with some success. For a start SMS now seems to work, but not MMS yet  Sad

Code:
gnome-calls[4149]: Unexpected error response to netlink request while trying to fetch local IP address

Apparently this may be due to a failure to obtain both IPv4 and IPv6 addresses - there is an open issue here.

Code:
gsd-media-keys[4005]: Unable to get default sink/source

This may be/have been a pipewire problem. Again an open issue can be found here.

The slightly better news is that after a mammoth update today, including a pipewire update, I have at last made a call which at least went through. There was some sound, but I will still have to test whether two way calling really works again.

RE: Still No Calls or SMS - AndyM - 03-10-2023

There does not seem to be much activity here so I thought to continue this thread instead of starting a new one.

Some issues have been resolved by updates so today I have tried again to solve the calls problem. I placed a call to the Pinephone and it rang, I answered on the Pinephone but there was no audio. The errors in the journals are:

Code:
wys[3654]: wys_audio_ensure_loopback: assertion 'self->modem' failed
wys[3654]: wys_audio_ensure_loopback: assertion 'self->modem' failed

wys[3654]: wys_audio_ensure_no_loopback: assertion 'self->modem' failed
wys[3654]: wys_audio_ensure_no_loopback: assertion 'self->modem' failed
callaudiod[3785]: card has no usable source
callaudiod[3785]: card has no usable sink
callaudiod[3785]: card has no usable source
callaudiod[3785]: card has no usable sink

Here is the complete journal of the call:


Code:
ModemManager[3259]: <info>  [modem110/call3] call state changed: unknown -> ringing-in (incoming-new)
eg25-manager[3095]: Response: [RING]
eg25-manager[3095]: Executed soft sleep sequence
phosh[44553]: The XKEYBOARD keymap compiler (xkbcomp) reports:
phosh[44553]: > Warning:          Unsupported maximum keycode 708, clipping.
phosh[44553]: >                   X11 cannot support keycodes above 255.
phosh[44553]: Errors from xkbcomp are not fatal to the X server
gnome-calls[26818]: Unexpected error response to netlink request while trying to fetch local IP address
eg25-manager[3095]: Response: [RING]
eg25-manager[3095]: Executed soft sleep sequence
ModemManager[3259]: <info>  [modem110/call3] user request to accept call
ModemManager[3259]: <info>  [modem110/call3] call is accepted
ModemManager[3259]: <info>  [modem110/call3] call state changed: ringing-in -> active (accepted)
wys[3654]: wys_audio_ensure_loopback: assertion 'self->modem' failed
wys[3654]: wys_audio_ensure_loopback: assertion 'self->modem' failed
ModemManager[3259]: <info>  [modem110/call3] user request to hangup call
ModemManager[3259]: <info>  [modem110/call3] call state changed: active -> terminated (terminated)
wys[3654]: wys_audio_ensure_no_loopback: assertion 'self->modem' failed
wys[3654]: wys_audio_ensure_no_loopback: assertion 'self->modem' failed
callaudiod[3785]: card has no usable source
callaudiod[3785]: card has no usable sink
callaudiod[3785]: card has no usable source
callaudiod[3785]: card has no usable sink

Any help would be appreciated.

RE: Still No Calls or SMS - AndyM - 03-10-2023

Question: Why do I have both wys and callaudiod running. It seem that they are both supposed to do the same thing, and according to this issue wys does not seem to work with pipewire?