Skip to content
Snippets Groups Projects

should abort when hitting timeout

    • Open Issue created by Guido Gunther @guido.gunther

    when the phone has bad reception placing calls fails like

    Okt 01 18:21:32 pureos calls[851]: Error starting outgoing call ModemManager call to `+49xxxxxxxxx': Zeitüberschreitung wurde erreicht
Okt 01 18:21:49 pureos ModemManager[510]: <info>  [modem1/call0] user request to hangup call
Okt 01 18:21:51 pureos ModemManager[510]: <info>  [modem1/call0] user request to hangup call
Okt 01 18:22:14 pureos calls[851]: Error hanging up ModemManager call to `+49xxxxxxxx': Zeitüberschreitung wurde erreicht
Okt 01 18:22:16 pureos calls[851]: Error hanging up ModemManager call to `+49xxxxxxxx': Zeitüberschreitung wurde erreicht
Okt 01 18:22:37 pureos ModemManager[510]: <warn>  [modem1/call0] couldn't start call: Serial command timed out
Okt 01 18:22:37 pureos ModemManager[510]: <info>  [modem1/call0] call state changed: dialing -> terminated (unknown)
Okt 01 18:22:37 pureos ModemManager[510]: <warn>  [modem1] port ttyUSB1 timed out 2 consecutive times
Okt 01 18:22:42 pureos ModemManager[510]: <warn>  [modem1] couldn't load call list: Serial command timed out
Okt 01 18:22:42 pureos ModemManager[510]: <warn>  [modem1] port ttyUSB1 timed out 3 consecutive times
Okt 01 18:22:44 pureos ModemManager[510]: <warn>  [modem1] port ttyUSB1 timed out 4 consecutive times
Okt 01 18:22:46 pureos ModemManager[510]: <warn>  [modem1] port ttyUSB1 timed out 5 consecutive times

    (Zeitüberschreitung wurde erreicht = ETIMEDOUT)

    There's no 'beep' sound, the speaker stays perfectly silent. Hitting the red button does nothing until much later. It would likely be better to cancel the call right away when hitting the first timeout so the user gets feedback about the problem (that's what my other phone does under these circumstances).

    Edited

    Designs

      Child items 0

      2. No child items are currently assigned. Use child items to break down this issue into smaller parts.

      Activity

      • Guido Gunther changed the description

        changed the description

      • Guido Gunther added important label

        added important label

      • Evangelos Ribeiro Tzaras mentioned in issue #204

        mentioned in issue #204

      • Evangelos Ribeiro Tzaras mentioned in merge request !264 (merged)

        mentioned in merge request !264 (merged)

      • Evangelos Ribeiro Tzaras assigned to @evangelos.tzaras

        assigned to @evangelos.tzaras

        • Evangelos Ribeiro Tzaras
          Evangelos Ribeiro Tzaras @evangelos.tzaras ·
          Maintainer

          Just had the same thing happening and I'm adding some more information from the logs (including the hickups from the modem).

          Mar 29 15:26:49 poseidon ModemManager[528]: Cannot read from istream: connection broken
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] QMI endpoint hangup: removed
Mar 29 15:26:49 poseidon ModemManager[528]: <info>  [modem0] connection to qmi-proxy for /dev/cdc-wdm0 lost, reprobing
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'voice' client with flags 'release-cid'...
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'voice' client with ID '1'
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'pdc' client with flags 'release-cid'...
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'pdc' client with ID '1'
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'loc' client with flags 'release-cid'...
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'loc' client with ID '1'
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'uim' client with flags 'release-cid'...
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'uim' client with ID '1'
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'wms' client with flags 'release-cid'...
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'wms' client with ID '1'
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'nas' client with flags 'release-cid'...
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'nas' client with ID '1'
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'dms' client with flags 'release-cid'...
Mar 29 15:26:49 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'dms' client with ID '1'
Mar 29 15:26:49 poseidon ModemManager[528]: <info>  [base-manager] port ttyUSB1 released by device '/sys/devices/platform/soc@0/38200000.usb/xhci-hcd.4>
Mar 29 15:26:49 poseidon ModemManager[528]: <info>  [base-manager] port ttyUSB2 released by device '/sys/devices/platform/soc@0/38200000.usb/xhci-hcd.4>
Mar 29 15:26:49 poseidon ModemManager[528]: <info>  [base-manager] port ttyUSB3 released by device '/sys/devices/platform/soc@0/38200000.usb/xhci-hcd.4>
Mar 29 15:26:49 poseidon ModemManager[528]: <info>  [base-manager] port ttyUSB0 released by device '/sys/devices/platform/soc@0/38200000.usb/xhci-hcd.4>
Mar 29 15:26:49 poseidon ModemManager[528]: <info>  [base-manager] port cdc-wdm0 released by device '/sys/devices/platform/soc@0/38200000.usb/xhci-hcd.>
Mar 29 15:26:49 poseidon ModemManager[528]: <info>  [base-manager] port wwx2e37c7b2b333 released by device '/sys/devices/platform/soc@0/38200000.usb/xh>
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0] created endpoint
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0] Checking version info (25 retries)...
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0] QMI Device supports 30 services:
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    ctl (1.5)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    wds (1.67)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    dms (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    nas (1.25)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    qos (1.12)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    wms (1.10)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    auth (1.3)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    at (1.4)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    voice (2.1)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    cat2 (2.24)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    uim (1.46)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    pbm (1.4)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    test (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    loc (2.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    sar (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    ims (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    csd (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    ts (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    tmd (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    wda (1.16)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    csvt (1.1)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    imsp (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    imsa (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    coex (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    pdc (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    rfrpe (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    dsd (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    unknown [0x2d] (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    unknown [0x30] (1.0)
Mar 29 15:26:51 poseidon ModemManager[528]: [/dev/cdc-wdm0]    unknown [0x36] (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: <info>  [device /sys/devices/platform/soc@0/38200000.usb/xhci-hcd.4.auto/usb1/1-1/1-1.2] creating modem wit>
Mar 29 15:26:52 poseidon ModemManager[528]: <info>  [base-manager] modem for device '/sys/devices/platform/soc@0/38200000.usb/xhci-hcd.4.auto/usb1/1-1/>
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] created endpoint
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Checking version info (25 retries)...
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] QMI Device supports 30 services:
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    ctl (1.5)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    wds (1.67)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    dms (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    nas (1.25)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    qos (1.12)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    wms (1.10)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    auth (1.3)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    at (1.4)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    voice (2.1)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    cat2 (2.24)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    uim (1.46)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    pbm (1.4)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    test (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    loc (2.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    sar (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    ims (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    csd (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    ts (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    tmd (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    wda (1.16)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    csvt (1.1)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    imsp (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    imsa (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    coex (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    pdc (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    rfrpe (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    dsd (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    unknown [0x2d] (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    unknown [0x30] (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0]    unknown [0x36] (1.0)
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Reading expected data format from: /sys/class/net/wwx2e37c7b2b333/qmi/raw_ip
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Allocating new client ID...
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Registered 'wda' (version 1.16) client with ID '1'
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Writing expected data format to: /sys/class/net/wwx2e37c7b2b333/qmi/raw_ip
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Reading expected data format from: /sys/class/net/wwx2e37c7b2b333/qmi/raw_ip
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Allocating new client ID...
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'wda' client with flags 'release-cid'...
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'wda' client with ID '1'
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Registered 'dms' (version 1.0) client with ID '1'
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Allocating new client ID...
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Registered 'nas' (version 1.25) client with ID '1'
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Allocating new client ID...
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Registered 'wms' (version 1.10) client with ID '1'
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Allocating new client ID...
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Registered 'uim' (version 1.46) client with ID '1'
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Allocating new client ID...
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Registered 'loc' (version 2.0) client with ID '1'
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Allocating new client ID...
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Registered 'pdc' (version 1.0) client with ID '1'
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Allocating new client ID...
Mar 29 15:26:52 poseidon ModemManager[528]: [/dev/cdc-wdm0] Registered 'voice' (version 2.1) client with ID '1'
Mar 29 15:26:52 poseidon ModemManager[528]: <warn>  [modem1/sim1] couldn't load list of emergency numbers: uninitialized emergency numbers list
Mar 29 15:26:52 poseidon ModemManager[528]: <warn>  [modem1] couldn't load facility locks: QMI protocol error (94): 'NotSupported'
Mar 29 15:26:53 poseidon ModemManager[528]: <info>  [modem1] state changed (unknown -> disabled)
Mar 29 15:26:53 poseidon ModemManager[528]: <info>  [modem1] state changed (disabled -> enabling)
Mar 29 15:26:53 poseidon ModemManager[528]: [/dev/cdc-wdm0] Allocating new client ID...
Mar 29 15:26:53 poseidon ModemManager[528]: [/dev/cdc-wdm0] Registered 'wds' (version 1.67) client with ID '1'
Mar 29 15:26:53 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'wds' client with flags 'release-cid'...
Mar 29 15:26:53 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'wds' client with ID '1'
Mar 29 15:26:53 poseidon ModemManager[528]: <info>  [modem1] state changed (enabling -> enabled)
Mar 29 15:26:53 poseidon ModemManager[528]: <info>  [modem1] 3GPP registration state changed (unknown -> registering)
Mar 29 15:26:53 poseidon ModemManager[528]: <info>  [modem1] 3GPP registration state changed (registering -> home)
Mar 29 15:26:53 poseidon ModemManager[528]: <info>  [modem1] state changed (enabled -> registered)
Mar 29 15:27:01 poseidon ModemManager[528]: <warn>  [modem1] couldn't load network timezone from the current network
Mar 29 15:28:48 poseidon ModemManager[528]: <info>  [modem1/call3] user request to start call
Mar 29 15:28:48 poseidon ModemManager[528]: <info>  [modem1/call3] call state changed: unknown -> dialing (outgoing-started)
Mar 29 15:29:47 poseidon ModemManager[528]: <info>  [modem1/call3] user request to hangup call
Mar 29 15:29:53 poseidon ModemManager[528]: <info>  [modem1/call3] user request to hangup call
Mar 29 15:30:19 poseidon ModemManager[528]: <warn>  [modem1/call3] couldn't start call: Serial command timed out
Mar 29 15:30:19 poseidon ModemManager[528]: <info>  [modem1/call3] call state changed: dialing -> terminated (unknown)
Mar 29 15:30:19 poseidon ModemManager[528]: <warn>  [modem1] port ttyUSB1 timed out 2 consecutive times
Mar 29 15:30:24 poseidon ModemManager[528]: <warn>  [modem1] couldn't load call list: Serial command timed out
Mar 29 15:30:24 poseidon ModemManager[528]: <warn>  [modem1] port ttyUSB1 timed out 3 consecutive times
Mar 29 15:30:26 poseidon ModemManager[528]: <warn>  [modem1] port ttyUSB1 timed out 4 consecutive times

          Trying again to place a call:

          Mar 29 15:30:28 poseidon ModemManager[528]: <warn>  [modem1] port ttyUSB1 timed out 5 consecutive times
Mar 29 15:34:33 poseidon ModemManager[528]: <info>  [modem1/call4] user request to start call
Mar 29 15:34:33 poseidon ModemManager[528]: <info>  [modem1/call4] call state changed: unknown -> dialing (outgoing-started)
Mar 29 15:35:34 poseidon ModemManager[528]: <info>  [modem1/call4] user request to hangup call
Mar 29 15:35:38 poseidon ModemManager[528]: <info>  [modem1/call4] user request to hangup call
Mar 29 15:35:41 poseidon ModemManager[528]: <info>  [modem1/call4] user request to hangup call
Mar 29 15:35:53 poseidon ModemManager[528]: <info>  [modem1/call4] user request to hangup call
Mar 29 15:36:00 poseidon ModemManager[528]: <info>  [modem1/call4] user request to hangup call
Mar 29 15:36:04 poseidon ModemManager[528]: <warn>  [modem1/call4] couldn't start call: Serial command timed out
Mar 29 15:36:04 poseidon ModemManager[528]: <info>  [modem1/call4] call state changed: dialing -> terminated (unknown)
Mar 29 15:36:04 poseidon ModemManager[528]: <warn>  [modem1] port ttyUSB1 timed out 6 consecutive times
Mar 29 15:36:09 poseidon ModemManager[528]: <warn>  [modem1] couldn't load call list: Serial command timed out
Mar 29 15:36:09 poseidon ModemManager[528]: <warn>  [modem1] port ttyUSB1 timed out 7 consecutive times
Mar 29 15:36:11 poseidon ModemManager[528]: <warn>  [modem1] port ttyUSB1 timed out 8 consecutive times
Mar 29 15:36:13 poseidon ModemManager[528]: <warn>  [modem1] port ttyUSB1 timed out 9 consecutive times
Mar 29 15:36:15 poseidon ModemManager[528]: <error> [modem1] port ttyUSB1 timed out 10 consecutive times, marking modem as invalid
Mar 29 15:36:15 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'voice' client with flags 'release-cid'...
Mar 29 15:36:15 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'voice' client with ID '1'
Mar 29 15:36:15 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'pdc' client with flags 'release-cid'...
Mar 29 15:36:15 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'pdc' client with ID '1'
Mar 29 15:36:15 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'loc' client with flags 'release-cid'...
Mar 29 15:36:15 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'loc' client with ID '1'
Mar 29 15:36:15 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'uim' client with flags 'release-cid'...
Mar 29 15:36:15 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'uim' client with ID '1'
Mar 29 15:36:15 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'wms' client with flags 'release-cid'...
Mar 29 15:36:15 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'wms' client with ID '1'
Mar 29 15:36:15 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'nas' client with flags 'release-cid'...
Mar 29 15:36:15 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'nas' client with ID '1'
Mar 29 15:36:15 poseidon ModemManager[528]: [/dev/cdc-wdm0] Releasing 'dms' client with flags 'release-cid'...
Mar 29 15:36:15 poseidon ModemManager[528]: [/dev/cdc-wdm0] Unregistered 'dms' client with ID '1'
Mar 29 15:36:18 poseidon ModemManager[528]: transaction 0xe aborted, but message is not abortable
Mar 29 15:36:18 poseidon ModemManager[528]: transaction 0xf aborted, but message is not abortable
Mar 29 15:36:18 poseidon ModemManager[528]: transaction 0x10 aborted, but message is not abortable
Mar 29 15:36:18 poseidon ModemManager[528]: transaction 0x11 aborted, but message is not abortable
Mar 29 15:36:18 poseidon ModemManager[528]: transaction 0x12 aborted, but message is not abortable
Mar 29 15:36:18 poseidon ModemManager[528]: transaction 0x13 aborted, but message is not abortable
Mar 29 15:36:18 poseidon ModemManager[528]: transaction 0x14 aborted, but message is not abortable

          And the modem is gone :'(

          After enganging and disengaging the killswitch the modem comes back online.

          I did try placing a call which did work. But interestingly that transmitted audio is NOT current. I noticed this because I could hear myself typing, while I was not actually typing :? Also I could hear some music (which according to my playlist I listened to more than half an hour ago). what the ...?

        • Sam Bull
          Sam Bull @sambull ·

          I did try placing a call which did work. But interestingly that transmitted audio is NOT current. I noticed this because I could hear myself typing, while I was not actually typing :? Also I could hear some music (which according to my playlist I listened to more than half an hour ago). what the ...?

          Sounds like the privacy focused phone is recording you secretly... :P

          Did the UI actually show you were on a call when this happened? I always get 'dialing...' and it never seems to place a call. Just wondering if I've actually been calling people without knowing it and giving them the silent treatment.

        • Evangelos Ribeiro Tzaras
          Evangelos Ribeiro Tzaras @evangelos.tzaras ·
          Maintainer

          Yes the UI did show the active call ;)

          As for your first point: Quoting @sebastian.krzyszkowiak :

          Old audio being transmitted is actually unrelated to the modem - it's PulseAudio's doing. The modem stops accepting audio once call ends, but seems like in this case wys didn't realize that the call has finished, so it didn't tear down the PA loopbacks, which caused PA to still record the mic and buffer audio that then got replayed to the modem from the beginning once it started to accept audio again (when another call was placed).

          So there are two things that we can do that should make it more robust:

          • make PulseAudio drop the audio instead of buffering it in case the modem doesn't receive data
          • make sure that wys correctly ends call audio when the modem goes away or becomes invalid
          Edited by Evangelos Ribeiro Tzaras
        • Evangelos Ribeiro Tzaras
          Evangelos Ribeiro Tzaras @evangelos.tzaras ·
          Maintainer

          Kind of related: After the modem had a hickup I tried placing another outgoing call:

          ** (sm.puri.Calls:17952): WARNING **: 10:12:32.143: Error starting outgoing call ModemManager call to `+491764XXXXXXX': GDBus.Error:org.freedesktop.ModemManager1.Error.Core.Failed: Couldn't start the call: Unhandled response '$GNGNS,,,,,,NNN,,,,,,*1D

