Google Groups no longer supports new Usenet posts or subscriptions. Historical content remains viewable.
Dismiss

Ericsson wwan, linux 3.8 problemer (cdc_mbim??)

248 views
Skip to first unread message

Geir Haatveit

unread,
Mar 9, 2013, 4:11:37 PM3/9/13
to
Thinkpad x230 med en Ericsson 3g-modem (innebygget). Kjører ArchLinux.

Bruker NetworkManager til å koble opp (modemmanager-0.6.0.0-1,
networkmanager-0.9.8.0-2)

Under linux 3.8 klarer ikke modemet å koble seg opp mot mobilnettet lenger.
Under 3.7 kjører det helt problemfritt.

Utfra logg til NetworkManager og ModemManager ser jeg at den får connect,
men ikke klarer å oppdrive en ip-adresse via DHCP.

Det ser ut som en ny modul, cdc_mbim, er kommet til under 3.8. Forsøkte å black-
liste den, men da ville ikke modemet koble seg opp overhodet.

Linux think 3.8.2-1-ARCH #1 SMP PREEMPT Mon Mar 4 09:06:43 CET 2013 x86_64 GNU/Linux
cdc_ncm 13751 1 cdc_mbim
usbnet 24598 2 cdc_mbim,cdc_ncm

Linux think 3.7.5-2-ck #1 SMP PREEMPT Mon Jan 28 22:51:32 EST 2013 x86_64 GNU/Linux
usbnet 21119 1 cdc_ncm

Kort (lsusb):
Bus 003 Device 002: ID 0bdb:1926 Ericsson Business Mobile Networks BV


Noen som har noen ideer?

--
Geir Haatveit.

Bjørn Mork

unread,
Mar 9, 2013, 4:29:24 PM3/9/13
to
Geir Haatveit <nos...@haatveit.nu> writes:

> Thinkpad x230 med en Ericsson 3g-modem (innebygget). Kjører ArchLinux.
>
> Bruker NetworkManager til å koble opp (modemmanager-0.6.0.0-1,
> networkmanager-0.9.8.0-2)
>
> Under linux 3.8 klarer ikke modemet å koble seg opp mot mobilnettet lenger.
> Under 3.7 kjører det helt problemfritt.
>
> Utfra logg til NetworkManager og ModemManager ser jeg at den får connect,
> men ikke klarer å oppdrive en ip-adresse via DHCP.
>
> Det ser ut som en ny modul, cdc_mbim, er kommet til under 3.8. Forsøkte å black-
> liste den, men da ville ikke modemet koble seg opp overhodet.

Hmm. Det er forventet at den modulen blir lastet, men ting burde virke
like bra uavhengig av dette. Og det burde definitivt ikke være noe
problem å blackliste den.


> Linux think 3.8.2-1-ARCH #1 SMP PREEMPT Mon Mar 4 09:06:43 CET 2013 x86_64 GNU/Linux
> cdc_ncm 13751 1 cdc_mbim
> usbnet 24598 2 cdc_mbim,cdc_ncm
>
> Linux think 3.7.5-2-ck #1 SMP PREEMPT Mon Jan 28 22:51:32 EST 2013 x86_64 GNU/Linux
> usbnet 21119 1 cdc_ncm
>
> Kort (lsusb):
> Bus 003 Device 002: ID 0bdb:1926 Ericsson Business Mobile Networks BV
>
>
> Noen som har noen ideer?


Har ikke egentlig det, men jeg har en smule interesse av å prøve å finne
ut av det der siden jeg har skrevet cdc_mbim sammen med Greg Suarez og
omarbeidet cdc_ncm i den forbindelse. Har jo en uggen følelse av å være
ansvarlig her :)

Er det et F5521gw modem du har? Kan du poste "lsusb -vd 0bdb:1926"? Har
du en ModemManager debug log?

FWIW, så har jeg testet alle endringene vi har gjort med et F5521gw
modem og jeg kan heller ikke i farten skjønne hva som kan ha køddet til
med de symptomene du nevner. Men noe er det tydeligvis.... Kanskje
modemet ditt faktisk har MBIM firmware? Det ville i så fall forklare at
du får trøbbel, tror jeg.



Bjørn
--
Save the Honeywell dps8s

Geir Haatveit

unread,
Mar 9, 2013, 5:25:18 PM3/9/13
to
Snakk om flaks, og å treffe på rett mann! :)

> Er det et F5521gw modem du har? Kan du poste "lsusb -vd 0bdb:1926"? Har
> du en ModemManager debug log?

Den sier H5321 gw Mobile Broadband Device.

Hele lsusb'en: https://dl.dropbox.com/u/302767/lsusb_0bdb-1926.txt

Mislykket oppkobling under 3.8:
ModemManager log: https://dl.dropbox.com/u/302767/modem-manager.txt
NetworkManager: https://dl.dropbox.com/u/302767/networkmanager.txt

> FWIW, så har jeg testet alle endringene vi har gjort med et F5521gw
> modem og jeg kan heller ikke i farten skjønne hva som kan ha køddet til
> med de symptomene du nevner. Men noe er det tydeligvis.... Kanskje
> modemet ditt faktisk har MBIM firmware? Det ville i så fall forklare at
> du får trøbbel, tror jeg.

Det blir for teknisk for meg :(, men jeg har iallefall ikke oppgradert
noe firmware på den (fikk maskinen for en uke siden).

--
Geir Haatveit.

Bjørn Mork

unread,
Mar 9, 2013, 5:58:52 PM3/9/13
to
Ah, right. Det er et halvstørrelse-kort, og høyst sannsynlig vesentlig
nyere.

> Hele lsusb'en: https://dl.dropbox.com/u/302767/lsusb_0bdb-1926.txt
>
> Mislykket oppkobling under 3.8:
> ModemManager log: https://dl.dropbox.com/u/302767/modem-manager.txt
> NetworkManager: https://dl.dropbox.com/u/302767/networkmanager.txt
>
>> FWIW, så har jeg testet alle endringene vi har gjort med et F5521gw
>> modem og jeg kan heller ikke i farten skjønne hva som kan ha køddet til
>> med de symptomene du nevner. Men noe er det tydeligvis.... Kanskje
>> modemet ditt faktisk har MBIM firmware? Det ville i så fall forklare at
>> du får trøbbel, tror jeg.
>
> Det blir for teknisk for meg :(, men jeg har iallefall ikke oppgradert
> noe firmware på den (fikk maskinen for en uke siden).

Jammen har det ikke det gitt:

Interface Descriptor:
bLength 9
bDescriptorType 4
bInterfaceNumber 6
bAlternateSetting 0
bNumEndpoints 1
bInterfaceClass 2 Communications
bInterfaceSubClass 13
bInterfaceProtocol 0
iInterface 11 Ericsson H5321 gw
CDC Header:
bcdCDC 1.20
CDC Union:
bMasterInterface 6
bSlaveInterface 7
CDC Ethernet:
iMacAddress 12 0215E0EC0100
bmEthernetStatistics 0x00000000
wMaxSegmentSize 1514
wNumberMCFilters 0x8040
bNumberPowerFilters 1
UNRECOGNIZED CDC: 06 24 1a 00 01 1b
UNRECOGNIZED CDC: 16 24 16 00 01 0d 52 99 83 03 e6 2a 47 23 b1 ba e9 42 1e 20 1b ca
Endpoint Descriptor:
bLength 7
bDescriptorType 5
bEndpointAddress 0x86 EP 6 IN
bmAttributes 3
Transfer Type Interrupt
Synch Type None
Usage Type Data
wMaxPacketSize 0x0020 1x 32 bytes
bInterval 7
Interface Descriptor:
bLength 9
bDescriptorType 4
bInterfaceNumber 6
bAlternateSetting 1
bNumEndpoints 1
bInterfaceClass 2 Communications
bInterfaceSubClass 14
bInterfaceProtocol 0
iInterface 11 Ericsson H5321 gw
CDC Header:
bcdCDC 1.20
CDC Union:
bMasterInterface 6
bSlaveInterface 7
UNRECOGNIZED CDC: 0c 24 1b 00 01 00 02 10 c0 00 08 1f
Endpoint Descriptor:
bLength 7
bDescriptorType 5
bEndpointAddress 0x86 EP 6 IN
bmAttributes 3
Transfer Type Interrupt
Synch Type None
Usage Type Data
wMaxPacketSize 0x0020 1x 32 bytes
bInterval 7


OK. Det forklarer ihvertfall problemet. Nå må jeg innrømme at jeg ikke
helt hadde tenkt så langt som til hvordan pokker vi skal jobbe oss rundt
det der...

La meg ta bakgrunnen, selv om det er i overkant teknisk:

MBIM er en relativt fersk standard for mobile bredbåndsmodem, som har
tatt av som en kule som følge av at Microsoft mer eller mindre krever
det for Windows 8.

En ganske uggen ting i MBIM er at den definerer en bakoverkompatibilitet
med NCM (MBIM er en slags videreutvikling, og startet som NCM 2.0). Det
gjøres ved at kontroll-interfacet har to alternative innstillinger, én
NCM og én MBIM. Dette ser ut som det du ser ovenfor. Legg merke til at
begge beskriver samme interface (bInterfaceNumber == 6), men
bAlternateSetting er forskjellig. Den første er NCM (bInterfaceSubClass
== 13) og den andre er MBIM (bInterfaceSubClass == 14).

Det er egentlig ganske yucky at samme interface bytter klasse på denne
måten, og i Linux bytter de altså også driver. For å få det til å
spille, måtte vi legge inn kode både i cdc_ncm og cdc_mbim som skjønner
at dette skal brukes som et MBIM interface. Og her ligger bikkja
begravet. Denne koden blir enablet så lenge cdc_mbim driveren er valgt
i configen. Det er altså en compile-time greie, og det hjelper ikke å
blackliste cdc_mbim, som du har funnet ut. cdc_ncm driveren vil
fremdeles "vite" at dette er en MBIM-funksjon som skal overlates til
cdc_mbim.

Nå er jo det fint. Du har en driver og alt burde bare virke. Rent
bortsett fra den lille detaljen at MBIM har en omfattende
management-protokoll, som kommer til å bli implementert i userspace som
en del av ModemManager. Det er bare ikke ferdig på langt nær. Se
http://sigquit.wordpress.com/2013/02/06/an-introduction-to-libmbim/

Dermed får du i praksis ikke brukt cdc_mbim til noe nyttig ennå. Og pga
at cdc_ncm ser at det er et MBIM interface, så får du ikke brukt den
driveren heller.

Arrgh! hvorfor tenkte vi ikke på dette? Vi trenger definitivt en måte å
fortelle cdc_ncm at du faktisk ønsker å bruke den i stedet for cdc_mbim
her. Må gruble over hvordan det skal implementeres.

Inntil videre er alternativene dine
1) å kompilere en kjerne (eller bare cdc_ncm) _uten_ å konfigurere
MBIM-driveren, eller
2) å skrive nok på libmbim til at det kan brukes til oppkobling.

Det første er nok det enkleste...


Bjørn
--
You sound like a real jerk

Geir Haatveit

unread,
Mar 9, 2013, 6:40:29 PM3/9/13
to
In no.it.os.unix.linux.diverse Bjørn Mork <bm...@dod.no> wrote:
> Geir Haatveit <nos...@haatveit.nu> writes:
>> In no.it.os.unix.linux.diverse Bjørn Mork <bm...@dod.no> wrote:
>>> FWIW, så har jeg testet alle endringene vi har gjort med et F5521gw
>>> modem og jeg kan heller ikke i farten skjønne hva som kan ha køddet til
>>> med de symptomene du nevner. Men noe er det tydeligvis.... Kanskje
>>> modemet ditt faktisk har MBIM firmware? Det ville i så fall forklare at
>>> du får trøbbel, tror jeg.
>>
>> Det blir for teknisk for meg :(, men jeg har iallefall ikke oppgradert
>> noe firmware på den (fikk maskinen for en uke siden).
>
> Jammen har det ikke det gitt:
>
> Interface Descriptor:
[snip]
Takk for god og utførlig forklaring. Da har jeg fått sjelero på at det
ikke er noen feilaktige innstillinger, eller en enkel løsning rundt
problemet. Foreløpig tror jeg at jeg går for alternativ 3, å beholde
3.7 inntil videre :-).

--
Geir Haatveit.

Bjørn Mork

unread,
Mar 10, 2013, 7:39:49 AM3/10/13
to
Geir Haatveit <nos...@haatveit.nu> writes:

> Takk for god og utførlig forklaring. Da har jeg fått sjelero på at det
> ikke er noen feilaktige innstillinger, eller en enkel løsning rundt
> problemet. Foreløpig tror jeg at jeg går for alternativ 3, å beholde
> 3.7 inntil videre :-).

Flott hvis det løser problemet for deg. Jeg vet ikke helt hvordan dette
bør fikses. Det beste forslaget jeg har kommet opp med så langt er å
legge til en option til cdc_ncm for å velge om MBIM eller NCM skal ha
førsteprioritet for denne type duppeditter der begge muligheter er til
stede.

Veldig bra at du sendte dette nå. Det er faktisk det første modemet jeg
har sett der dette er implementert. Har selv et par MBIM-modemer, men
de støtter ikke denne NCM bakoverkompatibiliteten.

Jeg er forresten også en smule nysgjerrig på hva slags annen MBIM-
funksjonalitet modemet ditt støtter. Så om du føler for å leke litt en
dag hadde det vært fint om du kunne laste ned libmbim og kjøre følgende
kommandoer (under Linux 3.8 med cdc_mbim naturligvis):

mbimcli -d /dev/cdc-wdm0 --basic-connect-query-device-caps
mbimcli -d /dev/cdc-wdm0 --basic-connect-query-device-services

Jeg undrer på om ikke kanskje Ericsson har implementert ting som DSS og
multipleksing av flere IP-sesjoner. Begge deler er i teorien støttet av
driveren, men en smule vanskelig å få testet ordentlig uten hardware å
teste på...



Bjørn
--
It takes a working disease carrier like you to say that the PLO are
tapping your phone, but just because you read it in the Sun that
doesn't mean it's true

Geir Haatveit

unread,
Mar 10, 2013, 11:51:44 AM3/10/13
to
In no.it.os.unix.linux.diverse Bjørn Mork <bm...@dod.no> wrote:
> Geir Haatveit <nos...@haatveit.nu> writes:
>
>> Takk for god og utførlig forklaring. Da har jeg fått sjelero på at det
>> ikke er noen feilaktige innstillinger, eller en enkel løsning rundt
>> problemet. Foreløpig tror jeg at jeg går for alternativ 3, å beholde
>> 3.7 inntil videre :-).
>
> Flott hvis det løser problemet for deg. Jeg vet ikke helt hvordan dette
> bør fikses. Det beste forslaget jeg har kommet opp med så langt er å
> legge til en option til cdc_ncm for å velge om MBIM eller NCM skal ha
> førsteprioritet for denne type duppeditter der begge muligheter er til
> stede.
>
> Veldig bra at du sendte dette nå. Det er faktisk det første modemet jeg
> har sett der dette er implementert. Har selv et par MBIM-modemer, men
> de støtter ikke denne NCM bakoverkompatibiliteten.
>
> Jeg er forresten også en smule nysgjerrig på hva slags annen MBIM-
> funksjonalitet modemet ditt støtter. Så om du føler for å leke litt en
> dag hadde det vært fint om du kunne laste ned libmbim og kjøre følgende
> kommandoer (under Linux 3.8 med cdc_mbim naturligvis):
>
> mbimcli -d /dev/cdc-wdm0 --basic-connect-query-device-caps
> mbimcli -d /dev/cdc-wdm0 --basic-connect-query-device-services
>
> Jeg undrer på om ikke kanskje Ericsson har implementert ting som DSS og
> multipleksing av flere IP-sesjoner. Begge deler er i teorien støttet av
> driveren, men en smule vanskelig å få testet ordentlig uten hardware å
> teste på...

Her kom det dessverre ut lite nytting info, ser ikke ut til å være noe
liv i andre enden overhodet...:

error: couldn't open the MbimDevice: Transaction timed out

på begge kommandoene. Forsøkte også mot /dev/cdc-wdm1 og wdm2 med samme
resultat.

med "verbose" flagg:

# mbimcli -v -d /dev/cdc-wdm0 --basic-connect-query-device-services
[10 Mar 2013, 16:19:11] [Debug] [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:00:00:00:10:00:00:00:01:00:00:00:00:10:00:00

[10 Mar 2013, 16:19:11] [Debug] [/dev/cdc-wdm0] Sent message
(translated)...
<<<<<< Header:
<<<<<< length = 16
<<<<<< type = open (0x00000001)
<<<<<< transaction = 1
<<<<<< Contents:
<<<<<< max_control_transfer = 4096

error: couldn't open the MbimDevice: Transaction timed out

Jeg fant forøvrig denne:
http://support.lenovo.com/en_US/research/hints-or-tips/detail.page?DocID=HT075544

ang. versjonnr på firmware ift. Win8-support. Ut fra det du forklarte
tidligere forstod jeg at har dette har med MBIM-funksjonaliteten å gjøre.
Modemet mitt rapporterer R3C11 på ATI1 mot /dev/ttyACM0, noe som skulle
tilsi at den har firmware for Win8 installert.

--
Geir Haatveit.

Bjørn Mork

unread,
Mar 10, 2013, 2:31:59 PM3/10/13
to
Geir Haatveit <nos...@haatveit.nu> writes:

> Her kom det dessverre ut lite nytting info, ser ikke ut til å være noe
> liv i andre enden overhodet...:
>
> error: couldn't open the MbimDevice: Transaction timed out
>
> på begge kommandoene. Forsøkte også mot /dev/cdc-wdm1 og wdm2 med samme
> resultat.

Ah, tenkte ikke på at dette modemet også hadde ekte WDM-funksjoner. Du
må bruke den som hører til MBIM-driveren. Det er bare denne som vil gi
noen respons på MBIM. De andre snakker antageligvis AT-kommandoer
kjenner jeg Ericsson rett.

F.eks. hos meg blir det /dev/cdc-wdm0 :

bjorn@nemi:~$ ls -l /sys/bus/usb/drivers/cdc_mbim/*/usbmisc
total 0
drwxr-xr-x 3 root root 0 Mar 10 18:30 cdc-wdm0

Men hos deg er det antageligvis /dev/cdc-wdm1 eller /dev/cdc-wdm2. Litt
rart at de begge skulle gi samme resultat. Kanksje ModemManagers bruk
av AT-kommandoer tuller det til? Eller kanskje det var forsøket på
MBIM-kommandoer mot de AT-snakkende /dev/cdc-wdmX?

Uansett, takk for at du sjekket. Jeg skjønner at jeg får skaffe meg et
slikt modem etter hvert.



Bjørn
--
Man, how can you be so malignant

Geir Haatveit

unread,
Mar 10, 2013, 6:20:22 PM3/10/13
to
In no.it.os.unix.linux.diverse Bjørn Mork <bm...@dod.no> wrote:
> Geir Haatveit <nos...@haatveit.nu> writes:
>
>> Her kom det dessverre ut lite nytting info, ser ikke ut til å være noe
>> liv i andre enden overhodet...:
>>
>> error: couldn't open the MbimDevice: Transaction timed out
>>
>> på begge kommandoene. Forsøkte også mot /dev/cdc-wdm1 og wdm2 med samme
>> resultat.
>
> Ah, tenkte ikke på at dette modemet også hadde ekte WDM-funksjoner. Du
> må bruke den som hører til MBIM-driveren. Det er bare denne som vil gi
> noen respons på MBIM. De andre snakker antageligvis AT-kommandoer
> kjenner jeg Ericsson rett.
>
> F.eks. hos meg blir det /dev/cdc-wdm0 :
>
> bjorn@nemi:~$ ls -l /sys/bus/usb/drivers/cdc_mbim/*/usbmisc
> total 0
> drwxr-xr-x 3 root root 0 Mar 10 18:30 cdc-wdm0

cdc-wdm2 her. Forsøkte på nytt, og stengte for sikkerhets skyld ned både
NetworkManager og ModemManager først. Fikk dog samme resultat.

--
Geir Haatveit.

Torfinn Ingolfsen

unread,
Mar 10, 2013, 10:32:13 PM3/10/13
to
On 03/09/2013 23:58, Bjørn Mork wrote:
>
> Arrgh! hvorfor tenkte vi ikke på dette? Vi trenger definitivt en måte å
> fortelle cdc_ncm at du faktisk ønsker å bruke den i stedet for cdc_mbim
> her. Må gruble over hvordan det skal implementeres.
>

Ofte brukte løsninger i denne sammenhengen er
a) alternativ config i usb-device (som kan velges / endres av bruker ved
hjelp av verktøy i operativsystemet)
b) et "mode switch" program som forteller usb-device at den skal bytte modus
(ofte brukt på dingser som insister på å presentere en usb mass storage
device med drivere for et annet operativsystem enn det du kjører)

