ptxdist-2013.12.0 + ModemManager + Cinterion PLS8 rel.1
Dan Williams
dcbw at redhat.com
Mon Aug 31 09:09:50 PDT 2015
On Mon, 2015-08-31 at 13:17 +0100, Raul Suarez Moreno wrote:
> Finally, ModemManager detected my modem. Problem was the udev startup
> script, udevd was not being correctly initiated.
>
> Now, "mmcli -L" shows my modem device correctly. I could enable it and even
> send a text SMS, but I am not being successful at using "mmcli -m 0
> --simple-connect", always getting:
>
> *error: couldn't connect the modem: 'Timeout was reached'*
>From the traces, I can't figure out why MM thinks the modem isn't
registered. Are you able to rebuild MM with some small patches to
further debug the issue?
Dan
> And if I use the command with "--timeout=300", the error returned is
> because of Network timeout. I've enabled --debug mode in ModemManager and
> here it is the log file extract when trying the --simple-connect:
>
> *<info> [947188221.002169] [mm-iface-modem-simple.c:641]
> connect_auth_ready(): Simple connect started...*
> *<debug> [947188221.022600] [mm-iface-modem-simple.c:651]
> connect_auth_ready(): PIN: unspecified*
> *<debug> [947188221.030484] [mm-iface-modem-simple.c:653]
> connect_auth_ready(): Operator ID: unspecified*
> *<debug> [947188221.038286] [mm-iface-modem-simple.c:655]
> connect_auth_ready(): Allowed roaming: yes*
> *<debug> [947188221.047354] [mm-iface-modem-simple.c:657]
> connect_auth_ready(): APN: airtelnet.es <http://airtelnet.es>*
> *<debug> [947188221.056593] [mm-iface-modem-simple.c:665]
> connect_auth_ready(): IP family: unspecified*
> *<debug> [947188221.064477] [mm-iface-modem-simple.c:673]
> connect_auth_ready(): Allowed authentication: unspecified*
> *<debug> [947188221.073221] [mm-iface-modem-simple.c:675]
> connect_auth_ready(): User: vodafone*
> *<debug> [947188221.081010] [mm-iface-modem-simple.c:677]
> connect_auth_ready(): Password: vodafone*
> *<debug> [947188221.088754] [mm-iface-modem-simple.c:679]
> connect_auth_ready(): Number: unspecified*
> *<info> [947188221.097630] [mm-iface-modem-simple.c:469]
> connection_step(): Simple connect state (4/8): Wait to get fully enabled*
> *<info> [947188221.107283] [mm-iface-modem-simple.c:478]
> connection_step(): Simple connect state (5/8): Register*
> *<debug> [947188221.115731] [mm-iface-modem-3gpp.c:397]
> mm_iface_modem_3gpp_register_in_network(): Launching automatic network
> registration...*
> *[/dev/cdc-wdm0] Sent message...*
> *<<<<<< RAW:*
> *<<<<<< length = 17*
> *<<<<<< data = 01:10:00:00:03:04:00:35:00:22:00:04:00:01:01:00:01*
> *[/dev/cdc-wdm0] Sent message (translated)...*
> *<<<<<< QMUX:*
> *<<<<<< length = 16*
> *<<<<<< flags = 0x00*
> *<<<<<< service = "nas"*
> *<<<<<< client = 4*
> *<<<<<< QMI:*
> *<<<<<< flags = "none"*
> *<<<<<< transaction = 53*
> *<<<<<< tlv_length = 4*
> *<<<<<< message = "Initiate Network Register" (0x0022)*
> *<<<<<< TLV:*
> *<<<<<< type = "Action" (0x01)*
> *<<<<<< length = 1*
> *<<<<<< value = 01*
> *<<<<<< translated = automatic*
> *[/dev/cdc-wdm0] Received message...*
> *>>>>>> RAW:*
> *>>>>>> length = 20*
> *>>>>>> data =
> 01:13:00:80:03:04:02:35:00:22:00:07:00:02:04:00:00:00:00:00*
> *[/dev/cdc-wdm0] Received message (translated)...*
> *>>>>>> QMUX:*
> *>>>>>> length = 19*
> *>>>>>> flags = 0x80*
> *>>>>>> service = "nas"*
> *>>>>>> client = 4*
> *>>>>>> QMI:*
> *>>>>>> flags = "response"*
> *>>>>>> transaction = 53*
> *>>>>>> tlv_length = 7*
> *>>>>>> message = "Initiate Network Register" (0x0022)*
> *>>>>>> TLV:*
> *>>>>>> type = "Result" (0x02)*
> *>>>>>> length = 4*
> *>>>>>> value = 00:00:00:00*
> *>>>>>> translated = SUCCESS*
> *<debug> [947188221.161321] [mm-iface-modem-3gpp.c:751]
> mm_iface_modem_3gpp_run_registration_checks(): Running registration checks
> (CS: 'yes', PS: 'yes', EPS: 'no')*
> *[/dev/cdc-wdm0] Sent message...*
> *<<<<<< RAW:*
> *<<<<<< length = 13*
> *<<<<<< data = 01:0C:00:00:03:04:00:36:00:4D:00:00:00*
> *[/dev/cdc-wdm0] Sent message (translated)...*
> *<<<<<< QMUX:*
> *<<<<<< length = 12*
> *<<<<<< flags = 0x00*
> *<<<<<< service = "nas"*
> *<<<<<< client = 4*
> *<<<<<< QMI:*
> *<<<<<< flags = "none"*
> *<<<<<< transaction = 54*
> *<<<<<< tlv_length = 0*
> *<<<<<< message = "Get System Info" (0x004D)*
> *[/dev/cdc-wdm0] Received message...*
> *>>>>>> RAW:*
> *>>>>>> length = 97*
> *>>>>>> data =
> 01:60:00:80:03:04:02:36:00:4D:00:54:00:02:04:00:00:00:00:00:12:03:00:00:00:00:13:03:00:00:00:00:14:03:00:02:02:00:19:1D:00:01:03:01:03:01:00:01:00:00:FF:FF:01:02:0B:66:05:00:00:00:01:32:31*
> *:34:30:31:FF:01:02:11:1E:02:00:FF:FF:21:01:00:01:27:04:00:01:00:00:00:29:01:00:00:2A:04:00:00:00:00:00*
> *[/dev/cdc-wdm0] Received message (translated)...*
> *>>>>>> QMUX:*
> *>>>>>> length = 96*
> *>>>>>> flags = 0x80*
> *>>>>>> service = "nas"*
> *>>>>>> client = 4*
> *>>>>>> QMI:*
> *>>>>>> flags = "response"*
> *>>>>>> transaction = 54*
> *>>>>>> tlv_length = 84*
> *>>>>>> message = "Get System Info" (0x004D)*
> *>>>>>> TLV:*
> *>>>>>> type = "Result" (0x02)*
> *>>>>>> length = 4*
> *>>>>>> value = 00:00:00:00*
> *>>>>>> translated = SUCCESS*
> *>>>>>> TLV:*
> *>>>>>> type = "GSM Service Status" (0x12)*
> *>>>>>> length = 3*
> *>>>>>> value = 00:00:00*
> *>>>>>> translated = [ service_status = 'none' true_service_status =
> 'none' preferred_data_path = 'no' ]*
> *>>>>>> TLV:*
> *>>>>>> type = "WCDMA Service Status" (0x13)*
> *>>>>>> length = 3*
> *>>>>>> value = 00:00:00*
> *>>>>>> translated = [ service_status = 'none' true_service_status =
> 'none' preferred_data_path = 'no' ]*
> *>>>>>> TLV:*
> *>>>>>> type = "LTE Service Status" (0x14)*
> *>>>>>> length = 3*
> *>>>>>> value = 02:02:00*
> *>>>>>> translated = [ service_status = 'available' true_service_status =
> 'available' preferred_data_path = 'no' ]*
> *>>>>>> TLV:*
> *>>>>>> type = "LTE System Info" (0x19)*
> *>>>>>> length = 29*
> *>>>>>> value =
> 01:03:01:03:01:00:01:00:00:FF:FF:01:02:0B:66:05:00:00:00:01:32:31:34:30:31:FF:01:02:11*
> *>>>>>> translated = [ domain_valid = 'yes' domain = 'cs-ps'
> service_capability_valid = 'yes' service_capability = 'cs-ps'
> roaming_status_valid = 'yes' roaming_status = 'off' forbidden_valid = 'yes'
> forbid*
> *den = 'no' lac_valid = 'no' lac = '65535' cid_valid = 'yes' cid =
> '90573570' registration_reject_info_valid = 'no' registration_reject_domain
> = 'none' registration_reject_cause = '0' network_id_valid = 'yes*
> *' mcc = '214' mnc = '01�' tac_valid = 'yes' tac = '4354' ]*
> *>>>>>> TLV:*
> *>>>>>> type = "Additional LTE System Info" (0x1e)*
> *>>>>>> length = 2*
> *>>>>>> value = FF:FF*
> *>>>>>> translated = [ geo_system_index = '65535' ]*
> *>>>>>> TLV:*
> *>>>>>> type = "LTE Voice Support" (0x21)*
> *>>>>>> length = 1*
> *>>>>>> value = 01*
> *>>>>>> translated = yes*
> *>>>>>> TLV:*
> *>>>>>> type = "SIM Reject Info" (0x27)*
> *>>>>>> length = 4*
> *>>>>>> value = 01:00:00:00*
> *>>>>>> translated = vailable*
> *>>>>>> TLV:*
> *>>>>>> type = 0x29*
> *>>>>>> length = 1*
> *>>>>>> value = 00*
> *>>>>>> TLV:*
> *>>>>>> type = 0x2a*
> *>>>>>> length = 4*
> *>>>>>> value = 00:00:00:00*
> *<info> [947188221.227560] [mm-iface-modem-3gpp.c:1169]
> update_registration_state(): Modem /org/freedesktop/ModemManager1/Modem/0:
> 3GPP Registration state changed (idle -> unknown)*
> *<debug> [947188221.239038] [mm-iface-modem-3gpp.c:287]
> run_registration_checks_ready(): Modem not yet registered in a 3GPP
> network... will recheck soon*
> *<debug> [947188224.081628] [mm-iface-modem-3gpp.c:751]
> mm_iface_modem_3gpp_run_registration_checks(): Running registration checks
> (CS: 'yes', PS: 'yes', EPS: 'no')*
> *[...]*
>
> And it continues that way until timeout. It seems that modem is not
> registered, however if I run "mmcli -m 0 --3gpp-scan --timeout=300", next
> is returned:
>
> *Found 12 networks:*
> *21407 - Movistar (umts, forbidden)*
> *21404 - YOIGO (gsm, forbidden)*
> *21404 - YOIGO (umts, forbidden)*
> *21403 - Orange (umts, forbidden)*
> *21407 - Movistar (gsm, forbidden)*
> *21403 - Orange (lte, forbidden)*
> *21404 - YOIGO (lte, forbidden)*
> *21403 - Orange (gsm, forbidden)*
> *21407 - Movistar (lte, forbidden)*
> *21401 - vodafone ES (lte, current)*
> *21401 - Voda ES (umts, available)*
> *21401 - Voda ES (gsm, available)*
>
> And if "mmcli -m 0 --3gpp-register-home --timeout=300", same as with
> connect:
>
> *error: couldn't register the modem:
> 'GDBus.Error:org.freedesktop.ModemManager1.Error.MobileEquipment.NetworkTimeout:
> Network timeout'*
>
> Maybe some previous settings config missing??
>
> On Thu, Aug 27, 2015 at 3:29 PM, Aleksander Morgado <
> aleksander at aleksander.es> wrote:
>
> > On Thu, Aug 27, 2015 at 4:26 PM, Raul Suarez Moreno
> > <raul.suarez at blue-tc.com> wrote:
> > > Ok, thinks are clearer now.
> > >
> > > Indeed, I did it. I attached the ModemManager log to this message.
> >
> > Ah, I see it now in the other email.
> >
> > Do you have udev setup in your system? Looks like ModemManager wasn't
> > notified of any port via udev.
> >
> > --
> > Aleksander
> > https://aleksander.es
> >
>
>
>
> _______________________________________________
> ModemManager-devel mailing list
> ModemManager-devel at lists.freedesktop.org
> http://lists.freedesktop.org/mailman/listinfo/modemmanager-devel
More information about the ModemManager-devel
mailing list