Cannot initialize Cinterion PLS63-W modems after SIM hot swap
Hi, I obeserved that sometimes ModemManager cannot initialize Cinterion PLS63-W modems in QMI mode after SIM hot swap.
Log fragment(click to expand)
13:11:09 ModemManager[1953]: ModemManager[1953]: transaction 0xd aborted, but message is not abortable
13:11:09 ModemManager[1953]: ModemManager[1953]: transaction 0xe aborted, but message is not abortable
13:11:09 ModemManager[1953]: ModemManager[1953]: transaction 0xf aborted, but message is not abortable
13:11:09 ModemManager[1953]: ModemManager[1953]: transaction 0x10 aborted, but message is not abortable
13:11:09 ModemManager[1953]: ModemManager[1953]: transaction 0x11 aborted, but message is not abortable
13:11:09 ModemManager[1953]: ModemManager[1953]: transaction 0x12 aborted, but message is not abortable
13:11:09 ModemManager[1953]: ModemManager[1953]: transaction 0x13 aborted, but message is not abortable
13:11:09 ModemManager[1953]: ModemManager[1953]: transaction 0x14 aborted, but message is not abortable
13:11:13 ModemManager[1953]: ModemManager[1953]: transaction 0x1 aborted, but message is not abortable
13:11:13 ModemManager[1953]: ModemManager[1953]: <debug> [1643202673.880966] [modem3/cdc-wdm0/qmi] QMI port open operation failed: Transaction timed out
13:11:13 ModemManager[1953]: ModemManager[1953]: <debug> [1643202673.881546] [modem3] couldn't open QMI port with data format update: Transaction timed out
13:11:13 ModemManager[1953]: ModemManager[1953]: <debug> [1643202673.881704] [modem3/cdc-wdm0/qmi] Opening QMI device...
13:11:13 ModemManager[1953]: ModemManager[1953]: <debug> [1643202673.881786] [modem3/cdc-wdm0/qmi] Checking if QMI device already opening...
13:11:13 ModemManager[1953]: ModemManager[1953]: <debug> [1643202673.881867] [modem3/cdc-wdm0/qmi] Checking if QMI device already open...
13:11:13 ModemManager[1953]: ModemManager[1953]: <debug> [1643202673.881990] [modem3/cdc-wdm0/qmi] Creating QMI device...
13:11:13 ModemManager[1953]: ModemManager[1953]: <debug> [1643202673.883959] [modem3/cdc-wdm0/qmi] Opening device without data format update...
13:11:13 ModemManager[1953]: ModemManager[1953]: [/dev/cdc-wdm0] Opening device with flags 'version-info, proxy'...
13:11:13 ModemManager[1953]: ModemManager[1953]: [/dev/cdc-wdm0] created endpoint
13:11:13 ModemManager[1953]: ModemManager[1953]: [/dev/cdc-wdm0] Sent message...
13:11:13 ModemManager[1953]: <<<<<< RAW:
13:11:13 ModemManager[1953]: <<<<<< length = 28
13:11:13 ModemManager[1953]: <<<<<< data = 01:1B:00:00:00:00:00:01:00:FF:10:00:01:0D:00:2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
13:11:13 ModemManager[1953]: ModemManager[1953]: [/dev/cdc-wdm0] Sent generic request (translated)...
13:11:13 ModemManager[1953]: <<<<<< QMUX:
13:11:13 ModemManager[1953]: <<<<<< length = 27
13:11:13 ModemManager[1953]: <<<<<< flags = 0x00
13:11:13 ModemManager[1953]: <<<<<< service = "ctl"
13:11:13 ModemManager[1953]: <<<<<< client = 0
13:11:13 ModemManager[1953]: <<<<<< QMI:
13:11:13 ModemManager[1953]: <<<<<< flags = "none"
13:11:13 ModemManager[1953]: <<<<<< transaction = 1
13:11:13 ModemManager[1953]: <<<<<< tlv_length = 16
13:11:13 ModemManager[1953]: <<<<<< message = "Internal Proxy Open" (0xFF00)
13:11:13 ModemManager[1953]: <<<<<< TLV:
13:11:13 ModemManager[1953]: <<<<<< type = "Device Path" (0x01)
13:11:13 ModemManager[1953]: <<<<<< length = 13
13:11:13 ModemManager[1953]: <<<<<< value = 2F:64:65:76:2F:63:64:63:2D:77:64:6D:30
13:11:13 ModemManager[1953]: <<<<<< translated = /dev/cdc-wdm0
13:11:18 ModemManager[1953]: ModemManager[1953]: transaction 0x1 aborted, but message is not abortable
13:11:18 ModemManager[1953]: ModemManager[1953]: <debug> [1643202678.879975] [modem3/cdc-wdm0/qmi] QMI port open operation failed: Transaction timed out
13:11:18 ModemManager[1953]: ModemManager[1953]: <warn> [1643202678.880593] [modem3] couldn't start initialization: Transaction timed out
13:11:18 ModemManager[1953]: ModemManager[1953]: <warn> [1643202678.880774] [modem3] couldn't initialize: 'Modem is unusable, cannot fully initialize'Steps to reproduce
This is very sporadic and happens only when the SIM was hot-swapped. Sometimes it's necessary to reinsert the SIM ~20 times, but sometimes this might appear on the first try.
Possible workaround
I've found that it's possible to fix that only via MM restart and kill the qmi-proxy process.
I can gather more information if this is necessary. I will be very grateful if you share your thoughts on this issue.