men jeg antar at ingen av disse kan brukes?
--
Torfinn Ingolfsen

Bjørn Mork

unread,
Mar 11, 2013, 3:14:40 AM3/11/13
to
Nei, de kan nok ikke det. Det er opp til produsenten å bestemme den
slags. Vi er rimelig låst til at vi må velge én av to altsettinger på
samme USB interface/config. Jeg kjenner ingen andre standarder som
bruker den slags med forskjellige klasser. Forskjellige altsettinger er
vanlig, men klasse-kodene endrer seg ikke så du bruker samme driver før
og etter. USB core koden i Linux er designet rundt dette. Det er opp
til driveren (i entall) å switche til korrekt altsetting. Vi kunne
selvsagt gått løs på dette, men jeg har ingen klar formening om hvordan
vi skulle få til det uten å brekke bakoverkompatibilitet med
eksisterende bruk. Og det er jo fy-fy-fy i Linux.

"mode switch" er en vederstyggelighet som vi heldigvis blir kvitt med
introduksjonen av Windows8. Microsoft har bestemt seg for å kreve MBIM,
og det skal være tilgjengelig fra oppstart av modemet. Det er tillatt å
støtte "mode switch" i _tillegg_ av hensyn til operativsystemer laget
før 2012, men MBIM-funksjonen skal ikke være gjemt bak dette.

Forskjellige konfigurasjoner er en mulighet, og hvis du ser på
lsusboutputen Geir postet så bruker Ericsson mange (4) av dem. Men jeg
sjekket, og MBIM-funksjonen ser identisk ut i alle sammen. De har ingen
konfigurasjon som gir deg kun NCM eller kun MBIM.

Så jeg tror vi må ha en løsning i driveren. Jeg har spyttet ut et forslag:
http://www.mail-archive.com/linu...@vger.kernel.org/msg16580.html
Så får vi se om noen kommer opp med noe 'nuftigere.



Bjørn
--
I can't believe how heretical you are

Bjørn Mork

unread,
Mar 11, 2013, 7:39:09 AM3/11/13
to
Geir Haatveit <nos...@haatveit.nu> writes:

> cdc-wdm2 her. Forsøkte på nytt, og stengte for sikkerhets skyld ned både
> NetworkManager og ModemManager først. Fikk dog samme resultat.

rart. Hvis du gidder, kan vi jo prøve en sterkt forenklet utgave for å
se om det i det hele tatt funker å snakke MBIM med dette modemet.

Kjør f.eks.

hexdump -C /dev/cdc-wdm2

i en xterm, og prøv

perl -e 'print pack("C*", map { hex } @ARGV)' 01 00 00 00 10 00 00 00 01 00 00 00 00 10 00 00 >/dev/cdc-wdm2

samtidig i en annen xterm. Dette er MBIM_OPEN, og du skal da få
MBIM_OPEN_DONE til svar. Pluss sannsynligvis noen ekstra
info-meldinger. Jeg får f.eks:

bjorn@nemi:/tmp$ hexdump -C /dev/cdc-wdm0
00000000 01 00 00 80 10 00 00 00 01 00 00 00 00 00 00 00 |................|
00000010 07 00 00 80 40 00 00 00 00 00 00 00 01 00 00 00 |....@...........|
00000020 00 00 00 00 4b f3 84 76 1e 6a 41 db b1 d8 be d2 |....K..v.jA.....|
00000030 89 c2 5b db 01 00 00 00 14 00 00 00 01 00 00 00 |..[.............|
00000040 f0 00 00 00 02 00 00 00 50 00 00 00 16 00 00 00 |........P.......|

hvor de første 16 bytes er MBIM_OPEN_DONE og bør være identisk hos deg
også. Dersom ikke det virker, så er det noe meget mystisk på gang...

Resten hos meg er MBIM_CID_PHONEBOOK_CONFIGURATION, men det er
modem/firmware-avhengig. "07" først viser at det er en unsolicited
notfication og ikke et svar (som alltid vil starte med "01"). Byte 4-7
av hver melding inneholder lengde i LE slik at du se at den første
meldingen her er 16 bytes (0x00000010) og den neste er 64 bytes
(0x00000040).


Bjørn
--
I don't want to hear about your right wing death squad

Geir Haatveit

unread,
Mar 11, 2013, 12:15:33 PM3/11/13
to
In no.it.os.unix.linux.diverse Bjørn Mork <bm...@dod.no> wrote:
> Geir Haatveit <nos...@haatveit.nu> writes:
>
>> cdc-wdm2 her. Forsøkte på nytt, og stengte for sikkerhets skyld ned både
>> NetworkManager og ModemManager først. Fikk dog samme resultat.
>
> rart. Hvis du gidder, kan vi jo prøve en sterkt forenklet utgave for å
> se om det i det hele tatt funker å snakke MBIM med dette modemet.
>
> Kjør f.eks.
>
> hexdump -C /dev/cdc-wdm2
>
> i en xterm, og prøv
>
> perl -e 'print pack("C*", map { hex } @ARGV)' 01 00 00 00 10 00 00 00 01 00 00 00 00 10 00 00 >/dev/cdc-wdm2

>
> samtidig i en annen xterm. Dette er MBIM_OPEN, og du skal da få
> MBIM_OPEN_DONE til svar. Pluss sannsynligvis noen ekstra
> info-meldinger. Jeg får f.eks:

# hexdump -C /dev/cdc-wdm2
00000000 04 00 00 80 10 00 00 00 01 00 00 00 06 00 00 00 |................|

Ikke helt som forventet, altså....

--
Geir Haatveit.

Bjørn Mork

unread,
Mar 11, 2013, 12:51:07 PM3/11/13
to
Ah, men kjempenyttig! 0x80000004 er MBIM_HOST_ERROR_MSG og det betyr
altså at modemet mener det er noe feil her. Og jeg tror til og med jeg
aner hva det er. Hadde helt glemt det, men dagens libmbim hardkoder
meldingsstørrelsen til 4096 byte. Det er ikke korrekt. Og jeg gjorde
det samme ovenfor.

Har på todo-listen å fikse litt assistanse i driveren for å sjekke
riktig størrelse, men det er ikke gjort ennå (cdc-wdm maintaineren likte
ikke mitt første forslag om en sysfs-fil). Inntil dette er på plass må
libmbim og andre parse descriptorer slik lsusb gjør.

Kikket nærmere på lsusb-outputen din, og dette modemet spør om
forholdsvis små pakker på 512 byte. Det bør vi respektere. Hvis du
istedet prøver

perl -e 'print pack("C*", map { hex } @ARGV)' 01 00 00 00 10 00 00 00 01 00 00 00 00 02 00 00 >/dev/cdc-wdm2

så burde det funke bedre. Spørs om du ikke må endre en hardkodet 4096 i
dagens libmbim for å få mbimcli til å virke.



Bjørn
--
You sound like a real whimpering scumbag

Geir Haatveit

unread,
Mar 11, 2013, 2:41:03 PM3/11/13
to
In no.it.os.unix.linux.diverse Bjørn Mork <bm...@dod.no> wrote:
>
> Kikket nærmere på lsusb-outputen din, og dette modemet spør om
> forholdsvis små pakker på 512 byte. Det bør vi respektere. Hvis du
> istedet prøver
>
> perl -e 'print pack("C*", map { hex } @ARGV)' 01 00 00 00 10 00 00 00 01 00 00 00 00 02 00 00 >/dev/cdc-wdm2

Se der ja!

# hexdump -C /dev/cdc-wdm2
00000000 01 00 00 80 10 00 00 00 01 00 00 00 00 00 00 00 |................|
00000010 07 00 00 80 78 00 00 00 00 00 00 00 01 00 00 00 |....x...........|
00000020 00 00 00 00 a2 89 cc 33 bc bb 8b 4f b6 b0 13 3e |.......3...O...>|
00000030 c2 aa e6 df 09 00 00 00 4c 00 00 00 00 00 00 00 |........L.......|
00000040 03 00 00 00 01 00 00 00 03 00 00 00 01 00 00 00 |................|
00000050 30 00 00 00 0a 00 00 00 3c 00 00 00 10 00 00 00 |0.......<.......|
00000060 00 00 00 00 00 00 00 00 02 00 00 00 32 00 34 00 |............2.4.|
00000070 32 00 30 00 32 00 00 00 4e 00 20 00 4e 00 65 00 |2.0.2...N. .N.e.|
00000080 74 00 43 00 6f 00 6d 00 07 00 00 80 40 00 00 00 |t.C.o.m.....@...|
00000090 00 00 00 00 01 00 00 00 00 00 00 00 a2 89 cc 33 |...............3|
000000a0 bc bb 8b 4f b6 b0 13 3e c2 aa e6 df 0b 00 00 00 |...O...>........|
000000b0 14 00 00 00 63 00 00 00 63 00 00 00 00 00 00 00 |....c...c.......|
000000c0 00 00 00 00 00 00 00 00 07 00 00 80 78 00 00 00 |............x...|
000000d0 00 00 00 00 01 00 00 00 00 00 00 00 a2 89 cc 33 |...............3|
000000e0 bc bb 8b 4f b6 b0 13 3e c2 aa e6 df 09 00 00 00 |...O...>........|
000000f0 4c 00 00 00 00 00 00 00 03 00 00 00 01 00 00 00 |L...............|
00000100 03 00 00 00 01 00 00 00 30 00 00 00 0a 00 00 00 |........0.......|
00000110 3c 00 00 00 10 00 00 00 00 00 00 00 00 00 00 00 |<...............|
00000120 02 00 00 00 32 00 34 00 32 00 30 00 32 00 00 00 |....2.4.2.0.2...|
00000130 4e 00 20 00 4e 00 65 00 74 00 43 00 6f 00 6d 00 |N. .N.e.t.C.o.m.|
00000140 07 00 00 80 48 00 00 00 00 00 00 00 01 00 00 00 |....H...........|
00000150 00 00 00 00 a2 89 cc 33 bc bb 8b 4f b6 b0 13 3e |.......3...O...>|
00000160 c2 aa e6 df 0a 00 00 00 1c 00 00 00 00 00 00 00 |................|
00000170 04 00 00 00 00 00 00 00 00 00 00 00 00 00 00 00 |................|


> så burde det funke bedre. Spørs om du ikke må endre en hardkodet 4096 i
> dagens libmbim for å få mbimcli til å virke.

Hehe, det overgår nok min kompetanse betydelig :-).

--
Geir Haatveit.

Bjørn Mork

unread,
Mar 11, 2013, 5:44:20 PM3/11/13
to
Just. Da har vi ihvertfall funnet ut hvor feilen lå. Det er jo noe.

Nå vet jeg ikke om jeg kommer noen vei med å prøve å få driveren til å
eksportere denne verdien. Sist når jeg implementerte det som en sysfs
attributt fikk jeg beskjed om å bruke ioctl, og nå når jeg implementerte
det som en ioctl fikk jeg beskjed om å bruke sysfs ;)

Vi får se... Jeg forstår forsåvidt innvendingene. Man skal tenke seg om
en gang eller førti før man definerer et nytt API for userspace.

>> så burde det funke bedre. Spørs om du ikke må endre en hardkodet 4096 i
>> dagens libmbim for å få mbimcli til å virke.
>
> Hehe, det overgår nok min kompetanse betydelig :-).

Tror ikke det er verre enn å endre 4096 => 512 her:

libmbim-glib/mbim-device.c:#define MAX_CONTROL_TRANSFER 4096



Bjørn
--
Bloated Moonie whimpering scumbag

Geir Haatveit

unread,
Mar 11, 2013, 6:06:11 PM3/11/13
to
Og verre var det ikke!

# mbimcli -d /dev/cdc-wdm2 --basic-connect-query-device-caps
[/dev/cdc-wdm2] Device capabilities retrieved:
Device type: 'embedded'
Cellular class: 'gsm'
Voice class: 'embedded'
Sim class: 'removable'
Data class: 'gprs, edge, umts, hsdpa, hsupa, custom'
SMS caps: 'pdu-receive, pdu-send'
Ctrl caps: 'reg-manual, hw-radio-switch'
Max sessions: '1'
Custom data class: 'HSPA+'
Device ID: '359001040736584'
Firmware info: 'CXP 901 8700/1 - R3C18'
Hardware info: 'KRD 131 21/822 - R1A'

# mbimcli -d /dev/cdc-wdm2 --basic-connect-query-device-services
[/dev/cdc-wdm2] Device services retrieved:
Max DSS sessions: '0'
Services: (10)

Service: 'basic-connect'
UUID: [a289cc33-bcbb-8b4f-b6b0-133ec2aae6df]:
DSS payload: 0
Max DSS instances: 0
CIDs: subscriber-ready-status (2),
pin (4),
home-provider (6),
pin-list (5),
preferred-providers (7),
radio-state (3),
device-caps (1),
device-services (16),
device-service-subscriber-list (19),
signal-state (11),
register-state (9),
packet-service (10),
visible-providers (8),
network-idle-hint (21),
connect (12),
ip-configuration (15),
packet-statistics (20),
ip-packet-filters (23)

Service: 'unknown'
UUID: [2c5d4170-9de7-41d0-aabd-a2b2a2d96a41]:
DSS payload: 0
Max DSS instances: 0
CIDs: 83886081, 33554433, 67108869, 67108867, 67108868, 33554434, 16777218, 16777219,
67108870, 67108871

Service: 'unknown'
UUID: [883b7c26-985f-43fa-9804-27d7fb80959c]:
DSS payload: 0
Max DSS instances: 0
CIDs: 1

Service: 'unknown'
UUID: [ed19555d-a6ac-4327-8eb1-fc022e5e2388]:
DSS payload: 0
Max DSS instances: 0
CIDs: 33554448

Service: 'unknown'
UUID: [2ee5c6ba-f57b-4069-91b5-0be17a6b5e09]:
DSS payload: 0
Max DSS instances: 0
CIDs: 1, 2

Service: 'unknown'
UUID: [f463ed8b-d598-4804-8dc7-85b7621b819e]:
DSS payload: 0
Max DSS instances: 0
CIDs: 1

Service: 'ussd'
UUID: [e550a0c8-5e82-479e-82f7-10abf4c3351f]:
DSS payload: 0
Max DSS instances: 0
CIDs: ussd (1)

Service: 'sms'
UUID: [533fbeeb-14fe-4467-9f90-33a223e56c3f]:
DSS payload: 0
Max DSS instances: 0
CIDs: configuration (1),
read (2),
send (3),
delete (4),
message-store-status (5)

Service: 'auth'
UUID: [1d2b5ff7-0aa1-48b2-aa52-50f15767174e]:
DSS payload: 0
Max DSS instances: 0
CIDs: aka (1),
sim (3)

Service: 'stk'
UUID: [d8f20131-fcb5-4e17-8602-d6ed3816164c]:
DSS payload: 0
Max DSS instances: 0
CIDs: pac (1),
terminal-response (2),
envelope (3)

--
Geir Haatveit.

Bjørn Mork

unread,
Mar 11, 2013, 6:41:52 PM3/11/13
to
Geir Haatveit <nos...@haatveit.nu> writes:
> In no.it.os.unix.linux.diverse Bjørn Mork <bm...@dod.no> wrote:
>
>> Tror ikke det er verre enn å endre 4096 => 512 her:
>>
>> libmbim-glib/mbim-device.c:#define MAX_CONTROL_TRANSFER 4096
>
> Og verre var det ikke!

Flott! Takk

> # mbimcli -d /dev/cdc-wdm2 --basic-connect-query-device-caps
> [/dev/cdc-wdm2] Device capabilities retrieved:
> Device type: 'embedded'
> Cellular class: 'gsm'
> Voice class: 'embedded'
> Sim class: 'removable'
> Data class: 'gprs, edge, umts, hsdpa, hsupa, custom'
> SMS caps: 'pdu-receive, pdu-send'
> Ctrl caps: 'reg-manual, hw-radio-switch'
> Max sessions: '1'
> Custom data class: 'HSPA+'
> Device ID: '359001040736584'
> Firmware info: 'CXP 901 8700/1 - R3C18'
> Hardware info: 'KRD 131 21/822 - R1A'

OK, Max sessions er 1, så ingen samtidige IP-sesjoner her.

>
> # mbimcli -d /dev/cdc-wdm2 --basic-connect-query-device-services
> [/dev/cdc-wdm2] Device services retrieved:
> Max DSS sessions: '0'

Og heller ingen DSS sesjoner. Æsj, skuffende. Vel, da har vi en sjanse
til å implementere userspace støtte først :) Per i dag er det litt
kronglete å bruke, med vlan med magiske IDer, socat og mere til.


> Services: (10)
>
> Service: 'basic-connect'
> UUID: [a289cc33-bcbb-8b4f-b6b0-133ec2aae6df]:
> DSS payload: 0
> Max DSS instances: 0
> CIDs: subscriber-ready-status (2),
> pin (4),
> home-provider (6),
> pin-list (5),
> preferred-providers (7),
> radio-state (3),
> device-caps (1),
> device-services (16),
> device-service-subscriber-list (19),
> signal-state (11),
> register-state (9),
> packet-service (10),
> visible-providers (8),
> network-idle-hint (21),
> connect (12),
> ip-configuration (15),
> packet-statistics (20),
> ip-packet-filters (23)
>

Standard tjeneste.

> Service: 'unknown'
> UUID: [2c5d4170-9de7-41d0-aabd-a2b2a2d96a41]:
> DSS payload: 0
> Max DSS instances: 0
> CIDs: 83886081, 33554433, 67108869, 67108867, 67108868, 33554434, 16777218, 16777219,
> 67108870, 67108871

Hmm, de CID-verdiene så litt rare ut, men det er jo ingenting i veien
for den slags. De gir litt mer mening i hex. Jeg aner ikke hva slags
service dette er. Har aldri sett den før.


>
> Service: 'unknown'
> UUID: [883b7c26-985f-43fa-9804-27d7fb80959c]:
> DSS payload: 0
> Max DSS instances: 0
> CIDs: 1

Dette er Microsofts "HOST Shutdown Device Service". Ikke veldig nyttig,
men Microsoft er ihvertfall så greie at de dokumenterer slikt.


> Service: 'unknown'
> UUID: [ed19555d-a6ac-4327-8eb1-fc022e5e2388]:
> DSS payload: 0
> Max DSS instances: 0
> CIDs: 33554448

Aner ikke hva den er heller. Samme morsomme måte å sette sammen CID.

> Service: 'unknown'
> UUID: [2ee5c6ba-f57b-4069-91b5-0be17a6b5e09]:
> DSS payload: 0
> Max DSS instances: 0
> CIDs: 1, 2

Aner heller ikke.

> Service: 'unknown'
> UUID: [f463ed8b-d598-4804-8dc7-85b7621b819e]:
> DSS payload: 0
> Max DSS instances: 0
> CIDs: 1

Og heller ikke den. Skulle veldig gjere hatt det "MBIM Registry"et som
standarden refererer til, men som tydeligvis aldri har blitt opprettet.
Slik det er nå så er det få muligheter til å finne ut av hva disse
vendor-spesifikke tingene er hvis ikke den som har laget det
dokumenterer greiene.

