Aw: Re: trying to connect to different operators using --3gpp-register-in-operator

Sun Oct 22 06:12:53 UTC 2017

Hey,

>
> I did some further testing and I found some warnings while performing my
> test for switching between a few operators:
>
> ModemManager[2837]: <warn>  [1508280013.114086] [mm-port-serial.c:515]
> real_config_fd(): (ttyUSB0): port attributes not fully set
> ModemManager[2837]: <debug> [1508280013.114511] [mm-port-serial.c:1237]
> mm_port_serial_open(): (ttyUSB0) device open count is 1 (open)
> ModemManager[2837]: <debug> [1508280013.114716] [mm-port-serial-at.c:476]
> mm_port_serial_at_run_init_sequence(): (ttyUSB0): running init sequence...
> ModemManager[2837]: <debug> [1508280013.115232] [mm-port-serial.c:1099]
> mm_port_serial_open(): (ttyUSB2) opening serial port...
> ModemManager[2837]: <warn>  [1508280013.117749] [mm-port-serial.c:515]
> real_config_fd(): (ttyUSB2): port attributes not fully set
> ModemManager[2837]: <debug> [1508280013.119844] [mm-port-serial.c:1237]
> mm_port_serial_open(): (ttyUSB2) device open count is 1 (open)
> ModemManager[2837]: <debug> [1508280013.122116] [mm-port-serial-at.c:476]
> mm_port_serial_at_run_init_sequence(): (ttyUSB2): running init sequence...
> ModemManager[2837]: <debug> [1508280013.123278] [mm-broadband-modem.c:8016]
> enabling_started(): Flashing primary AT port before enabling...
> ModemManager[2837]: <debug> [1508280013.127274] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB0): --> 'ATE0<CR>'
> ModemManager[2837]: <debug> [1508280013.128563] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB2): --> 'ATE0<CR>'
> ModemManager[2837]: <debug> [1508280013.227661] [mm-broadband-modem.c:3132]
> load_power_state(): loading power state...
> ModemManager[2837]: <debug> [1508280013.234163] [mm-port-serial.c:1237]
> mm_port_serial_open(): (ttyUSB0) device open count is 2 (open)
> ModemManager[2837]: <debug> [1508280013.603986] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
> ModemManager[2837]: <debug> [1508280013.609967] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB0): --> 'ATV1<CR>'
> ModemManager[2837]: <debug> [1508280013.623845] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB2): <-- '<CR><LF>OK<CR><LF>'
> ModemManager[2837]: <debug> [1508280013.628900] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
> ModemManager[2837]: <debug> [1508280013.630455] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB0): --> 'AT+CMEE=1<CR>'
> ModemManager[2837]: <debug> [1508280013.648701] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
> ModemManager[2837]: <debug> [1508280013.650298] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB0): --> 'ATX4<CR>'
> ModemManager[2837]: <debug> [1508280013.662284] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
> ModemManager[2837]: <debug> [1508280013.663120] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB0): --> 'AT&C1<CR>'
> ModemManager[2837]: <debug> [1508280013.672534] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB0): <-- '<CR><LF>OK<CR><LF>'
> ModemManager[2837]: <debug> [1508280013.673366] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB0): --> 'AT+CFUN?<CR>'
> ModemManager[2837]: <debug> [1508280013.690510] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB0): <-- '<CR><LF>+CFUN: 1<CR><LF><CR><LF>OK<CR><LF>'
> ModemManager[2837]: <debug> [1508280013.692354] [mm-iface-modem.c:3259]
> set_power_state(): No need to change power state: already in 'on' power
> state
> ModemManager[2837]: <debug> [1508280013.693418] [mm-port-serial.c:1296]
> mm_port_serial_close(): (ttyUSB0) device open count is 1 (close)
> ModemManager[2837]: <debug> [1508280013.694636] [mm-port-serial.c:1237]
> mm_port_serial_open(): (ttyUSB0) device open count is 2 (open)
> ModemManager[2837]: <debug> [1508280013.697023] [mm-port-serial.c:1237]
> mm_port_serial_open(): (ttyUSB0) device open count is 3 (open)
> ModemManager[2837]: <debug> [1508280013.702588] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB0): --> 'AT+IFC=1,1<CR>'
> ModemManager[2837]: <debug> [1508280013.724477] [mm-port-serial-at.c:440]
> debug_log(): (ttyUSB0): <-- '<CR><LF>+CME ERROR: 50<CR><LF>'
> ModemManager[2837]: Invalid mobile equipment error code: 50
> ModemManager[2837]: <debug> [1508280013.725338] [mm-serial-parsers.c:364]
> mm_serial_parser_v1_parse(): Got failure code 100: Unknown error
> ModemManager[2837]: <debug> [1508280013.725604] [mm-port-serial.c:1296]
> mm_port_serial_close(): (ttyUSB0) device open count is 2 (close)
> ModemManager[2837]: <warn>  [1508280013.725990] [mm-port-serial.c:811]
> port_serial_queue_process(): (ttyUSB0) response array is not empty when
> using cached reply, cleaning up 14 bytes
>
> is one error sequence
>

This one is probably not a big issue, although not clear why it happens.

> the other error is
>
> ModemManager[2837]: <warn>  [1508280021.578899]
> [huawei/mm-broadband-modem-huawei.c:2181]
> mm_broadband_modem_huawei_peek_port_at_for_data(): Couldn't find associated
> cdc-wdm port for 'net/usb0'
>

Oh, hum. Looks like your modem isn't being managed by the
"huawei-cdc-ncm" driver, so no cdc-wdm port to control it. I wonder if
you're using the usb0 network interface at all during connection, or
just falling back to PPP. Could you get us a full "lsusb -v -d
VID:PID" output for this device?

>
> Just to update:
>
> The modem is still a Huawei ME909s-120
>
> it's about connecting to 3 or 4 mobile networks, measuring download speed or
> similar stuff for each of them
>
> One problem is that I get timeout errors for --3gpp-register-in-operator if
> I was connected to a different operator before
>
> e.g. I connect to one operator, it succeeds and I can measure download speed
> etc, then if I want to connect to a different operator like 15 seconds after
> I connected to the first one and it needs 10-15 seconds until I get a
> timeout error, I suggest because the modem is still busy with the previous
> operations for connecting with the first operator?!(correct me if I'm wrong)
>
> Is there a way to stabilize connecting that way to different operators?
>

Do you explicitly disconnect from the previous operator before trying
to register in the next one? Does that succeed?

> Because I think I wouldn't have the second problem if that first one was
> solved
>
>
> The second problem is that I get a weird timeout error at disabling the
> modem after I recognize that connecting to the third network fails and I
> therefore want to disconnect, disable and enable the modem to try connecting
> again
>
> When I try to connect to the second operator I just get a timeout error and
> after that I try it again with disconnecting, disable and enable the modem
> again and then it works
>

Without logs it's hard to say what's happening, but this could really
look like you're using PPP over a TTY (instead of the network
interface) and the TTY isn't being correctly disconnected between
attempts.

-- 
Aleksander
https://aleksander.es