You are not logged in.
Hi,...
my lte modem initially does not work, when i boot. After giving it some time and another try to connect it starts working.
Here are the the logs:
-- Journal begins at Wed 2020-12-23 04:05:41 CET. --
Dez 23 04:13:26 NetworkManager[564]: <info> [1608693206.9738] manager: (ttyUSB2): new Broadband device (/org/freedesktop/NetworkManager/Devices/5)
Dez 23 04:13:26 NetworkManager[564]: <info> [1608693206.9757] device (ttyUSB2): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Dez 23 04:13:26 NetworkManager[564]: <info> [1608693206.9768] device (ttyUSB2): modem state 'enabling'
Dez 23 04:13:26 NetworkManager[564]: <info> [1608693206.9793] device (ttyUSB2): state change: unavailable -> disconnected (reason 'none', sys-iface-state: 'managed')
Dez 23 04:13:26 NetworkManager[564]: <info> [1608693206.9807] policy: auto-activating connection '1&1 Mobile Broadband' (7299256f-c997-40e8-b0ab-0fa1ef8e871f)
Dez 23 04:13:26 NetworkManager[564]: <info> [1608693206.9824] device (ttyUSB2): Activation: starting connection '1&1 Mobile Broadband' (7299256f-c997-40e8-b0ab-0fa1ef8e871f)
Dez 23 04:13:26 NetworkManager[564]: <info> [1608693206.9827] device (ttyUSB2): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Dez 23 04:13:27 ModemManager[621]: <info> [modem0] state changed (disabled -> enabling)
Dez 23 04:13:27 ModemManager[621]: <info> [modem0] simple connect started...
Dez 23 04:13:27 ModemManager[621]: <info> [modem0] simple connect state (4/8): wait to get fully enabled
Dez 23 04:13:32 systemd[1]: fprintd.service: Succeeded.
Dez 23 04:13:32 systemd[1008]: Started Application launched by gnome-shell.
Dez 23 04:13:32 systemd[1008]: Started VTE child process 1429 launched by gnome-terminal-server process 1407.
Dez 23 04:13:32 systemd[1008]: app-gnome-org.gnome.Terminal-1423.scope: Succeeded.
Dez 23 04:13:37 ModemManager[621]: <warn> [modem0] port ttyUSB3 timed out 2 consecutive times
Dez 23 04:13:37 ModemManager[621]: <info> [modem0] simple connect started...
Dez 23 04:13:37 ModemManager[621]: <info> [modem0] simple connect state (4/8): wait to get fully enabled
Dez 23 04:13:40 systemd[1]: systemd-localed.service: Succeeded.
Dez 23 04:13:40 systemd[1]: systemd-hostnamed.service: Succeeded.
Dez 23 04:13:40 ModemManager[621]: <warn> [modem0] port ttyUSB2 timed out 2 consecutive times
Dez 23 04:13:40 systemd[1]: systemd-timedated.service: Succeeded.
Dez 23 04:13:43 ModemManager[621]: <warn> [modem0] port ttyUSB3 timed out 3 consecutive times
Dez 23 04:13:46 ModemManager[621]: <warn> [modem0] port ttyUSB2 timed out 3 consecutive times
Dez 23 04:13:47 NetworkManager[564]: <warn> [1608693227.5629] modem-broadband[ttyUSB2]: failed to connect modem: Too much time waiting to get to a final state
Dez 23 04:13:47 NetworkManager[564]: <info> [1608693227.5630] device (ttyUSB2): state change: prepare -> failed (reason 'unknown', sys-iface-state: 'managed')
Dez 23 04:13:47 NetworkManager[564]: <warn> [1608693227.5679] device (ttyUSB2): Activation: failed for connection '1&1 Mobile Broadband'
Dez 23 04:13:47 NetworkManager[564]: <info> [1608693227.5684] device (ttyUSB2): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Dez 23 04:13:47 NetworkManager[564]: <info> [1608693227.5691] policy: auto-activating connection '1&1 Mobile Broadband' (7299256f-c997-40e8-b0ab-0fa1ef8e871f)
Dez 23 04:13:47 NetworkManager[564]: <info> [1608693227.5697] device (ttyUSB2): Activation: starting connection '1&1 Mobile Broadband' (7299256f-c997-40e8-b0ab-0fa1ef8e871f)
Dez 23 04:13:47 NetworkManager[564]: <info> [1608693227.5699] device (ttyUSB2): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Dez 23 04:13:47 ModemManager[621]: <info> [modem0] simple connect started...
Dez 23 04:13:47 ModemManager[621]: <info> [modem0] simple connect state (4/8): wait to get fully enabled
Dez 23 04:13:49 systemd[1008]: Started Application launched by gnome-shell.
Dez 23 04:13:49 ModemManager[621]: <warn> [modem0] port ttyUSB3 timed out 4 consecutive times
Dez 23 04:13:51 NetworkManager[564]: <warn> [1608693231.9747] modem-broadband[ttyUSB2]: failed to enable modem: Zeitüberschreitung wurde erreicht
Dez 23 04:13:52 ModemManager[621]: <info> [modem0] state changed (enabling -> enabled)
Dez 23 04:13:52 ModemManager[621]: <info> [modem0] simple connect state (5/8): register
Dez 23 04:13:52 ModemManager[621]: <warn> [modem0] port ttyUSB2 timed out 4 consecutive times
Dez 23 04:13:52 NetworkManager[564]: <info> [1608693232.5688] modem["ttyUSB2"]: modem state changed, 'enabling' --> 'enabled' (reason: user-requested)
Dez 23 04:13:52 geoclue[763]: Failed to set GPS refresh rate: GDBus.Error:org.freedesktop.ModemManager1.Error.Core.Unauthorized: PolicyKit authorization failed: not authorized for 'org.freedesktop.ModemManager1.Device.Control'
Dez 23 04:13:58 ModemManager[621]: <info> [modem0] 3GPP registration state changed (unknown -> registering)
Dez 23 04:13:58 ModemManager[621]: <info> [modem0] simple connect state (6/8): bearer
Dez 23 04:13:58 ModemManager[621]: <info> [modem0] simple connect state (7/8): connect
Dez 23 04:13:58 ModemManager[621]: <info> [modem0] state changed (enabled -> connecting)
Dez 23 04:13:58 NetworkManager[564]: <info> [1608693238.6008] modem["ttyUSB2"]: modem state changed, 'enabled' --> 'connecting' (reason: user-requested)
Dez 23 04:13:58 ModemManager[621]: <info> [modem0] 3GPP registration state changed (registering -> home)
Dez 23 04:13:58 ModemManager[621]: <info> [modem0] state changed (connecting -> registered)
Dez 23 04:13:58 NetworkManager[564]: <info> [1608693238.6279] modem["ttyUSB2"]: modem state changed, 'connecting' --> 'registered' (reason: unknown)
Dez 23 04:13:58 ModemManager[621]: <info> [modem0] state changed (registered -> connected)
Dez 23 04:13:58 ModemManager[621]: <info> [modem0] simple connect state (8/8): all done
Dez 23 04:13:58 NetworkManager[564]: <info> [1608693238.7805] modem["ttyUSB2"]: modem state changed, 'registered' --> 'connected' (reason: user-requested)
Dez 23 04:13:58 NetworkManager[564]: <info> [1608693238.7902] device (ttyUSB2): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Dez 23 04:13:58 NetworkManager[564]: <info> [1608693238.7927] device (ttyUSB2): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Dez 23 04:13:58 NetworkManager[564]: <info> [1608693238.7950] modem-broadband[ttyUSB2]: IPv4 static configuration:
Dez 23 04:13:58 NetworkManager[564]: <info> [1608693238.7951] modem-broadband[ttyUSB2]: address 100.90.72.153/30
Dez 23 04:13:58 NetworkManager[564]: <info> [1608693238.7952] modem-broadband[ttyUSB2]: gateway 100.90.72.154
Dez 23 04:13:58 avahi-daemon[562]: Joining mDNS multicast group on interface wwp0s20f0u3c2.IPv4 with address 100.90.72.153.
Dez 23 04:13:58 NetworkManager[564]: <info> [1608693238.7952] modem-broadband[ttyUSB2]: DNS 139.7.30.126
Dez 23 04:13:58 avahi-daemon[562]: New relevant interface wwp0s20f0u3c2.IPv4 for mDNS.
Dez 23 04:13:58 NetworkManager[564]: <info> [1608693238.7953] modem-broadband[ttyUSB2]: DNS 139.7.30.125
Dez 23 04:13:58 avahi-daemon[562]: Registering new address record for 100.90.72.153 on wwp0s20f0u3c2.IPv4.
Dez 23 04:13:58 NetworkManager[564]: <info> [1608693238.8008] device (ttyUSB2): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Dez 23 04:13:58 dbus-daemon[563]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.6' (uid=0 pid=564 comm="/usr/bin/NetworkManager --no-daemon ")
Dez 23 04:13:58 systemd[1]: Starting Network Manager Script Dispatcher Service...
Dez 23 04:13:58 dbus-daemon[563]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Dez 23 04:13:58 systemd[1]: Started Network Manager Script Dispatcher Service.
Dez 23 04:13:58 NetworkManager[564]: <info> [1608693238.8207] device (ttyUSB2): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Dez 23 04:13:58 NetworkManager[564]: <info> [1608693238.8212] device (ttyUSB2): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Dez 23 04:13:58 NetworkManager[564]: <info> [1608693238.8338] device (ttyUSB2): Activation: successful, device activated.
Dez 23 04:14:09 systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Dez 23 04:14:12 systemd[1]: pcscd.service: Succeeded.Hardware is this one:
# lsusb|grep -i huawei
Bus 001 Device 002: ID 12d1:15c1 Huawei Technologies Co., Ltd. ME906s LTE M.2 Module# mmcli -m 0
--------------------------------
General | dbus path: /org/freedesktop/ModemManager1/Modem/0
| device id: 3b4e861fd41e5cfdfb88eec5229105a7f5ea5c27
--------------------------------
Hardware | manufacturer: Huawei Technologies Co., Ltd.
| model: ME906s-158
| firmware revision: 11.617.00.00.11
| supported: gsm-umts
| current: gsm-umts
| equipment id: xxxxxxxxxxxxxxxxx
--------------------------------
System | device: /sys/devices/pci0000:00/0000:00:14.0/usb1/1-3
| drivers: option1, cdc_ether
| plugin: huawei
| primary port: ttyUSB2
| ports: ttyUSB0 (at), ttyUSB2 (at), ttyUSB3 (at),
| wwp0s20f0u3c2 (net)
--------------------------------
Status | unlock retries: sim-pin (3), sim-puk (10), sim-pin2 (3), sim-puk2 (10)
| state: connected
| power state: on
| access tech: lte
| signal quality: 45% (recent)
--------------------------------
Modes | supported: allowed: 4g; preferred: none
| allowed: 3g; preferred: none
| allowed: 2g; preferred: none
| allowed: 2g, 3g, 4g; preferred: none
| current: allowed: any; preferred: none
--------------------------------
IP | supported: ipv4
--------------------------------
3GPP | imei: xxxxxxxxxxxxxxxxxxx
| operator id: 26202
| operator name: 1&1
| registration: home
--------------------------------
3GPP EPS | ue mode of operation: csps-2
--------------------------------
SIM | dbus path: /org/freedesktop/ModemManager1/SIM/0
--------------------------------
Bearer | dbus path: /org/freedesktop/ModemManager1/Bearer/0It think this part from the above log shows the problem:
Dez 23 04:13:40 ModemManager[621]: <warn> [modem0] port ttyUSB2 timed out 2 consecutive times
Dez 23 04:13:43 ModemManager[621]: <warn> [modem0] port ttyUSB3 timed out 3 consecutive times
Dez 23 04:13:46 ModemManager[621]: <warn> [modem0] port ttyUSB2 timed out 3 consecutive times
Dez 23 04:13:47 NetworkManager[564]: <warn> [1608693227.5629] modem-broadband[ttyUSB2]: failed to connect modem: Too much time waiting to get to a final state
Dez 23 04:13:47 NetworkManager[564]: <info> [1608693227.5630] device (ttyUSB2): state change: prepare -> failed (reason 'unknown', sys-iface-state: 'managed')
Dez 23 04:13:47 NetworkManager[564]: <warn> [1608693227.5679] device (ttyUSB2): Activation: failed for connection '1&1 Mobile Broadband'
Dez 23 04:13:47 NetworkManager[564]: <info> [1608693227.5684] device (ttyUSB2): state change: failed -> disconnected (reason 'none', sys-iface-state: 'managed')
Dez 23 04:13:47 NetworkManager[564]: <info> [1608693227.5691] policy: auto-activating connection '1&1 Mobile Broadband' (7299256f-c997-40e8-b0ab-0fa1ef8e871f)
Dez 23 04:13:47 NetworkManager[564]: <info> [1608693227.5697] device (ttyUSB2): Activation: starting connection '1&1 Mobile Broadband' (7299256f-c997-40e8-b0ab-0fa1ef8e871f)
Dez 23 04:13:47 NetworkManager[564]: <info> [1608693227.5699] device (ttyUSB2): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Dez 23 04:13:47 ModemManager[621]: <info> [modem0] simple connect started...
Dez 23 04:13:47 ModemManager[621]: <info> [modem0] simple connect state (4/8): wait to get fully enabled
Dez 23 04:13:49 ModemManager[621]: <warn> [modem0] port ttyUSB3 timed out 4 consecutive times
Dez 23 04:13:51 NetworkManager[564]: <warn> [1608693231.9747] modem-broadband[ttyUSB2]: failed to enable modem: Zeitüberschreitung wurde erreichtIt is really annoying, also GPS doesn't work out of the box:
# mmcli -m 0 --location-status
------------------------
Location | capabilities: 3gpp-lac-ci
| enabled: 3gpp-lac-ci
| signals: no# mmcli -m 0 --location-enable-gps-raw --location-enable-gps-nmea
error: couldn't setup location gathering: 'GDBus.Error:org.freedesktop.ModemManager1.Error.Core.Unsupported: Cannot enable unsupported location sources: 'gps-raw, gps-nmea''When I enable GPS manually, it works and I start getting NMEA data on /dev/ttyUSB3
# minicom -D /dev/ttyUSB0 -b 115200
# AT^WPDGP
# OK
Can I make it work somehow? I don't care that much about the GPS, but it would be very nice, if the connection would work immediately after boot.
Edit: removed imei from log
Last edited by mushotoku (2020-12-26 00:40:10)
Offline
It got better, after flashing the newest firmware. I now have:
firmware revision: 11.617.14.00.11It feels like the modem has become a lot "snappier" and the connection errors are gone. Nice!
Some warning are still there and no GPS or even IPv6 available, though.
-- Journal begins at Sat 2020-12-26 00:44:02 CET, ends at Sat 2020-12-26 01:02:34 CET. --
Dez 26 00:57:33 systemd[1]: Starting Modem Manager...
Dez 26 00:57:33 ModemManager[627]: <info> ModemManager (version 1.14.8) starting in system bus...
Dez 26 00:57:33 systemd[1]: Started Modem Manager.
Dez 26 00:57:36 ModemManager[627]: <info> [base-manager] couldn't check support for device '/sys/devices/pci0000:00/0000:00:1c.2/0000:04:00.0': not supported by any plugin
Dez 26 00:57:36 ModemManager[627]: <info> [base-manager] couldn't check support for device '/sys/devices/pci0000:00/0000:00:1f.6': not supported by any plugin
Dez 26 00:58:00 ModemManager[627]: <info> [device /sys/devices/pci0000:00/0000:00:14.0/usb1/1-3] creating modem with plugin 'huawei' and '6' ports
Dez 26 00:58:00 ModemManager[627]: <warn> [plugin/huawei] could not grab port ttyUSB4: Cannot add port 'tty/ttyUSB4', unhandled serial type
Dez 26 00:58:00 ModemManager[627]: <warn> [plugin/huawei] could not grab port ttyUSB1: Cannot add port 'tty/ttyUSB1', unhandled serial type
Dez 26 00:58:00 ModemManager[627]: <info> [base-manager] modem for device '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3' successfully created
Dez 26 00:58:06 ModemManager[627]: <warn> [modem0/sim0] couldn't load list of emergency numbers: Failed to parse CRSM query result '+CRSM: 105,129,""'
Dez 26 00:58:06 ModemManager[627]: <info> [modem0] state changed (unknown -> disabled)
Dez 26 00:58:06 ModemManager[627]: <info> [modem0] state changed (disabled -> enabling)
Dez 26 00:58:06 ModemManager[627]: <info> [modem0] simple connect started...
Dez 26 00:58:06 ModemManager[627]: <info> [modem0] simple connect state (4/8): wait to get fully enabled
Dez 26 00:58:07 ModemManager[627]: <info> [modem0] state changed (enabling -> enabled)
Dez 26 00:58:07 ModemManager[627]: <info> [modem0] simple connect state (5/8): register
Dez 26 00:58:07 ModemManager[627]: <info> [modem0] 3GPP registration state changed (unknown -> registering)
Dez 26 00:58:07 ModemManager[627]: <info> [modem0] simple connect state (6/8): bearer
Dez 26 00:58:07 ModemManager[627]: <info> [modem0] simple connect state (7/8): connect
Dez 26 00:58:07 ModemManager[627]: <info> [modem0] state changed (enabled -> connecting)
Dez 26 00:58:08 ModemManager[627]: <info> [modem0] 3GPP registration state changed (registering -> home)
Dez 26 00:58:08 ModemManager[627]: <info> [modem0] state changed (connecting -> registered)
Dez 26 00:58:10 ModemManager[627]: <info> [modem0] state changed (registered -> connected)
Dez 26 00:58:10 ModemManager[627]: <info> [modem0] simple connect state (8/8): all doneMarking as "solved", because it is presumable a firmware related problem...
Offline
From where did you got the Firmware and how to install it?
Best regards
Last edited by linuxio (2021-06-29 09:43:04)
Offline