Pussig. Jeg hadde forventet Microsofts "Mobile Broadband Device
Firmware Update on Windows 8", men den ser ut til å mangle hvis ikke jeg
blingser.
Flere standard-tjenester. Det meste er vel selvforklarende.



Bjørn
--
Ban text editors

Bjørn Mork

unread,
Mar 14, 2013, 8:15:57 AM3/14/13
to
Geir Haatveit <nos...@haatveit.nu> writes:

> Thinkpad x230 med en Ericsson 3g-modem (innebygget). Kjører ArchLinux.
>
> Bruker NetworkManager til å koble opp (modemmanager-0.6.0.0-1,
> networkmanager-0.9.8.0-2)
>
> Under linux 3.8 klarer ikke modemet å koble seg opp mot mobilnettet lenger.
> Under 3.7 kjører det helt problemfritt.
>
> Utfra logg til NetworkManager og ModemManager ser jeg at den får connect,
> men ikke klarer å oppdrive en ip-adresse via DHCP.

Just FYI, du er ikke alene om problemet:
https://bugzilla.kernel.org/show_bug.cgi?id=54791

Jeg ga opp å vente på bedre forslag, så jeg sendte det opprinnelige
utkastet nokså uendret: http://patchwork.ozlabs.org/patch/227623/
Så får vi se hva davem sier til det.

Om dette går gjennom, så ender du opp med en parameter til cdc_ncm for å
velge om du foretrekker MBIM eller NCM:

bjorn@nemi:/usr/local/src/git/linux$ modinfo -p /usr/local/src/git/linux/drivers/net/usb/cdc_ncm.ko
prefer_mbim:Prefer MBIM setting on dual NCM/MBIM functions (bool)

Default-verdien er som før, dvs at MBIM blir foretrukket dersom driveren
er valgt i .config. Men du kan legge inn noe ala

options cdc_ncm prefer_mbim=N

i en /etc/modprobe.d/foo.conf fil for å overstyre default preferanse.
Evt cdc_ncm.prefer_mbim=N på kommando-linja til kjernen dersom du bygger
driveren inn i kjernen (har ikke verifisert at det funker, men det burde
jo gjøre det).

Parameteren er også skrivbar, så du kan endre dette etter at modulen er
lastet vha

echo N >/sys/module/cdc_ncm/parameters/prefer_mbim

Sammen med kreativ bruk av bind/unbind burde det gi full kontroll med
hvilken av driverene som er i bruk.



Bjørn
--
No nukes

Geir Haatveit

unread,
Mar 14, 2013, 5:25:13 PM3/14/13
to
In no.it.os.unix.linux.diverse Bjørn Mork <bm...@dod.no> wrote:
Det ser helt topp ut!

--
Geir Haatveit.

Bjørn Mork

unread,
Mar 20, 2013, 3:19:01 AM3/20/13
to
On Thu, 14 Mar 2013 13:15:57 +0100, Bjᅵrn Mork<bm...@dod.no> wrote:
> Jeg ga opp ᅵ vente pᅵ bedre forslag, sᅵ jeg sendte det opprinnelige
> utkastet noksᅵ uendret: http://patchwork.ozlabs.org/patch/227623/
> Sᅵ fᅵr vi se hva davem sier til det.


> Om dette gᅵr gjennom, sᅵ ender du opp med en parameter til cdc_ncm
for ᅵ
> velge om du foretrekker MBIM eller NCM:


> bjorn@nemi:/usr/local/src/git/linux$ modinfo -p
/usr/local/src/git/linux/drivers/net/usb/cdc_ncm.ko
> prefer_mbim:Prefer MBIM setting on dual NCM/MBIM functions (bool)


> Default-verdien er som fᅵr, dvs at MBIM blir foretrukket dersom
driveren
> er valgt i .config. Men du kan legge inn noe ala


> options cdc_ncm prefer_mbim=N


> i en /etc/modprobe.d/foo.conf fil for ᅵ overstyre default
preferanse.
> Evt cdc_ncm.prefer_mbim=N pᅵ kommando-linja til kjernen dersom du
bygger
> driveren inn i kjernen (har ikke verifisert at det funker, men det
burde
> jo gjᅵre det).


> Parameteren er ogsᅵ skrivbar, sᅵ du kan endre dette etter at
modulen er
> lastet vha


> echo N >/sys/module/cdc_ncm/parameters/prefer_mbim


> Sammen med kreativ bruk av bind/unbind burde det gi full kontroll
med
> hvilken av driverene som er i bruk.

Da skulle det der vᅵre i boks for 3.9 iaffal:

https://git.kernel.org/cgit/linux/kernel/git/torvalds/linux.git/commit/
?id=1e8bbe6cd02fc300c88bd48244ce61ad9c7d1776


Ligger ogsᅵ i stable kᅵen til davem, sᅵ etter hvert burde det havne i
3.8.x ogsᅵ. Men han vil gjerne at ting koker en stund i mainline
fᅵrst. Sikkert fornuftig.


Bjᅵrn

Geir Haatveit

unread,
Mar 29, 2013, 4:49:41 AM3/29/13
to
In no.it.os.unix.linux.diverse Bjørn Mork <bm...@dod.no> wrote:
> Just FYI, du er ikke alene om problemet:
> https://bugzilla.kernel.org/show_bug.cgi?id=54791
>
> Jeg ga opp å vente på bedre forslag, så jeg sendte det opprinnelige
> utkastet nokså uendret: http://patchwork.ozlabs.org/patch/227623/
> Så får vi se hva davem sier til det.
>
> Om dette går gjennom, så ender du opp med en parameter til cdc_ncm for å
> velge om du foretrekker MBIM eller NCM:
>
> bjorn@nemi:/usr/local/src/git/linux$ modinfo -p /usr/local/src/git/linux/drivers/net/usb/cdc_ncm.ko
> prefer_mbim:Prefer MBIM setting on dual NCM/MBIM functions (bool)
>
> Default-verdien er som før, dvs at MBIM blir foretrukket dersom driveren
> er valgt i .config. Men du kan legge inn noe ala
>
> options cdc_ncm prefer_mbim=N
>
> i en /etc/modprobe.d/foo.conf fil for å overstyre default preferanse.
> Evt cdc_ncm.prefer_mbim=N på kommando-linja til kjernen dersom du bygger
> driveren inn i kjernen (har ikke verifisert at det funker, men det burde
> jo gjøre det).

3.8.5 dukket opp nå, og mobilt bredbånd fungerer helt utmerket igjen med
prefer_mbim=N.

Takk igjen! :)

--
Geir Haatveit.

Bjørn Mork

unread,
Apr 18, 2013, 4:21:14 AM4/18/13
to
Geir Haatveit <nos...@haatveit.nu> writes:

> 3.8.5 dukket opp nå, og mobilt bredbånd fungerer helt utmerket igjen med
> prefer_mbim=N.

Og hvis du har lyst til å eksperimentere litt mer, så har Aleksander
jobbet noe vanvittig de siste to ukene (jeg tror forsåvidt han gjør det
hele tiden, men nå har det vært MBIM han har jobbet med :). Resultatet
er at MBIM nå er inkludert i master-branchen i ModemManager:
https://mail.gnome.org/archives/networkmanager-list/2013-April/msg00165.html

Ericssons MBIM-firmware er foreløpig den eneste kjente implementasjonen
som ikke er testet med dette, siden hverken Aleksander, Dan eller jeg
har tilgang til et slikt modem. Den er også den eneste vi vet om som
har dette NCM/MBIM problemet. Så om du føler for å leke litt...


Bjørn
--
You're a typical underpriviledged person, totally egocentric

Geir Haatveit

unread,
May 4, 2013, 3:03:24 PM5/4/13
to
In no.it.os.unix.linux.diverse Bjørn Mork <bm...@dod.no> wrote:
Dette var merkelig.

Jeg installerte libmbim, og fjernet prefer_mbim=N. Nå kommer ikke mobil
bredbånd opp som alternativt i det hele tatt i NetworkManager.

Modemet blir funnet iflg. dmesg:
[ 14.100067] cdc_mbim 1-4:1.6: cdc-wdm2: USB WDM device
[ 14.100153] cdc_mbim 1-4:1.6 wwan0: register 'cdc_mbim' at usb-0000:00:14.0-4, CDC MBIM, 2a:0a:ef:0c:da:2a
[ 14.100170] usbcore: registered new interface driver cdc_mbim


networkmanager 0.9.8.0-3
modemmanager 0.7.990-4



--
Geir Haatveit.

Bjørn Mork

unread,
May 4, 2013, 3:27:38 PM5/4/13
to
Merkelig. Og jeg antar at modemmanager også er bygd med libmbim-støtten
enablet? Får du listet modemet hvis du gjør

mmcli -L


Ser du noe åpenbart galt når du kjører

/usr/sbin/ModemManager --debug

Det vil vise deg hva som probes og hvorfor, samt alle MBIM-meldinger på
veien. Mulig du må stanse både networkmanager og modemmanager først for
å få sjansen til å starte modemmanager manuelt.




Bjørn
--
I couldn't care less about your right wing death squad

Geir Haatveit

unread,
May 4, 2013, 3:28:41 PM5/4/13
to
In no.it.os.unix.linux.diverse Geir Haatveit <nos...@haatveit.nu> wrote:
> Dette var merkelig.
>
> Jeg installerte libmbim, og fjernet prefer_mbim=N. Nå kommer ikke mobil
> bredbånd opp som alternativt i det hele tatt i NetworkManager.
>
> Modemet blir funnet iflg. dmesg:
> [ 14.100067] cdc_mbim 1-4:1.6: cdc-wdm2: USB WDM device
> [ 14.100153] cdc_mbim 1-4:1.6 wwan0: register 'cdc_mbim' at usb-0000:00:14.0-4, CDC MBIM, 2a:0a:ef:0c:da:2a
> [ 14.100170] usbcore: registered new interface driver cdc_mbim
>
>
> networkmanager 0.9.8.0-3
> modemmanager 0.7.990-4

Og det viste seg at mobilt bredbånd heller ikke lenger fungerte med
prefer_mbim=N. Ser ut som modemmanager er blitt oppdatert nylig, og en
nedgradering til v. 0.6.0.0 løste det hele.

Er visst flere som har problemer:
https://bbs.archlinux.org/viewtopic.php?id=162295
https://bugs.launchpad.net/ubuntu/+source/modemmanager/+bug/1174288

Skal sjekke git-versjonen.


--
Geir Haatveit.

Bjørn Mork

unread,
May 4, 2013, 3:51:47 PM5/4/13
to
Den siste av de der ser jo ut som har løst seg? Loggen ser helt fin ut
så langt jeg kan se, og det ser da ut til at Aleksander er enig.

Det eneste som gjenstår er

[mm-plugin.c:900] mm_plugin_create_modem(): Could not grab port (usbmisc/cdc-wdm1): 'Cannot add port 'usbmisc/cdc-wdm1', unsupported'

og det er forventet og ufarlig. Ericsson-modemene har et par slike CDC
WDM porter som støtter AT-kommandoer, men ModemManager bryr seg ikke om
det. Tidligere ville disse blitt helt ignorert, men nå dukker de opp i
listen over porter fordi MM ser etter QMI og MBIM management-porter.

Bare en liten skjønnhetsfeil at de dukker opp som 'unsupported'



Bjørn
--
Ban the war on spammers

Geir Haatveit

unread,
May 4, 2013, 4:04:04 PM5/4/13
to
In no.it.os.unix.linux.diverse Bjørn Mork <bm...@dod.no> wrote:
> Geir Haatveit <nos...@haatveit.nu> writes:
>
>> In no.it.os.unix.linux.diverse Geir Haatveit <nos...@haatveit.nu> wrote:
>>> Dette var merkelig.
>>>
>>> Jeg installerte libmbim, og fjernet prefer_mbim=N. Nå kommer ikke mobil
>>> bredbånd opp som alternativt i det hele tatt i NetworkManager.
>>>
>>> Modemet blir funnet iflg. dmesg:
>>> [ 14.100067] cdc_mbim 1-4:1.6: cdc-wdm2: USB WDM device
>>> [ 14.100153] cdc_mbim 1-4:1.6 wwan0: register 'cdc_mbim' at usb-0000:00:14.0-4, CDC MBIM, 2a:0a:ef:0c:da:2a
>>> [ 14.100170] usbcore: registered new interface driver cdc_mbim
>>>
>>>
>>> networkmanager 0.9.8.0-3
>>> modemmanager 0.7.990-4
>>
>> Og det viste seg at mobilt bredbånd heller ikke lenger fungerte med
>> prefer_mbim=N. Ser ut som modemmanager er blitt oppdatert nylig, og en
>> nedgradering til v. 0.6.0.0 løste det hele.
>>
>> Er visst flere som har problemer:
>> https://bbs.archlinux.org/viewtopic.php?id=162295
>> https://bugs.launchpad.net/ubuntu/+source/modemmanager/+bug/1174288
>
> Den siste av de der ser jo ut som har løst seg? Loggen ser helt fin ut
> så langt jeg kan se, og det ser da ut til at Aleksander er enig.

Forstod det slik at noe er galt med pakken i Arch, og det vil løse
seg ved neste release.
>
> Det eneste som gjenstår er
>
> [mm-plugin.c:900] mm_plugin_create_modem(): Could not grab port (usbmisc/cdc-wdm1): 'Cannot add port 'usbmisc/cdc-wdm1', unsupported'
>
> og det er forventet og ufarlig. Ericsson-modemene har et par slike CDC
> WDM porter som støtter AT-kommandoer, men ModemManager bryr seg ikke om
> det. Tidligere ville disse blitt helt ignorert, men nå dukker de opp i
> listen over porter fordi MM ser etter QMI og MBIM management-porter.
>
> Bare en liten skjønnhetsfeil at de dukker opp som 'unsupported'

Da fikk jeg installert modemmanager fra git, og testet at alt fungerte
med prefer_mbim=N.

Prøvde så uten prefer_mbim=N, men resultatet var dessverre ikke oppløftende:

Feilmeldingen er etter hva jeg kan se "[mm-bearer.c:220] modem_3gpp_registration_state_changed():
Bearer not allowed to connect, not registered in 3GPP network"


NetworkManager log:

NetworkManager[1191]: <info> ModemManager available in the bus
NetworkManager[1191]: <warn> (ttyACM0): failed to look up interface index
NetworkManager[1191]: <info> (ttyACM0): new Broadband device (driver: 'cdc_acm, cdc_mbim, cdc_wdm' ifindex: 0)
NetworkManager[1191]: <info> (ttyACM0): exported as /org/freedesktop/NetworkManager/Devices/3
NetworkManager[1191]: <info> (ttyACM0): device state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
NetworkManager[1191]: <info> (ttyACM0): deactivating device (reason 'managed') [2]
NetworkManager[1191]: <info> (ttyACM0): device state change: unavailable -> disconnected (reason 'none') [20 30 0]
NetworkManager[1191]: <info> Activation (ttyACM0) starting connection 'TELIO mobilt bredbånd'
NetworkManager[1191]: <info> (ttyACM0): device state change: disconnected -> prepare (reason 'none') [30 40 0]
NetworkManager[1191]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[1191]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) started...
NetworkManager[1191]: <info> (ttyACM0): device state change: prepare -> need-auth (reason 'none') [40 60 0]
NetworkManager[1191]: mm_modem_simple_disconnect: assertion `MM_IS_MODEM_SIMPLE (self)' failed
NetworkManager[1191]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[1191]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) scheduled...
NetworkManager[1191]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) started...
NetworkManager[1191]: <info> (ttyACM0): device state change: need-auth -> prepare (reason 'none') [60 40 0]
NetworkManager[1191]: <info> Activation (ttyACM0) Stage 1 of 5 (Device Prepare) complete.
NetworkManager[1191]: <info> (ttyACM0) state changed, 'disabled' --> 'enabling' (reason: user-requested)
NetworkManager[1191]: <info> (ttyACM0) state changed, 'enabling' --> 'registered' (reason: user-requested)
NetworkManager[1191]: <info> WWAN now enabled by management service
NetworkManager[1191]: <info> (ttyACM0) state changed, 'registered' --> 'connecting' (reason: user-requested)
NetworkManager[1191]: <info> (ttyACM0) state changed, 'connecting' --> 'registered' (reason: user-requested)
NetworkManager[1191]: <warn> (ttyACM0) failed to connect modem: No valid data port found to launch connection
NetworkManager[1191]: <info> (ttyACM0): device state change: prepare -> failed (reason 'unknown') [40 120 1]
NetworkManager[1191]: <warn> Activation (ttyACM0) failed for connection 'TELIO mobilt bredbånd'
NetworkManager[1191]: <info> (ttyACM0): device state change: failed -> disconnected (reason 'none') [120 30 0]
NetworkManager[1191]: <info> (ttyACM0): deactivating device (reason 'none') [0]
NetworkManager[1191]: <info> (ttyACM0) state changed, 'registered' --> 'disabling' (reason: user-requested)
NetworkManager[1191]: <info> WWAN now disabled by management service
NetworkManager[1191]: <info> (ttyACM0) state changed, 'disabling' --> 'disabled' (reason: user-requested)
NetworkManager[1191]: <info> (ttyACM0): device state change: disconnected -> unmanaged (reason 'removed') [30 10 36]
NetworkManager[1191]: <info> (ttyACM0): cleaning up...
NetworkManager[1191]: <info> (ttyACM0): taking down device.
NetworkManager[1191]: nm_system_iface_set_up: assertion `ifindex > 0' failed
NetworkManager[1191]: <info> ModemManager disappeared from bus


ModemManager:

