modem is disconnected and re-enumerated after system resume
The following failed reset results in the modem being re-enumerated and modemmanager is then too slow to answer an incoming call. It usually goes to voicemail. We need to fix USB for this case. If modemmanager needs to be faster after this is fixed, we have the --test-quick-suspend-resume option to try ( Librem5/debs/ModemManager#47 (closed) )
[ 642.265942] usb 1-1: reset high-speed USB device number 2 using xhci-hcd
[ 642.425782] usb 4-1: reset SuperSpeed USB device number 2 using xhci-hcd
[ 642.618253] usb 3-1: reset high-speed USB device number 2 using xhci-hcd
[ 642.862970] usb 1-1.1: reset high-speed USB device number 3 using xhci-hcd
[ 642.874863] usb 4-1.4: reset SuperSpeed USB device number 6 using xhci-hcd
[ 642.958550] r8152 4-1.4:1.0: skip request firmware
[ 643.258596] usb 1-1.2: reset high-speed USB device number 8 using xhci-hcd
[ 643.488244] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
[ 643.496303] option 1-1.2:1.0: device disconnected
[ 643.503887] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
[ 643.511937] option 1-1.2:1.1: device disconnected
[ 643.518014] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
[ 643.526064] option 1-1.2:1.2: device disconnected
[ 643.531470] option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
[ 643.539526] option 1-1.2:1.3: device disconnected
[ 643.546169] OOM killer enabled.
[ 643.546903] option 1-1.2:1.0: GSM modem (1-port) converter detected
[ 643.549314] Restarting tasks ...
[ 643.556485] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB3
[ 643.577265] done.
[ 643.590048] option 1-1.2:1.1: GSM modem (1-port) converter detected
[ 643.608371] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB0
[ 643.616448] PM: suspend exit
[ 643.625222] option 1-1.2:1.2: GSM modem (1-port) converter detected
[ 643.631925] rfkill: input handler enabled
[ 643.641919] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB1
[ 643.662419] option 1-1.2:1.3: GSM modem (1-port) converter detected
[ 643.666498] rfkill: input handler disabled
[ 643.674758] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB2
[ 643.687768] hantro_vpu: module is from the staging directory, the quality is unknown, you have been warned.
[ 643.718479] hantro-vpu 38300000.video-codec: registered nxp,imx8mq-vpu-g1-dec as /dev/video1
[ 643.814375] hantro-vpu 38310000.video-codec: registered nxp,imx8mq-vpu-g2-dec as /dev/video3
Jun 03 11:56:04 pureos ModemManager[2418]: <info> [base-manager] port ttyUSB3 released by device '/sys/devices/platform/soc@0/38200000.usb/xhci-hcd.4.auto/usb1/1-1/1-1.2'
Jun 03 11:56:04 pureos ModemManager[2418]: <info> [base-manager] port ttyUSB2 released by device '/sys/devices/platform/soc@0/38200000.usb/xhci-hcd.4.auto/usb1/1-1/1-1.2'
Jun 03 11:56:04 pureos ModemManager[2418]: <info> [base-manager] port ttyUSB0 released by device '/sys/devices/platform/soc@0/38200000.usb/xhci-hcd.4.auto/usb1/1-1/1-1.2'
Jun 03 11:56:04 pureos ModemManager[2418]: <info> [base-manager] port ttyUSB1 released by device '/sys/devices/platform/soc@0/38200000.usb/xhci-hcd.4.auto/usb1/1-1/1-1.2'
[ 644.259809] redpine_91x: Antenna is changed to 3
[ 644.375118] edt_ft5x06 2-0038: Unable to fetch data, error: -6
Jun 03 11:56:06 pureos ModemManager[2418]: <info> [modem1] simple connect started...
Jun 03 11:56:06 pureos ModemManager[2418]: <info> [modem1] simple connect state (4/8): wait to get fully enabled
Jun 03 11:56:06 pureos ModemManager[2418]: <info> [modem1] simple connect state (5/8): register
Jun 03 11:56:06 pureos ModemManager[2418]: <info> [modem1] simple connect state (6/8): bearer
Jun 03 11:56:06 pureos ModemManager[2418]: <info> [modem1] simple connect state (7/8): connect
Jun 03 11:56:06 pureos ModemManager[2418]: <info> [modem1] state changed (registered -> connecting)
[ 644.452320] [drm] Connector status: 0
[ 648.019216] wlan0: authenticate with 60:14:66:ff:9f:1a
[ 648.028127] wlan0: bad VHT capabilities, disabling VHT
[ 648.033489] wlan0: send auth to 60:14:66:ff:9f:1a (try 1/3)
[ 648.042079] wlan0: authenticated
[ 648.049392] wlan0: associate with 60:14:66:ff:9f:1a (try 1/3)
[ 648.064159] wlan0: RX AssocResp from 60:14:66:ff:9f:1a (capab=0x411 status=0 aid=2)
[ 648.073732] wlan0: associated
[ 648.099515] redpine_91x: EAPOL 4 confirm
[ 648.141694] IPv6: ADDRCONF(NETDEV_CHANGE): wlan0: link becomes ready
[ 648.174658] redpine_91x: Packet Dropped as RX PN is less than last received PN
Jun 03 11:56:17 pureos ModemManager[2418]: transaction 0x8 aborted, but message is not abortable
[ 656.476548] usb 1-1.2: USB disconnect, device number 8
[ 656.482818] option1 ttyUSB3: GSM modem (1-port) converter now disconnected from ttyUSB3
[ 656.491069] option 1-1.2:1.0: device disconnected
[ 656.503849] option1 ttyUSB0: GSM modem (1-port) converter now disconnected from ttyUSB0
[ 656.512670] option 1-1.2:1.1: device disconnected
[ 656.519331] option1 ttyUSB1: GSM modem (1-port) converter now disconnected from ttyUSB1
[ 656.528799] option 1-1.2:1.2: device disconnected
[ 656.534714] option1 ttyUSB2: GSM modem (1-port) converter now disconnected from ttyUSB2
[ 656.543290] option 1-1.2:1.3: device disconnected
[ 656.548415] qmi_wwan 1-1.2:1.4 wwan0: unregister 'qmi_wwan' usb-xhci-hcd.4.auto-1.2, WWAN/QMI device
[ 656.769938] usb 1-1.2: new high-speed USB device number 9 using xhci-hcd
Jun 03 11:56:17 pureos ModemManager[2418]: <info> [base-manager] port ttyUSB3 released by device '/sys/devices/platform/soc@0/38200000.usb/xhci-hcd.4.auto/usb1/1-1/1-1.2'
Jun 03 11:56:17 pureos ModemManager[2418]: <info> [base-manager] port ttyUSB0 released by device '/sys/devices/platform/soc@0/38200000.usb/xhci-hcd.4.auto/usb1/1-1/1-1.2'
Jun 03 11:56:17 pureos ModemManager[2418]: <info> [base-manager] port ttyUSB1 released by device '/sys/devices/platform/soc@0/38200000.usb/xhci-hcd.4.auto/usb1/1-1/1-1.2'
Jun 03 11:56:17 pureos ModemManager[2418]: <info> [base-manager] port ttyUSB2 released by device '/sys/devices/platform/soc@0/38200000.usb/xhci-hcd.4.auto/usb1/1-1/1-1.2'
Jun 03 11:56:17 pureos ModemManager[2418]: Cannot read from istream: connection broken
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] QMI endpoint hangup: removed
Jun 03 11:56:17 pureos ModemManager[2418]: <info> [modem1] connection to qmi-proxy for /dev/cdc-wdm0 lost, reprobing
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Releasing 'wds' client with flags 'release-cid'...
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Unregistered 'wds' client with ID '3'
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Releasing 'wds' client with flags 'release-cid'...
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Unregistered 'wds' client with ID '2'
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Releasing 'voice' client with flags 'release-cid'...
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Unregistered 'voice' client with ID '1'
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Releasing 'pdc' client with flags 'release-cid'...
Jun 03 11:56:17 pureos ModemManager[2418]: [/de[ 656.985469] usb 1-1.2: New USB device found, idVendor=2020, idProduct=2060, bcdDevice= 0.00
[ 656.995460] usb 1-1.2: New USB device strings: Mfr=3, Product=2, SerialNumber=4
[ 657.002813] usb 1-1.2: Product: Qualcomm CDMA Technologies MSM
v/cdc-wdm0] Unregistered 'pdc' cl[ 657.008685] usb 1-1.2: Manufacturer: Qualcomm, Incorporated
ient with ID '5'
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Releasing 'loc' client with flags 'release-cid'...
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Unregistered 'loc' client with ID '5'
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Releasing 'uim' client with flags 'release-cid'...
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Unregistered 'uim' client with ID '1'
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Releasing 'wms' client with flags 'release-cid'...
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Unregistered 'wms' client with ID '1'
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Releasing 'wds' client with flags 'release-cid'...
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Unregistered 'wds' client with ID '1'
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Releasing 'nas' client with flags 'release-cid'...
Jun 03 11:56:17 pureos ModemManager[2418]: [/dev/cdc-wdm0] Unregistered 'nas' client with ID '1'
Jun[ 657.152928] option 1-1.2:1.0: GSM modem (1-port) converter detected
03 11:56:17 pureos ModemManager[[ 657.160892] usb 1-1.2: GSM modem (1-port) converter now attached to ttyUSB0