When testing the PUK handling in ModemManager 1.4.6 I noticed some problems.
The PUK is triggered by trying to change the PIN with the wrong old PIN: # mmcli -i 0 --change-pin 1111 --pin 0815 error: couldn't change PIN code in the SIM: 'GDBus.Error:org.freedesktop.libmbim.Error.Status.Failure: Failure' <repeated some times> # mmcli -i 0 --change-pin 1111 --pin 0815 error: couldn't change PIN code in the SIM: 'GDBus.Error:org.freedesktop.ModemManager1.Error.Core.WrongState: Need to be unlocked to allow changing PIN' Now the PUK is required. Problem 1: But this is not shown in the state of SIM or modem: # mmcli -m 0 /org/freedesktop/ModemManager1/Modem/0 (device id '3e4e3d78ec186aa790387e228a2a8fbafaec4a48') ------------------------- Hardware | manufacturer: 'Ericsson MBM' | model: 'MBIM [0BDB:193E]' | revision: 'CXP 901 8700/1 - R3C18' | supported: 'gsm-umts' | current: 'gsm-umts' | equipment id: '353997050320019' ------------------------- System | device: '/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10' | drivers: 'cdc_acm, cdc_mbim, cdc_wdm' | plugin: 'Ericsson MBM' | primary port: 'cdc-wdm2' | ports: 'ttyACM1 (at), wwan0 (net), cdc-wdm2 (mbim), ttyACM2 (at), ttyACM0 (at)' ------------------------- Numbers | own : 'unknown' ------------------------- Status | lock: 'none' | unlock retries: 'sim-puk (10)' | state: 'enabled' | power state: 'on' | access tech: 'unknown' | signal quality: '0' (cached) ------------------------- Modes | supported: 'allowed: 2g, 3g; preferred: none' | current: 'allowed: 2g, 3g; preferred: none' ------------------------- Bands | supported: 'unknown' | current: 'unknown' ------------------------- IP | supported: 'ipv4, ipv6, ipv4v6' ------------------------- 3GPP | imei: '353997050320019' | enabled locks: 'sim' | operator id: 'unknown' | operator name: 'unknown' | subscription: 'unknown' | registration: 'unknown' ------------------------- SIM | path: '/org/freedesktop/ModemManager1/SIM/0' ------------------------- Bearers | paths: 'none' [mobil-toh:~] mmcli -m 0 i 0 SIM '/org/freedesktop/ModemManager1/SIM/0' ------------------------- Properties | imsi : '262022913989449' | id : '89492029307551762080' | operator id : '26202' | operator name : 'Vodafone.de' When trying to unlock the PUK everything works fine when the correct PUK is sent. However, if I sent a wrong PUK the following happens: # mmcli -i 0 --puk 1234 --pin 1234 successfully sent PUK code to the SIM Problem 2: There is no indication that the PUK was wrong. But the PUK lock remains active. Is there something I'm missing in the API here? With this behaviour it seems impossible to write client-side code supporting PUK unlocking with failure handling. Log files from ModemManager attached to this mail. Versions of related components: ModemManager 1.4.6 libmbim 1.12.2 libqmi 1.12.4 With regards, Torsten Hilbrich
****************************************************** * * Starting log for modem-manager * Current time: 2015-Jul-20 13:26:28 * ****************************************************** <info> [1437398788.607018] ModemManager (version 1.4.6) starting in system bus... <debug> [1437398788.608064] Bus acquired, creating manager... <debug> [1437398788.608295] Looking for plugins in '/usr/lib/ModemManager' <debug> [1437398788.608857] Loaded plugin 'Altair LTE' <debug> [1437398788.608960] Loaded plugin 'AnyDATA' <debug> [1437398788.609060] Loaded plugin 'Cinterion' <debug> [1437398788.609148] Loaded plugin 'Generic' <debug> [1437398788.609233] Loaded plugin 'Gobi' <debug> [1437398788.609324] Loaded plugin 'Option High-Speed' <debug> [1437398788.609425] Loaded plugin 'Huawei' <debug> [1437398788.609512] Loaded plugin 'Iridium' <debug> [1437398788.609595] Loaded plugin 'Linktop' <debug> [1437398788.609681] Loaded plugin 'Longcheer' <debug> [1437398788.609765] Loaded plugin 'Ericsson MBM' <debug> [1437398788.609846] Loaded plugin 'Motorola' <debug> [1437398788.609928] Loaded plugin 'MTK' <debug> [1437398788.610025] Loaded plugin 'Nokia (Icera)' <debug> [1437398788.610110] Loaded plugin 'Nokia' <debug> [1437398788.610202] Loaded plugin 'Novatel LTE' <debug> [1437398788.610290] Loaded plugin 'Novatel' <debug> [1437398788.610375] Loaded plugin 'Option' <debug> [1437398788.610458] Loaded plugin 'Pantech' <debug> [1437398788.610543] Loaded plugin 'Samsung' <debug> [1437398788.610638] Loaded plugin 'Sierra' <debug> [1437398788.610722] Loaded plugin 'SimTech' <debug> [1437398788.610808] Loaded plugin 'Telit' <debug> [1437398788.610894] Loaded plugin 'Via CBP7' <debug> [1437398788.610984] Loaded plugin 'Wavecom' <debug> [1437398788.611102] Loaded plugin 'X22X' <debug> [1437398788.611196] Loaded plugin 'ZTE' <debug> [1437398788.611206] Successfully loaded 27 plugins <debug> [1437398788.611505] Service name 'org.freedesktop.ModemManager1' was acquired <debug> [1437398788.611509] Starting automatic device scan... <debug> [1437398788.612894] Finished device scan... <debug> [1437398788.613091] (Plugin Manager) [/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10] Checking device support... <debug> [1437398788.613115] (/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10) could not get vendor/product ID <debug> [1437398788.613139] (Altair LTE) [ttyACM0] filtered by vendor/product IDs <debug> [1437398788.613142] (AnyDATA) [ttyACM0] filtered by vendor/product IDs <debug> [1437398788.613145] (Gobi) [ttyACM0] filtered by drivers <debug> [1437398788.613147] (Option High-Speed) [ttyACM0] filtered by drivers <debug> [1437398788.613149] (Huawei) [ttyACM0] filtered by vendor/product IDs <debug> [1437398788.613151] (Linktop) [ttyACM0] filtered by vendor/product IDs <debug> [1437398788.613153] (Longcheer) [ttyACM0] filtered by vendor/product IDs <debug> [1437398788.613156] (Plugin Manager) [ttyACM0] Found '2' plugins to try... <debug> [1437398788.613158] (Plugin Manager) [ttyACM0] Will try with plugin 'Ericsson MBM' <debug> [1437398788.613159] (Plugin Manager) [ttyACM0] Will try with plugin 'Generic' <debug> [1437398788.613168] (Ericsson MBM) [ttyACM0] probe required: 'at' <debug> [1437398788.613172] (tty/ttyACM0) launching port probing: 'at' <debug> [1437398788.613256] (/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10) could not get vendor/product ID <debug> [1437398788.613271] (Altair LTE) [ttyACM1] filtered by vendor/product IDs <debug> [1437398788.613274] (AnyDATA) [ttyACM1] filtered by vendor/product IDs <debug> [1437398788.613276] (Gobi) [ttyACM1] filtered by drivers <debug> [1437398788.613278] (Option High-Speed) [ttyACM1] filtered by drivers <debug> [1437398788.613280] (Huawei) [ttyACM1] filtered by vendor/product IDs <debug> [1437398788.613281] (Linktop) [ttyACM1] filtered by vendor/product IDs <debug> [1437398788.613283] (Longcheer) [ttyACM1] filtered by vendor/product IDs <debug> [1437398788.613286] (Plugin Manager) [ttyACM1] Found '2' plugins to try... <debug> [1437398788.613287] (Plugin Manager) [ttyACM1] Will try with plugin 'Ericsson MBM' <debug> [1437398788.613289] (Plugin Manager) [ttyACM1] Will try with plugin 'Generic' <debug> [1437398788.613292] (Ericsson MBM) [ttyACM1] probe required: 'at' <debug> [1437398788.613295] (tty/ttyACM1) launching port probing: 'at' <debug> [1437398788.613378] (/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10) could not get vendor/product ID <debug> [1437398788.613388] (Altair LTE) [ttyACM2] filtered by vendor/product IDs <debug> [1437398788.613392] (AnyDATA) [ttyACM2] filtered by vendor/product IDs <debug> [1437398788.613394] (Gobi) [ttyACM2] filtered by drivers <debug> [1437398788.613396] (Option High-Speed) [ttyACM2] filtered by drivers <debug> [1437398788.613398] (Huawei) [ttyACM2] filtered by vendor/product IDs <debug> [1437398788.613400] (Linktop) [ttyACM2] filtered by vendor/product IDs <debug> [1437398788.613401] (Longcheer) [ttyACM2] filtered by vendor/product IDs <debug> [1437398788.613404] (Plugin Manager) [ttyACM2] Found '2' plugins to try... <debug> [1437398788.613406] (Plugin Manager) [ttyACM2] Will try with plugin 'Ericsson MBM' <debug> [1437398788.613407] (Plugin Manager) [ttyACM2] Will try with plugin 'Generic' <debug> [1437398788.613411] (Ericsson MBM) [ttyACM2] probe required: 'at' <debug> [1437398788.613413] (tty/ttyACM2) launching port probing: 'at' <debug> [1437398788.613464] (tty/ttyS0): port's parent platform driver is not whitelisted <debug> [1437398788.613511] (tty/ttyS1): port's parent platform driver is not whitelisted <debug> [1437398788.613557] (tty/ttyS2): port's parent platform driver is not whitelisted <debug> [1437398788.613600] (tty/ttyS3): port's parent platform driver is not whitelisted <debug> [1437398788.613678] (tty/ptyp0): could not get port's parent device <debug> [1437398788.613708] (tty/ptyp1): could not get port's parent device <debug> [1437398788.613737] (tty/ptyp2): could not get port's parent device <debug> [1437398788.613767] (tty/ptyp3): could not get port's parent device <debug> [1437398788.613796] (tty/ptyp4): could not get port's parent device <debug> [1437398788.613825] (tty/ptyp5): could not get port's parent device <debug> [1437398788.613854] (tty/ptyp6): could not get port's parent device <debug> [1437398788.613883] (tty/ptyp7): could not get port's parent device <debug> [1437398788.614107] (tty/ttyp0): could not get port's parent device <debug> [1437398788.614137] (tty/ttyp1): could not get port's parent device <debug> [1437398788.614169] (tty/ttyp2): could not get port's parent device <debug> [1437398788.614198] (tty/ttyp3): could not get port's parent device <debug> [1437398788.614227] (tty/ttyp4): could not get port's parent device <debug> [1437398788.614256] (tty/ttyp5): could not get port's parent device <debug> [1437398788.614285] (tty/ttyp6): could not get port's parent device <debug> [1437398788.614314] (tty/ttyp7): could not get port's parent device <debug> [1437398788.614401] (Altair LTE) [wwan0] filtered by vendor/product IDs <debug> [1437398788.614404] (AnyDATA) [wwan0] filtered by vendor/product IDs <debug> [1437398788.614406] (Cinterion) [wwan0] filtered by vendor/product IDs <debug> [1437398788.614408] (Gobi) [wwan0] filtered by drivers <debug> [1437398788.614410] (Option High-Speed) [wwan0] filtered by drivers <debug> [1437398788.614411] (Huawei) [wwan0] filtered by vendor/product IDs <debug> [1437398788.614413] (Iridium) [wwan0] filtered by subsystem <debug> [1437398788.614415] (Linktop) [wwan0] filtered by subsystem <debug> [1437398788.614416] (Longcheer) [wwan0] filtered by subsystem <debug> [1437398788.614419] (Plugin Manager) [wwan0] Found '2' plugins to try... <debug> [1437398788.614420] (Plugin Manager) [wwan0] Will try with plugin 'Ericsson MBM' <debug> [1437398788.614422] (Plugin Manager) [wwan0] Will try with plugin 'Generic' <debug> [1437398788.614425] (Ericsson MBM) [wwan0] probing deferred until result suggested <debug> [1437398788.614483] (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] Checking device support... <debug> [1437398788.614503] (Altair LTE) [eth0-link] filtered by vendor/product IDs <debug> [1437398788.614505] (AnyDATA) [eth0-link] filtered by vendor/product IDs <debug> [1437398788.614507] (Cinterion) [eth0-link] filtered by vendor/product IDs <debug> [1437398788.614509] (Gobi) [eth0-link] filtered by drivers <debug> [1437398788.614511] (Option High-Speed) [eth0-link] filtered by drivers <debug> [1437398788.614512] (Huawei) [eth0-link] filtered by vendor/product IDs <debug> [1437398788.614514] (Iridium) [eth0-link] filtered by subsystem <debug> [1437398788.614516] (Linktop) [eth0-link] filtered by subsystem <debug> [1437398788.614517] (Longcheer) [eth0-link] filtered by subsystem <debug> [1437398788.614519] (Ericsson MBM) [eth0-link] filtered by udev tags <debug> [1437398788.614521] (Motorola) [eth0-link] filtered by subsystem <debug> [1437398788.614523] (MTK) [eth0-link] filtered by subsystem <debug> [1437398788.614525] (Nokia (Icera)) [eth0-link] filtered by vendor/product IDs <debug> [1437398788.614526] (Nokia) [eth0-link] filtered by subsystem <debug> [1437398788.614528] (Novatel LTE) [eth0-link] filtered by vendor/product IDs <debug> [1437398788.614530] (Novatel) [eth0-link] filtered by vendor/product IDs <debug> [1437398788.614532] (Option) [eth0-link] filtered by subsystem <debug> [1437398788.614533] (Pantech) [eth0-link] filtered by vendor/product IDs <debug> [1437398788.614535] (Samsung) [eth0-link] filtered by vendor/product IDs <debug> [1437398788.614537] (Sierra) [eth0-link] filtered by drivers <debug> [1437398788.614539] (SimTech) [eth0-link] filtered by subsystem <debug> [1437398788.614540] (Telit) [eth0-link] filtered by subsystem <debug> [1437398788.614542] (Via CBP7) [eth0-link] filtered by subsystem <debug> [1437398788.614543] (Wavecom) [eth0-link] filtered by subsystem <debug> [1437398788.614545] (X22X) [eth0-link] filtered by vendor/product IDs <debug> [1437398788.614547] (ZTE) [eth0-link] filtered by vendor/product IDs <debug> [1437398788.614549] (Plugin Manager) [eth0-link] Found '1' plugins to try... <debug> [1437398788.614550] (Plugin Manager) [eth0-link] Will try with plugin 'Generic' <debug> [1437398788.614554] (Generic) [eth0-link] probing deferred until result suggested <debug> [1437398788.614612] (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0] Checking device support... <debug> [1437398788.614632] (Altair LTE) [wlan0] filtered by vendor/product IDs <debug> [1437398788.614635] (AnyDATA) [wlan0] filtered by vendor/product IDs <debug> [1437398788.614636] (Cinterion) [wlan0] filtered by vendor/product IDs <debug> [1437398788.614638] (Gobi) [wlan0] filtered by drivers <debug> [1437398788.614640] (Option High-Speed) [wlan0] filtered by drivers <debug> [1437398788.614641] (Huawei) [wlan0] filtered by vendor/product IDs <debug> [1437398788.614643] (Iridium) [wlan0] filtered by subsystem <debug> [1437398788.614644] (Linktop) [wlan0] filtered by subsystem <debug> [1437398788.614646] (Longcheer) [wlan0] filtered by subsystem <debug> [1437398788.614648] (Ericsson MBM) [wlan0] filtered by udev tags <debug> [1437398788.614650] (Motorola) [wlan0] filtered by subsystem <debug> [1437398788.614651] (MTK) [wlan0] filtered by subsystem <debug> [1437398788.614653] (Nokia (Icera)) [wlan0] filtered by vendor/product IDs <debug> [1437398788.614655] (Nokia) [wlan0] filtered by subsystem <debug> [1437398788.614656] (Novatel LTE) [wlan0] filtered by vendor/product IDs <debug> [1437398788.614658] (Novatel) [wlan0] filtered by vendor/product IDs <debug> [1437398788.614660] (Option) [wlan0] filtered by subsystem <debug> [1437398788.614661] (Pantech) [wlan0] filtered by vendor/product IDs <debug> [1437398788.614663] (Samsung) [wlan0] filtered by vendor/product IDs <debug> [1437398788.614665] (Sierra) [wlan0] filtered by drivers <debug> [1437398788.614666] (SimTech) [wlan0] filtered by subsystem <debug> [1437398788.614668] (Telit) [wlan0] filtered by subsystem <debug> [1437398788.614669] (Via CBP7) [wlan0] filtered by subsystem <debug> [1437398788.614671] (Wavecom) [wlan0] filtered by subsystem <debug> [1437398788.614673] (X22X) [wlan0] filtered by vendor/product IDs <debug> [1437398788.614674] (ZTE) [wlan0] filtered by vendor/product IDs <debug> [1437398788.614676] (Plugin Manager) [wlan0] Found '1' plugins to try... <debug> [1437398788.614678] (Plugin Manager) [wlan0] Will try with plugin 'Generic' <debug> [1437398788.614681] (Generic) [wlan0] probing deferred until result suggested <debug> [1437398788.614716] (net/eth0): could not get port's parent device <debug> [1437398788.614747] (net/ipsec0): could not get port's parent device <debug> [1437398788.614777] (net/ipsec1): could not get port's parent device <debug> [1437398788.614806] (net/ipsec2): could not get port's parent device <debug> [1437398788.614834] (net/ipsec3): could not get port's parent device <debug> [1437398788.614884] (net/tap0): could not get port's parent device <debug> [1437398788.614913] (net/tap1): could not get port's parent device <debug> [1437398788.614942] (net/tap2): could not get port's parent device <debug> [1437398788.614971] (net/tap3): could not get port's parent device <debug> [1437398788.614999] (net/tap4): could not get port's parent device <debug> [1437398788.615043] (net/tap5): could not get port's parent device <debug> [1437398788.615165] (Altair LTE) [cdc-wdm0] filtered by subsystem <debug> [1437398788.615168] (AnyDATA) [cdc-wdm0] filtered by vendor/product IDs <debug> [1437398788.615170] (Cinterion) [cdc-wdm0] filtered by vendor/product IDs <debug> [1437398788.615171] (Gobi) [cdc-wdm0] filtered by drivers <debug> [1437398788.615182] (Option High-Speed) [cdc-wdm0] filtered by subsystem <debug> [1437398788.615183] (Huawei) [cdc-wdm0] filtered by vendor/product IDs <debug> [1437398788.615184] (Iridium) [cdc-wdm0] filtered by subsystem <debug> [1437398788.615185] (Linktop) [cdc-wdm0] filtered by subsystem <debug> [1437398788.615186] (Longcheer) [cdc-wdm0] filtered by subsystem <debug> [1437398788.615188] (Plugin Manager) [cdc-wdm0] Found '2' plugins to try... <debug> [1437398788.615189] (Plugin Manager) [cdc-wdm0] Will try with plugin 'Ericsson MBM' <debug> [1437398788.615191] (Plugin Manager) [cdc-wdm0] Will try with plugin 'Generic' <debug> [1437398788.615194] (Ericsson MBM) [cdc-wdm0] probe required: 'at' <debug> [1437398788.615196] (usbmisc/cdc-wdm0) launching port probing: 'at' <debug> [1437398788.615284] (Altair LTE) [cdc-wdm2] filtered by subsystem <debug> [1437398788.615286] (AnyDATA) [cdc-wdm2] filtered by vendor/product IDs <debug> [1437398788.615288] (Cinterion) [cdc-wdm2] filtered by vendor/product IDs <debug> [1437398788.615289] (Gobi) [cdc-wdm2] filtered by drivers <debug> [1437398788.615299] (Option High-Speed) [cdc-wdm2] filtered by subsystem <debug> [1437398788.615301] (Huawei) [cdc-wdm2] filtered by vendor/product IDs <debug> [1437398788.615302] (Iridium) [cdc-wdm2] filtered by subsystem <debug> [1437398788.615303] (Linktop) [cdc-wdm2] filtered by subsystem <debug> [1437398788.615304] (Longcheer) [cdc-wdm2] filtered by subsystem <debug> [1437398788.615305] (Plugin Manager) [cdc-wdm2] Found '2' plugins to try... <debug> [1437398788.615306] (Plugin Manager) [cdc-wdm2] Will try with plugin 'Ericsson MBM' <debug> [1437398788.615308] (Plugin Manager) [cdc-wdm2] Will try with plugin 'Generic' <debug> [1437398788.615311] (Ericsson MBM) [cdc-wdm2] probe required: 'mbim' <debug> [1437398788.615312] (usbmisc/cdc-wdm2) launching port probing: 'mbim' <debug> [1437398788.615398] (Altair LTE) [cdc-wdm1] filtered by subsystem <debug> [1437398788.615400] (AnyDATA) [cdc-wdm1] filtered by vendor/product IDs <debug> [1437398788.615402] (Cinterion) [cdc-wdm1] filtered by vendor/product IDs <debug> [1437398788.615403] (Gobi) [cdc-wdm1] filtered by drivers <debug> [1437398788.615404] (Option High-Speed) [cdc-wdm1] filtered by subsystem <debug> [1437398788.615415] (Huawei) [cdc-wdm1] filtered by vendor/product IDs <debug> [1437398788.615416] (Iridium) [cdc-wdm1] filtered by subsystem <debug> [1437398788.615417] (Linktop) [cdc-wdm1] filtered by subsystem <debug> [1437398788.615418] (Longcheer) [cdc-wdm1] filtered by subsystem <debug> [1437398788.615419] (Plugin Manager) [cdc-wdm1] Found '2' plugins to try... <debug> [1437398788.615420] (Plugin Manager) [cdc-wdm1] Will try with plugin 'Ericsson MBM' <debug> [1437398788.615421] (Plugin Manager) [cdc-wdm1] Will try with plugin 'Generic' <debug> [1437398788.615424] (Ericsson MBM) [cdc-wdm1] probe required: 'at' <debug> [1437398788.615426] (usbmisc/cdc-wdm1) launching port probing: 'at' <debug> [1437398788.615435] (Plugin Manager) [wwan0] deferring support check until result suggested <debug> [1437398788.615438] (Plugin Manager) [eth0-link] deferring support check until result suggested <debug> [1437398788.615439] (Plugin Manager) [wlan0] deferring support check until result suggested <debug> [1437398788.615532] (ttyACM0) opening serial port... <debug> [1437398788.618975] (ttyACM0) device open count is 1 (open) <debug> [1437398788.619162] (ttyACM1) opening serial port... <debug> [1437398788.631074] (ttyACM1) device open count is 1 (open) <debug> [1437398788.631520] (ttyACM2) opening serial port... <debug> [1437398788.643051] (ttyACM2) device open count is 1 (open) <debug> [1437398788.643490] (cdc-wdm0) opening serial port... <debug> [1437398788.643530] (cdc-wdm0) device open count is 1 (open) <debug> [1437398788.643540] (usbmisc/cdc-wdm2) probing MBIM... <debug> [1437398788.643793] (cdc-wdm1) opening serial port... <debug> [1437398788.643810] (cdc-wdm1) device open count is 1 (open) <debug> [1437398788.643862] (cdc-wdm0): --> 'AT<CR><LF>' <debug> [1437398788.643873] (cdc-wdm1): --> 'AT<CR><LF>' opening device...[/dev/cdc-wdm2] Read max control message size from descriptors file: 512[/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 88 <<<<<< data = 03:00:00:00:58:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:01:00:00:00:28:00:00:00:0C:00:00:00:1A:00:00:00:1E:00:00:00:2F:00:64:00:65:00:76:00:2F:00:63:00:64:00:63:00:2D:00:77:00:64:00:6D:00:32:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 88 <<<<<< type = command (0x00000003) <<<<<< transaction = 1 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b) <<<<<< cid = 'configuration' (0x00000001) <<<<<< type = 'set' (0x00000001) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 48 >>>>>> data = 03:00:00:80:30:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 48 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 1 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b) >>>>>> cid = 'configuration' (0x00000001) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:02:00:00:00:00:02:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 2 <<<<<< Contents: <<<<<< max_control_transfer = 512 [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 16 >>>>>> data = 01:00:00:80:10:00:00:00:02:00:00:00:00:00:00:00 <debug> [1437398788.644591] (usbmisc/cdc-wdm2) port is MBIM-capable [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 12 <<<<<< data = 02:00:00:00:0C:00:00:00:03:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 12 <<<<<< type = close (0x00000002) <<<<<< transaction = 3 [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 16 >>>>>> data = 02:00:00:80:10:00:00:00:03:00:00:00:00:00:00:00 <debug> [1437398788.644703] (Plugin Manager) (Ericsson MBM) [cdc-wdm2] found best plugin for port <debug> [1437398788.644708] (Plugin Manager) (Ericsson MBM) [cdc-wdm2]: found best plugin for device (/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10) <debug> [1437398788.644717] (Plugin Manager) (Ericsson MBM) [cdc-wdm1] suggested plugin for port <debug> [1437398788.644719] (Plugin Manager) (Ericsson MBM) [cdc-wdm0] suggested plugin for port <debug> [1437398788.644721] (Plugin Manager) (Ericsson MBM) [wwan0] deferred task completed, got suggested plugin <debug> [1437398788.644724] (Plugin Manager) (Ericsson MBM) [ttyACM2] suggested plugin for port <debug> [1437398788.644726] (Plugin Manager) (Ericsson MBM) [ttyACM1] suggested plugin for port <debug> [1437398788.644728] (Plugin Manager) (Ericsson MBM) [ttyACM0] suggested plugin for port <debug> [1437398788.644733] (Plugin Manager) 'cdc-wdm2' port probe finished, still 6 running probes in this device (cdc-wdm1, cdc-wdm0, wwan0, ttyACM2, ttyACM1, ttyACM0) <debug> [1437398788.644746] (Ericsson MBM) [wwan0] probing deferred until result suggested <debug> [1437398788.644752] (Plugin Manager) (Ericsson MBM) [wwan0] task completed, got suggested plugin <debug> [1437398788.644756] (Plugin Manager) 'wwan0' port probe finished, still 5 running probes in this device (cdc-wdm1, cdc-wdm0, ttyACM2, ttyACM1, ttyACM0) <debug> [1437398788.720367] (ttyACM0): --> 'AT<CR>' <debug> [1437398788.796928] (ttyACM1): <-- '<CR><LF>*EMRDY: 1<CR><LF>' <debug> [1437398788.798077] (ttyACM2): <-- '<CR><LF>*EMRDY: 1<CR><LF>' <debug> [1437398788.920408] (ttyACM0): <-- 'A<CR><LF>*EMRDY: 1<CR><LF>T' <debug> [1437398788.920443] (ttyACM1): --> 'AT<CR>' <debug> [1437398788.920449] (ttyACM2): --> 'AT<CR>' <debug> [1437398788.921022] (ttyACM0): <-- '<CR>' <debug> [1437398788.921924] (ttyACM0): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398788.921971] (tty/ttyACM0) port is AT-capable <debug> [1437398788.921994] (ttyACM0) device open count is 0 (close) <debug> [1437398788.921997] (ttyACM0) closing serial port... <debug> [1437398788.923575] (ttyACM0) serial port closed <debug> [1437398788.923592] (ttyACM0) forced to close port <debug> [1437398788.923639] (Plugin Manager) (Ericsson MBM) [ttyACM0] found best plugin for port <debug> [1437398788.923646] (Plugin Manager) 'ttyACM0' port probe finished, still 4 running probes in this device (cdc-wdm1, cdc-wdm0, ttyACM2, ttyACM1) <debug> [1437398789.120470] (ttyACM1): <-- 'AT' <debug> [1437398789.120499] (ttyACM2): <-- 'AT' <debug> [1437398789.121014] (ttyACM1): <-- '<CR>' <debug> [1437398789.121335] (ttyACM2): <-- '<CR>' <debug> [1437398789.121834] (ttyACM1): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398789.121858] (tty/ttyACM1) port is AT-capable <debug> [1437398789.121876] (ttyACM1) device open count is 0 (close) <debug> [1437398789.121880] (ttyACM1) closing serial port... <debug> [1437398789.122986] (ttyACM1) serial port closed <debug> [1437398789.122990] (ttyACM1) forced to close port <debug> [1437398789.123010] (Plugin Manager) (Ericsson MBM) [ttyACM1] found best plugin for port <debug> [1437398789.123014] (Plugin Manager) 'ttyACM1' port probe finished, still 3 running probes in this device (cdc-wdm1, cdc-wdm0, ttyACM2) <debug> [1437398789.123315] (ttyACM2): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398789.123335] (tty/ttyACM2) port is AT-capable <debug> [1437398789.123352] (ttyACM2) device open count is 0 (close) <debug> [1437398789.123357] (ttyACM2) closing serial port... <debug> [1437398789.127830] (ttyACM2) serial port closed <debug> [1437398789.127845] (ttyACM2) forced to close port <debug> [1437398789.127882] (Plugin Manager) (Ericsson MBM) [ttyACM2] found best plugin for port <debug> [1437398789.127887] (Plugin Manager) 'ttyACM2' port probe finished, still 2 running probes in this device (cdc-wdm1, cdc-wdm0) <debug> [1437398791.002800] (Plugin Manager) [/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10] Minimum probing time consumed <debug> [1437398791.002811] (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] Minimum probing time consumed <debug> [1437398791.002815] (Plugin Manager) [eth0-link] deferred task cancelled, no suggested plugin <debug> [1437398791.002819] (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0] Minimum probing time consumed <debug> [1437398791.002821] (Plugin Manager) [wlan0] deferred task cancelled, no suggested plugin <debug> [1437398791.002828] (Plugin Manager) [eth0-link] not supported by any plugin <debug> [1437398791.002834] Fully ignoring port 'net/eth0-link' from now on <debug> [1437398791.002837] (Plugin Manager) 'eth0-link' port probe finished, last one in device <debug> [1437398791.002841] (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] device support check finished in '2.388352' seconds <warn> [1437398791.002870] Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:19.0': not supported by any plugin <debug> [1437398791.002883] (Plugin Manager) [wlan0] not supported by any plugin <debug> [1437398791.002886] Fully ignoring port 'net/wlan0' from now on <debug> [1437398791.002888] (Plugin Manager) 'wlan0' port probe finished, last one in device <debug> [1437398791.002890] (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0] device support check finished in '2.388271' seconds <warn> [1437398791.002893] Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0': not supported by any plugin <debug> [1437398792.001986] Parsing AT got: 'Serial command timed out' <debug> [1437398792.002017] Parsing AT got: 'Serial command timed out' <debug> [1437398792.002036] (cdc-wdm0): --> 'AT<CR><LF>' <debug> [1437398792.002051] (cdc-wdm1): --> 'AT<CR><LF>' <debug> [1437398795.003110] Parsing AT got: 'Serial command timed out' <debug> [1437398795.003130] Parsing AT got: 'Serial command timed out' <debug> [1437398795.003147] (cdc-wdm0): --> 'AT<CR><LF>' <debug> [1437398795.003160] (cdc-wdm1): --> 'AT<CR><LF>' <debug> [1437398798.003196] Parsing AT got: 'Serial command timed out' <debug> [1437398798.003206] (usbmisc/cdc-wdm0) port is not AT-capable <debug> [1437398798.003226] Parsing AT got: 'Serial command timed out' <debug> [1437398798.003228] (usbmisc/cdc-wdm1) port is not AT-capable <debug> [1437398798.003242] (cdc-wdm0) device open count is 0 (close) <debug> [1437398798.003246] (cdc-wdm0) closing serial port... <debug> [1437398798.003284] (cdc-wdm0) serial port closed <debug> [1437398798.003290] (cdc-wdm0) forced to close port <debug> [1437398798.003305] (cdc-wdm1) device open count is 0 (close) <debug> [1437398798.003307] (cdc-wdm1) closing serial port... <debug> [1437398798.003339] (cdc-wdm1) serial port closed <debug> [1437398798.003347] (cdc-wdm1) forced to close port <debug> [1437398798.003371] (Plugin Manager) (Ericsson MBM) [cdc-wdm0] found best plugin for port <debug> [1437398798.003377] (Plugin Manager) 'cdc-wdm0' port probe finished, still 1 running probes in this device (cdc-wdm1) <debug> [1437398798.003383] (Plugin Manager) (Ericsson MBM) [cdc-wdm1] found best plugin for port <debug> [1437398798.003386] (Plugin Manager) 'cdc-wdm1' port probe finished, last one in device <debug> [1437398798.003388] (Plugin Manager) [/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10] device support check finished in '9.390288' seconds <info> [1437398798.003397] Creating modem with plugin 'Ericsson MBM' and '7' ports <debug> [1437398798.003405] MBIM-powered Ericsson modem found... <warn> [1437398798.003920] Could not grab port (usbmisc/cdc-wdm1): 'Cannot add port 'usbmisc/cdc-wdm1', unsupported' <debug> [1437398798.003933] (cdc-wdm2) type 'mbim' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10 <warn> [1437398798.003941] Could not grab port (usbmisc/cdc-wdm0): 'Cannot add port 'usbmisc/cdc-wdm0', unsupported' <debug> [1437398798.003949] (wwan0) type 'net' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10 <debug> [1437398798.003989] (ttyACM2) type 'at' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10 <debug> [1437398798.004032] (ttyACM1) type 'at' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10 <debug> [1437398798.004066] (ttyACM0) type 'at' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb3/3-10 <debug> [1437398798.004073] (/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10) tty/ttyACM1 at (primary) <debug> [1437398798.004076] (/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10) tty/ttyACM2 at (secondary) <debug> [1437398798.004078] (/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10) net/wwan0 data (primary) <debug> [1437398798.004081] (/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10) usb/cdc-wdm2 mbim (primary) <info> [1437398798.004202] Modem for device at '/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10' successfully created opening device...[/dev/cdc-wdm2] Read max control message size from descriptors file: 512[/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 88 <<<<<< data = 03:00:00:00:58:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:01:00:00:00:28:00:00:00:0C:00:00:00:1A:00:00:00:1E:00:00:00:2F:00:64:00:65:00:76:00:2F:00:63:00:64:00:63:00:2D:00:77:00:64:00:6D:00:32:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 88 <<<<<< type = command (0x00000003) <<<<<< transaction = 1 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b) <<<<<< cid = 'configuration' (0x00000001) <<<<<< type = 'set' (0x00000001) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 48 >>>>>> data = 03:00:00:80:30:00:00:00:01:00:00:00:01:00:00:00:00:00:00:00:83:8C:F7:FB:8D:0D:4D:7F:87:1E:D7:1D:BE:FB:B3:9B:01:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 48 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 1 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'proxy-control' (838cf7fb-8d0d-4d7f-871e-d71dbefbb39b) >>>>>> cid = 'configuration' (0x00000001) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 16 <<<<<< data = 01:00:00:00:10:00:00:00:02:00:00:00:00:02:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 16 <<<<<< type = open (0x00000001) <<<<<< transaction = 2 <<<<<< Contents: <<<<<< max_control_transfer = 512 [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 16 >>>>>> data = 01:00:00:80:10:00:00:00:02:00:00:00:00:00:00:00 <debug> [1437398798.004728] (ttyACM1) opening serial port... <debug> [1437398798.007247] (ttyACM1) device open count is 1 (open) <debug> [1437398798.007254] (ttyACM1): running init sequence... <debug> [1437398798.007282] (ttyACM1) device open count is 2 (open) <debug> [1437398798.007292] (ttyACM1) device open count is 3 (open) <debug> [1437398798.007685] loading current capabilities... [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:03:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:01:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 3 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'device-caps' (0x00000001) <<<<<< type = 'query' (0x00000000) <debug> [1437398798.007744] (ttyACM1): --> 'ATE0<CR>' <debug> [1437398798.011975] (ttyACM1): <-- 'AT' <debug> [1437398798.012341] (ttyACM1): <-- 'E0' <debug> [1437398798.012616] (ttyACM1): <-- '<CR>' <debug> [1437398798.013665] (ttyACM1): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398798.013711] (ttyACM1): --> 'ATV1<CR>' [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 240 >>>>>> data = 03:00:00:80:F0:00:00:00:03:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:01:00:00:00:00:00:00:00:C0:00:00:00:01:00:00:00:01:00:00:00:01:00:00:00:02:00:00:00:1F:00:00:80:03:00:00:00:03:00:00:00:01:00:00:00:40:00:00:00:0A:00:00:00:4C:00:00:00:1E:00:00:00:6C:00:00:00:2C:00:00:00:98:00:00:00:28:00:00:00:48:00:53:00:50:00:41:00:2B:00:00:00:33:00:35:00:33:00:39:00:39:00:37:00:30:00:35:00:30:00:33:00:32:00:30:00:30:00:31:00:39:00:00:00:43:00:58:00:50:00:20:00:39:00:30:00:31:00:20:00:38:00:37:00:30:00:30:00:2F:00:31:00:20:00:2D:00:20:00:52:00:33:00:43:00:31:00:38:00:4B:00:52:00:44:00:20:00:31:00:33:00:31:00:20:00:33:00:30:00:2F:00:31:00:32:00:32:00:20:00:2D:00:20:00:52:00:31:00:41:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 240 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 3 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'device-caps' (0x00000001) <debug> [1437398798.019620] loaded modem capabilities: gsm-umts <debug> [1437398798.019672] Modem allows up to 1 bearers <debug> [1437398798.019675] Creating bearer list (max: 1, max active: 1) <debug> [1437398798.019758] Device ID source '00000bdb0000193e353997050320019CXP9018700/1-R3C18MBIM[0BDB:193E]EricssonMBM' <debug> [1437398798.019761] Device ID '3e4e3d78ec186aa790387e228a2a8fbafaec4a48' [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:04:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:03:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 4 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'radio-state' (0x00000003) <<<<<< type = 'query' (0x00000000) <debug> [1437398798.020563] (ttyACM1): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398798.020611] (ttyACM1): --> 'AT+CMEE=1<CR>' [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 56 >>>>>> data = 03:00:00:80:38:00:00:00:04:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:03:00:00:00:00:00:00:00:08:00:00:00:01:00:00:00:01:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 56 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 4 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'radio-state' (0x00000003) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:05:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 5 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'subscriber-ready-status' (0x00000002) <<<<<< type = 'query' (0x00000000) <debug> [1437398798.035378] (ttyACM1): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398798.035432] (ttyACM1): <-- '<CR><LF>*EMRDY: 1<CR><LF>' <debug> [1437398798.035447] (ttyACM1): --> 'ATX4<CR>' <debug> [1437398798.041296] (ttyACM1): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398798.041344] (ttyACM1): --> 'AT&C1<CR>' <debug> [1437398798.049626] (ttyACM1): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398798.049673] (ttyACM1): --> 'ATE0<CR>' <debug> [1437398798.056317] (ttyACM1): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398798.056357] (ttyACM1) device open count is 2 (close) <debug> [1437398798.056371] (ttyACM1): --> 'AT+CMEE=1<CR>' <debug> [1437398798.068107] (ttyACM1): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398798.068146] (ttyACM1) device open count is 1 (close) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 148 >>>>>> data = 03:00:00:80:94:00:00:00:05:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:64:00:00:00:01:00:00:00:1C:00:00:00:1E:00:00:00:3C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:32:00:36:00:32:00:30:00:32:00:32:00:39:00:31:00:33:00:39:00:38:00:39:00:34:00:34:00:39:00:00:00:38:00:39:00:34:00:39:00:32:00:30:00:32:00:39:00:33:00:30:00:37:00:35:00:35:00:31:00:37:00:36:00:32:00:30:00:38:00:30:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 148 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 5 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'subscriber-ready-status' (0x00000002) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:06:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 6 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'pin' (0x00000004) <<<<<< type = 'query' (0x00000000) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 60 >>>>>> data = 03:00:00:80:3C:00:00:00:06:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:0C:00:00:00:00:00:00:00:00:00:00:00:FF:FF:FF:FF [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 60 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 6 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'pin' (0x00000004) <debug> [1437398798.203206] SIM is ready, and no need for the after SIM unlock step... [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:07:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 7 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'subscriber-ready-status' (0x00000002) <<<<<< type = 'query' (0x00000000) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 148 >>>>>> data = 03:00:00:80:94:00:00:00:07:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:64:00:00:00:01:00:00:00:1C:00:00:00:1E:00:00:00:3C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:32:00:36:00:32:00:30:00:32:00:32:00:39:00:31:00:33:00:39:00:38:00:39:00:34:00:34:00:39:00:00:00:38:00:39:00:34:00:39:00:32:00:30:00:32:00:39:00:33:00:30:00:37:00:35:00:35:00:31:00:37:00:36:00:32:00:30:00:38:00:30:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 148 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 7 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'subscriber-ready-status' (0x00000002) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:08:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 8 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'subscriber-ready-status' (0x00000002) <<<<<< type = 'query' (0x00000000) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 148 >>>>>> data = 03:00:00:80:94:00:00:00:08:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:64:00:00:00:01:00:00:00:1C:00:00:00:1E:00:00:00:3C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:32:00:36:00:32:00:30:00:32:00:32:00:39:00:31:00:33:00:39:00:38:00:39:00:34:00:34:00:39:00:00:00:38:00:39:00:34:00:39:00:32:00:30:00:32:00:39:00:33:00:30:00:37:00:35:00:35:00:31:00:37:00:36:00:32:00:30:00:38:00:30:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 148 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 8 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'subscriber-ready-status' (0x00000002) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:09:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 9 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'home-provider' (0x00000006) <<<<<< type = 'query' (0x00000000) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 116 >>>>>> data = 03:00:00:80:74:00:00:00:09:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:00:00:00:00:44:00:00:00:20:00:00:00:0A:00:00:00:01:00:00:00:2C:00:00:00:16:00:00:00:01:00:00:00:00:00:00:00:00:00:00:00:32:00:36:00:32:00:30:00:32:00:00:00:56:00:6F:00:64:00:61:00:66:00:6F:00:6E:00:65:00:2E:00:64:00:65:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 116 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 9 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'home-provider' (0x00000006) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:0A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 10 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'home-provider' (0x00000006) <<<<<< type = 'query' (0x00000000) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 116 >>>>>> data = 03:00:00:80:74:00:00:00:0A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:06:00:00:00:00:00:00:00:44:00:00:00:20:00:00:00:0A:00:00:00:01:00:00:00:2C:00:00:00:16:00:00:00:01:00:00:00:00:00:00:00:00:00:00:00:32:00:36:00:32:00:30:00:32:00:00:00:56:00:6F:00:64:00:61:00:66:00:6F:00:6E:00:65:00:2E:00:64:00:65:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 116 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 10 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'home-provider' (0x00000006) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:0B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 11 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'subscriber-ready-status' (0x00000002) <<<<<< type = 'query' (0x00000000) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 148 >>>>>> data = 03:00:00:80:94:00:00:00:0B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:00:00:00:00:64:00:00:00:01:00:00:00:1C:00:00:00:1E:00:00:00:3C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:32:00:36:00:32:00:30:00:32:00:32:00:39:00:31:00:33:00:39:00:38:00:39:00:34:00:34:00:39:00:00:00:38:00:39:00:34:00:39:00:32:00:30:00:32:00:39:00:33:00:30:00:37:00:35:00:35:00:31:00:37:00:36:00:32:00:30:00:38:00:30:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 148 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 11 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'subscriber-ready-status' (0x00000002) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:0C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 12 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'pin-list' (0x00000005) <<<<<< type = 'query' (0x00000000) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 208 >>>>>> data = 03:00:00:80:D0:00:00:00:0C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:00:00:00:00:A0:00:00:00:01:00:00:00:01:00:00:00:04:00:00:00:08:00:00:00:00:00:00:00:00:00:00:00:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:FF:FF:FF:FF:FF:FF:FF:FF:02:00:00:00:01:00:00:00:08:00:00:00:10:00:00:00:00:00:00:00:00:00:00:00:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:FF:FF:FF:FF:FF:FF:FF:FF:00:00:00:00:00:00:00:00:FF:FF:FF:FF:FF:FF:FF:FF [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 208 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 12 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'pin-list' (0x00000005) <debug> [1437398798.291437] (ttyACM1) device open count is 2 (open) <debug> [1437398798.291455] (ttyACM1): --> 'AT+CUSD=?<CR>' <debug> [1437398798.303100] (ttyACM1): <-- '<CR><LF>+CUSD: (0-2)<CR><LF>' <debug> [1437398798.303700] (ttyACM1): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398798.303743] (ttyACM1) device open count is 1 (close) <debug> [1437398798.303872] Messaging capabilities supported <debug> [1437398798.303894] Supported storages loaded: <debug> [1437398798.303896] mem1 (list/read/delete) storages: 'mt' <debug> [1437398798.303898] mem2 (write/send) storages: 'mt' <debug> [1437398798.303899] mem3 (reception) storages: 'mt' <debug> [1437398798.303955] Couldn't initialize interface: 'Time not supported' <debug> [1437398798.304014] Couldn't initialize interface: 'Extended Signal information not supported' <debug> [1437398798.304079] Couldn't initialize interface: 'OMA not supported' <debug> [1437398798.304112] Couldn't initialize interface: 'Firmware not supported' <info> [1437398798.304158] Modem: state changed (unknown -> disabled) <debug> [1437398798.304196] (ttyACM1) device open count is 0 (close) <debug> [1437398798.304200] (ttyACM1) closing serial port... <debug> [1437398798.305155] (ttyACM1) serial port closed <debug> [1437398798.305164] modem properly initialized <debug> [1437398798.305423] Exported modem '/sys/devices/pci0000:00/0000:00:14.0/usb3/3-10' at path '/org/freedesktop/ModemManager1/Modem/0' <debug> [1437398798.305427] (/org/freedesktop/ModemManager1/Modem/0): 'Ericsson MBM' modem, VID 0x0BDB PID 0x193E (usb) <info> [1437398798.307508] Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling) <debug> [1437398798.307623] Skipping modem initialization: not required <debug> [1437398798.307626] (ttyACM1) opening serial port... <debug> [1437398798.311058] (ttyACM1) device open count is 1 (open) <debug> [1437398798.311067] (ttyACM1): running init sequence... <debug> [1437398798.311099] (ttyACM2) opening serial port... <debug> [1437398798.322865] (ttyACM2) device open count is 1 (open) <debug> [1437398798.322869] (ttyACM2): running init sequence... <debug> [1437398798.322883] Flashing primary AT port before enabling... <debug> [1437398798.323061] (ttyACM1): --> 'ATE0<CR>' <debug> [1437398798.323080] (ttyACM2): --> 'ATE0<CR>' <debug> [1437398798.327363] (ttyACM2): <-- 'A' <debug> [1437398798.327824] (ttyACM2): <-- 'TE0' <debug> [1437398798.328170] (ttyACM2): <-- '<CR>' <debug> [1437398798.328948] (ttyACM2): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398798.356797] (ttyACM2): <-- '<CR><LF>*EMRDY: 1<CR><LF>' [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:0D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:03:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 13 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'radio-state' (0x00000003) <<<<<< type = 'query' (0x00000000) <debug> [1437398798.433097] (ttyACM1): <-- 'T' <debug> [1437398798.433719] (ttyACM1): <-- 'E0<CR>' [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 56 >>>>>> data = 03:00:00:80:38:00:00:00:0D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:03:00:00:00:00:00:00:00:08:00:00:00:01:00:00:00:01:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 56 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 13 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'radio-state' (0x00000003) <debug> [1437398798.434996] No need to change power state: already in 'on' power state <debug> [1437398798.435016] Modem has 3GPP capabilities, enabling the Modem 3GPP interface... <debug> [1437398798.435026] Supported notifications: signal (yes), registration (no), sms (no), connect (yes), subscriber (yes), packet (yes) <debug> [1437398798.435039] Enabled notifications: signal (yes), registration (no), sms (no), connect (yes), subscriber (yes), packet (yes) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 96 <<<<<< data = 03:00:00:00:60:00:00:00:0E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:13:00:00:00:01:00:00:00:30:00:00:00:01:00:00:00:0C:00:00:00:24:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:0B:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 96 <<<<<< type = command (0x00000003) <<<<<< transaction = 14 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'device-service-subscribe-list' (0x00000013) <<<<<< type = 'set' (0x00000001) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 96 >>>>>> data = 03:00:00:80:60:00:00:00:0E:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:13:00:00:00:00:00:00:00:30:00:00:00:01:00:00:00:0C:00:00:00:24:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:0B:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 96 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 14 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'device-service-subscribe-list' (0x00000013) <debug> [1437398798.435138] Supported notifications: signal (yes), registration (yes), sms (no), connect (yes), subscriber (yes), packet (yes) <debug> [1437398798.435152] Enabled notifications: signal (yes), registration (yes), sms (no), connect (yes), subscriber (yes), packet (yes) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 100 <<<<<< data = 03:00:00:00:64:00:00:00:0F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:13:00:00:00:01:00:00:00:34:00:00:00:01:00:00:00:0C:00:00:00:28:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:0B:00:00:00:09:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 100 <<<<<< type = command (0x00000003) <<<<<< transaction = 15 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'device-service-subscribe-list' (0x00000013) <<<<<< type = 'set' (0x00000001) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 100 >>>>>> data = 03:00:00:80:64:00:00:00:0F:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:13:00:00:00:00:00:00:00:34:00:00:00:01:00:00:00:0C:00:00:00:28:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:0B:00:00:00:09:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 100 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 15 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'device-service-subscribe-list' (0x00000013) <debug> [1437398798.435233] Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no') [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:10:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:09:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 16 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'register-state' (0x00000009) <<<<<< type = 'query' (0x00000000) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 96 >>>>>> data = 03:00:00:80:60:00:00:00:10:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:09:00:00:00:00:00:00:00:30:00:00:00:07:00:00:00:06:00:00:00:01:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:02:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 96 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 16 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'register-state' (0x00000009) <debug> [1437398798.443133] Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface... <debug> [1437398798.443150] (ttyACM1) Setting unsolicited result code handlers <debug> [1437398798.443154] (ttyACM2) Setting unsolicited result code handlers <debug> [1437398798.443169] (ttyACM1) device open count is 2 (open) <debug> [1437398798.450245] (ttyACM1): <-- '<CR><LF>*EMRDY: 1<CR><LF>' <debug> [1437398802.003870] (ttyACM1): --> 'ATV1<CR>' <debug> [1437398802.007967] (ttyACM1): <-- 'ATV1' <debug> [1437398802.008431] (ttyACM1): <-- '<CR>' <debug> [1437398802.009446] (ttyACM1): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398802.009503] (ttyACM1): --> 'AT+CMEE=1<CR>' <debug> [1437398802.018544] (ttyACM1): <-- 'AT+CMEE=1' <debug> [1437398802.019714] (ttyACM1): <-- '<CR>' <debug> [1437398802.023039] (ttyACM1): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398802.023094] (ttyACM1): --> 'ATX4<CR>' <debug> [1437398802.027130] (ttyACM1): <-- 'ATX4' <debug> [1437398802.027619] (ttyACM1): <-- '<CR>' <debug> [1437398802.028596] (ttyACM1): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398802.028652] (ttyACM1): --> 'AT&C1<CR>' <debug> [1437398802.033687] (ttyACM1): <-- 'AT&C1' <debug> [1437398802.034131] (ttyACM1): <-- '<CR>' <debug> [1437398802.035116] (ttyACM1): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398802.035172] (ttyACM1): --> 'AT+CUSD=1<CR>' <debug> [1437398802.044224] (ttyACM1): <-- 'AT+CUSD=1' <debug> [1437398802.044684] (ttyACM1): <-- '<CR>' <debug> [1437398802.046481] (ttyACM1): <-- '<CR><LF>OK<CR><LF>' <debug> [1437398802.046543] (ttyACM1) device open count is 1 (close) <debug> [1437398802.046617] Modem has location capabilities, enabling the Location interface... <debug> [1437398802.046632] Need to enable the following location sources: '3gpp-lac-ci' <debug> [1437398802.046665] Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no') [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:11:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:09:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 17 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'register-state' (0x00000009) <<<<<< type = 'query' (0x00000000) <debug> [1437398802.046774] Modem has messaging capabilities, enabling the Messaging interface... <debug> [1437398802.046823] loading SMS parts... [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 60 <<<<<< data = 03:00:00:00:3C:00:00:00:12:00:00:00:01:00:00:00:00:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:02:00:00:00:00:00:00:00:0C:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 60 <<<<<< type = command (0x00000003) <<<<<< transaction = 18 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'sms' (533fbeeb-14fe-4467-9f90-33a223e56c3f) <<<<<< cid = 'read' (0x00000002) <<<<<< type = 'query' (0x00000000) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 96 >>>>>> data = 03:00:00:80:60:00:00:00:11:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:09:00:00:00:00:00:00:00:30:00:00:00:07:00:00:00:06:00:00:00:01:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:02:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 96 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 17 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'register-state' (0x00000009) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 56 >>>>>> data = 03:00:00:80:38:00:00:00:12:00:00:00:01:00:00:00:00:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:02:00:00:00:00:00:00:00:08:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 56 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 18 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'sms' (533fbeeb-14fe-4467-9f90-33a223e56c3f) >>>>>> cid = 'read' (0x00000002) <debug> [1437398802.067192] Supported notifications: signal (yes), registration (yes), sms (yes), connect (yes), subscriber (yes), packet (yes) <debug> [1437398802.067209] Enabled notifications: signal (yes), registration (yes), sms (yes), connect (yes), subscriber (yes), packet (yes) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 136 <<<<<< data = 03:00:00:00:88:00:00:00:13:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:13:00:00:00:01:00:00:00:58:00:00:00:02:00:00:00:14:00:00:00:28:00:00:00:3C:00:00:00:1C:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:0B:00:00:00:09:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:02:00:00:00:02:00:00:00:05:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 136 <<<<<< type = command (0x00000003) <<<<<< transaction = 19 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'device-service-subscribe-list' (0x00000013) <<<<<< type = 'set' (0x00000001) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 136 >>>>>> data = 03:00:00:80:88:00:00:00:13:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:13:00:00:00:00:00:00:00:58:00:00:00:02:00:00:00:14:00:00:00:28:00:00:00:3C:00:00:00:1C:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:05:00:00:00:0B:00:00:00:09:00:00:00:0C:00:00:00:02:00:00:00:0A:00:00:00:53:3F:BE:EB:14:FE:44:67:9F:90:33:A2:23:E5:6C:3F:02:00:00:00:02:00:00:00:05:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 136 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 19 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'device-service-subscribe-list' (0x00000013) <info> [1437398802.067396] Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> enabled) <debug> [1437398846.496068] Changing PIN <debug> [1437398846.496101] PIN logging enabled [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 88 <<<<<< data = 03:00:00:00:58:00:00:00:14:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:01:00:00:00:28:00:00:00:02:00:00:00:03:00:00:00:18:00:00:00:08:00:00:00:20:00:00:00:08:00:00:00:30:00:38:00:31:00:35:00:31:00:31:00:31:00:31:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 88 <<<<<< type = command (0x00000003) <<<<<< transaction = 20 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'pin' (0x00000004) <<<<<< type = 'set' (0x00000001) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 60 >>>>>> data = 03:00:00:80:3C:00:00:00:14:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:02:00:00:00:0C:00:00:00:02:00:00:00:01:00:00:00:02:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 60 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 20 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'Failure' (0x00000002) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'pin' (0x00000004) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:15:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 21 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'pin' (0x00000004) <<<<<< type = 'query' (0x00000000) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 60 >>>>>> data = 03:00:00:80:3C:00:00:00:15:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:0C:00:00:00:00:00:00:00:00:00:00:00:FF:FF:FF:FF [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 60 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 21 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'pin' (0x00000004) <debug> [1437398846.603196] SIM is ready, and no need for the after SIM unlock step... <debug> [1437398851.241263] Changing PIN <debug> [1437398851.241288] PIN logging enabled [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 88 <<<<<< data = 03:00:00:00:58:00:00:00:16:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:01:00:00:00:28:00:00:00:02:00:00:00:03:00:00:00:18:00:00:00:08:00:00:00:20:00:00:00:08:00:00:00:30:00:38:00:31:00:35:00:31:00:31:00:31:00:31:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 88 <<<<<< type = command (0x00000003) <<<<<< transaction = 22 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'pin' (0x00000004) <<<<<< type = 'set' (0x00000001) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 60 >>>>>> data = 03:00:00:80:3C:00:00:00:16:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:02:00:00:00:0C:00:00:00:02:00:00:00:01:00:00:00:01:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 60 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 22 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'Failure' (0x00000002) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'pin' (0x00000004) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:17:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 23 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'pin' (0x00000004) <<<<<< type = 'query' (0x00000000) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 60 >>>>>> data = 03:00:00:80:3C:00:00:00:17:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:0C:00:00:00:00:00:00:00:00:00:00:00:FF:FF:FF:FF [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 60 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 23 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'pin' (0x00000004) <debug> [1437398851.347240] SIM is ready, and no need for the after SIM unlock step... <debug> [1437398852.458626] Changing PIN <debug> [1437398852.458650] PIN logging enabled [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 88 <<<<<< data = 03:00:00:00:58:00:00:00:18:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:01:00:00:00:28:00:00:00:02:00:00:00:03:00:00:00:18:00:00:00:08:00:00:00:20:00:00:00:08:00:00:00:30:00:38:00:31:00:35:00:31:00:31:00:31:00:31:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 88 <<<<<< type = command (0x00000003) <<<<<< transaction = 24 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'pin' (0x00000004) <<<<<< type = 'set' (0x00000001) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 144 >>>>>> data = 07:00:00:80:90:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:02:00:00:00:64:00:00:00:06:00:00:00:1C:00:00:00:1E:00:00:00:3C:00:00:00:28:00:00:00:00:00:00:00:00:00:00:00:32:00:36:00:32:00:30:00:32:00:32:00:39:00:31:00:33:00:39:00:38:00:39:00:34:00:34:00:39:00:00:00:38:00:39:00:34:00:39:00:32:00:30:00:32:00:39:00:33:00:30:00:37:00:35:00:35:00:31:00:37:00:36:00:32:00:30:00:38:00:30:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 144 >>>>>> type = indicate-status (0x80000007) >>>>>> transaction = 0 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'subscriber-ready-status' (0x00000002) <debug> [1437398852.523394] Received notification (service 'basic-connect', command 'subscriber-ready-status') [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 60 >>>>>> data = 03:00:00:80:3C:00:00:00:18:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:02:00:00:00:0C:00:00:00:02:00:00:00:01:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 60 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 24 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'Failure' (0x00000002) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'pin' (0x00000004) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:19:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 25 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'pin' (0x00000004) <<<<<< type = 'query' (0x00000000) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 60 >>>>>> data = 03:00:00:80:3C:00:00:00:19:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:0C:00:00:00:0B:00:00:00:01:00:00:00:0A:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 60 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 25 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'pin' (0x00000004) <debug> [1437398852.595271] SIM is ready, and no need for the after SIM unlock step... <debug> [1437398853.420732] Changing PIN <debug> [1437398853.420756] PIN logging enabled [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 88 <<<<<< data = 03:00:00:00:58:00:00:00:1A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:01:00:00:00:28:00:00:00:02:00:00:00:03:00:00:00:18:00:00:00:08:00:00:00:20:00:00:00:08:00:00:00:30:00:38:00:31:00:35:00:31:00:31:00:31:00:31:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 88 <<<<<< type = command (0x00000003) <<<<<< transaction = 26 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'pin' (0x00000004) <<<<<< type = 'set' (0x00000001) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 60 >>>>>> data = 03:00:00:80:3C:00:00:00:1A:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:05:00:00:00:0C:00:00:00:0B:00:00:00:01:00:00:00:0A:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 60 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 26 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'PinRequired' (0x00000005) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'pin' (0x00000004) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:1B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 27 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'pin' (0x00000004) <<<<<< type = 'query' (0x00000000) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 60 >>>>>> data = 03:00:00:80:3C:00:00:00:1B:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:0C:00:00:00:0B:00:00:00:01:00:00:00:0A:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 60 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 27 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'pin' (0x00000004) <debug> [1437398853.483252] SIM is ready, and no need for the after SIM unlock step... <debug> [1437398894.570907] Sending PUK... <debug> [1437398894.570929] PIN logging enabled [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 88 <<<<<< data = 03:00:00:00:58:00:00:00:1C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:01:00:00:00:28:00:00:00:0B:00:00:00:00:00:00:00:18:00:00:00:08:00:00:00:20:00:00:00:08:00:00:00:31:00:32:00:33:00:34:00:31:00:32:00:33:00:34:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 88 <<<<<< type = command (0x00000003) <<<<<< transaction = 28 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'pin' (0x00000004) <<<<<< type = 'set' (0x00000001) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 60 >>>>>> data = 03:00:00:80:3C:00:00:00:1C:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:02:00:00:00:0C:00:00:00:0B:00:00:00:01:00:00:00:09:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 60 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 28 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'Failure' (0x00000002) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'pin' (0x00000004) [/dev/cdc-wdm2] Sent message... <<<<<< RAW: <<<<<< length = 48 <<<<<< data = 03:00:00:00:30:00:00:00:1D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Sent message (translated)... <<<<<< Header: <<<<<< length = 48 <<<<<< type = command (0x00000003) <<<<<< transaction = 29 <<<<<< Fragment header: <<<<<< total = 1 <<<<<< current = 0 <<<<<< Contents: <<<<<< service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) <<<<<< cid = 'pin' (0x00000004) <<<<<< type = 'query' (0x00000000) [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 60 >>>>>> data = 03:00:00:80:3C:00:00:00:1D:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:04:00:00:00:00:00:00:00:0C:00:00:00:0B:00:00:00:01:00:00:00:09:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 60 >>>>>> type = command-done (0x80000003) >>>>>> transaction = 29 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> status error = 'None' (0x00000000) >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'pin' (0x00000004) <debug> [1437398894.659277] SIM is ready, and no need for the after SIM unlock step... [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 92 >>>>>> data = 07:00:00:80:5C:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:09:00:00:00:30:00:00:00:07:00:00:00:06:00:00:00:01:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:02:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 92 >>>>>> type = indicate-status (0x80000007) >>>>>> transaction = 0 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'register-state' (0x00000009) <debug> [1437398936.907859] Received notification (service 'basic-connect', command 'register-state') [/dev/cdc-wdm2] Received message... >>>>>> RAW: >>>>>> length = 72 >>>>>> data = 07:00:00:80:48:00:00:00:00:00:00:00:01:00:00:00:00:00:00:00:A2:89:CC:33:BC:BB:8B:4F:B6:B0:13:3E:C2:AA:E6:DF:0A:00:00:00:1C:00:00:00:07:00:00:00:04:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00:00 [/dev/cdc-wdm2] Received message (translated)... >>>>>> Header: >>>>>> length = 72 >>>>>> type = indicate-status (0x80000007) >>>>>> transaction = 0 >>>>>> Fragment header: >>>>>> total = 1 >>>>>> current = 0 >>>>>> Contents: >>>>>> service = 'basic-connect' (a289cc33-bcbb-8b4f-b6b0-133ec2aae6df) >>>>>> cid = 'packet-service' (0x0000000a) <debug> [1437398936.915402] Received notification (service 'basic-connect', command 'packet-service') <debug> [1437398936.915412] Packet service state: 'detached', data class: '(null)'
_______________________________________________ ModemManager-devel mailing list ModemManager-devel@lists.freedesktop.org http://lists.freedesktop.org/mailman/listinfo/modemmanager-devel