<info> [1367697014.173307] [main.c:128] main(): ModemManager (version 0.7.990) starting...
<debug> [1367697014.276163] [main.c:61] bus_acquired_cb(): Bus acquired, creating manager...
<debug> [1367697014.283387] [mm-plugin-manager.c:735] load_plugins(): Looking for plugins in '/usr/lib/ModemManager'
<debug> [1367697014.288061] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Altair LTE'
<debug> [1367697014.292500] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Nokia (Icera)'
<debug> [1367697014.296760] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Novatel LTE'
<debug> [1367697014.301014] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Iridium'
<debug> [1367697014.364784] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Sierra'
<debug> [1367697014.372268] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Via CBP7'
<debug> [1367697014.378662] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Pantech'
<debug> [1367697014.384910] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Option High-Speed'
<debug> [1367697014.388906] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Nokia'
<debug> [1367697014.393230] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Option'
<debug> [1367697014.438179] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Generic'
<debug> [1367697014.498294] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Huawei'
<debug> [1367697014.561555] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Novatel'
<debug> [1367697014.621167] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Longcheer'
<debug> [1367697014.660690] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Cinterion'
<debug> [1367697014.667352] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Gobi'
<debug> [1367697014.671762] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Linktop'
<debug> [1367697014.676265] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Ericsson MBM'
<debug> [1367697014.680855] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'AnyDATA'
<debug> [1367697014.685316] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'X22X'
<debug> [1367697014.692982] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Wavecom'
<debug> [1367697014.700539] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Samsung'
<debug> [1367697014.746891] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'Motorola'
<debug> [1367697014.772900] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'ZTE'
<debug> [1367697014.797459] [mm-plugin-manager.c:760] load_plugins(): Loaded plugin 'SimTech'
<debug> [1367697014.816870] [mm-plugin-manager.c:785] load_plugins(): Successfully loaded 25 plugins
<debug> [1367697014.862147] [main.c:79] name_acquired_cb(): Service name 'org.freedesktop.ModemManager1' was acquired
<debug> [1367697014.883501] [mm-manager.c:450] mm_manager_start(): Starting automatic device scan...
<debug> [1367697014.931163] [mm-manager.c:489] mm_manager_start(): Finished device scan...
<debug> [1367697014.959696] [mm-plugin-manager.c:622] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:14.0/usb1/1-4] Checking device
support...
<debug> [1367697014.976167] [mm-plugin.c:322] apply_pre_probing_filters(): (Altair LTE) [ttyACM0] filtered by vendor/product IDs
<debug> [1367697015.023428] [mm-plugin.c:322] apply_pre_probing_filters(): (Nokia (Icera)) [ttyACM0] filtered by vendor/product IDs
<debug> [1367697015.040052] [mm-plugin.c:322] apply_pre_probing_filters(): (Novatel LTE) [ttyACM0] filtered by vendor/product IDs
<debug> [1367697015.064533] [mm-plugin.c:254] apply_pre_probing_filters(): (Sierra) [ttyACM0] filtered by drivers
<debug> [1367697015.107758] [mm-plugin.c:322] apply_pre_probing_filters(): (Pantech) [ttyACM0] filtered by vendor/product IDs
<debug> [1367697015.148039] [mm-plugin.c:254] apply_pre_probing_filters(): (Option High-Speed) [ttyACM0] filtered by drivers
<debug> [1367697015.194390] [mm-plugin.c:254] apply_pre_probing_filters(): (Option) [ttyACM0] filtered by drivers
<debug> [1367697015.254385] [mm-plugin.c:322] apply_pre_probing_filters(): (Huawei) [ttyACM0] filtered by vendor/product IDs
<debug> [1367697015.300212] [mm-plugin.c:254] apply_pre_probing_filters(): (Novatel) [ttyACM0] filtered by drivers
<debug> [1367697015.318753] [mm-plugin.c:322] apply_pre_probing_filters(): (Longcheer) [ttyACM0] filtered by vendor/product IDs
<debug> [1367697015.322800] [mm-plugin.c:254] apply_pre_probing_filters(): (Gobi) [ttyACM0] filtered by drivers
<debug> [1367697015.326796] [mm-plugin.c:322] apply_pre_probing_filters(): (Linktop) [ttyACM0] filtered by vendor/product IDs
<debug> [1367697015.330798] [mm-plugin-manager.c:523] build_plugins_list(): (Plugin Manager) [ttyACM0] Found '2' plugins to try...
<debug> [1367697015.374636] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [ttyACM0] Will try with plugin 'Ericsson MBM'
<debug> [1367697015.396693] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [ttyACM0] Will try with plugin 'Generic'
<debug> [1367697015.444407] [mm-plugin.c:772] mm_plugin_supports_port(): (Ericsson MBM) [ttyACM0] probe required: 'at'
<debug> [1367697015.462181] [mm-port-probe.c:1331] mm_port_probe_run(): (tty/ttyACM0) launching port probing: 'at'
<debug> [1367697015.507201] [mm-plugin.c:322] apply_pre_probing_filters(): (Altair LTE) [ttyACM1] filtered by vendor/product IDs
<debug> [1367697015.523346] [mm-plugin.c:322] apply_pre_probing_filters(): (Nokia (Icera)) [ttyACM1] filtered by vendor/product IDs
<debug> [1367697015.527394] [mm-plugin.c:322] apply_pre_probing_filters(): (Novatel LTE) [ttyACM1] filtered by vendor/product IDs
<debug> [1367697015.531651] [mm-plugin.c:254] apply_pre_probing_filters(): (Sierra) [ttyACM1] filtered by drivers
<debug> [1367697015.535614] [mm-plugin.c:322] apply_pre_probing_filters(): (Pantech) [ttyACM1] filtered by vendor/product IDs
<debug> [1367697015.580986] [mm-plugin.c:254] apply_pre_probing_filters(): (Option High-Speed) [ttyACM1] filtered by drivers
<debug> [1367697015.621099] [mm-plugin.c:254] apply_pre_probing_filters(): (Option) [ttyACM1] filtered by drivers
<debug> [1367697015.627464] [mm-plugin.c:322] apply_pre_probing_filters(): (Huawei) [ttyACM1] filtered by vendor/product IDs
<debug> [1367697015.633536] [mm-plugin.c:254] apply_pre_probing_filters(): (Novatel) [ttyACM1] filtered by drivers
<debug> [1367697015.677965] [mm-plugin.c:322] apply_pre_probing_filters(): (Longcheer) [ttyACM1] filtered by vendor/product IDs
<debug> [1367697015.696880] [mm-plugin.c:254] apply_pre_probing_filters(): (Gobi) [ttyACM1] filtered by drivers
<debug> [1367697015.700800] [mm-plugin.c:322] apply_pre_probing_filters(): (Linktop) [ttyACM1] filtered by vendor/product IDs
<debug> [1367697015.741244] [mm-plugin-manager.c:523] build_plugins_list(): (Plugin Manager) [ttyACM1] Found '2' plugins to try...
<debug> [1367697015.748538] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [ttyACM1] Will try with plugin 'Ericsson MBM'
<debug> [1367697015.755548] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [ttyACM1] Will try with plugin 'Generic'
<debug> [1367697015.761898] [mm-plugin.c:772] mm_plugin_supports_port(): (Ericsson MBM) [ttyACM1] probe required: 'at'
<debug> [1367697015.782701] [mm-port-probe.c:1331] mm_port_probe_run(): (tty/ttyACM1) launching port probing: 'at'
<debug> [1367697015.787252] [mm-plugin.c:322] apply_pre_probing_filters(): (Altair LTE) [ttyACM2] filtered by vendor/product IDs
<debug> [1367697015.791170] [mm-plugin.c:322] apply_pre_probing_filters(): (Nokia (Icera)) [ttyACM2] filtered by vendor/product IDs
<debug> [1367697015.851235] [mm-plugin.c:322] apply_pre_probing_filters(): (Novatel LTE) [ttyACM2] filtered by vendor/product IDs
<debug> [1367697015.855210] [mm-plugin.c:254] apply_pre_probing_filters(): (Sierra) [ttyACM2] filtered by drivers
<debug> [1367697015.862179] [mm-plugin.c:322] apply_pre_probing_filters(): (Pantech) [ttyACM2] filtered by vendor/product IDs
<debug> [1367697015.869273] [mm-plugin.c:254] apply_pre_probing_filters(): (Option High-Speed) [ttyACM2] filtered by drivers
<debug> [1367697015.875311] [mm-plugin.c:254] apply_pre_probing_filters(): (Option) [ttyACM2] filtered by drivers
<debug> [1367697015.881309] [mm-plugin.c:322] apply_pre_probing_filters(): (Huawei) [ttyACM2] filtered by vendor/product IDs
<debug> [1367697015.885238] [mm-plugin.c:254] apply_pre_probing_filters(): (Novatel) [ttyACM2] filtered by drivers
<debug> [1367697015.888861] [mm-plugin.c:322] apply_pre_probing_filters(): (Longcheer) [ttyACM2] filtered by vendor/product IDs
<debug> [1367697015.892869] [mm-plugin.c:254] apply_pre_probing_filters(): (Gobi) [ttyACM2] filtered by drivers
<debug> [1367697015.896789] [mm-plugin.c:322] apply_pre_probing_filters(): (Linktop) [ttyACM2] filtered by vendor/product IDs
<debug> [1367697015.903875] [mm-plugin-manager.c:523] build_plugins_list(): (Plugin Manager) [ttyACM2] Found '2' plugins to try...
<debug> [1367697015.910881] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [ttyACM2] Will try with plugin 'Ericsson MBM'
<debug> [1367697015.916951] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [ttyACM2] Will try with plugin 'Generic'
<debug> [1367697015.923080] [mm-plugin.c:772] mm_plugin_supports_port(): (Ericsson MBM) [ttyACM2] probe required: 'at'
<debug> [1367697015.926957] [mm-port-probe.c:1331] mm_port_probe_run(): (tty/ttyACM2) launching port probing: 'at'
<debug> [1367697015.930908] [mm-manager.c:288] device_added(): (tty/ttyS0): port's parent platform driver is not whitelisted
<debug> [1367697015.934703] [mm-manager.c:288] device_added(): (tty/ttyS1): port's parent platform driver is not whitelisted
<debug> [1367697015.938303] [mm-manager.c:288] device_added(): (tty/ttyS2): port's parent platform driver is not whitelisted
<debug> [1367697015.945161] [mm-manager.c:288] device_added(): (tty/ttyS3): port's parent platform driver is not whitelisted
<debug> [1367697015.952756] [mm-plugin.c:322] apply_pre_probing_filters(): (Altair LTE) [wwp0s20u4i6] filtered by vendor/product IDs
<debug> [1367697015.958319] [mm-plugin.c:322] apply_pre_probing_filters(): (Nokia (Icera)) [wwp0s20u4i6] filtered by vendor/product IDs
<debug> [1367697015.964262] [mm-plugin.c:322] apply_pre_probing_filters(): (Novatel LTE) [wwp0s20u4i6] filtered by vendor/product IDs
<debug> [1367697015.968077] [mm-plugin.c:213] apply_pre_probing_filters(): (Iridium) [wwp0s20u4i6] filtered by subsystem
<debug> [1367697015.971975] [mm-plugin.c:254] apply_pre_probing_filters(): (Sierra) [wwp0s20u4i6] filtered by drivers
<debug> [1367697015.975942] [mm-plugin.c:213] apply_pre_probing_filters(): (Via CBP7) [wwp0s20u4i6] filtered by subsystem
<debug> [1367697015.979851] [mm-plugin.c:322] apply_pre_probing_filters(): (Pantech) [wwp0s20u4i6] filtered by vendor/product IDs
<debug> [1367697016.006921] [mm-plugin.c:254] apply_pre_probing_filters(): (Option High-Speed) [wwp0s20u4i6] filtered by drivers
<debug> [1367697016.067513] [mm-plugin.c:213] apply_pre_probing_filters(): (Nokia) [wwp0s20u4i6] filtered by subsystem
<debug> [1367697016.085460] [mm-plugin.c:213] apply_pre_probing_filters(): (Option) [wwp0s20u4i6] filtered by subsystem
<debug> [1367697016.091594] [mm-plugin.c:322] apply_pre_probing_filters(): (Huawei) [wwp0s20u4i6] filtered by vendor/product IDs
<debug> [1367697016.127810] [mm-plugin.c:213] apply_pre_probing_filters(): (Novatel) [wwp0s20u4i6] filtered by subsystem
<debug> [1367697016.147575] [mm-plugin.c:213] apply_pre_probing_filters(): (Longcheer) [wwp0s20u4i6] filtered by subsystem
<debug> [1367697016.151472] [mm-plugin.c:213] apply_pre_probing_filters(): (Cinterion) [wwp0s20u4i6] filtered by subsystem
<debug> [1367697016.155222] [mm-plugin.c:254] apply_pre_probing_filters(): (Gobi) [wwp0s20u4i6] filtered by drivers
<debug> [1367697016.162598] [mm-plugin.c:213] apply_pre_probing_filters(): (Linktop) [wwp0s20u4i6] filtered by subsystem
<debug> [1367697016.169581] [mm-plugin.c:376] apply_pre_probing_filters(): (Ericsson MBM) [wwp0s20u4i6] filtered by udev tags
<debug> [1367697016.175535] [mm-plugin.c:322] apply_pre_probing_filters(): (AnyDATA) [wwp0s20u4i6] filtered by vendor/product IDs
<debug> [1367697016.181633] [mm-plugin.c:213] apply_pre_probing_filters(): (X22X) [wwp0s20u4i6] filtered by subsystem
<debug> [1367697016.185467] [mm-plugin.c:213] apply_pre_probing_filters(): (Wavecom) [wwp0s20u4i6] filtered by subsystem
<debug> [1367697016.189229] [mm-plugin.c:322] apply_pre_probing_filters(): (Samsung) [wwp0s20u4i6] filtered by vendor/product IDs
<debug> [1367697016.192922] [mm-plugin.c:213] apply_pre_probing_filters(): (Motorola) [wwp0s20u4i6] filtered by subsystem
<debug> [1367697016.196663] [mm-plugin.c:322] apply_pre_probing_filters(): (ZTE) [wwp0s20u4i6] filtered by vendor/product IDs
<debug> [1367697016.203532] [mm-plugin.c:213] apply_pre_probing_filters(): (SimTech) [wwp0s20u4i6] filtered by subsystem
<debug> [1367697016.210631] [mm-plugin-manager.c:523] build_plugins_list(): (Plugin Manager) [wwp0s20u4i6] Found '1' plugins to try...
<debug> [1367697016.236356] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [wwp0s20u4i6] Will try with plugin 'Generic'
<debug> [1367697016.256282] [mm-plugin.c:700] mm_plugin_supports_port(): (Generic) [wwp0s20u4i6] probing deferred until result suggested
<debug> [1367697016.260460] [mm-plugin-manager.c:622] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] Checking device support...
<debug> [1367697016.264215] [mm-plugin.c:322] apply_pre_probing_filters(): (Altair LTE) [enp0s25] filtered by vendor/product IDs
<debug> [1367697016.267856] [mm-plugin.c:322] apply_pre_probing_filters(): (Nokia (Icera)) [enp0s25] filtered by vendor/product IDs
<debug> [1367697016.271856] [mm-plugin.c:322] apply_pre_probing_filters(): (Novatel LTE) [enp0s25] filtered by vendor/product IDs
<debug> [1367697016.278810] [mm-plugin.c:213] apply_pre_probing_filters(): (Iridium) [enp0s25] filtered by subsystem
<debug> [1367697016.285859] [mm-plugin.c:254] apply_pre_probing_filters(): (Sierra) [enp0s25] filtered by drivers
<debug> [1367697016.291842] [mm-plugin.c:213] apply_pre_probing_filters(): (Via CBP7) [enp0s25] filtered by subsystem
<debug> [1367697016.297954] [mm-plugin.c:322] apply_pre_probing_filters(): (Pantech) [enp0s25] filtered by vendor/product IDs
<debug> [1367697016.301914] [mm-plugin.c:254] apply_pre_probing_filters(): (Option High-Speed) [enp0s25] filtered by drivers
<debug> [1367697016.305818] [mm-plugin.c:213] apply_pre_probing_filters(): (Nokia) [enp0s25] filtered by subsystem
<debug> [1367697016.309747] [mm-plugin.c:213] apply_pre_probing_filters(): (Option) [enp0s25] filtered by subsystem
<debug> [1367697016.313684] [mm-plugin.c:322] apply_pre_probing_filters(): (Huawei) [enp0s25] filtered by vendor/product IDs
<debug> [1367697016.320689] [mm-plugin.c:213] apply_pre_probing_filters(): (Novatel) [enp0s25] filtered by subsystem
<debug> [1367697016.339737] [mm-plugin.c:213] apply_pre_probing_filters(): (Longcheer) [enp0s25] filtered by subsystem
<debug> [1367697016.345978] [mm-plugin.c:213] apply_pre_probing_filters(): (Cinterion) [enp0s25] filtered by subsystem
<debug> [1367697016.352020] [mm-plugin.c:254] apply_pre_probing_filters(): (Gobi) [enp0s25] filtered by drivers
<debug> [1367697016.355908] [mm-plugin.c:213] apply_pre_probing_filters(): (Linktop) [enp0s25] filtered by subsystem
<debug> [1367697016.359878] [mm-plugin.c:376] apply_pre_probing_filters(): (Ericsson MBM) [enp0s25] filtered by udev tags
<debug> [1367697016.381118] [mm-plugin.c:322] apply_pre_probing_filters(): (AnyDATA) [enp0s25] filtered by vendor/product IDs
<debug> [1367697016.385054] [mm-plugin.c:213] apply_pre_probing_filters(): (X22X) [enp0s25] filtered by subsystem
<debug> [1367697016.392109] [mm-plugin.c:213] apply_pre_probing_filters(): (Wavecom) [enp0s25] filtered by subsystem
<debug> [1367697016.399348] [mm-plugin.c:322] apply_pre_probing_filters(): (Samsung) [enp0s25] filtered by vendor/product IDs
<debug> [1367697016.405446] [mm-plugin.c:213] apply_pre_probing_filters(): (Motorola) [enp0s25] filtered by subsystem
<debug> [1367697016.411607] [mm-plugin.c:322] apply_pre_probing_filters(): (ZTE) [enp0s25] filtered by vendor/product IDs
<debug> [1367697016.415464] [mm-plugin.c:213] apply_pre_probing_filters(): (SimTech) [enp0s25] filtered by subsystem
<debug> [1367697016.441166] [mm-plugin-manager.c:523] build_plugins_list(): (Plugin Manager) [enp0s25] Found '1' plugins to try...
<debug> [1367697016.501128] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [enp0s25] Will try with plugin 'Generic'
<debug> [1367697016.561135] [mm-plugin.c:700] mm_plugin_supports_port(): (Generic) [enp0s25] probing deferred until result suggested
<debug> [1367697016.624771] [mm-plugin-manager.c:622] mm_plugin_manager_find_device_support(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0] Checking device
support...
<debug> [1367697016.683868] [mm-plugin.c:322] apply_pre_probing_filters(): (Altair LTE) [wlp3s0] filtered by vendor/product IDs
<debug> [1367697016.743339] [mm-plugin.c:322] apply_pre_probing_filters(): (Nokia (Icera)) [wlp3s0] filtered by vendor/product IDs
<debug> [1367697016.803183] [mm-plugin.c:322] apply_pre_probing_filters(): (Novatel LTE) [wlp3s0] filtered by vendor/product IDs
<debug> [1367697016.807122] [mm-plugin.c:213] apply_pre_probing_filters(): (Iridium) [wlp3s0] filtered by subsystem
<debug> [1367697016.811043] [mm-plugin.c:254] apply_pre_probing_filters(): (Sierra) [wlp3s0] filtered by drivers
<debug> [1367697016.814935] [mm-plugin.c:213] apply_pre_probing_filters(): (Via CBP7) [wlp3s0] filtered by subsystem
<debug> [1367697016.818918] [mm-plugin.c:322] apply_pre_probing_filters(): (Pantech) [wlp3s0] filtered by vendor/product IDs
<debug> [1367697016.825943] [mm-plugin.c:254] apply_pre_probing_filters(): (Option High-Speed) [wlp3s0] filtered by drivers
<debug> [1367697016.832895] [mm-plugin.c:213] apply_pre_probing_filters(): (Nokia) [wlp3s0] filtered by subsystem
<debug> [1367697016.838875] [mm-plugin.c:213] apply_pre_probing_filters(): (Option) [wlp3s0] filtered by subsystem
<debug> [1367697016.844725] [mm-plugin.c:322] apply_pre_probing_filters(): (Huawei) [wlp3s0] filtered by vendor/product IDs
<debug> [1367697016.848494] [mm-plugin.c:213] apply_pre_probing_filters(): (Novatel) [wlp3s0] filtered by subsystem
<debug> [1367697016.852479] [mm-plugin.c:213] apply_pre_probing_filters(): (Longcheer) [wlp3s0] filtered by subsystem
<debug> [1367697016.856475] [mm-plugin.c:213] apply_pre_probing_filters(): (Cinterion) [wlp3s0] filtered by subsystem
<debug> [1367697016.860454] [mm-plugin.c:254] apply_pre_probing_filters(): (Gobi) [wlp3s0] filtered by drivers
<debug> [1367697016.867486] [mm-plugin.c:213] apply_pre_probing_filters(): (Linktop) [wlp3s0] filtered by subsystem
<debug> [1367697016.874517] [mm-plugin.c:376] apply_pre_probing_filters(): (Ericsson MBM) [wlp3s0] filtered by udev tags
<debug> [1367697016.880565] [mm-plugin.c:322] apply_pre_probing_filters(): (AnyDATA) [wlp3s0] filtered by vendor/product IDs
<debug> [1367697016.886416] [mm-plugin.c:213] apply_pre_probing_filters(): (X22X) [wlp3s0] filtered by subsystem
<debug> [1367697016.890610] [mm-plugin.c:213] apply_pre_probing_filters(): (Wavecom) [wlp3s0] filtered by subsystem
<debug> [1367697016.934511] [mm-plugin.c:322] apply_pre_probing_filters(): (Samsung) [wlp3s0] filtered by vendor/product IDs
<debug> [1367697016.994417] [mm-plugin.c:213] apply_pre_probing_filters(): (Motorola) [wlp3s0] filtered by subsystem
<debug> [1367697016.998525] [mm-plugin.c:322] apply_pre_probing_filters(): (ZTE) [wlp3s0] filtered by vendor/product IDs
<debug> [1367697017.019946] [mm-plugin.c:213] apply_pre_probing_filters(): (SimTech) [wlp3s0] filtered by subsystem
<debug> [1367697017.027014] [mm-plugin-manager.c:523] build_plugins_list(): (Plugin Manager) [wlp3s0] Found '1' plugins to try...
<debug> [1367697017.033176] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [wlp3s0] Will try with plugin 'Generic'
<debug> [1367697017.039260] [mm-plugin.c:700] mm_plugin_supports_port(): (Generic) [wlp3s0] probing deferred until result suggested
<debug> [1367697017.043959] [mm-plugin.c:213] apply_pre_probing_filters(): (Altair LTE) [cdc-wdm0] filtered by subsystem
<debug> [1367697017.127723] [mm-plugin.c:213] apply_pre_probing_filters(): (Nokia (Icera)) [cdc-wdm0] filtered by subsystem
<debug> [1367697017.151086] [mm-plugin.c:213] apply_pre_probing_filters(): (Novatel LTE) [cdc-wdm0] filtered by subsystem
<debug> [1367697017.191265] [mm-plugin.c:213] apply_pre_probing_filters(): (Iridium) [cdc-wdm0] filtered by subsystem
<debug> [1367697017.213379] [mm-plugin.c:254] apply_pre_probing_filters(): (Sierra) [cdc-wdm0] filtered by drivers
<debug> [1367697017.220541] [mm-plugin.c:213] apply_pre_probing_filters(): (Via CBP7) [cdc-wdm0] filtered by subsystem
<debug> [1367697017.226665] [mm-plugin.c:322] apply_pre_probing_filters(): (Pantech) [cdc-wdm0] filtered by vendor/product IDs
<debug> [1367697017.232779] [mm-plugin.c:213] apply_pre_probing_filters(): (Option High-Speed) [cdc-wdm0] filtered by subsystem
<debug> [1367697017.236508] [mm-plugin.c:213] apply_pre_probing_filters(): (Nokia) [cdc-wdm0] filtered by subsystem
<debug> [1367697017.240483] [mm-plugin.c:213] apply_pre_probing_filters(): (Option) [cdc-wdm0] filtered by subsystem
<debug> [1367697017.244505] [mm-plugin.c:322] apply_pre_probing_filters(): (Huawei) [cdc-wdm0] filtered by vendor/product IDs
<debug> [1367697017.248172] [mm-plugin.c:213] apply_pre_probing_filters(): (Novatel) [cdc-wdm0] filtered by subsystem
<debug> [1367697017.274025] [mm-plugin.c:213] apply_pre_probing_filters(): (Longcheer) [cdc-wdm0] filtered by subsystem
<debug> [1367697017.293040] [mm-plugin.c:213] apply_pre_probing_filters(): (Cinterion) [cdc-wdm0] filtered by subsystem
<debug> [1367697017.340008] [mm-plugin.c:254] apply_pre_probing_filters(): (Gobi) [cdc-wdm0] filtered by drivers
<debug> [1367697017.386566] [mm-plugin.c:213] apply_pre_probing_filters(): (Linktop) [cdc-wdm0] filtered by subsystem
<debug> [1367697017.444306] [mm-plugin-manager.c:523] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Found '2' plugins to try...
<debug> [1367697017.467823] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Will try with plugin 'Ericsson MBM'
<debug> [1367697017.471719] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [cdc-wdm0] Will try with plugin 'Generic'
<debug> [1367697017.475613] [mm-plugin.c:772] mm_plugin_supports_port(): (Ericsson MBM) [cdc-wdm0] probe required: 'mbim'
<debug> [1367697017.482773] [mm-port-probe.c:1331] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'mbim'
<debug> [1367697017.510362] [mm-plugin.c:213] apply_pre_probing_filters(): (Altair LTE) [cdc-wdm2] filtered by subsystem
<debug> [1367697017.569944] [mm-plugin.c:213] apply_pre_probing_filters(): (Nokia (Icera)) [cdc-wdm2] filtered by subsystem
<debug> [1367697017.629827] [mm-plugin.c:213] apply_pre_probing_filters(): (Novatel LTE) [cdc-wdm2] filtered by subsystem
<debug> [1367697017.667927] [mm-plugin.c:213] apply_pre_probing_filters(): (Iridium) [cdc-wdm2] filtered by subsystem
<debug> [1367697017.707854] [mm-plugin.c:254] apply_pre_probing_filters(): (Sierra) [cdc-wdm2] filtered by drivers
<debug> [1367697017.711780] [mm-plugin.c:213] apply_pre_probing_filters(): (Via CBP7) [cdc-wdm2] filtered by subsystem
<debug> [1367697017.715689] [mm-plugin.c:322] apply_pre_probing_filters(): (Pantech) [cdc-wdm2] filtered by vendor/product IDs
<debug> [1367697017.760809] [mm-plugin.c:213] apply_pre_probing_filters(): (Option High-Speed) [cdc-wdm2] filtered by subsystem
<debug> [1367697017.820818] [mm-plugin.c:213] apply_pre_probing_filters(): (Nokia) [cdc-wdm2] filtered by subsystem
<debug> [1367697017.866715] [mm-plugin.c:213] apply_pre_probing_filters(): (Option) [cdc-wdm2] filtered by subsystem
<debug> [1367697017.926462] [mm-plugin.c:322] apply_pre_probing_filters(): (Huawei) [cdc-wdm2] filtered by vendor/product IDs
<debug> [1367697017.970748] [mm-plugin.c:213] apply_pre_probing_filters(): (Novatel) [cdc-wdm2] filtered by subsystem
<debug> [1367697018.011234] [mm-plugin.c:213] apply_pre_probing_filters(): (Longcheer) [cdc-wdm2] filtered by subsystem
<debug> [1367697018.051630] [mm-plugin.c:213] apply_pre_probing_filters(): (Cinterion) [cdc-wdm2] filtered by subsystem
<debug> [1367697018.055553] [mm-plugin.c:254] apply_pre_probing_filters(): (Gobi) [cdc-wdm2] filtered by drivers
<debug> [1367697018.062401] [mm-plugin.c:213] apply_pre_probing_filters(): (Linktop) [cdc-wdm2] filtered by subsystem
<debug> [1367697018.090034] [mm-plugin-manager.c:523] build_plugins_list(): (Plugin Manager) [cdc-wdm2] Found '2' plugins to try...
<debug> [1367697018.108728] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [cdc-wdm2] Will try with plugin 'Ericsson MBM'
<debug> [1367697018.114721] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [cdc-wdm2] Will try with plugin 'Generic'
<debug> [1367697018.137668] [mm-plugin.c:772] mm_plugin_supports_port(): (Ericsson MBM) [cdc-wdm2] probe required: 'mbim'
<debug> [1367697018.176998] [mm-port-probe.c:1331] mm_port_probe_run(): (usbmisc/cdc-wdm2) launching port probing: 'mbim'
<debug> [1367697018.200489] [mm-plugin.c:213] apply_pre_probing_filters(): (Altair LTE) [cdc-wdm1] filtered by subsystem
<debug> [1367697018.261062] [mm-plugin.c:213] apply_pre_probing_filters(): (Nokia (Icera)) [cdc-wdm1] filtered by subsystem
<debug> [1367697018.267890] [mm-plugin.c:213] apply_pre_probing_filters(): (Novatel LTE) [cdc-wdm1] filtered by subsystem
<debug> [1367697018.274606] [mm-plugin.c:213] apply_pre_probing_filters(): (Iridium) [cdc-wdm1] filtered by subsystem
<debug> [1367697018.280616] [mm-plugin.c:254] apply_pre_probing_filters(): (Sierra) [cdc-wdm1] filtered by drivers
<debug> [1367697018.286494] [mm-plugin.c:213] apply_pre_probing_filters(): (Via CBP7) [cdc-wdm1] filtered by subsystem
<debug> [1367697018.290174] [mm-plugin.c:322] apply_pre_probing_filters(): (Pantech) [cdc-wdm1] filtered by vendor/product IDs
<debug> [1367697018.293815] [mm-plugin.c:213] apply_pre_probing_filters(): (Option High-Speed) [cdc-wdm1] filtered by subsystem
<debug> [1367697018.297429] [mm-plugin.c:213] apply_pre_probing_filters(): (Nokia) [cdc-wdm1] filtered by subsystem
<debug> [1367697018.317411] [mm-plugin.c:213] apply_pre_probing_filters(): (Option) [cdc-wdm1] filtered by subsystem
<debug> [1367697018.324281] [mm-plugin.c:322] apply_pre_probing_filters(): (Huawei) [cdc-wdm1] filtered by vendor/product IDs
<debug> [1367697018.331088] [mm-plugin.c:213] apply_pre_probing_filters(): (Novatel) [cdc-wdm1] filtered by subsystem
<debug> [1367697018.336959] [mm-plugin.c:213] apply_pre_probing_filters(): (Longcheer) [cdc-wdm1] filtered by subsystem
<debug> [1367697018.355670] [mm-plugin.c:213] apply_pre_probing_filters(): (Cinterion) [cdc-wdm1] filtered by subsystem
<debug> [1367697018.362836] [mm-plugin.c:254] apply_pre_probing_filters(): (Gobi) [cdc-wdm1] filtered by drivers
<debug> [1367697018.369855] [mm-plugin.c:213] apply_pre_probing_filters(): (Linktop) [cdc-wdm1] filtered by subsystem
<debug> [1367697018.376034] [mm-plugin-manager.c:523] build_plugins_list(): (Plugin Manager) [cdc-wdm1] Found '2' plugins to try...
<debug> [1367697018.386005] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [cdc-wdm1] Will try with plugin 'Ericsson MBM'
<debug> [1367697018.396801] [mm-plugin-manager.c:527] build_plugins_list(): (Plugin Manager) [cdc-wdm1] Will try with plugin 'Generic'
<debug> [1367697018.407780] [mm-plugin.c:772] mm_plugin_supports_port(): (Ericsson MBM) [cdc-wdm1] probe required: 'mbim'
<debug> [1367697018.427487] [mm-port-probe.c:1331] mm_port_probe_run(): (usbmisc/cdc-wdm1) launching port probing: 'mbim'
<debug> [1367697018.432138] [mm-plugin-manager.c:593] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:14.0/usb1/1-4] Minimum probing time consumed
<debug> [1367697018.457119] [mm-plugin-manager.c:452] plugin_supports_port_ready(): (Plugin Manager) [wwp0s20u4i6] deferring support check until result suggested
<debug> [1367697018.516607] [mm-plugin-manager.c:452] plugin_supports_port_ready(): (Plugin Manager) [enp0s25] deferring support check until result suggested
<debug> [1367697018.521999] [mm-plugin-manager.c:452] plugin_supports_port_ready(): (Plugin Manager) [wlp3s0] deferring support check until result suggested
<debug> [1367697018.526625] [mm-serial-port.c:891] mm_serial_port_open(): (ttyACM0) opening serial port...
<debug> [1367697018.534437] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
<debug> [1367697018.542405] [mm-serial-port.c:891] mm_serial_port_open(): (ttyACM1) opening serial port...
<debug> [1367697018.569928] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM1) device open count is 1 (open)
<debug> [1367697018.590509] [mm-serial-port.c:891] mm_serial_port_open(): (ttyACM2) opening serial port...
<debug> [1367697018.614223] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM2) device open count is 1 (open)
<debug> [1367697018.618970] [mm-port-probe.c:317] mm_port_probe_set_result_mbim(): (usbmisc/cdc-wdm0) port is not MBIM-capable
<debug> [1367697018.623238] [mm-port-probe.c:317] mm_port_probe_set_result_mbim(): (usbmisc/cdc-wdm2) port is not MBIM-capable
<debug> [1367697018.627512] [mm-port-probe.c:317] mm_port_probe_set_result_mbim(): (usbmisc/cdc-wdm1) port is not MBIM-capable
<debug> [1367697018.634600] [mm-plugin-manager.c:365] plugin_supports_port_ready(): (Plugin Manager) (Ericsson MBM) [cdc-wdm0] found best plugin for port
<debug> [1367697018.674293] [mm-plugin-manager.c:201] port_probe_context_finished(): (Plugin Manager) (Ericsson MBM) [cdc-wdm0]: found best plugin for device
(/sys/devices/pci0000:00/0000:00:14.0/usb1/1-4)
<debug> [1367697018.733136] [mm-plugin-manager.c:325] suggest_port_probe_result(): (Plugin Manager) (Ericsson MBM) [cdc-wdm1] suggested plugin for port
<debug> [1367697018.758863] [mm-plugin-manager.c:325] suggest_port_probe_result(): (Plugin Manager) (Ericsson MBM) [cdc-wdm2] suggested plugin for port
<debug> [1367697018.796603] [mm-plugin-manager.c:299] suggest_port_probe_result(): (Plugin Manager) (Ericsson MBM) [wwp0s20u4i6] deferred task completed, got suggested plugin
<debug> [1367697018.836323] [mm-plugin-manager.c:325] suggest_port_probe_result(): (Plugin Manager) (Ericsson MBM) [ttyACM2] suggested plugin for port
<debug> [1367697018.860093] [mm-plugin-manager.c:325] suggest_port_probe_result(): (Plugin Manager) (Ericsson MBM) [ttyACM1] suggested plugin for port
<debug> [1367697018.899945] [mm-plugin-manager.c:325] suggest_port_probe_result(): (Plugin Manager) (Ericsson MBM) [ttyACM0] suggested plugin for port
<debug> [1367697018.926078] [mm-plugin-manager.c:239] port_probe_context_finished(): (Plugin Manager) 'cdc-wdm0' port probe finished, still 6 running probes in this device (cdc-wdm1,
cdc-wdm2, wwp0s20u4i6, ttyACM2, ttyACM1, ttyACM0)
<debug> [1367697018.946634] [mm-plugin-manager.c:365] plugin_supports_port_ready(): (Plugin Manager) (Ericsson MBM) [cdc-wdm2] found best plugin for port
<debug> [1367697018.972150] [mm-plugin-manager.c:239] port_probe_context_finished(): (Plugin Manager) 'cdc-wdm2' port probe finished, still 5 running probes in this device (cdc-wdm1,
wwp0s20u4i6, ttyACM2, ttyACM1, ttyACM0)
<debug> [1367697018.978383] [mm-plugin-manager.c:365] plugin_supports_port_ready(): (Plugin Manager) (Ericsson MBM) [cdc-wdm1] found best plugin for port
<debug> [1367697018.982546] [mm-plugin-manager.c:239] port_probe_context_finished(): (Plugin Manager) 'cdc-wdm1' port probe finished, still 4 running probes in this device
(wwp0s20u4i6, ttyACM2, ttyACM1, ttyACM0)
<debug> [1367697019.000857] [mm-plugin-manager.c:593] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] Minimum probing time consumed
<debug> [1367697019.005075] [mm-plugin-manager.c:306] suggest_port_probe_result(): (Plugin Manager) [enp0s25] deferred task cancelled, no suggested plugin
<debug> [1367697019.027738] [mm-plugin-manager.c:593] min_probing_timeout_cb(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0] Minimum probing time consumed
<debug> [1367697019.053289] [mm-plugin-manager.c:306] suggest_port_probe_result(): (Plugin Manager) [wlp3s0] deferred task cancelled, no suggested plugin
<debug> [1367697019.085368] [mm-plugin-manager.c:158] port_probe_context_finished(): (Plugin Manager) [wwp0s20u4i6] assuming port can be handled by the 'Ericsson MBM' plugin
<debug> [1367697019.091725] [mm-plugin-manager.c:239] port_probe_context_finished(): (Plugin Manager) 'wwp0s20u4i6' port probe finished, still 3 running probes in this device (ttyACM2,
ttyACM1, ttyACM0)
<debug> [1367697019.097974] [mm-plugin-manager.c:164] port_probe_context_finished(): (Plugin Manager) [enp0s25] not supported by any plugin
<debug> [1367697019.102179] [mm-device.c:337] mm_device_ignore_port(): Fully ignoring port 'net/enp0s25' from now on
<debug> [1367697019.106367] [mm-plugin-manager.c:250] port_probe_context_finished(): (Plugin Manager) 'enp0s25' port probe finished, last one in device
<debug> [1367697019.126942] [mm-plugin-manager.c:107] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:19.0] device support check
finished in '2.862826' seconds
<warn> [1367697019.149918] [mm-manager.c:140] find_device_support_ready(): Couldn't find support for device at '/sys/devices/pci0000:00/0000:00:19.0': not supported by any plugin
<debug> [1367697019.157112] [mm-plugin-manager.c:164] port_probe_context_finished(): (Plugin Manager) [wlp3s0] not supported by any plugin
<debug> [1367697019.204221] [mm-device.c:337] mm_device_ignore_port(): Fully ignoring port 'net/wlp3s0' from now on
<debug> [1367697019.263354] [mm-plugin-manager.c:250] port_probe_context_finished(): (Plugin Manager) 'wlp3s0' port probe finished, last one in device
<debug> [1367697019.323382] [mm-plugin-manager.c:107] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:1c.1/0000:03:00.0] device
support check finished in '2.639595' seconds
<warn> [1367697019.366477] [mm-manager.c:140] find_device_support_ready(): 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> [1367697019.426428] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>*EMRDY: 1<CR><LF>'
<debug> [1367697019.450412] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <-- '<CR><LF>*EMRDY: 1<CR><LF>'
<debug> [1367697019.454553] [mm-at-serial-port.c:408] debug_log(): (ttyACM2): <-- '<CR><LF>*EMRDY: 1<CR><LF>'
<debug> [1367697019.461823] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT<CR>'
<debug> [1367697019.469105] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): --> 'AT<CR>'
<debug> [1367697019.475284] [mm-at-serial-port.c:408] debug_log(): (ttyACM2): --> 'AT<CR>'
<debug> [1367697019.669126] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- 'AT'
<debug> [1367697019.674356] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><CR><LF>OK<CR><LF>'
<debug> [1367697019.714363] [mm-port-probe.c:151] mm_port_probe_set_result_at(): (tty/ttyACM0) port is AT-capable
<debug> [1367697019.733401] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 0 (close)
<debug> [1367697019.757940] [mm-serial-port.c:1019] mm_serial_port_close(): (ttyACM0) closing serial port...
<debug> [1367697019.781275] [mm-serial-port.c:1050] mm_serial_port_close(): (ttyACM0) serial port closed
<debug> [1367697019.788519] [mm-serial-port.c:1118] mm_serial_port_close_force(): (ttyACM0) forced to close port
<debug> [1367697019.794754] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <-- 'AT<CR><CR><LF>OK<CR><LF>'
<debug> [1367697019.801039] [mm-port-probe.c:151] mm_port_probe_set_result_at(): (tty/ttyACM1) port is AT-capable
<debug> [1367697019.804937] [mm-at-serial-port.c:408] debug_log(): (ttyACM2): <-- 'AT<CR><CR><LF>OK<CR><LF>'
<debug> [1367697019.808947] [mm-port-probe.c:151] mm_port_probe_set_result_at(): (tty/ttyACM2) port is AT-capable
<debug> [1367697019.813212] [mm-plugin-manager.c:365] plugin_supports_port_ready(): (Plugin Manager) (Ericsson MBM) [ttyACM0] found best plugin for port
<debug> [1367697019.817080] [mm-plugin-manager.c:239] port_probe_context_finished(): (Plugin Manager) 'ttyACM0' port probe finished, still 2 running probes in this device (ttyACM2,
ttyACM1)
<debug> [1367697019.824363] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM1) device open count is 0 (close)
<debug> [1367697019.831478] [mm-serial-port.c:1019] mm_serial_port_close(): (ttyACM1) closing serial port...
<debug> [1367697019.838542] [mm-serial-port.c:1050] mm_serial_port_close(): (ttyACM1) serial port closed
<debug> [1367697019.844667] [mm-serial-port.c:1118] mm_serial_port_close_force(): (ttyACM1) forced to close port
<debug> [1367697019.848467] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM2) device open count is 0 (close)
<debug> [1367697019.852416] [mm-serial-port.c:1019] mm_serial_port_close(): (ttyACM2) closing serial port...
<debug> [1367697019.857807] [mm-serial-port.c:1050] mm_serial_port_close(): (ttyACM2) serial port closed
<debug> [1367697019.861479] [mm-serial-port.c:1118] mm_serial_port_close_force(): (ttyACM2) forced to close port
<debug> [1367697019.868714] [mm-plugin-manager.c:365] plugin_supports_port_ready(): (Plugin Manager) (Ericsson MBM) [ttyACM1] found best plugin for port
<debug> [1367697019.876030] [mm-plugin-manager.c:239] port_probe_context_finished(): (Plugin Manager) 'ttyACM1' port probe finished, still 1 running probes in this device (ttyACM2)
<debug> [1367697019.882170] [mm-plugin-manager.c:365] plugin_supports_port_ready(): (Plugin Manager) (Ericsson MBM) [ttyACM2] found best plugin for port
<debug> [1367697019.888295] [mm-plugin-manager.c:250] port_probe_context_finished(): (Plugin Manager) 'ttyACM2' port probe finished, last one in device
<debug> [1367697019.892094] [mm-plugin-manager.c:107] find_device_support_context_complete_and_free(): (Plugin Manager) [/sys/devices/pci0000:00/0000:00:14.0/usb1/1-4] device support
check finished in '4.916196' seconds
<info> [1367697019.896114] [mm-device.c:484] mm_device_create_modem(): Creating modem with plugin 'Ericsson MBM' and '7' ports
<warn> [1367697019.900913] [mm-plugin.c:900] mm_plugin_create_modem(): Could not grab port (usbmisc/cdc-wdm1): 'Cannot add port 'usbmisc/cdc-wdm1', unsupported'
<warn> [1367697019.904809] [mm-plugin.c:900] mm_plugin_create_modem(): Could not grab port (usbmisc/cdc-wdm2): 'Cannot add port 'usbmisc/cdc-wdm2', unsupported'
<warn> [1367697019.911841] [mm-plugin.c:900] mm_plugin_create_modem(): Could not grab port (usbmisc/cdc-wdm0): 'Cannot add port 'usbmisc/cdc-wdm0', unsupported'
<warn> [1367697019.918914] [mm-plugin.c:900] mm_plugin_create_modem(): Could not grab port (net/wwp0s20u4i6): 'ignoring MBIM net port'
<debug> [1367697019.925067] [mm-base-modem.c:275] mm_base_modem_grab_port(): (ttyACM2) type 'at' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb1/1-4
<debug> [1367697019.931200] [mm-base-modem.c:275] mm_base_modem_grab_port(): (ttyACM1) type 'at' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb1/1-4
<debug> [1367697019.954557] [mm-base-modem.c:275] mm_base_modem_grab_port(): (ttyACM0) type 'at' claimed by /sys/devices/pci0000:00/0000:00:14.0/usb1/1-4
<debug> [1367697019.996823] [mm-base-modem.c:1009] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-4) tty/ttyACM0 at (primary)
<debug> [1367697020.020276] [mm-base-modem.c:1009] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-4) tty/ttyACM1 at (secondary)
<debug> [1367697020.061137] [mm-base-modem.c:1009] log_port(): (/sys/devices/pci0000:00/0000:00:14.0/usb1/1-4) tty/ttyACM0 data (primary)
<debug> [1367697020.087056] [mm-serial-port.c:891] mm_serial_port_open(): (ttyACM0) opening serial port...
<debug> [1367697020.128205] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
<debug> [1367697020.165241] [mm-at-serial-port.c:445] mm_at_serial_port_run_init_sequence(): (ttyACM0): running init sequence...
<debug> [1367697020.208497] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697020.230983] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<info> [1367697020.251282] [mm-manager.c:149] find_device_support_ready(): Modem for device at '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-4' successfully created
<debug> [1367697020.313309] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>*EMRDY: 1<CR><LF>'
<debug> [1367697020.318117] [mm-broadband-modem.c:675] modem_load_current_capabilities(): loading current capabilities...
<debug> [1367697020.325172] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
<debug> [1367697020.332445] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'ATE0<CR>'
<debug> [1367697020.342522] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- 'ATE0'
<debug> [1367697020.348780] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><CR><LF>OK<CR><LF>'
<debug> [1367697020.366833] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'ATV1<CR>'
<debug> [1367697020.377343] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697020.390057] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
<debug> [1367697020.406976] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697020.414238] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'ATX4<CR>'
<debug> [1367697020.446292] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697020.459311] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT&C1<CR>'
<debug> [1367697020.473397] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697020.477297] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'ATE0<CR>'
<debug> [1367697020.487613] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697020.491451] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
<debug> [1367697020.514464] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
<debug> [1367697020.553084] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697020.560321] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697020.600090] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+GCAP<CR>'
<debug> [1367697020.650625] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+GCAP: +CGSM, +DS<CR><LF>'
<debug> [1367697020.656760] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697020.697901] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697020.721117] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697020.725253] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+WS46=?<CR>'
<debug> [1367697020.748544] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>ERROR<CR><LF>'
<debug> [1367697020.755904] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
<debug> [1367697020.793396] [mm-broadband-modem.c:336] modem_load_current_capabilities_finish(): loaded current capabilities: gsm-umts
<debug> [1367697020.819197] [mm-iface-modem.c:3885] interface_initialization_step(): Modem allows up to 1 bearers
<debug> [1367697020.863093] [mm-bearer-list.c:273] mm_bearer_list_new(): Creating bearer list (max: 1, max active: 1)
<debug> [1367697020.907853] [mm-broadband-modem.c:735] modem_load_manufacturer(): loading manufacturer...
<debug> [1367697020.966287] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697020.991484] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697020.998686] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CGMI<CR>'
<debug> [1367697021.016300] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>Lenovo<CR><LF>'
<debug> [1367697021.022682] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697021.028872] [mm-broadband-modem.c:719] modem_load_manufacturer_finish(): loaded manufacturer: Lenovo
<debug> [1367697021.033215] [mm-broadband-modem.c:775] modem_load_model(): loading model...
<debug> [1367697021.037112] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697021.077808] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697021.100358] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CGMM<CR>'
<debug> [1367697021.156800] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>H5321 gw<CR><LF>'
<debug> [1367697021.166739] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697021.193410] [mm-broadband-modem.c:759] modem_load_model_finish(): loaded model: H5321 gw
<debug> [1367697021.253284] [mm-broadband-modem.c:815] modem_load_revision(): loading revision...
<debug> [1367697021.257221] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697021.261268] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697021.265241] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CGMR<CR>'
<debug> [1367697021.280426] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>R3C11<CR><LF>'
<debug> [1367697021.290124] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697021.317616] [mm-broadband-modem.c:799] modem_load_revision_finish(): loaded revision: R3C11
<debug> [1367697021.376708] [mm-broadband-modem.c:876] modem_load_equipment_identifier(): loading equipment identifier...
<debug> [1367697021.416771] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697021.474493] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697021.497919] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CGSN<CR>'
<debug> [1367697021.522537] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>359001040736584<CR><LF>'
<debug> [1367697021.526818] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697021.533992] [mm-broadband-modem.c:858] modem_load_equipment_identifier_finish(): loaded equipment identifier: 359001040736584
<debug> [1367697021.574468] [mm-broadband-modem.c:964] modem_load_device_identifier(): loading device identifier...
<debug> [1367697021.580517] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697021.586519] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697021.590342] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'ATI<CR>'
<debug> [1367697021.599055] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>H5321 gw<CR><LF>'
<debug> [1367697021.614024] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697021.617949] [mm-modem-helpers.c:148] mm_create_device_identifier(): Device ID source '00000bdb00001926H5321gw359001040736584R3C11H5321gwLenovo'
<debug> [1367697021.624856] [mm-modem-helpers.c:149] mm_create_device_identifier(): Device ID 'af9d40b4198b9faceb3d4b7389817b01d09fd76e'
<debug> [1367697021.632104] [mm-broadband-modem.c:927] modem_load_device_identifier_finish(): loaded device identifier: af9d40b4198b9faceb3d4b7389817b01d09fd76e
<debug> [1367697021.638430] [mm-broadband-modem.c:1518] modem_load_supported_modes(): loading initial supported modes...
<debug> [1367697021.644636] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697021.648512] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697021.652372] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+WS46=?<CR>'
<debug> [1367697021.690682] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>ERROR<CR><LF>'
<debug> [1367697021.694518] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
<debug> [1367697021.721317] [mm-broadband-modem.c:1455] supported_modes_ws46_test_ready(): Generic query of supported 3GPP networks failed: 'Unknown error'
<debug> [1367697021.746622] [mm-broadband-modem.c:1462] supported_modes_ws46_test_ready(): Assuming device allows (3GPP) 2G/3G network modes
<debug> [1367697021.773581] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697021.779811] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697021.783812] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CFUN?<CR>'
<debug> [1367697021.802372] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CFUN: 1<CR><LF>'
<debug> [1367697021.806519] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697021.810647] [mm-broadband-modem.c:1263] modem_load_unlock_required(): checking if unlock required...
<debug> [1367697021.817879] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697021.825081] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697021.863470] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CPIN?<CR>'
<debug> [1367697021.881999] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CPIN: READY<CR><LF>'
<debug> [1367697021.886796] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697021.890840] [mbm/mm-broadband-modem-mbm.c:712] load_unlock_retries(): loading unlock retries (mbm)...
<debug> [1367697021.931286] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697021.954516] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697021.996468] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT*EPIN?<CR>'
<debug> [1367697022.144458] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>*EPIN: 3,10,3,10,260,260,260,260,260<CR><LF>'
<debug> [1367697022.152048] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697022.158458] [mm-iface-modem.c:2715] update_lock_info_context_step(): SIM is ready, running after SIM unlock step...
<debug> [1367697022.162702] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697022.663893] [mm-sim.c:1077] load_sim_identifier(): loading SIM identifier...
<debug> [1367697022.686558] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697022.727079] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CRSM=176,12258,0,0,10<CR>'
<debug> [1367697022.813430] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CRSM: 144,0,"987480003500302230F8"<CR><LF>'
<debug> [1367697022.820983] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697022.846508] [mm-sim.c:1066] load_sim_identifier_finish(): loaded SIM identifier: 8947080053000322038
<debug> [1367697022.871996] [mm-sim.c:1147] load_imsi(): loading IMSI...
<debug> [1367697022.891246] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697022.914446] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697022.954509] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CIMI<CR>'
<debug> [1367697022.988456] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>242027200032203<CR><LF>'
<debug> [1367697023.000240] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697023.007443] [mm-sim.c:1136] load_imsi_finish(): loaded IMSI: 242027200032203
<debug> [1367697023.013682] [mm-sim.c:1283] load_operator_identifier(): loading Operator ID...
<debug> [1367697023.019885] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697023.023925] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697023.028268] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CRSM=176,28589,0,0,4<CR>'
<debug> [1367697023.078817] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CRSM: 144,0,"01000102"<CR><LF>'
<debug> [1367697023.082974] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697023.127774] [mm-sim.c:1394] load_operator_name(): loading Operator Name...
<debug> [1367697023.172771] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697023.232487] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697023.292316] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CRSM=176,28486,0,0,17<CR>'
<debug> [1367697023.361809] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CRSM: 144,0,"0054656C696FFFFFFFFFFFFFFFFFFFFFFF"<CR><LF>'
<debug> [1367697023.365953] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697023.404604] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697023.428154] [mm-broadband-modem.c:1141] modem_load_own_numbers(): loading own numbers...
<debug> [1367697023.435379] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697023.442786] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CNUM<CR>'
<debug> [1367697023.518704] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697023.525042] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697023.547193] [mm-broadband-modem.c:3055] modem_3gpp_load_imei(): loading IMEI...
<debug> [1367697023.569937] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697023.574180] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CGSN<CR>'
<debug> [1367697023.631931] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>359001040736584<CR><LF>'
<debug> [1367697023.638833] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697023.667943] [mm-broadband-modem.c:3046] modem_3gpp_load_imei_finish(): loaded IMEI: 359001040736584
<debug> [1367697023.674225] [mm-broadband-modem.c:3214] modem_3gpp_load_enabled_facility_locks(): loading enabled facility locks...
<debug> [1367697023.680400] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697023.684447] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697023.688488] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CLCK=?<CR>'
<debug> [1367697023.706503] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK:
("CS","PS","PF","SC","P2","AO","OI","OX","AI","IR","NT","NM","NS","NA","AB","AG","AC","PN","PU","PP","PC","PI","LL","AL","FD")<CR><LF>'
<debug> [1367697023.710426] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697023.736164] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697023.763022] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697023.769343] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CLCK="SC",2<CR>'
<debug> [1367697023.816285] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 1<CR><LF>'
<debug> [1367697023.820165] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697023.844647] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697023.866986] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697023.871072] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CLCK="FD",2<CR>'
<debug> [1367697023.908313] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF>'
<debug> [1367697023.915873] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697023.922207] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697023.928554] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697023.932233] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CLCK="PS",2<CR>'
<debug> [1367697023.961683] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF>'
<debug> [1367697023.965682] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697023.989780] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697024.034569] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697024.041769] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CLCK="PF",2<CR>'
<debug> [1367697024.068088] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CME ERROR: 4<CR><LF>'
<debug> [1367697024.074309] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 4: Operation not supported
<debug> [1367697024.097536] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697024.116948] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697024.121022] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CLCK="PN",2<CR>'
<debug> [1367697024.151023] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF>'
<debug> [1367697024.176875] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697024.238020] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697024.283473] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697024.343289] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CLCK="PU",2<CR>'
<debug> [1367697024.413970] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF>'
<debug> [1367697024.418124] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697024.441125] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697024.460237] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697024.467513] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CLCK="PP",2<CR>'
<debug> [1367697024.500050] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF>'
<debug> [1367697024.506393] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697024.512769] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697024.516836] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697024.520868] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CLCK="PC",2<CR>'
<debug> [1367697024.550249] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CLCK: 0<CR><LF>'
<debug> [1367697024.554485] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697024.581194] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697024.641566] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697024.699999] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CUSD=?<CR>'
<debug> [1367697024.753833] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CUSD: (0-2)<CR><LF>'
<debug> [1367697024.758028] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697024.781230] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697024.822178] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697024.826415] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CNMI=?<CR>'
<debug> [1367697024.847315] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CNMI: (0,2),(0-3),(0,2),(0,1),(0,1)<CR><LF>'
<debug> [1367697024.854563] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697024.860628] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697024.866600] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697024.870434] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CPMS=?<CR>'
<debug> [1367697024.908052] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: ("ME","SM"),("ME","SM"),("ME","SM")<CR><LF>'
<debug> [1367697024.913070] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697024.937858] [mm-iface-modem-messaging.c:1113] load_supported_storages_ready(): Supported storages loaded:
<debug> [1367697024.960001] [mm-iface-modem-messaging.c:1114] load_supported_storages_ready(): mem1 (list/read/delete) storages: 'me, sm'
<debug> [1367697024.987665] [mm-iface-modem-messaging.c:1115] load_supported_storages_ready(): mem2 (write/send) storages: 'me, sm'
<debug> [1367697025.012417] [mm-iface-modem-messaging.c:1116] load_supported_storages_ready(): mem3 (reception) storages: 'me, sm'
<debug> [1367697025.040306] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697025.063869] [mm-broadband-modem.c:8708] iface_modem_time_initialize_ready(): Couldn't initialize interface: 'Time not supported'
<debug> [1367697025.087238] [mm-broadband-modem.c:8709] iface_modem_firmware_initialize_ready(): Couldn't initialize interface: 'Firmware not supported'
<info> [1367697025.111538] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem: state changed (unknown -> disabled)
<debug> [1367697025.133752] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 0 (close)
<debug> [1367697025.181122] [mm-serial-port.c:1019] mm_serial_port_close(): (ttyACM0) closing serial port...
<debug> [1367697025.222594] [mm-serial-port.c:1050] mm_serial_port_close(): (ttyACM0) serial port closed
<debug> [1367697025.239662] [mm-base-modem.c:978] initialize_ready(): modem properly initialized
<debug> [1367697025.287789] [mm-device.c:414] export_modem(): Exported modem '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-4' at path '/org/freedesktop/ModemManager1/Modem/0'
<debug> [1367697025.309420] [mm-device.c:422] export_modem(): (/org/freedesktop/ModemManager1/Modem/0): 'Ericsson MBM' modem, VID 0x0BDB PID 0x1926 (usb)
<info> [1367697028.156391] [mm-iface-modem-simple.c:779] connect_auth_ready(): Simple connect started...
<debug> [1367697028.162256] [mm-iface-modem-simple.c:793] connect_auth_ready(): PIN: unspecified
<debug> [1367697028.186552] [mm-iface-modem-simple.c:797] connect_auth_ready(): Allowed mode: any
<debug> [1367697028.229912] [mm-iface-modem-simple.c:800] connect_auth_ready(): Preferred mode: none
<debug> [1367697028.256876] [mm-iface-modem-simple.c:809] connect_auth_ready(): Bands: unknown
<debug> [1367697028.302662] [mm-iface-modem-simple.c:814] connect_auth_ready(): Operator ID: unspecified
<debug> [1367697028.308912] [mm-iface-modem-simple.c:816] connect_auth_ready(): Allowed roaming: no
<debug> [1367697028.312746] [mm-iface-modem-simple.c:818] connect_auth_ready(): APN: internet.netcom.no
<debug> [1367697028.316819] [mm-iface-modem-simple.c:824] connect_auth_ready(): IP family: unspecified
<debug> [1367697028.340339] [mm-iface-modem-simple.c:832] connect_auth_ready(): Allowed authentication: unspecified
<debug> [1367697028.399671] [mm-iface-modem-simple.c:835] connect_auth_ready(): User: netcom
<debug> [1367697028.423231] [mm-iface-modem-simple.c:837] connect_auth_ready(): Password: netcom
<debug> [1367697028.430243] [mm-iface-modem-simple.c:839] connect_auth_ready(): Number: *99#
<info> [1367697028.436336] [mm-iface-modem-simple.c:544] connection_step(): Simple connect state (3/10): Enable
<info> [1367697028.442529] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabled -> enabling)
<debug> [1367697028.468377] [mm-serial-port.c:891] mm_serial_port_open(): (ttyACM0) opening serial port...
<debug> [1367697028.487288] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 1 (open)
<debug> [1367697028.491250] [mm-serial-port.c:891] mm_serial_port_open(): (ttyACM1) opening serial port...
<debug> [1367697028.495553] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM1) device open count is 1 (open)
<debug> [1367697028.502345] [mm-broadband-modem.c:7782] enabling_started(): Flashing primary AT port before enabling...
<debug> [1367697028.509622] [mm-broadband-modem.c:7671] enabling_flash_done(): Running modem initialization sequence...
<debug> [1367697028.515638] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697028.521882] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT&F<CR>'
<debug> [1367697028.529620] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- 'AT&F'
<debug> [1367697028.543900] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><CR><LF>OK<CR><LF><CR><LF>*EMRDY: 1<CR><LF>'
<debug> [1367697028.567886] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <-- '<CR><LF>*EMRDY: 1<CR><LF>'
<debug> [1367697028.610780] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT*ENAP=0<CR>'
<debug> [1367697028.663544] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- 'AT*ENAP=0'
<debug> [1367697028.672893] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><CR><LF>ERROR<CR><LF>'
<debug> [1367697028.719574] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
<debug> [1367697028.759766] [mm-broadband-modem.c:7653] enabling_modem_init_ready(): Giving some time to settle the modem...
<debug> [1367697028.796228] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697029.297925] [mm-at-serial-port.c:445] mm_at_serial_port_run_init_sequence(): (ttyACM0): running init sequence...
<debug> [1367697029.303507] [mm-at-serial-port.c:445] mm_at_serial_port_run_init_sequence(): (ttyACM1): running init sequence...
<debug> [1367697029.323513] [mm-iface-modem.c:2903] mm_iface_modem_set_power_state(): No need to change power state: already in 'on' power state
<debug> [1367697029.350213] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697029.409810] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697029.470080] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'ATE0<CR>'
<debug> [1367697029.528972] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): --> 'ATE0<CR>'
<debug> [1367697029.590917] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- 'ATE0'
<debug> [1367697029.606930] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><CR><LF>OK<CR><LF>'
<debug> [1367697029.631369] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <-- 'ATE0'
<debug> [1367697029.635532] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <-- '<CR><CR><LF>OK<CR><LF>'
<debug> [1367697029.642791] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'ATV1<CR>'
<debug> [1367697029.656510] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697029.669370] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CMEE=1<CR>'
<debug> [1367697029.702716] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697029.706719] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'ATX4<CR>'
<debug> [1367697029.737062] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697029.750584] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT&C1<CR>'
<debug> [1367697029.801849] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697029.817117] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+IFC=1,1<CR>'
<debug> [1367697029.878483] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>ERROR<CR><LF>'
<debug> [1367697029.884775] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
<debug> [1367697029.908715] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697029.967820] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CSCS=?<CR>'
<debug> [1367697030.039215] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CSCS: ('
<debug> [1367697030.046949] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '"GSM","IRA","8859-1","UTF-8","UCS2")<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1367697030.051101] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697030.058319] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697030.065615] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CSCS="UTF-8"<CR>'
<debug> [1367697030.109866] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697030.116095] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697030.154525] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697030.177560] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CSCS?<CR>'
<debug> [1367697030.193235] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CSCS: "'
<debug> [1367697030.200166] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- 'UTF-8"<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1367697030.226754] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697030.253728] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697030.293623] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CFUN?<CR>'
<debug> [1367697030.348319] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CFUN: 1<CR><LF>'
<debug> [1367697030.369898] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697030.414708] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697030.474510] [mm-broadband-modem.c:8326] enabling_step(): Modem has 3GPP capabilities, enabling the Modem 3GPP interface...
<debug> [1367697030.514728] [mm-broadband-modem.c:2414] modem_3gpp_setup_unsolicited_events(): Checking indicator support...
<debug> [1367697030.539933] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697030.566931] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CIND=?<CR>'
<debug> [1367697030.606822] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND:
("battchg",(0-5)),("signal",(0-5)),("batterywarning",(0-1)),("chargerconnected",(0-1)),("service",(0-1)),("sounder",(0-1)),("message",(0-1)),("call",(0-1)),("roam",(0-1)),("smsfull",(0-1)),("callsetup",(0-3)),("callheld",(0-1))<CR><LF>'
<debug> [1367697030.613000] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697030.638069] [mm-broadband-modem.c:2367] cind_format_check_ready(): Modem supports signal quality indications via CIND at index '2'(min: 0, max: 5)
<debug> [1367697030.659773] [mm-broadband-modem.c:2376] cind_format_check_ready(): Modem supports roaming indications via CIND at index '9'
<debug> [1367697030.680405] [mm-broadband-modem.c:2385] cind_format_check_ready(): Modem supports service indications via CIND at index '5'
<debug> [1367697030.723123] [mm-broadband-modem.c:2318] set_unsolicited_events_handlers(): (ttyACM0) Setting 3GPP unsolicited events handlers
<debug> [1367697030.746892] [mm-broadband-modem.c:2318] set_unsolicited_events_handlers(): (ttyACM1) Setting 3GPP unsolicited events handlers
<debug> [1367697030.773519] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697030.831814] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697030.872969] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CMER=3,0,0,1<CR>'
<debug> [1367697030.898698] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697030.902799] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM1) device open count is 2 (open)
<debug> [1367697030.926479] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697030.967937] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): --> 'AT+CMER=3,0,0,1<CR>'
<debug> [1367697030.996607] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697031.004162] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697031.028679] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM1) device open count is 1 (close)
<debug> [1367697031.069342] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT*ERINFO=1<CR>'
<debug> [1367697031.113307] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697031.117417] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT*E2NAP=1<CR>'
<debug> [1367697031.137774] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697031.142113] [mm-broadband-modem.c:3378] modem_3gpp_setup_unsolicited_registration_events(): (ttyACM0) setting up 3GPP unsolicited registration messages handlers
<debug> [1367697031.149278] [mm-broadband-modem.c:3378] modem_3gpp_setup_unsolicited_registration_events(): (ttyACM1) setting up 3GPP unsolicited registration messages handlers
<debug> [1367697031.169985] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697031.176199] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697031.215891] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CREG=2<CR>'
<debug> [1367697031.247794] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697031.270928] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM1) device open count is 2 (open)
<debug> [1367697031.311185] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697031.349975] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): --> 'AT+CREG=2<CR>'
<debug> [1367697031.407678] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697031.415047] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697031.439107] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM1) device open count is 1 (close)
<debug> [1367697031.478843] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CGREG=2<CR>'
<debug> [1367697031.532094] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697031.536542] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM1) device open count is 2 (open)
<debug> [1367697031.560938] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697031.583339] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): --> 'AT+CGREG=2<CR>'
<debug> [1367697031.625295] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697031.649953] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM1) device open count is 1 (close)
<debug> [1367697031.689874] [mm-iface-modem-3gpp.c:752] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
<debug> [1367697031.716793] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697031.740115] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CREG?<CR>'
<debug> [1367697031.773736] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 2,1,"12C5","012D5F41",2<CR><LF>'
<info> [1367697031.817883] [mm-iface-modem-3gpp.c:1148] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (unknown ->
registering)
<debug> [1367697031.836822] [mm-broadband-modem.c:3250] modem_3gpp_load_operator_code(): loading Operator Code...
<debug> [1367697031.884366] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<info> [1367697031.943218] [mm-iface-modem.c:726] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (unknown ->
umts)
<debug> [1367697032.002220] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697032.042112] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
<debug> [1367697032.081330] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
<debug> [1367697032.123471] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
<debug> [1367697032.169835] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,2,"24202",2 <CR><LF>'
<debug> [1367697032.173833] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697032.200491] [mm-broadband-modem.c:3240] modem_3gpp_load_operator_code_finish(): loaded Operator Code: 24202
<debug> [1367697032.241237] [mm-broadband-modem.c:3286] modem_3gpp_load_operator_name(): loading Operator Name...
<debug> [1367697032.266275] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
<debug> [1367697032.292181] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
<debug> [1367697032.318130] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CGREG?<CR>'
<debug> [1367697032.369836] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 2,1,"12C5","012D5F41",6<CR><LF>'
<info> [1367697032.376900] [mm-iface-modem.c:726] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (umts -> hspa)
<debug> [1367697032.421364] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697032.428677] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697032.435862] [mm-broadband-modem.c:8339] enabling_step(): Modem has 3GPP/USSD capabilities, enabling the Modem 3GPP/USSD interface...
<debug> [1367697032.441975] [mm-broadband-modem.c:4692] set_unsolicited_result_code_handlers(): (ttyACM0) Setting unsolicited result code handlers
<debug> [1367697032.448047] [mm-broadband-modem.c:4692] set_unsolicited_result_code_handlers(): (ttyACM1) Setting unsolicited result code handlers
<debug> [1367697032.452223] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697032.456278] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
<debug> [1367697032.629478] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,0,"Telio",2 <CR><LF>'
<debug> [1367697032.635479] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697032.659693] [mm-broadband-modem.c:3276] modem_3gpp_load_operator_name_finish(): loaded Operator Name: Telio
<debug> [1367697032.720677] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<info> [1367697032.746879] [mm-iface-modem-3gpp.c:1030] update_registration_reload_current_operator_ready(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state
changed (registering -> home)
<debug> [1367697032.786911] [mm-iface-modem.c:1372] get_updated_consolidated_state(): Will start keeping track of state for subsystem '3gpp'
<debug> [1367697032.831351] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CUSD=1<CR>'
<debug> [1367697032.884232] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697032.888524] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697032.910982] [mm-broadband-modem.c:8367] enabling_step(): Modem has location capabilities, enabling the Location interface...
<debug> [1367697032.933497] [mm-iface-modem-location.c:739] setup_gathering(): Need to enable the following location sources: '3gpp-lac-ci'
<debug> [1367697032.979774] [mm-iface-modem-3gpp.c:752] mm_iface_modem_3gpp_run_registration_checks(): Running registration checks (CS: 'yes', PS: 'yes', EPS: 'no')
<debug> [1367697033.040142] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697033.086764] [mm-broadband-modem.c:3250] modem_3gpp_load_operator_code(): loading Operator Code...
<debug> [1367697033.143198] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697033.147341] [mm-broadband-modem.c:8380] enabling_step(): Modem has messaging capabilities, enabling the Messaging interface...
<debug> [1367697033.151064] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 4 (open)
<debug> [1367697033.173124] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CREG?<CR>'
<debug> [1367697033.192895] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CREG: 2,1,"12C5","012D5F41",2<CR><LF>'
<info> [1367697033.220295] [mm-iface-modem.c:726] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (hspa -> umts)
<info> [1367697033.226538] [mm-iface-modem-location.c:293] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '0', MNC: '0',
Location area code: '12C5', Cell ID: '12D5F41')
<debug> [1367697033.253483] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697033.297650] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 5 (open)
<debug> [1367697033.341114] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 4 (close)
<debug> [1367697033.384001] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+COPS=3,2;+COPS?<CR>'
<debug> [1367697033.433441] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,2,"24202",2 <CR><LF>'
<debug> [1367697033.440798] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697033.466581] [mm-broadband-modem.c:3240] modem_3gpp_load_operator_code_finish(): loaded Operator Code: 24202
<info> [1367697033.513471] [mm-iface-modem-location.c:293] notify_3gpp_location_update(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP location updated (MCC: '242', MNC: '2',
Location area code: '12C5', Cell ID: '12D5F41')
<debug> [1367697033.559934] [mm-broadband-modem.c:3286] modem_3gpp_load_operator_name(): loading Operator Name...
<debug> [1367697033.604252] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 5 (open)
<debug> [1367697033.647799] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 4 (close)
<debug> [1367697033.651891] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CMGF=?<CR>'
<debug> [1367697033.669271] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CMGF: (0)<CR><LF>'
<debug> [1367697033.676499] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697033.683729] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 5 (open)
<debug> [1367697033.689871] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 4 (close)
<debug> [1367697033.696146] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CGREG?<CR>'
<debug> [1367697033.713509] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CGREG: 2,1,"12C5","012D5F41",6<CR><LF>'
<info> [1367697033.717510] [mm-iface-modem.c:726] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (umts -> hspa)
<debug> [1367697033.721229] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697033.724931] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 3 (close)
<debug> [1367697033.731883] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+COPS=3,0;+COPS?<CR>'
<debug> [1367697033.884785] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+COPS: 0,0,"Telio",2 <CR><LF>'
<debug> [1367697033.892494] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697033.918762] [mm-broadband-modem.c:3276] modem_3gpp_load_operator_name_finish(): loaded Operator Name: Telio
<debug> [1367697033.922752] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697033.926666] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CMGF=0<CR>'
<debug> [1367697033.944399] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697033.948438] [mm-broadband-modem.c:5269] cmgf_set_ready(): Successfully set preferred SMS mode: 'PDU'
<debug> [1367697033.973604] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697034.000061] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697034.039488] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CPMS="","ME","ME"<CR>'
<debug> [1367697034.094526] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CMS ERROR: 321<CR><LF>'
<debug> [1367697034.098452] [mm-serial-parsers.c:363] mm_serial_parser_v1_parse(): Got failure code 321: Invalid index
<debug> [1367697034.120762] [mm-iface-modem-messaging.c:779] set_default_storage_ready(): Couldn't set default storage: 'Invalid index'
<debug> [1367697034.140624] [mm-broadband-modem.c:5967] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'me'
<debug> [1367697034.144466] [mm-broadband-modem.c:5163] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (ME), mem2 (none)...
<debug> [1367697034.151450] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697034.158742] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697034.164892] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CPMS="ME"<CR>'
<debug> [1367697034.190909] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 2,30,0,20,2,30<CR><LF>'
<debug> [1367697034.197997] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697034.204929] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697034.228984] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697034.257664] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CMGL=4<CR>'
<debug> [1367697034.284140] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CMGL: 1,1,,159<CR><LF>'
<debug> [1367697034.295922] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <--
'06917429000171600BD0CE327DF86E03000031306071046140A00500037D020190E5340B647ECB410FD03CBD969741E4F219B47ECBE5E5351D9476BBE7F4349B9D769FCB7290F92D07359BD316E87D06B5DFE2349B0E4ABAE965B9BB4CA783E00F1039ED06D1CBECB2F9ED06D9D36C903D0D7AB741653A081E9683DA69779D4E2FCB41F3B29B5C0691CB6750DAED9ED3D36C76DA7D2ECB41F4341B442FB3CBE6B7BBEC768188<CR><LF>+CMGL:
2,1,,159<CR><LF>06917429000171640BD0CE327DF86E030000313060710461409F0500037D0202EA207B9A0D22874165F7BACCA683D675B7BB0C9A97DDE4F2ABFDA6D3C3A066730A7A9F4162797D5D06B5DFE2349B0E4ABAE965B9BB4CA7BB40CE871C44AE83DA6F3A3D2C07A5DDEE393DCD66A7DDE7B2BB0C42D7E76BD0034497E7D76B10337C96975FC1F5BC0CA797E5A0F71944AE83CA72501A740EBBCF2110089966CFCB6E10B5CC4EBF01<CR><LF><CR><LF>OK<CR><LF>'
<debug> [1367697034.303315] [mm-sms-part.c:518] mm_sms_part_new_from_binary_pdu(): Parsing PDU (1)...
<debug> [1367697034.307886] [mm-sms-part.c:547] mm_sms_part_new_from_binary_pdu(): SMSC address parsed: '+4792001017'
<debug> [1367697034.312145] [mm-sms-part.c:560] mm_sms_part_new_from_binary_pdu(): Deliver type PDU detected
<debug> [1367697034.334154] [mm-sms-part.c:621] mm_sms_part_new_from_binary_pdu(): Number parsed: 'NetCom'
<debug> [1367697034.353900] [mm-sms-part.c:725] mm_sms_part_new_from_binary_pdu(): PID: 0
<debug> [1367697034.358121] [mm-sms-part.c:736] mm_sms_part_new_from_binary_pdu(): user data encoding is GSM7
<debug> [1367697034.365675] [mm-sms-part.c:764] mm_sms_part_new_from_binary_pdu(): user data length: 160 elements
<debug> [1367697034.373162] [mm-sms-part.c:770] mm_sms_part_new_from_binary_pdu(): user data length: 140 bytes
<debug> [1367697034.379628] [mm-sms-part.c:845] mm_sms_part_new_from_binary_pdu(): Decoding SMS text with '153' elements
<debug> [1367697034.386190] [mm-sms-part.c:244] sms_decode_text(): Converting SMS part text from GSM7 to UTF8...
<debug> [1367697034.390344] [mm-sms-part.c:247] sms_decode_text(): Got UTF-8 text: 'Hei, for å sikre deg korrekt innstillinger for MMS- og mobilt Internett på din telefon vil vi om
et par minutter sende deg innstillinger til telefonen. D'
<debug> [1367697034.430595] [mm-broadband-modem.c:5900] sms_pdu_part_list_ready(): Correctly parsed PDU (1)
<debug> [1367697034.471483] [mm-sms-list.c:369] mm_sms_list_take_part(): SMS part at 'me/1' is from a multipart SMS (reference: '125', sequence: '1')
<debug> [1367697034.476186] [mm-iface-modem-messaging.c:500] sms_added(): Added received SMS at '/org/freedesktop/ModemManager1/SMS/0'
<debug> [1367697034.483602] [mm-sms-part.c:518] mm_sms_part_new_from_binary_pdu(): Parsing PDU (2)...
<debug> [1367697034.491470] [mm-sms-part.c:547] mm_sms_part_new_from_binary_pdu(): SMSC address parsed: '+4792001017'
<debug> [1367697034.536847] [mm-sms-part.c:560] mm_sms_part_new_from_binary_pdu(): Deliver type PDU detected
<debug> [1367697034.576476] [mm-sms-part.c:621] mm_sms_part_new_from_binary_pdu(): Number parsed: 'NetCom'
<debug> [1367697034.613998] [mm-sms-part.c:725] mm_sms_part_new_from_binary_pdu(): PID: 0
<debug> [1367697034.654510] [mm-sms-part.c:736] mm_sms_part_new_from_binary_pdu(): user data encoding is GSM7
<debug> [1367697034.658609] [mm-sms-part.c:764] mm_sms_part_new_from_binary_pdu(): user data length: 159 elements
<debug> [1367697034.662541] [mm-sms-part.c:770] mm_sms_part_new_from_binary_pdu(): user data length: 140 bytes
<debug> [1367697034.669442] [mm-sms-part.c:845] mm_sms_part_new_from_binary_pdu(): Decoding SMS text with '152' elements
<debug> [1367697034.676671] [mm-sms-part.c:244] sms_decode_text(): Converting SMS part text from GSM7 to UTF8...
<debug> [1367697034.682692] [mm-sms-part.c:247] sms_decode_text(): Got UTF-8 text: 'u vil da enkelt kunne sende/motta MMS og bruke mobilt Internett. Når du mottar innstillingene
husk å trykk Lagre/Aksepter og du er i gang! Hilsen Telio'
<debug> [1367697034.689025] [mm-broadband-modem.c:5900] sms_pdu_part_list_ready(): Correctly parsed PDU (2)
<debug> [1367697034.693129] [mm-sms-list.c:369] mm_sms_list_take_part(): SMS part at 'me/2' is from a multipart SMS (reference: '125', sequence: '2')
<debug> [1367697034.697307] [mm-broadband-modem.c:5967] modem_messaging_load_initial_sms_parts(): Listing SMS parts in storage 'sm'
<debug> [1367697034.701520] [mm-broadband-modem.c:5163] mm_broadband_modem_lock_sms_storages(): Locking SMS storages to: mem1 (SM), mem2 (none)...
<debug> [1367697034.705700] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697034.747816] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697034.788221] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CPMS="SM"<CR>'
<debug> [1367697034.845877] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CPMS: 0,20,0,20,2,30<CR><LF>'
<debug> [1367697034.892212] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697034.930157] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697034.934397] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697034.938484] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CMGL=4<CR>'
<debug> [1367697034.992729] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697035.019806] [mm-broadband-modem.c:5575] set_messaging_unsolicited_events_handlers(): (ttyACM0) Setting messaging unsolicited events handlers
<debug> [1367697035.027085] [mm-broadband-modem.c:5575] set_messaging_unsolicited_events_handlers(): (ttyACM1) Setting messaging unsolicited events handlers
<debug> [1367697035.033379] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697035.039570] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697035.043616] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CNMI=2,1,2,1,0<CR>'
<debug> [1367697035.111881] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697035.116083] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<info> [1367697035.134436] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (enabling -> registered)
<debug> [1367697035.160344] [mm-iface-modem.c:1127] periodic_signal_quality_check_enable(): Periodic signal quality checks enabled (interval = 3s)
<debug> [1367697035.180519] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
<debug> [1367697035.226720] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697035.252526] [mm-iface-modem.c:844] periodic_access_technologies_check_enable(): Periodic access technology checks enabled
<info> [1367697035.291271] [mm-iface-modem-simple.c:552] connection_step(): Simple connect state (4/10): Wait to get fully enabled
<info> [1367697035.350565] [mm-iface-modem-simple.c:564] connection_step(): Simple connect state (5/10): Allowed mode
<debug> [1367697035.373742] [mm-iface-modem-simple.c:311] set_allowed_modes_ready(): Will wait to settle down after updating allowed modes
<debug> [1367697035.416581] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
<debug> [1367697035.455363] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
<debug> [1367697035.462809] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697035.469026] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<info> [1367697035.475341] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (100)
<debug> [1367697035.478832] [mm-iface-modem.c:1046] signal_quality_check_ready(): Periodic signal quality checks rescheduled (interval = 30s)
<info> [1367697037.889455] [mm-iface-modem-simple.c:587] connection_step(): Simple connect state (6/10): Bands
<info> [1367697037.895662] [mm-iface-modem-simple.c:616] connection_step(): Simple connect state (7/10): Register
<debug> [1367697037.917663] [mm-iface-modem-3gpp.c:391] mm_iface_modem_3gpp_register_in_network(): Already registered in network '24202', automatic registration not launched...
<info> [1367697037.924816] [mm-iface-modem-simple.c:639] connection_step(): Simple connect state (8/10): Bearer
<debug> [1367697037.932011] [mm-iface-modem-simple.c:659] connection_step(): Creating new bearer...
<debug> [1367697037.955592] [mbm/mm-broadband-modem-mbm.c:127] modem_create_bearer(): Creating MBM bearer...
<debug> [1367697037.976966] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697037.980548] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697038.003601] [mbm/mm-broadband-modem-mbm.c:90] modem_create_bearer_finish(): New MBM bearer created at DBus path '/org/freedesktop/ModemManager1/Bearer/0'
<info> [1367697038.063415] [mm-iface-modem-simple.c:721] connection_step(): Simple connect state (9/10): Connect
<debug> [1367697038.067285] [mm-bearer.c:512] mm_bearer_connect(): Connecting bearer '/org/freedesktop/ModemManager1/Bearer/0'
<info> [1367697038.109789] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> connecting)
<debug> [1367697038.117271] [mm-broadband-bearer.c:173] detailed_connect_context_new(): No specific IP family requested, defaulting to ipv4
<debug> [1367697038.123176] [mm-broadband-bearer.c:1015] connect_3gpp(): Looking for best CID...
<debug> [1367697038.129285] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697038.173364] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CGDCONT?<CR>'
<debug> [1367697038.224204] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CGDCONT: 1,"IP","internet.netcom.no","0.0.0.0",0,0<CR><LF>'
<debug> [1367697038.239650] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '+CGDCONT: 2,"IP","vpn.netcom.no","0.0.0.0",0,0<CR><LF>'
<debug> [1367697038.243839] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697038.269469] [mm-broadband-bearer.c:939] parse_pdp_list(): Found '2' PDP contexts
<debug> [1367697038.276193] [mm-broadband-bearer.c:946] parse_pdp_list(): PDP context [cid=1] [type='ipv4'] [apn='internet.netcom.no']
<debug> [1367697038.282184] [mm-broadband-bearer.c:946] parse_pdp_list(): PDP context [cid=2] [type='ipv4'] [apn='vpn.netcom.no']
<debug> [1367697038.288040] [mm-broadband-bearer.c:967] parse_pdp_list(): Found PDP context with CID 1 and PDP type ipv4 for APN 'internet.netcom.no'
<debug> [1367697038.346523] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697038.350570] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697038.370373] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CGDCONT=1,"IP","internet.netcom.no"<CR>'
<debug> [1367697038.494778] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697038.503593] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697038.531061] [mm-bearer.c:387] connect_ready(): Couldn't connect bearer '/org/freedesktop/ModemManager1/Bearer/0': 'No valid data port found to launch connection'
<info> [1367697038.555803] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (connecting -> registered)
<debug> [1367697038.597105] [mm-iface-modem-simple.c:228] connect_bearer_ready(): Couldn't connect bearer: 'No valid data port found to launch connection'
<debug> [1367697065.926986] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
<debug> [1367697065.933552] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697065.937817] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
<debug> [1367697065.972476] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
<debug> [1367697066.021217] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697066.067807] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<info> [1367697066.126870] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (100)
<debug> [1367697095.915246] [mm-broadband-modem.c:1833] modem_load_signal_quality(): loading signal quality...
<debug> [1367697095.924927] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697095.931065] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CIND?<CR>'
<debug> [1367697095.950272] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>+CIND: 5,5,0,0,1,1,0,0,0,0,0,0<CR><LF>'
<debug> [1367697095.954385] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697095.958374] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<info> [1367697095.962425] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (100)
<info> [1367697099.072774] [main.c:46] quit_cb(): Caught signal, shutting down...
<info> [1367697099.082222] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (registered -> disabling)
<info> [1367697099.089248] [mm-iface-modem.c:976] update_signal_quality(): Modem /org/freedesktop/ModemManager1/Modem/0: signal quality updated (0)
<debug> [1367697099.129128] [mm-iface-modem.c:1093] periodic_signal_quality_check_disable(): Periodic signal quality checks disabled
<info> [1367697099.148435] [mm-iface-modem.c:726] mm_iface_modem_update_access_technologies(): Modem /org/freedesktop/ModemManager1/Modem/0: access technology changed (hspa ->
unknown)
<debug> [1367697099.152169] [mm-iface-modem.c:816] periodic_access_technologies_check_disable(): Periodic access technology checks disabled
<debug> [1367697099.156484] [mm-broadband-modem.c:8020] disabling_step(): Modem has messaging capabilities, disabling the Messaging interface...
<debug> [1367697099.160445] [mm-broadband-modem.c:5575] set_messaging_unsolicited_events_handlers(): (ttyACM0) Removing messaging unsolicited events handlers
<debug> [1367697099.165615] [mm-broadband-modem.c:5575] set_messaging_unsolicited_events_handlers(): (ttyACM1) Removing messaging unsolicited events handlers
<debug> [1367697099.194178] [mm-broadband-modem.c:8032] disabling_step(): Modem has location capabilities, disabling the Location interface...
<debug> [1367697099.220411] [mm-iface-modem-location.c:745] setup_gathering(): Need to disable the following location sources: '3gpp-lac-ci'
<debug> [1367697099.259440] [mm-iface-modem-location.c:660] setup_gathering_step(): Disabled location '3gpp-lac-ci' gathering...
<debug> [1367697099.297928] [mm-broadband-modem.c:8060] disabling_step(): Modem has 3GPP/USSD capabilities, disabling the Modem 3GPP/USSD interface...
<debug> [1367697099.317443] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697099.340080] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CUSD=0<CR>'
<debug> [1367697099.376737] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697099.405501] [mm-broadband-modem.c:4692] set_unsolicited_result_code_handlers(): (ttyACM0) Removing unsolicited result code handlers
<debug> [1367697099.451276] [mm-broadband-modem.c:4692] set_unsolicited_result_code_handlers(): (ttyACM1) Removing unsolicited result code handlers
<debug> [1367697099.457790] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697099.464487] [mm-broadband-modem.c:8072] disabling_step(): Modem has 3GPP capabilities, disabling the Modem 3GPP interface...
<debug> [1367697099.469587] [mm-iface-modem-3gpp.c:1306] periodic_registration_check_disable(): Periodic 3GPP registration checks disabled
<debug> [1367697099.473422] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697099.497302] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CREG=0<CR>'
<debug> [1367697099.532532] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697099.581418] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM1) device open count is 2 (open)
<debug> [1367697099.624569] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697099.649767] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): --> 'AT+CREG=0<CR>'
<debug> [1367697099.667527] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697099.715583] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697099.774662] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM1) device open count is 1 (close)
<debug> [1367697099.833261] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CGREG=0<CR>'
<debug> [1367697099.885700] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697099.916278] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM1) device open count is 2 (open)
<debug> [1367697099.937686] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697099.944392] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): --> 'AT+CGREG=0<CR>'
<debug> [1367697099.962424] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697099.967342] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM1) device open count is 1 (close)
<debug> [1367697099.971289] [mm-broadband-modem.c:3432] modem_3gpp_cleanup_unsolicited_registration_events(): (ttyACM0) cleaning up unsolicited registration messages handlers
<debug> [1367697099.975076] [mm-broadband-modem.c:3432] modem_3gpp_cleanup_unsolicited_registration_events(): (ttyACM1) cleaning up unsolicited registration messages handlers
<debug> [1367697099.979192] [mm-broadband-modem.c:2318] set_unsolicited_events_handlers(): (ttyACM0) Removing 3GPP unsolicited events handlers
<debug> [1367697100.005103] [mm-broadband-modem.c:2318] set_unsolicited_events_handlers(): (ttyACM1) Removing 3GPP unsolicited events handlers
<debug> [1367697100.051342] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 2 (open)
<debug> [1367697100.109559] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT*ERINFO=0<CR>'
<debug> [1367697100.131950] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697100.154850] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT*E2NAP=0<CR>'
<debug> [1367697100.212440] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697100.216355] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM0) device open count is 3 (open)
<debug> [1367697100.220345] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 2 (close)
<debug> [1367697100.226017] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): --> 'AT+CMER=0<CR>'
<debug> [1367697100.244950] [mm-at-serial-port.c:408] debug_log(): (ttyACM0): <-- '<CR><LF>OK<CR><LF>'
<debug> [1367697100.252786] [mm-serial-port.c:958] mm_serial_port_open(): (ttyACM1) device open count is 2 (open)
<debug> [1367697100.258841] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 1 (close)
<debug> [1367697100.264105] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): --> 'AT+CMER=0<CR>'
<debug> [1367697100.316176] [mm-at-serial-port.c:408] debug_log(): (ttyACM1): <-- '<CR><LF>OK<CR><LF>'
<info> [1367697100.344633] [mm-iface-modem-3gpp.c:1181] update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0: 3GPP Registration state changed (home -> unknown)
<debug> [1367697100.368064] [mm-bearer.c:220] modem_3gpp_registration_state_changed(): Bearer not allowed to connect, not registered in 3GPP network
<debug> [1367697100.373697] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM1) device open count is 1 (close)
<debug> [1367697100.401796] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM0) device open count is 0 (close)
<debug> [1367697100.419622] [mm-serial-port.c:1019] mm_serial_port_close(): (ttyACM0) closing serial port...
<debug> [1367697100.427281] [mm-serial-port.c:1050] mm_serial_port_close(): (ttyACM0) serial port closed
<debug> [1367697100.432174] [mm-serial-port.c:1003] mm_serial_port_close(): (ttyACM1) device open count is 0 (close)
<debug> [1367697100.435890] [mm-serial-port.c:1019] mm_serial_port_close(): (ttyACM1) closing serial port...
<debug> [1367697100.440608] [mm-serial-port.c:1050] mm_serial_port_close(): (ttyACM1) serial port closed
<info> [1367697100.444287] [mm-iface-modem.c:1203] __iface_modem_update_state_internal(): Modem /org/freedesktop/ModemManager1/Modem/0: state changed (disabling -> disabled)
<debug> [1367697100.450584] [mm-bearer.c:817] mm_bearer_dbus_unexport(): Removing from DBus bearer at '/org/freedesktop/ModemManager1/Bearer/0'
<debug> [1367697100.458319] [mm-device.c:362] unexport_modem(): Unexported modem '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-4' from path '/org/freedesktop/ModemManager1/Modem/0'
<debug> [1367697100.476122] [mm-serial-port.c:1118] mm_serial_port_close_force(): (ttyACM0) forced to close port
<debug> [1367697100.502696] [mm-serial-port.c:1118] mm_serial_port_close_force(): (ttyACM1) forced to close port
<debug> [1367697100.507747] [mm-serial-port.c:1118] mm_serial_port_close_force(): (ttyACM2) forced to close port
<debug> [1367697100.511611] [mm-base-modem.c:1509] finalize(): Modem (Ericsson MBM) '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-4' completely disposed
<info> [1367697100.517316] [main.c:179] main(): ModemManager is shut down