$GPVTG,,T,,M,,N,,K,N*2C

$GPGSA,A,1,,,,,,,,,,,,,,,,*32

$GNGSA,A,1,,,,,,,,,,,,,,,,*2C

$GPGGA,,,,,,0,,,,,,,,*66

$GPRMC,,V,,,,,,,,,,N*53

$GNGNS,,,,,,NNN,,,,,,*1D

$GPVTG,,T,,M,,N,,K,N*2C

$GPGSA,A,1,,,,,,,,,,,,,,,,*32

$GNGSA,A,1,,,,,,,,,,,,,,,,*2C

$GPGGA,,,,,,0,,,,,,,,*66

$GPRMC,,V,,,,,,,,,,N*53

$GNGNS,,,,,,NNN,,,,,,*1D

$GPVTG,,T,,M,,N,,K,N*2C

$GPGSA,A,1,,,,,,,,,,,,,,,,*32

$GNGSA,A,1,,,,,,,,,,,,,,,,*2C

....

          I had a couple of modem USB resets just before the weird behaviour started happening

        • Please register or sign in to reply
      • Evangelos Ribeiro Tzaras mentioned in merge request !308 (closed)

        mentioned in merge request !308 (closed)

      • Evangelos Ribeiro Tzaras mentioned in issue #275

        mentioned in issue #275

      • Evangelos Ribeiro Tzaras
        Evangelos Ribeiro Tzaras @evangelos.tzaras ·
        Maintainer

        Note to self: Capture logs from DBus (to see how these timeouts are seen from the calls side) as well as debug logs from MM (semi-small problem: So far this happened randomly).

        (Also unassigning for now)

      • Evangelos Ribeiro Tzaras unassigned @evangelos.tzaras

        unassigned @evangelos.tzaras

      • Evangelos Ribeiro Tzaras mentioned in issue OS-issues#239

        mentioned in issue OS-issues#239

      Please register or sign in to reply