[Telit] ModemManager Telit plugin hangs in "initializing" state after unlocking a SIM (HE910-D).
Daniele Palmas
dnlplm at gmail.com
Fri May 13 08:48:51 UTC 2016
Hi Jaroslaw,
2016-05-13 9:18 GMT+02:00 Jarosław Bielski <bielski.j at gmail.com>:
> Hi,
>
> To have additional information and capabilities regarding to the modem
> (pin/puk tries perform/remain count, supported network modes (2G/3G/4G)) I
> have checked the newer version of ModemManager-1.5.992. Unfortunately I have
> found a regression with unlocking of PIN locked SIM. Unlocking procedure
> takes a lot of time (about 5 seconds), finishes with success but modem
> remains in "initializing" state (never changed to other one). There is no
> possibility to configure/connect the modem from client side. Note that all
> thinks works fine if I insert not PIN locked SIM card into the modem.
> Previous one version of ModemManager (1.4.12) was this bug/behaviour free.
>
> Use case (step-by-step):
> 1. Start ModemManager
> 2. Plug modem into USB port
> 3. Check modem status using mmcli tool (mmcli -L, mmcli -m X)
> 4. provide PIN to unlock the SIM (mmcli -m X -i Y --pin ABCD)
>
> During performing step 4 the following message is displayed on kernel
> console:
>
> [ 2635.412000] tty_port_close_start: tty->count = 1 port count = 0.
>
> Environment:
> - Modem MTD-H5 (Telit HE910-D)
> - SIM card with PIN lock enabled
> - Linux 2.6.37/3.18.11 (both versions was checked)
> - udev-182
> - glib-2.38.2
> - dbus-1.4.20
> - libmbim-1.12.4
> - libqmi-1.14.2
>
>
> Please find the following logs from the use cases steps:
>
>> 3. Check modem status using mmcli tool.
>
> # mmcli -m 7
>
> /org/freedesktop/ModemManager1/Modem/7 (device id
> 'd085b4f507a00fb98af1e3e35a70c4e00b451b14')
> -------------------------
> Hardware | manufacturer: 'Telit'
> | model: 'HE910-D'
> | revision: '12.00.026'
> | supported: 'gsm-umts'
> | current: 'gsm-umts'
> | equipment id: '351579057922930'
> -------------------------
> System | device: '/sys/devices/platform/ehci-brcm.0/usb1/1-1'
> | drivers: 'cdc_acm'
> | plugin: 'Telit'
> | primary port: 'ttyACM0'
> | ports: 'ttyACM3 (at), ttyACM4 (unknown), ttyACM5
> (unknown), ttyACM0 (at), ttyACM1 (unknown), ttyACM2 (unknown)'
> -------------------------
> Numbers | own : 'unknown'
> -------------------------
> Status | lock: 'sim-pin'
> | unlock retries: 'sim-pin (3), sim-pin2 (3), sim-puk (10),
> sim-puk2 (10)'
> | state: 'locked'
> | power state: 'on'
> | access tech: 'unknown'
> | signal quality: '0' (cached)
> -------------------------
> Modes | supported: 'allowed: 2g; preferred: none
> | allowed: 3g; preferred: none
> | allowed: 2g, 3g; preferred: none'
> | current: 'allowed: 2g, 3g; preferred: none'
> -------------------------
> Bands | supported: 'egsm, dcs, pcs, g850, u2100, u1900, u850,
> u900, u17iv'
> | current: 'egsm, dcs, u900'
> -------------------------
> IP | supported: 'none'
> -------------------------
> SIM | path: '/org/freedesktop/ModemManager1/SIM/7'
>
> -------------------------
> Bearers | paths: 'none'
>
>
>> 4. provide PIN to unlock the SIM (mmcli -m X -i Y --pin ABCD)
>
> // mmcli hangs on 3/4 seconds
>
> successfully sent PIN code to the SIM
>
> // mmcli hangs on ~2 seconds
> // on kernel console the following line is visible:
>
> [ 1674.800000] tty_port_close_start: tty->count = 1 port count = 0.
>
>
>
>> 3. Check modem status using mmcli tool.
>
> # mmcli -m 0
>
> /org/freedesktop/ModemManager1/Modem/0 (device id
> 'd085b4f507a00fb98af1e3e35a70c4e00b451b14')
> -------------------------
> Hardware | manufacturer: 'Telit'
> | model: 'HE910-D'
> | revision: '12.00.026'
> | supported: 'gsm-umts'
> | current: 'gsm-umts'
> | equipment id: '351579057922930'
> -------------------------
> System | device: '/sys/devices/platform/ehci-brcm.0/usb1/1-1'
> | drivers: 'cdc_acm'
> | plugin: 'Telit'
> | primary port: 'ttyACM0'
> | ports: 'ttyACM3 (at), ttyACM4 (unknown), ttyACM5
> (unknown), ttyACM0 (at), ttyACM1 (unknown), ttyACM2 (unknown)'
> -------------------------
> Numbers | own : 'unknown'
> -------------------------
> Status | lock: 'none'
> | unlock retries: 'sim-pin (3), sim-pin2 (3), sim-puk (10),
> sim-puk2 (10)'
> | state: 'initializing'
> | power state: 'on'
> | access tech: 'unknown'
> | signal quality: '0' (cached)
> -------------------------
> Modes | supported: 'allowed: 2g; preferred: none
> | allowed: 3g; preferred: none
> | allowed: 2g, 3g; preferred: none'
> | current: 'allowed: 2g, 3g; preferred: none'
> -------------------------
> Bands | supported: 'egsm, dcs, pcs, g850, u2100, u1900, u850,
> u900, u17iv'
> | current: 'egsm, dcs, u900'
> -------------------------
> IP | supported: 'none'
> -------------------------
> SIM | path: '/org/freedesktop/ModemManager1/SIM/0'
>
> -------------------------
> Bearers | paths: 'none'
>
>
> Debug messages from ModemManager on step 4.
>
> <debug> (ttyACM0) opening serial port...
> <debug> (ttyACM0) device open count is 1 (open)
> <debug> (ttyACM0): --> 'AT+CPIN="1234"<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> checking if unlock required...
> <debug> (ttyACM0) device open count is 2 (open)
> <debug> (ttyACM0) device open count is 1 (close)
> <debug> (ttyACM0): --> 'AT+CPIN?<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>'
> <debug> (ttyACM0): <-- '+CPIN: READY<CR><LF><CR><LF>OK<CR><LF>'
> <debug> (ttyACM0) device open count is 0 (close)
> <debug> (ttyACM0) closing serial port...
> <debug> (ttyACM0) serial port closed
> <debug> (ttyACM0) opening serial port...
> <debug> (ttyACM0) device open count is 1 (open)
> <debug> (ttyACM0): --> 'AT+CSIM=10,0020000100<CR>'
> <warn> No respose for step 1: Serial command timed out
> <debug> (ttyACM0) device open count is 2 (open)
> <debug> (ttyACM0) device open count is 1 (close)
> <debug> (ttyACM0): --> 'AT+CSIM=10,002C000100<CR>'
> <warn> No respose for step 2: Serial command timed out
> <debug> (ttyACM0) device open count is 2 (open)
> <debug> (ttyACM0) device open count is 1 (close)
> <debug> (ttyACM0): --> 'AT+CSIM=10,0020008100<CR>'
> <warn> No respose for step 3: Sending command failed: 'Resource temporarily
> unavailable'
> <debug> (ttyACM0) device open count is 2 (open)
> <debug> (ttyACM0) device open count is 1 (close)
> <debug> (ttyACM0): --> 'AT+CSIM=10,002C008100<CR>'
> <warn> No respose for step 4: Sending command failed: 'Resource temporarily
> unavailable'
Looks like the modem got stuck.
Are you able to reproduce the issue with other SIM cards?
Can you please send also dmesg output when the problem happens?
We saw a different behaviour with some specific SIM cards: Carlo is
working on a patch for this, that can probably help also with your
situation.
Please see thread
https://lists.freedesktop.org/archives/modemmanager-devel/2016-May/002953.html
Regards,
Daniele
> <warn> Couldn't load unlock retries: 'Could not get any of the SIM unlock
> retries values'
> <debug> SIM is ready, and no need for the after SIM unlock step...
> <debug> (ttyACM0) device open count is 0 (close)
> <debug> (ttyACM0) closing serial port...
> <debug> (ttyACM0) serial port closed
> <info> Modem /org/freedesktop/ModemManager1/Modem/0: state changed (locked
> -> initializing)
> <debug> (ttyACM0) opening serial port...
> <warn> (ttyACM0) could not open serial device (5)
> <warn> Couldn't start initialization: Couldn't open ports during modem
> initialization: Couldn't open primary port: Could not open serial device
> ttyACM0: Input/output error
> <warn> Modem reinitialization failed: 'Modem is unusable, cannot fully
> initialize'
>
>
> Debug messages from ModemManager on step 2.
>
> <debug> [plugin manager] task 0: new support task for device:
> /sys/devices/platform/ehci-brcm.0/usb1
> <debug> [plugin manager] task 0: port grabbed: ttyACM0
> <debug> [plugin manager] task 0,ttyACM0: new support task for port
> <debug> [plugin manager) task 0,ttyACM0: deferred until min wait time
> elapsed
> <info> (tty/ttyACM0): released by modem
> /sys/devices/platform/ehci-brcm.0/usb1
> <debug> [plugin manager] task 0: port released: ttyACM0
> <debug> Removing empty device '/sys/devices/platform/ehci-brcm.0/usb1'
> <debug> [plugin manager) task 0: cancellation requested
> <debug> [plugin manager] task 0: no more ports to probe
> <debug> [plugin manager] task 0: finished in '0.031896' seconds
> <info> Couldn't check support for device at
> '/sys/devices/platform/ehci-brcm.0/usb1': Operation was cancelled
> <debug> Device support check has been cancelled
> <debug> [plugin manager] task 1: new support task for device:
> /sys/devices/platform/ehci-brcm.0/usb1/1-1
> <debug> [plugin manager] task 1: port grabbed: ttyACM0
> <debug> [plugin manager] task 1,ttyACM0: new support task for port
> <debug> [plugin manager) task 1,ttyACM0: deferred until min wait time
> elapsed
> <debug> [plugin manager] task 1: port grabbed: ttyACM1
> <debug> [plugin manager] task 1,ttyACM1: new support task for port
> <debug> [plugin manager) task 1,ttyACM1: deferred until min wait time
> elapsed
> <debug> [plugin manager] task 1: port grabbed: ttyACM3
> <debug> [plugin manager] task 1,ttyACM3: new support task for port
> <debug> [plugin manager) task 1,ttyACM3: deferred until min wait time
> elapsed
> <debug> [plugin manager] task 1: port grabbed: ttyACM4
> <debug> [plugin manager] task 1,ttyACM4: new support task for port
> <debug> [plugin manager) task 1,ttyACM4: deferred until min wait time
> elapsed
> <debug> [plugin manager] task 1: port grabbed: ttyACM2
> <debug> [plugin manager] task 1,ttyACM2: new support task for port
> <debug> [plugin manager) task 1,ttyACM2: deferred until min wait time
> elapsed
> <debug> [plugin manager] task 1: port grabbed: ttyACM5
> <debug> [plugin manager] task 1,ttyACM5: new support task for port
> <debug> [plugin manager) task 1,ttyACM5: deferred until min wait time
> elapsed
> <debug> [plugin manager] task 1: min wait time elapsed
> <debug> (Haier) [ttyACM5] filtered by vendor/product IDs
> <debug> (MTK) [ttyACM5] filtered by udev tags
> <debug> (Sierra (legacy)) [ttyACM5] filtered by drivers
> <debug> (Altair LTE) [ttyACM5] filtered by vendor/product IDs
> <debug> (X22X) [ttyACM5] filtered by vendor/product IDs
> <debug> (Linktop) [ttyACM5] filtered by vendor/product IDs
> <debug> (Novatel) [ttyACM5] filtered by vendor/product IDs
> <debug> (Wavecom) [ttyACM5] filtered by vendor/product IDs
> <debug> (Dell) [ttyACM5] filtered by vendor/product IDs
> <debug> (Ericsson MBM) [ttyACM5] filtered by udev tags
> <debug> (Novatel LTE) [ttyACM5] filtered by vendor/product IDs
> <debug> (Huawei) [ttyACM5] filtered by vendor/product IDs
> <debug> (AnyDATA) [ttyACM5] filtered by vendor/product IDs
> <debug> (Samsung) [ttyACM5] filtered by vendor/product IDs
> <debug> (Option High-Speed) [ttyACM5] filtered by drivers
> <debug> (ZTE) [ttyACM5] filtered by vendor/product IDs
> <debug> (Thuraya) [ttyACM5] filtered by vendor/product IDs
> <debug> (Nokia (Icera)) [ttyACM5] filtered by vendor/product IDs
> <debug> (Pantech) [ttyACM5] filtered by vendor/product IDs
> <debug> (Longcheer) [ttyACM5] filtered by vendor/product IDs
> <debug> (Sierra) [ttyACM5] filtered by drivers
> <debug> (SimTech) [ttyACM5] filtered by vendor/product IDs
> <debug> (Motorola) [ttyACM5] filtered by vendor/product IDs
> <debug> (Option) [ttyACM5] filtered by drivers
> <debug> [plugin manager] task 1,ttyACM5: found '6' plugins to try
> <debug> [plugin manager] task 1,ttyACM5: will try with plugin 'Telit'
> <debug> [plugin manager] task 1,ttyACM5: will try with plugin 'Via CBP7'
> <debug> [plugin manager] task 1,ttyACM5: will try with plugin 'Iridium'
> <debug> [plugin manager] task 1,ttyACM5: will try with plugin 'Cinterion'
> <debug> [plugin manager] task 1,ttyACM5: will try with plugin 'Nokia'
> <debug> [plugin manager] task 1,ttyACM5: will try with plugin 'Generic'
> <debug> [plugin manager) task 1,ttyACM5: started
> <debug> [plugin manager] task 1,ttyACM5: checking with plugin 'Telit'
> <debug> (Telit) [ttyACM5] probe required: 'at'
> <debug> (tty/ttyACM5) launching port probing: 'at'
> <debug> (Haier) [ttyACM2] filtered by vendor/product IDs
> <debug> (MTK) [ttyACM2] filtered by udev tags
> <debug> (Sierra (legacy)) [ttyACM2] filtered by drivers
> <debug> (Altair LTE) [ttyACM2] filtered by vendor/product IDs
> <debug> (X22X) [ttyACM2] filtered by vendor/product IDs
> <debug> (Linktop) [ttyACM2] filtered by vendor/product IDs
> <debug> (Novatel) [ttyACM2] filtered by vendor/product IDs
> <debug> (Wavecom) [ttyACM2] filtered by vendor/product IDs
> <debug> (Dell) [ttyACM2] filtered by vendor/product IDs
> <debug> (Ericsson MBM) [ttyACM2] filtered by udev tags
> <debug> (Novatel LTE) [ttyACM2] filtered by vendor/product IDs
> <debug> (Huawei) [ttyACM2] filtered by vendor/product IDs
> <debug> (AnyDATA) [ttyACM2] filtered by vendor/product IDs
> <debug> (Samsung) [ttyACM2] filtered by vendor/product IDs
> <debug> (Option High-Speed) [ttyACM2] filtered by drivers
> <debug> (ZTE) [ttyACM2] filtered by vendor/product IDs
> <debug> (Thuraya) [ttyACM2] filtered by vendor/product IDs
> <debug> (Nokia (Icera)) [ttyACM2] filtered by vendor/product IDs
> <debug> (Pantech) [ttyACM2] filtered by vendor/product IDs
> <debug> (Longcheer) [ttyACM2] filtered by vendor/product IDs
> <debug> (Sierra) [ttyACM2] filtered by drivers
> <debug> (SimTech) [ttyACM2] filtered by vendor/product IDs
> <debug> (Motorola) [ttyACM2] filtered by vendor/product IDs
> <debug> (Option) [ttyACM2] filtered by drivers
> <debug> [plugin manager] task 1,ttyACM2: found '6' plugins to try
> <debug> [plugin manager] task 1,ttyACM2: will try with plugin 'Telit'
> <debug> [plugin manager] task 1,ttyACM2: will try with plugin 'Via CBP7'
> <debug> [plugin manager] task 1,ttyACM2: will try with plugin 'Iridium'
> <debug> [plugin manager] task 1,ttyACM2: will try with plugin 'Cinterion'
> <debug> [plugin manager] task 1,ttyACM2: will try with plugin 'Nokia'
> <debug> [plugin manager] task 1,ttyACM2: will try with plugin 'Generic'
> <debug> [plugin manager) task 1,ttyACM2: started
> <debug> [plugin manager] task 1,ttyACM2: checking with plugin 'Telit'
> <debug> (Telit) [ttyACM2] probe required: 'at'
> <debug> (tty/ttyACM2) launching port probing: 'at'
> <debug> (Haier) [ttyACM4] filtered by vendor/product IDs
> <debug> (MTK) [ttyACM4] filtered by udev tags
> <debug> (Sierra (legacy)) [ttyACM4] filtered by drivers
> <debug> (Altair LTE) [ttyACM4] filtered by vendor/product IDs
> <debug> (X22X) [ttyACM4] filtered by vendor/product IDs
> <debug> (Linktop) [ttyACM4] filtered by vendor/product IDs
> <debug> (Novatel) [ttyACM4] filtered by vendor/product IDs
> <debug> (Wavecom) [ttyACM4] filtered by vendor/product IDs
> <debug> (Dell) [ttyACM4] filtered by vendor/product IDs
> <debug> (Ericsson MBM) [ttyACM4] filtered by udev tags
> <debug> (Novatel LTE) [ttyACM4] filtered by vendor/product IDs
> <debug> (Huawei) [ttyACM4] filtered by vendor/product IDs
> <debug> (AnyDATA) [ttyACM4] filtered by vendor/product IDs
> <debug> (Samsung) [ttyACM4] filtered by vendor/product IDs
> <debug> (Option High-Speed) [ttyACM4] filtered by drivers
> <debug> (ZTE) [ttyACM4] filtered by vendor/product IDs
> <debug> (Thuraya) [ttyACM4] filtered by vendor/product IDs
> <debug> (Nokia (Icera)) [ttyACM4] filtered by vendor/product IDs
> <debug> (Pantech) [ttyACM4] filtered by vendor/product IDs
> <debug> (Longcheer) [ttyACM4] filtered by vendor/product IDs
> <debug> (Sierra) [ttyACM4] filtered by drivers
> <debug> (SimTech) [ttyACM4] filtered by vendor/product IDs
> <debug> (Motorola) [ttyACM4] filtered by vendor/product IDs
> <debug> (Option) [ttyACM4] filtered by drivers
> <debug> [plugin manager] task 1,ttyACM4: found '6' plugins to try
> <debug> [plugin manager] task 1,ttyACM4: will try with plugin 'Telit'
> <debug> [plugin manager] task 1,ttyACM4: will try with plugin 'Via CBP7'
> <debug> [plugin manager] task 1,ttyACM4: will try with plugin 'Iridium'
> <debug> [plugin manager] task 1,ttyACM4: will try with plugin 'Cinterion'
> <debug> [plugin manager] task 1,ttyACM4: will try with plugin 'Nokia'
> <debug> [plugin manager] task 1,ttyACM4: will try with plugin 'Generic'
> <debug> [plugin manager) task 1,ttyACM4: started
> <debug> [plugin manager] task 1,ttyACM4: checking with plugin 'Telit'
> <debug> (Telit) [ttyACM4] probe required: 'at'
> <debug> (tty/ttyACM4) launching port probing: 'at'
> <debug> (Haier) [ttyACM3] filtered by vendor/product IDs
> <debug> (MTK) [ttyACM3] filtered by udev tags
> <debug> (Sierra (legacy)) [ttyACM3] filtered by drivers
> <debug> (Altair LTE) [ttyACM3] filtered by vendor/product IDs
> <debug> (X22X) [ttyACM3] filtered by vendor/product IDs
> <debug> (Linktop) [ttyACM3] filtered by vendor/product IDs
> <debug> (Novatel) [ttyACM3] filtered by vendor/product IDs
> <debug> (Wavecom) [ttyACM3] filtered by vendor/product IDs
> <debug> (Dell) [ttyACM3] filtered by vendor/product IDs
> <debug> (Ericsson MBM) [ttyACM3] filtered by udev tags
> <debug> (Novatel LTE) [ttyACM3] filtered by vendor/product IDs
> <debug> (Huawei) [ttyACM3] filtered by vendor/product IDs
> <debug> (AnyDATA) [ttyACM3] filtered by vendor/product IDs
> <debug> (Samsung) [ttyACM3] filtered by vendor/product IDs
> <debug> (Option High-Speed) [ttyACM3] filtered by drivers
> <debug> (ZTE) [ttyACM3] filtered by vendor/product IDs
> <debug> (Thuraya) [ttyACM3] filtered by vendor/product IDs
> <debug> (Nokia (Icera)) [ttyACM3] filtered by vendor/product IDs
> <debug> (Pantech) [ttyACM3] filtered by vendor/product IDs
> <debug> (Longcheer) [ttyACM3] filtered by vendor/product IDs
> <debug> (Sierra) [ttyACM3] filtered by drivers
> <debug> (SimTech) [ttyACM3] filtered by vendor/product IDs
> <debug> (Motorola) [ttyACM3] filtered by vendor/product IDs
> <debug> (Option) [ttyACM3] filtered by drivers
> <debug> [plugin manager] task 1,ttyACM3: found '6' plugins to try
> <debug> [plugin manager] task 1,ttyACM3: will try with plugin 'Telit'
> <debug> [plugin manager] task 1,ttyACM3: will try with plugin 'Via CBP7'
> <debug> [plugin manager] task 1,ttyACM3: will try with plugin 'Iridium'
> <debug> [plugin manager] task 1,ttyACM3: will try with plugin 'Cinterion'
> <debug> [plugin manager] task 1,ttyACM3: will try with plugin 'Nokia'
> <debug> [plugin manager] task 1,ttyACM3: will try with plugin 'Generic'
> <debug> [plugin manager) task 1,ttyACM3: started
> <debug> [plugin manager] task 1,ttyACM3: checking with plugin 'Telit'
> <debug> (Telit) [ttyACM3] probe required: 'at'
> <debug> (tty/ttyACM3) launching port probing: 'at'
> <debug> (Haier) [ttyACM1] filtered by vendor/product IDs
> <debug> (MTK) [ttyACM1] filtered by udev tags
> <debug> (Sierra (legacy)) [ttyACM1] filtered by drivers
> <debug> (Altair LTE) [ttyACM1] filtered by vendor/product IDs
> <debug> (X22X) [ttyACM1] filtered by vendor/product IDs
> <debug> (Linktop) [ttyACM1] filtered by vendor/product IDs
> <debug> (Novatel) [ttyACM1] filtered by vendor/product IDs
> <debug> (Wavecom) [ttyACM1] filtered by vendor/product IDs
> <debug> (Dell) [ttyACM1] filtered by vendor/product IDs
> <debug> (Ericsson MBM) [ttyACM1] filtered by udev tags
> <debug> (Novatel LTE) [ttyACM1] filtered by vendor/product IDs
> <debug> (Huawei) [ttyACM1] filtered by vendor/product IDs
> <debug> (AnyDATA) [ttyACM1] filtered by vendor/product IDs
> <debug> (Samsung) [ttyACM1] filtered by vendor/product IDs
> <debug> (Option High-Speed) [ttyACM1] filtered by drivers
> <debug> (ZTE) [ttyACM1] filtered by vendor/product IDs
> <debug> (Thuraya) [ttyACM1] filtered by vendor/product IDs
> <debug> (Nokia (Icera)) [ttyACM1] filtered by vendor/product IDs
> <debug> (Pantech) [ttyACM1] filtered by vendor/product IDs
> <debug> (Longcheer) [ttyACM1] filtered by vendor/product IDs
> <debug> (Sierra) [ttyACM1] filtered by drivers
> <debug> (SimTech) [ttyACM1] filtered by vendor/product IDs
> <debug> (Motorola) [ttyACM1] filtered by vendor/product IDs
> <debug> (Option) [ttyACM1] filtered by drivers
> <debug> [plugin manager] task 1,ttyACM1: found '6' plugins to try
> <debug> [plugin manager] task 1,ttyACM1: will try with plugin 'Telit'
> <debug> [plugin manager] task 1,ttyACM1: will try with plugin 'Via CBP7'
> <debug> [plugin manager] task 1,ttyACM1: will try with plugin 'Iridium'
> <debug> [plugin manager] task 1,ttyACM1: will try with plugin 'Cinterion'
> <debug> [plugin manager] task 1,ttyACM1: will try with plugin 'Nokia'
> <debug> [plugin manager] task 1,ttyACM1: will try with plugin 'Generic'
> <debug> [plugin manager) task 1,ttyACM1: started
> <debug> [plugin manager] task 1,ttyACM1: checking with plugin 'Telit'
> <debug> (Telit) [ttyACM1] probe required: 'at'
> <debug> (tty/ttyACM1) launching port probing: 'at'
> <debug> (Haier) [ttyACM0] filtered by vendor/product IDs
> <debug> (MTK) [ttyACM0] filtered by udev tags
> <debug> (Sierra (legacy)) [ttyACM0] filtered by drivers
> <debug> (Altair LTE) [ttyACM0] filtered by vendor/product IDs
> <debug> (X22X) [ttyACM0] filtered by vendor/product IDs
> <debug> (Linktop) [ttyACM0] filtered by vendor/product IDs
> <debug> (Novatel) [ttyACM0] filtered by vendor/product IDs
> <debug> (Wavecom) [ttyACM0] filtered by vendor/product IDs
> <debug> (Dell) [ttyACM0] filtered by vendor/product IDs
> <debug> (Ericsson MBM) [ttyACM0] filtered by udev tags
> <debug> (Novatel LTE) [ttyACM0] filtered by vendor/product IDs
> <debug> (Huawei) [ttyACM0] filtered by vendor/product IDs
> <debug> (AnyDATA) [ttyACM0] filtered by vendor/product IDs
> <debug> (Samsung) [ttyACM0] filtered by vendor/product IDs
> <debug> (Option High-Speed) [ttyACM0] filtered by drivers
> <debug> (ZTE) [ttyACM0] filtered by vendor/product IDs
> <debug> (Thuraya) [ttyACM0] filtered by vendor/product IDs
> <debug> (Nokia (Icera)) [ttyACM0] filtered by vendor/product IDs
> <debug> (Pantech) [ttyACM0] filtered by vendor/product IDs
> <debug> (Longcheer) [ttyACM0] filtered by vendor/product IDs
> <debug> (Sierra) [ttyACM0] filtered by drivers
> <debug> (SimTech) [ttyACM0] filtered by vendor/product IDs
> <debug> (Motorola) [ttyACM0] filtered by vendor/product IDs
> <debug> (Option) [ttyACM0] filtered by drivers
> <debug> [plugin manager] task 1,ttyACM0: found '6' plugins to try
> <debug> [plugin manager] task 1,ttyACM0: will try with plugin 'Telit'
> <debug> [plugin manager] task 1,ttyACM0: will try with plugin 'Via CBP7'
> <debug> [plugin manager] task 1,ttyACM0: will try with plugin 'Iridium'
> <debug> [plugin manager] task 1,ttyACM0: will try with plugin 'Cinterion'
> <debug> [plugin manager] task 1,ttyACM0: will try with plugin 'Nokia'
> <debug> [plugin manager] task 1,ttyACM0: will try with plugin 'Generic'
> <debug> [plugin manager) task 1,ttyACM0: started
> <debug> [plugin manager] task 1,ttyACM0: checking with plugin 'Telit'
> <debug> (Telit) [ttyACM0] probe required: 'at'
> <debug> (tty/ttyACM0) launching port probing: 'at'
> <debug> [plugin manager] task 1: min probing time elapsed
> <debug> [plugin Manager] task 1: still 6 running probes (6 active): ttyACM5,
> ttyACM2, ttyACM4, ttyACM3, ttyACM1, ttyACM0
> <debug> (ttyACM5) opening serial port...
> <debug> (ttyACM5) device open count is 1 (open)
> <debug> (ttyACM2) opening serial port...
> <debug> (ttyACM2) device open count is 1 (open)
> <debug> (ttyACM4) opening serial port...
> <debug> (ttyACM4) device open count is 1 (open)
> <debug> (ttyACM3) opening serial port...
> <debug> (ttyACM3) device open count is 1 (open)
> <debug> (ttyACM1) opening serial port...
> <debug> (ttyACM1) device open count is 1 (open)
> <debug> (ttyACM0) opening serial port...
> <debug> (ttyACM0) device open count is 1 (open)
> <debug> (ttyACM5): --> 'AT<CR>'
> <debug> (ttyACM2): --> 'AT<CR>'
> <debug> (ttyACM4): --> 'AT<CR>'
> <debug> (ttyACM3): --> 'AT<CR>'
> <debug> (ttyACM3): <-- 'AT'
> <debug> (ttyACM0): --> 'AT#PORTCFG?<CR>'
> <debug> (ttyACM1): --> 'AT<CR>'
> <debug> (ttyACM3): <-- '<CR><CR><LF>OK<CR><LF>'
> <debug> (tty/ttyACM3) port is AT-capable
> <debug> [plugin manager] task 1,ttyACM3: found best plugin for port (Telit)
> <debug> [plugin manager] task 1,ttyACM3: finished in '3.358319' seconds
> <debug> [plugin manager] task 1,ttyACM3: found best plugin: Telit
> <debug> [plugin manager] task 1,ttyACM5: got suggested plugin (Telit)
> <debug> [plugin manager] task 1,ttyACM2: got suggested plugin (Telit)
> <debug> [plugin manager] task 1,ttyACM4: got suggested plugin (Telit)
> <debug> [plugin manager] task 1,ttyACM1: got suggested plugin (Telit)
> <debug> [plugin manager] task 1,ttyACM0: got suggested plugin (Telit)
> <debug> [plugin Manager] task 1: still 5 running probes (5 active): ttyACM5,
> ttyACM2, ttyACM4, ttyACM1, ttyACM0
> <debug> (ttyACM3) device open count is 0 (close)
> <debug> (ttyACM3) closing serial port...
> <debug> (ttyACM3) serial port closed
> <debug> (ttyACM3) forced to close port
> <debug> (ttyACM0): <-- 'AT#PORTCFG?'
> <debug> (ttyACM0): <-- '<CR><CR><LF>#PORTCFG: 1,1<CR><LF><CR><LF>OK<CR><LF>'
> <debug> telit: retrieving port mode layout
> <debug> (tty/ttyACM0) port is AT-capable
> <debug> [plugin manager] task 1,ttyACM0: found best plugin for port (Telit)
> <debug> [plugin manager] task 1,ttyACM0: finished in '4.527071' seconds
> <debug> [plugin manager] task 1,ttyACM0: best plugin matches device reported
> one: Telit
> <debug> [plugin Manager] task 1: still 4 running probes (4 active): ttyACM5,
> ttyACM2, ttyACM4, ttyACM1
> <debug> (ttyACM0) device open count is 0 (close)
> <debug> (ttyACM0) closing serial port...
> <debug> (ttyACM0) serial port closed
> <debug> (ttyACM0) forced to close port
> <debug> Parsing AT got: 'Serial command timed out'
> <debug> Parsing AT got: 'Serial command timed out'
> <debug> Parsing AT got: 'Serial command timed out'
> <debug> Parsing AT got: 'Serial command timed out'
> <debug> (ttyACM5): --> 'AT<CR>'
> <debug> (ttyACM2): --> 'AT<CR>'
> <debug> (ttyACM4): --> 'AT<CR>'
> <debug> (ttyACM1): --> 'AT<CR>'
> <debug> Parsing AT got: 'Serial command timed out'
> <debug> Parsing AT got: 'Serial command timed out'
> <debug> Parsing AT got: 'Serial command timed out'
> <debug> Parsing AT got: 'Serial command timed out'
> <debug> (ttyACM5): --> 'AT<CR>'
> <debug> (ttyACM2): --> 'AT<CR>'
> <debug> (ttyACM4): --> 'AT<CR>'
> <debug> (ttyACM1): --> 'AT<CR>'
> <debug> Parsing AT got: 'Serial command timed out'
> <debug> (tty/ttyACM5) port is not AT-capable
> <debug> [plugin manager] task 1,ttyACM5: found best plugin for port (Telit)
> <debug> [plugin manager] task 1,ttyACM5: finished in '12.780406' seconds
> <debug> [plugin manager] task 1,ttyACM5: best plugin matches device reported
> one: Telit
> <debug> [plugin Manager] task 1: still 3 running probes (3 active): ttyACM2,
> ttyACM4, ttyACM1
> <debug> (ttyACM5) device open count is 0 (close)
> <debug> (ttyACM5) closing serial port...
> <debug> (ttyACM5) serial port closed
> <debug> (ttyACM5) forced to close port
> <debug> Parsing AT got: 'Serial command timed out'
> <debug> (tty/ttyACM2) port is not AT-capable
> <debug> [plugin manager] task 1,ttyACM2: found best plugin for port (Telit)
> <debug> [plugin manager] task 1,ttyACM2: finished in '12.825877' seconds
> <debug> [plugin manager] task 1,ttyACM2: best plugin matches device reported
> one: Telit
> <debug> [plugin Manager] task 1: still 2 running probes (2 active): ttyACM4,
> ttyACM1
> <debug> (ttyACM2) device open count is 0 (close)
> <debug> (ttyACM2) closing serial port...
> <debug> (ttyACM2) serial port closed
> <debug> (ttyACM2) forced to close port
> <debug> Parsing AT got: 'Serial command timed out'
> <debug> (tty/ttyACM4) port is not AT-capable
> <debug> [plugin manager] task 1,ttyACM4: found best plugin for port (Telit)
> <debug> [plugin manager] task 1,ttyACM4: finished in '12.869475' seconds
> <debug> [plugin manager] task 1,ttyACM4: best plugin matches device reported
> one: Telit
> <debug> [plugin Manager] task 1: still 1 running probes (1 active): ttyACM1
> <debug> (ttyACM4) device open count is 0 (close)
> <debug> (ttyACM4) closing serial port...
> <debug> (ttyACM4) serial port closed
> <debug> (ttyACM4) forced to close port
> <debug> Parsing AT got: 'Serial command timed out'
> <debug> (tty/ttyACM1) port is not AT-capable
> <debug> [plugin manager] task 1,ttyACM1: found best plugin for port (Telit)
> <debug> [plugin manager] task 1,ttyACM1: finished in '12.925920' seconds
> <debug> [plugin manager] task 1,ttyACM1: best plugin matches device reported
> one: Telit
> <debug> [plugin manager] task 1: no more ports to probe
> <debug> [plugin manager] task 1: finished in '12.974674' seconds
> <info> Creating modem with plugin 'Telit' and '6' ports
> <debug> (ttyACM5) type 'ignored' claimed by
> /sys/devices/platform/ehci-brcm.0/usb1/1-1
> <debug> (ttyACM2) type 'ignored' claimed by
> /sys/devices/platform/ehci-brcm.0/usb1/1-1
> <debug> (ttyACM4) type 'ignored' claimed by
> /sys/devices/platform/ehci-brcm.0/usb1/1-1
> <debug> telit: AT port 'tty/ttyACM3' flagged as secondary
> <debug> (ttyACM3) type 'at' claimed by
> /sys/devices/platform/ehci-brcm.0/usb1/1-1
> <debug> (ttyACM1) type 'ignored' claimed by
> /sys/devices/platform/ehci-brcm.0/usb1/1-1
> <debug> telit: AT port 'tty/ttyACM0' flagged as primary
> <debug> (ttyACM0) type 'at' claimed by
> /sys/devices/platform/ehci-brcm.0/usb1/1-1
> <debug> (/sys/devices/platform/ehci-brcm.0/usb1/1-1) tty/ttyACM0 at
> (primary)
> <debug> (/sys/devices/platform/ehci-brcm.0/usb1/1-1) tty/ttyACM3 at
> (secondary)
> <debug> (/sys/devices/platform/ehci-brcm.0/usb1/1-1) tty/ttyACM0 data
> (primary)
> <debug> (ttyACM0) opening serial port...
> <debug> (ttyACM0) device open count is 1 (open)
> <debug> (ttyACM0): running init sequence...
> <debug> (ttyACM0) device open count is 2 (open)
> <debug> (ttyACM0) device open count is 3 (open)
> <info> Modem for device at '/sys/devices/platform/ehci-brcm.0/usb1/1-1'
> successfully created
> <debug> (ttyACM1) device open count is 0 (close)
> <debug> (ttyACM1) closing serial port...
> <debug> (ttyACM1) serial port closed
> <debug> (ttyACM1) forced to close port
> <debug> loading current capabilities...
> <debug> (ttyACM0) device open count is 4 (open)
> <debug> (ttyACM0): --> 'ATE0<CR>'
> <debug> (ttyACM0): <-- 'ATE0<CR><CR><LF>OK<CR><LF>'
> <debug> (ttyACM0): --> 'ATV1<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> (ttyACM0): --> 'AT+CMEE=1<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> (ttyACM0): --> 'ATX4<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> (ttyACM0): --> 'AT&C1<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> (ttyACM0): --> 'ATE0<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> (ttyACM0) device open count is 3 (close)
> <debug> (ttyACM0): --> 'AT+CMEE=1<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+GCAP<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>'
> <debug> (ttyACM0): <-- '+GCAP:
> +CGSM,+DS,+FCLASS,+MS,+ES<CR><LF><CR><LF>OK<CR><LF>'
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+WS46=?<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>'
> <debug> (ttyACM0): <-- '+WS46: (12,22,25)<CR><LF><CR><LF>OK<CR><LF>'
> <debug> loaded current capabilities: gsm-umts
> <debug> Modem allows up to 1 bearers
> <debug> Creating bearer list (max: 1, max active: 1)
> <debug> loading manufacturer...
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+CGMI<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>'
> <debug> (ttyACM0): <-- 'Telit<CR><LF><CR><LF>OK<CR><LF>'
> <debug> loaded manufacturer: Telit
> <debug> loading model...
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+CGMM<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>HE910-D<CR><LF><CR><LF>OK<CR><LF>'
> <debug> loaded model: HE910-D
> <debug> loading revision...
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+CGMR<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>12.00.026<CR><LF><CR><LF>OK<CR><LF>'
> <debug> loaded revision: 12.00.026
> <debug> loading equipment identifier...
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+CGSN<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>351579057922930<CR><LF><CR><LF>OK<CR><LF>'
> <debug> loaded equipment identifier: 351579057922930
> <debug> loading device identifier...
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'ATI<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>'
> <debug> (ttyACM0): <-- '332<CR><LF><CR><LF>OK<CR><LF>'
> <debug> Device ID source
> '00001bc70000002133235157905792293012.00.026HE910-DTelit'
> <debug> Device ID 'd085b4f507a00fb98af1e3e35a70c4e00b451b14'
> <debug> loaded device identifier: d085b4f507a00fb98af1e3e35a70c4e00b451b14
> <debug> loading supported modes...
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT*CNTI=2<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>ERROR<CR><LF>'
> <debug> Got failure code 100: Unknown error
> <debug> Generic query of supported 3GPP networks with *CNTI failed: 'Unknown
> error'
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> Device allows (3GPP) 2G-only network mode
> <debug> Device allows (3GPP) 3G-only network mode
> <debug> Device allows every supported 3GPP network mode (2G/3G)
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT#BND=?<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>'
> <debug> (ttyACM0): <-- '#BND: (0,3),(0-7)<CR><LF><CR><LF>OK<CR><LF>'
> <debug> loading supported IP families...
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+CGDCONT=?<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>+CME ERROR: 11<CR><LF>'
> <debug> Got failure code 11: SIM PIN required
> <warn> couldn't load Supported IP families: 'SIM PIN required'
> <debug> loading power state...
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+CFUN?<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>'
> <debug> (ttyACM0): <-- '+CFUN: 1<CR><LF><CR><LF>OK<CR><LF>'
> <debug> checking if unlock required...
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+CPIN?<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>+CPIN: SIM PIN<CR><LF><CR><LF>OK<CR><LF>'
> <debug> (ttyACM0) device open count is 1 (close)
> <debug> (ttyACM0) device open count is 2 (open)
> <debug> (ttyACM0): --> 'AT+CSIM=10,0020000100<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>+CSIM: 4,"63C3"<CR><LF><CR><LF>OK<CR><LF>'
> <debug> PIN unlock retries left: 3
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+CSIM=10,002C000100<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>+CSIM: 4,"63CA"<CR><LF><CR><LF>OK<CR><LF>'
> <debug> PUK unlock retries left: 10
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+CSIM=10,0020008100<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>+CSIM: 4,"63C3"<CR><LF><CR><LF>OK<CR><LF>'
> <debug> PIN2 unlock retries left: 3
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+CSIM=10,002C008100<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>+CSIM: 4,"63CA"<CR><LF><CR><LF>OK<CR><LF>'
> <debug> PUK2 unlock retries left: 10
> <info> Modem: state changed (unknown -> locked)
> <debug> (ttyACM0) device open count is 1 (close)
> <debug> loading SIM identifier...
> <debug> (ttyACM0) device open count is 2 (open)
> <debug> (ttyACM0): --> 'AT+CRSM=176,12258,0,0,10<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>+CRSM:
> 145,11,988430512583766500F6<CR><LF><CR><LF>OK<CR><LF>'
> <debug> loaded SIM identifier: 8948031552386756006
> <debug> loading IMSI...
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+CIMI<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>+CME ERROR: 11<CR><LF>'
> <debug> Got failure code 11: SIM PIN required
> <warn> couldn't load IMSI: 'SIM PIN required'
> <debug> loading Operator ID...
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+CRSM=176,28589,0,0,4<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>+CRSM:
> 145,11,00FFFF02<CR><LF><CR><LF>OK<CR><LF>'
> <warn> couldn't load Operator identifier: 'Cannot load Operator ID without
> IMSI'
> <debug> loading Operator Name...
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+CRSM=176,28486,0,0,17<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>'
> <debug> (ttyACM0): <-- '+CRSM:
> 145,11,026E6A75FFFFFFFFFFFFFFFFFFFFFFFFFF<CR><LF><CR><LF>OK<CR><LF>'
> <debug> (ttyACM0) device open count is 1 (close)
> <debug> loading own numbers...
> <debug> (ttyACM0) device open count is 2 (open)
> <debug> (ttyACM0): --> 'AT+CNUM<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>+CME ERROR: 11<CR><LF>'
> <debug> Got failure code 11: SIM PIN required
> <warn> couldn't load list of Own Numbers: 'SIM PIN required'
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT+WS46?<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>+WS46: 25<CR><LF><CR><LF>OK<CR><LF>'
> <debug> (ttyACM0) device open count is 3 (open)
> <debug> (ttyACM0) device open count is 2 (close)
> <debug> (ttyACM0): --> 'AT#BND?<CR>'
> <debug> (ttyACM0): <-- '<CR><LF>'
> <debug> (ttyACM0): <-- '#BND: 0,5<CR><LF><CR><LF>OK<CR><LF>'
> <debug> (ttyACM0) device open count is 1 (close)
> <debug> Couldn't initialize interface: 'Firmware not supported'
> <debug> (ttyACM0) device open count is 0 (close)
> <debug> (ttyACM0) closing serial port...
> <debug> (ttyACM0) serial port closed
> <debug> Couldn't finish initialization in the current state: 'Modem is
> currently locked, cannot fully initialize'
> <debug> Exported modem '/sys/devices/platform/ehci-brcm.0/usb1/1-1' at path
> '/org/freedesktop/ModemManager1/Modem/0'
> <debug> (/org/freedesktop/ModemManager1/Modem/0): 'Telit' modem, VID 0x1BC7
> PID 0x0021 (usb)
>
> Cheers,
> Jaroslaw Bielski
>
> _______________________________________________
> ModemManager-devel mailing list
> ModemManager-devel at lists.freedesktop.org
> https://lists.freedesktop.org/mailman/listinfo/modemmanager-devel
>
More information about the ModemManager-devel
mailing list