--
Geir Haatveit.
Message has been deleted

Bjørn Mork

unread,
May 4, 2013, 4:22:57 PM5/4/13
to
Geir Haatveit <nos...@haatveit.nu> writes:

> Prøvde så uten prefer_mbim=N, men resultatet var dessverre ikke oppløftende:

Nei, det kan du si.


> <debug> [1367697017.475613] [mm-plugin.c:772] mm_plugin_supports_port(): (Ericsson MBM) [cdc-wdm0] probe required: 'mbim'
> <debug> [1367697017.482773] [mm-port-probe.c:1331] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'mbim'

> <debug> [1367697018.137668] [mm-plugin.c:772] mm_plugin_supports_port(): (Ericsson MBM) [cdc-wdm2] probe required: 'mbim'
> <debug> [1367697018.176998] [mm-port-probe.c:1331] mm_port_probe_run(): (usbmisc/cdc-wdm2) launching port probing: 'mbim'

> <debug> [1367697018.407780] [mm-plugin.c:772] mm_plugin_supports_port(): (Ericsson MBM) [cdc-wdm1] probe required: 'mbim'
> <debug> [1367697018.427487] [mm-port-probe.c:1331] mm_port_probe_run(): (usbmisc/cdc-wdm1) launching port probing: 'mbim'

