sim hot swap problem with Telit GE910-QUAD

[Aleksander Morgado]

On Thu, Jul 20, 2017 at 7:01 PM, Tim Small <tim at seoss.co.uk> wrote:
> On 20/07/17 17:26, Aleksander Morgado wrote:
>> The AT+CSIM= command there timed out (it took longer than we expected
>> it to take, a pity that these logs don't have timestamp), and then we
>> issued ATE0, and we got the response of the previous AT+CSIM command
>> back there. For this one maybe we should set a longer timeout.
>
> OK, sorry about that, I've turned on relative timestamps, and it doesn't
> look like the timeout is being hit here:
>
> https://gist.github.com/tim-seoss/c7ddefc8ecfd74c88c5ccf60caa5115f#file-gistfile1-txt-L129
>
> [000028.048532] (ttyUSB0): --> 'AT+CSIM=10,0020000100<CR>'
> [000028.074861] (ttyUSB0): <-- '<CR><LF>#QSS: 0<CR><LF>'
> [000028.075197] QSS: status changed 'sim-inserted -> sim-removed
> [000028.075254] QSS: SIM swap detected
> [000028.075462] load PIN unlock retries got no response: AT command was
> cancelled

Ah, so this #QSS:0 is triggering a sim removal operation and we cancel
the AT command, we don't wait any more for the response.

> [000028.075569] (ttyUSB0) device open count is 3 (open)
> [000028.075632] (ttyUSB0) device open count is 2 (close)
> [000028.075699] [device /sys/devices/pci0000:00/0000:00:14.0/usb1/1-9]
> creating modem with plugin 'Telit' and '1' ports
> [000028.075960] (ttyUSB0) type 'at' claimed by
> /sys/devices/pci0000:00/0000:00:14.0/usb1/1-9
> [000028.076064] (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-9)
> tty/ttyUSB0 at (primary)
> [000028.076120] (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-9)
> tty/ttyUSB0 data (primary)
> [000028.076280] (ttyUSB0) opening serial port...
> [000028.076444] (ttyUSB0): couldn't set serial port closing_wait to
> none: Inappropriate ioctl for device
> [000028.076467] (ttyUSB0): setting up baudrate: 115200
> [000028.076491] (ttyUSB0) device open count is 1 (open)
> [000028.076502] (ttyUSB0): running init sequence...
> [000028.076524] (ttyUSB0) device open count is 2 (open)
> [000028.076541] (ttyUSB0) device open count is 3 (open
> [000028.076560] Modem recreated for device
> '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-9'
> [000028.076712] loading current capabilities...
> [000028.076733] (ttyUSB0) device open count is 4 (open)
> [000028.076756] (ttyUSB0): --> 'AT+CSIM=10,002C000100<CR>'
> [000028.076775] (ttyUSB0): --> 'ATE0<CR>'
>

Oh wait, what happened here?... How did we "recreate" the modem object
and we're still sending AT+CSIM commands?