[Telit] ModemManager Telit plugin hangs in "initializing" state after unlocking a SIM (HE910-D).
Jarosław Bielski
bielski.j at gmail.com
Fri May 13 07:18:27 UTC 2016
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'
<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
-------------- next part --------------
An HTML attachment was scrubbed...
URL: <https://lists.freedesktop.org/archives/modemmanager-devel/attachments/20160513/eee51533/attachment-0001.html>
More information about the ModemManager-devel
mailing list