Dette ser riv ruskende galt ut. MM burde bare probe den sannsynlige
MBIM-porten. Hmm, jeg ser at måten Aleksander implmenterte det filteret
på er per modem, og ikke per port. Dermed ender du opp på den måten
der.

Det gjør jo forsåvidt ikke noe galt, men det er ikke bra heller. Tror
jeg skal klage litt igjen...


> <debug> [1367697018.618970] [mm-port-probe.c:317] mm_port_probe_set_result_mbim(): (usbmisc/cdc-wdm0) port is not MBIM-capable
> <debug> [1367697018.623238] [mm-port-probe.c:317] mm_port_probe_set_result_mbim(): (usbmisc/cdc-wdm2) port is not MBIM-capable
> <debug> [1367697018.627512] [mm-port-probe.c:317] mm_port_probe_set_result_mbim(): (usbmisc/cdc-wdm1) port is not MBIM-capable

Uansett burde jo én av disse lykkes. Med dette resultatet så er jo
resten lite interessant. Men jeg savner debug-output fra libmbim her.
Hvorfor får du ikke det? Trodde det ble automatisk enablet når du slo
på ModemManager debugging.

For sammenligningens skyld, slik ser det ut hos meg ifm MBIM-probingen:


ModemManager[3075]: <debug> [1367698625.869614] [mm-plugin.c:772] mm_plugin_supports_port(): (Generic) [cdc-wdm0] probe required: 'mbim'
ModemManager[3075]: <debug> [1367698625.869780] [mm-port-probe.c:1331] mm_port_probe_run(): (usbmisc/cdc-wdm0) launching port probing: 'mbim'
..
ModemManager[3075]: [/dev/cdc-wdm0] Queried max control message size: 4096
ModemManager[3075]: [/dev/cdc-wdm0] Sent message...
<<<<<< RAW:
<<<<<< length = 16
<<<<<< data = 01:00:00:00:10:00:00:00:01:00:00:00:00:10:00:00
ModemManager[3075]: [/dev/cdc-wdm0] Sent message (translated)...
<<<<<< Header:
<<<<<< length = 16
<<<<<< type = open (0x00000001)
<<<<<< transaction = 1
<<<<<< Contents:
<<<<<< max_control_transfer = 4096
ModemManager[3075]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 16
>>>>>> data = 02:00:00:80:10:00:00:00:0D:00:00:00:00:00:00:00
ModemManager[3075]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 16
>>>>>> type = close-done (0x80000002)
>>>>>> transaction = 13
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
ModemManager[3075]: [/dev/cdc-wdm0] No transaction matched in received message
ModemManager[3075]: [/dev/cdc-wdm0] Received message...
>>>>>> RAW:
>>>>>> length = 16
>>>>>> data = 01:00:00:80:10:00:00:00:01:00:00:00:00:00:00:00
ModemManager[3075]: [/dev/cdc-wdm0] Received message (translated)...
>>>>>> Header:
>>>>>> length = 16
>>>>>> type = open-done (0x80000001)
>>>>>> transaction = 1
>>>>>> Contents:
>>>>>> status error = 'None' (0x00000000)
ModemManager[3075]: <debug> [1367698628.744516] [mm-port-probe.c:300] mm_port_probe_set_result_mbim(): (usbmisc/cdc-wdm0) port is MBIM-capable


Bjørn
--
You're probably Polish yourself

Bjørn Mork

unread,
May 4, 2013, 5:03:11 PM5/4/13
to
Geir Haatveit <nos...@haatveit.nu> writes:
> In no.it.os.unix.linux.diverse Geir Haatveit <nos...@haatveit.nu> wrote:

>> Da fikk jeg installert modemmanager fra git, og testet at alt fungerte
>> med prefer_mbim=N.
>>
>> Prøvde så uten prefer_mbim=N, men resultatet var dessverre ikke oppløftende:
>>
>> Feilmeldingen er etter hva jeg kan se "[mm-bearer.c:220] modem_3gpp_registration_state_changed():
>> Bearer not allowed to connect, not registered in 3GPP network"
>
> Kan legge til at jeg testet et par ganger til i tilfelle det var
> noe med nettet (forstod feilmeldingen som om oppkoblingen ble avvist
> av nettopperatøren), men fikk samme resultat. Restartet med prefer_mbim=N,
> og koblet opp på første forsøk.

Det er jo bra at det fremdeles virker.

Den direkte årsaken til at oppkoblinge feiler med MBIM enablet er dette:

<debug> [1367697038.531061] [mm-bearer.c:387] connect_ready(): Couldn't connect bearer '/org/freedesktop/ModemManager1/Bearer/0': 'No valid data port found to launch connection'

I teorien burde det kunne funket med PPP, men det er jo totalt
uinteressant. Bedre å finne ut hvorfor MBIM-porten ikke blir funnet.

Du har absolutt siste versjon av libmbim? Du trenger commit a9ec433ba
for å få satt korrekt meldingsstørrelse. Hmmmm, det er først nå jeg
leser hva Aleksander skrev der. Ser ut til at jeg har satt et par øl på
at den kommer til å virke...


Bjørn
--
Ignorant young ignoramus
Message has been deleted

Bjørn Mork

unread,
May 5, 2013, 3:30:09 AM5/5/13
to
Geir Haatveit <nos...@haatveit.nu> writes:
> Hvordan finner jeg commit?
>
> Jeg kjørte git clone git://gitorious.org/lanedo/libmbim.git
>
> "master changed from 636992e to cf0dc94" står det som siste oppdatering
> den 17. april. https://gitorious.org/lanedo/libmbim

Da har du den (men i en annen versjon siden Aleksander rebaset ifm
flyttingen til offisielt repo). Mer enn en smule forvirrende, men
argumentet var at gitorious-repoet bare var et uoffisielt leke-repo.

Pga denne endringen så vil jo ikke dette funke, men det er lett å se
f.eks. hvilke lokale brancher som inneholder en bestemt commit:

git branch --contains a9ec433ba

Og om du vil se hva som skjedde mellom 636992e og cf0dc94, så er
forskjellige varianter av "git log" utmerket. Jeg liker

git log --oneline 636992e..cf0dc94

som gir hele lista med tittel og commit id. Du vil da oppdage at dette
bare er én commit som endrer versjonsnummeret.

(Og 636992e er commiten jeg lurte på om du hadde, men i gitorious
versjon.)


> Mulig dette ikke er siste versjon? Da kan det være lys
> i tunnelen likevel :-).

Nei, det er nok ikke dette som er problemet. Men du burde uansett endre
til å bruke git://anongit.freedesktop.org/libmbim/libmbim.git selv om
det i praksis ikke er noen forskjell i øyeblikket.

Merk at dette som sagt er en rebaset utgave, så om du ikke har noen egne
ting lokalt er det antageligvis like greit å klone på nytt.



Bjørn
--
You're a typical Jewish person, totally criminal
0 new messages