USB Modem Interface verbindet sich nicht (Network Manager / Modem Manager)

[Grivel]

Hallo zusammen,

Ich bin kein absoluter Linux-Crack, die Basics sollte ich aber doch beherrschen - Also ich muss ein USB Modem als Datenverbindung einrichten. Das Gerät + OS ist leider etwas missbraucht, ein neues OS einspielen ist aber ein NO-GO.
OS: Debian 9

Hier die Geschichte

- USB Modem wird via /dev/ttyUSB0 erkannt. Name ttyACM3
- Modem Manager erkennt Modem, PIN eingeben / SMS senden geht einwandfrei
- Bearer erstellen und via simple connect Befehl verbinden geht.

- Danach habe ich aber keine IP Adresse (Swisscom Provider)


- Networking Manager erkennt USB Gerät am Selben Port wie der ModemManager (ttyACM3)
- Erstellen von "Connection file" funktioniert
- Verbinden des Networking Managers geht aber nicht, er bricht bei der IP Konfiguration ab.


Dementsprechend habe ich keinen adapter bzw Gerät, dass erkannt wird.
Weiss jemand woran das liegen könnte?

Ich habe die Vermutung, dass das OS was ich vor mir habe irgendwo Parameter falsch eingestellt hat oder Servies installiert sind, die stören. ipv4 vom Bearer hat bspw. PPP was mich etwas irritiert - aber diese Informationen sollte doch der Provider bringen oder?



im Spoiler zusätzliche Infos.


Ablauf Aktivierungsvorgang:

Nov 07 16:37:09 staglinux-v3 NetworkManager[579]: <warn> [1478536629.0076] (ttyACM3): cannot enable/disable modem: locked
Nov 07 16:37:09 staglinux-v3 NetworkManager[579]: <info> [1478536629.0107] manager: (ttyACM3): new Broadband device (/org/freedesktop/NetworkManager/Devices/6)
Nov 07 16:37:09 staglinux-v3 NetworkManager[579]: <info> [1478536629.0119] device (ttyACM3): state change: unmanaged -> unavailable (reason 'managed') [10 20 2]
Nov 07 16:37:09 staglinux-v3 NetworkManager[579]: <info> [1478536629.0132] device (ttyACM3): modem state 'locked'
Nov 07 16:37:09 staglinux-v3 NetworkManager[579]: <info> [1478536629.0154] device (ttyACM3): state change: unavailable -> disconnected (reason 'none') [20 30 0]
Nov 07 16:37:09 staglinux-v3 NetworkManager[579]: <info> [1478536629.0171] policy: auto-activating connection 'gsm'
Nov 07 16:37:09 staglinux-v3 NetworkManager[579]: <info> [1478536629.0192] device (ttyACM3): Activation: starting connection 'gsm' (462f552d-bf7e-43cd-8ee2-a6d5390b76d8)
Nov 07 16:37:09 staglinux-v3 NetworkManager[579]: <info> [1478536629.0201] device (ttyACM3): state change: disconnected -> prepare (reason 'none') [30 40 0]
Nov 07 16:37:09 staglinux-v3 NetworkManager[579]: <info> [1478536629.0213] device (ttyACM3): state change: prepare -> need-auth (reason 'none') [40 60 0]
Nov 07 16:37:09 staglinux-v3 NetworkManager[579]: <warn> [1478536629.0226] (ttyACM3): No agents were available for this request.
Nov 07 16:37:09 staglinux-v3 NetworkManager[579]: <info> [1478536629.0227] device (ttyACM3): state change: need-auth -> failed (reason 'no-secrets') [60 120 7]
Nov 07 16:37:09 staglinux-v3 NetworkManager[579]: <warn> [1478536629.0238] device (ttyACM3): Activation: failed for connection 'gsm'
Nov 07 16:37:09 staglinux-v3 NetworkManager[579]: <info> [1478536629.0251] device (ttyACM3): state change: failed -> disconnected (reason 'none') [120 30 0]
Nov 07 16:42:22 staglinux-v3 NetworkManager[579]: <info> [1478536942.4952] (ttyACM3): modem state changed, 'locked' --> 'disabled' (reason: unknown)
Nov 07 16:42:22 staglinux-v3 NetworkManager[579]: <info> [1478536942.4959] (ttyACM3): modem state changed, 'disabled' --> 'enabling' (reason: user preference)
Nov 07 16:42:22 staglinux-v3 NetworkManager[579]: <warn> [1478536942.5057] modem-broadband[ttyACM3]: failed to enable modem: GDBus.Error:org.freedesktop.ModemManager1.Error.Core.InProgress: Cannot e
Nov 07 16:42:23 staglinux-v3 NetworkManager[579]: <info> [1478536943.4192] (ttyACM3): modem state changed, 'enabling' --> 'enabled' (reason: user-requested)
Nov 07 16:42:38 staglinux-v3 NetworkManager[579]: <info> [1478536958.5428] (ttyACM3): modem state changed, 'enabled' --> 'registered' (reason: unknown)
Nov 07 16:42:38 staglinux-v3 NetworkManager[579]: <info> [1478536958.5613] (ttyACM3): modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Nov 07 16:42:38 staglinux-v3 NetworkManager[579]: <info> [1478536958.7108] (ttyACM3): modem state changed, 'connecting' --> 'connected' (reason: user-requested)
Nov 07 16:56:34 staglinux-v3 NetworkManager[579]: <info> [1478537794.5609] policy: auto-activating connection 'gsm'
Nov 07 16:56:34 staglinux-v3 NetworkManager[579]: <info> [1478537794.5635] device (ttyACM3): Activation: starting connection 'gsm' (462f552d-bf7e-43cd-8ee2-a6d5390b76d8)
Nov 07 16:56:34 staglinux-v3 NetworkManager[579]: <info> [1478537794.5696] device (ttyACM3): state change: disconnected -> prepare (reason 'none') [30 40 0]
Nov 07 16:56:34 staglinux-v3 NetworkManager[579]: <info> [1478537794.5756] audit: op="connection-activate" uuid="462f552d-bf7e-43cd-8ee2-a6d5390b76d8" name="gsm" pid=1127 uid=1001 result="fail" reas
Nov 07 16:56:34 staglinux-v3 NetworkManager[579]: <warn> [1478537794.5983] modem-broadband[ttyACM3]: failed to connect modem: Cannot create new bearer: all existing bearers are connected
Nov 07 16:56:34 staglinux-v3 NetworkManager[579]: <info> [1478537794.5983] device (ttyACM3): state change: prepare -> failed (reason 'unknown') [40 120 1]
Nov 07 16:56:34 staglinux-v3 NetworkManager[579]: <warn> [1478537794.5994] device (ttyACM3): Activation: failed for connection 'gsm'
Nov 07 16:56:34 staglinux-v3 NetworkManager[579]: <info> [1478537794.6012] device (ttyACM3): state change: failed -> disconnected (reason 'none') [120 30 0]
Nov 07 16:56:34 staglinux-v3 NetworkManager[579]: <info> [1478537794.6029] policy: auto-activating connection 'gsm'
Nov 07 16:56:34 staglinux-v3 NetworkManager[579]: <info> [1478537794.6054] device (ttyACM3): Activation: starting connection 'gsm' (462f552d-bf7e-43cd-8ee2-a6d5390b76d8)
Nov 07 16:56:34 staglinux-v3 NetworkManager[579]: <info> [1478537794.6060] device (ttyACM3): state change: disconnected -> prepare (reason 'none') [30 40 0]
Nov 07 16:56:34 staglinux-v3 NetworkManager[579]: <info> [1478537794.6109] (ttyACM3): modem state changed, 'connected' --> 'disconnecting' (reason: user-requested)
Nov 07 16:56:36 staglinux-v3 NetworkManager[579]: <info> [1478537796.6700] (ttyACM3): modem state changed, 'disconnecting' --> 'registered' (reason: user-requested)
Nov 07 16:56:36 staglinux-v3 NetworkManager[579]: <info> [1478537796.6772] (ttyACM3): modem state changed, 'registered' --> 'connecting' (reason: user-requested)
Nov 07 16:56:36 staglinux-v3 NetworkManager[579]: <info> [1478537796.8036] (ttyACM3): modem state changed, 'connecting' --> 'connected' (reason: user-requested)
Nov 07 16:56:36 staglinux-v3 NetworkManager[579]: <warn> [1478537796.8195] device (ttyACM3): ip-ifname: set ifname 'ttyACM3', unknown ifindex
Nov 07 16:56:36 staglinux-v3 NetworkManager[579]: <info> [1478537796.8202] device (ttyACM3): state change: prepare -> config (reason 'none') [40 50 0]
Nov 07 16:56:36 staglinux-v3 NetworkManager[579]: <info> [1478537796.8210] device (ttyACM3): state change: config -> ip-config (reason 'none') [50 70 0]
Nov 07 16:56:36 staglinux-v3 NetworkManager[579]: <warn> [1478537796.8214] device (ttyACM3): interface ttyACM3 not up for IP configuration
Nov 07 16:56:36 staglinux-v3 NetworkManager[579]: <info> [1478537796.8214] (ttyACM3): using modem-specified IP timeout: 20 seconds
Nov 07 16:56:36 staglinux-v3 NetworkManager[579]: <info> [1478537796.8245] loaded PPP plugin /usr/lib/x86_64-linux-gnu/NetworkManager/libnm-ppp-plugin.so
Nov 07 16:56:36 staglinux-v3 NetworkManager[579]: <info> [1478537796.8261] ppp-manager: starting PPP connection
Nov 07 16:56:36 staglinux-v3 NetworkManager[579]: <info> [1478537796.8329] ppp-manager: pppd started with pid 1133
Nov 07 16:56:36 staglinux-v3 pppd[1133]: Plugin /usr/lib/pppd/2.4.7/nm-pppd-plugin.so loaded.
Nov 07 16:56:36 staglinux-v3 NetworkManager[579]: Plugin /usr/lib/pppd/2.4.7/nm-pppd-plugin.so loaded.
Nov 07 16:56:36 staglinux-v3 pppd[1133]: nm-ppp-plugin: (plugin_init): initializing
Nov 07 16:56:36 staglinux-v3 pppd[1133]: pppd 2.4.7 started by root, uid 0
Nov 07 16:56:36 staglinux-v3 pppd[1133]: nm-ppp-plugin: (nm_phasechange): status 3 / phase 'serial connection'
Nov 07 16:56:37 staglinux-v3 NetworkManager[579]: sh: 1: /etc/ppp/connect: Permission denied
Nov 07 16:56:37 staglinux-v3 NetworkManager[579]: Script /etc/ppp/connect finished (pid 1140), status = 0x7e
Nov 07 16:56:37 staglinux-v3 NetworkManager[579]: Connect script failed
Nov 07 16:56:37 staglinux-v3 pppd[1133]: nm-ppp-plugin: (nm_phasechange): status 1 / phase 'dead'
Nov 07 16:56:37 staglinux-v3 pppd[1133]: Script /etc/ppp/connect finished (pid 1140), status = 0x7e
Nov 07 16:56:37 staglinux-v3 pppd[1133]: Connect script failed
Nov 07 16:56:38 staglinux-v3 NetworkManager[579]: <warn> [1478537798.8994] ppp-manager: pppd pid 1133 exited with error: Connect script failed
Nov 07 16:56:38 staglinux-v3 NetworkManager[579]: <info> [1478537798.8997] device (ttyACM3): state change: ip-config -> failed (reason 'ip-config-unavailable') [70 120 5]
Nov 07 16:56:38 staglinux-v3 NetworkManager[579]: <warn> [1478537798.9048] device (ttyACM3): Activation: failed for connection 'gsm'



admin@staglinux-v3:~$ systemctl list-unit-files --state=enabled --no-pager
UNIT FILE STATE
tmp-service-swversion.mount enabled
tmp.mount enabled
anacron.service enabled
atd.service enabled
autovt@.service enabled
canproxy-ato.service enabled
console-setup.service enabled
cron.service enabled
dbus-org.freedesktop.ModemManager1.service enabled
dbus-org.freedesktop.nm-dispatcher.service enabled
getty@.service enabled
hwclock-save.service enabled
inetd.service enabled
keyboard-setup.service enabled
ModemManager.service enabled
network-manager.service enabled
networking.service enabled
NetworkManager-dispatcher.service enabled
NetworkManager-wait-online.service enabled
NetworkManager.service enabled
nmbd.service enabled
openbsd-inetd.service enabled
openvpn.service enabled
pppd-dns.service enabled
quota.service enabled
quotarpc.service enabled
resolvconf.service enabled
rsync.service enabled
smbd.service enabled
ssh.service enabled
sshd.service enabled
sysupdate.service enabled
default.target enabled
remote-fs.target enabled
sysupdate.target enabled
anacron.timer enabled
apt-daily-upgrade.timer enabled
apt-daily.timer enabled


admin@staglinux-v3:~$ mmcli -m 0

Screenshot gerade ohne
/org/freedesktop/ModemManager1/Modem/0 (device id 'e615f405eb345508dec252d8d48e0fc392f20221')
-------------------------
Hardware | manufacturer: 'u-blox'
| model: 'TOBY-L210'
| revision: '15.63'
| supported: 'gsm-umts, lte'
| current: 'gsm-umts, lte'
| equipment id: '352255061092585'
-------------------------
System | device: '/sys/devices/pci0000:00/0000:00:14.0/usb1/1-3/1-3.2'
| drivers: 'cdc_acm'
| plugin: 'Generic'
| primary port: 'ttyACM3'
| ports: 'ttyACM3 (at), ttyACM1 (at), ttyACM2 (at)'
-------------------------
Numbers | own : 'unknown'
-------------------------
Status | lock: 'none'
| unlock retries: 'unknown'
| state: 'connected'
| power state: 'on'
| access tech: 'umts'
| signal quality: '9' (recent)
-------------------------
Modes | supported: 'allowed: 2g, 3g, 4g; preferred: none'
| current: 'allowed: 2g, 3g, 4g; preferred: none'
-------------------------
Bands | supported: 'unknown'
| current: 'unknown'
-------------------------
IP | supported: 'ipv4, ipv6, ipv4v6'
-------------------------
3GPP | imei: '352255061092585'
| enabled locks: 'sim'
| operator id: '22801'
| operator name: 'Swisscom'
| subscription: 'unknown'
| registration: 'home'
-------------------------
SIM | path: '/org/freedesktop/ModemManager1/SIM/0'

-------------------------
Bearers | paths: '/org/freedesktop/ModemManager1/Bearer/1'


Bearer '/org/freedesktop/ModemManager1/Bearer/2'
-------------------------
Status | connected: 'yes'
| suspended: 'no'
| interface: 'ttyACM3'
| IP timeout: '20'
-------------------------
Properties | apn: 'gprs.swisscom.ch'
| roaming: 'allowed'
| IP type: 'none'
| user: 'none'
| password: 'none'
| number: 'none'
| Rm protocol: 'unknown'
-------------------------
IPv4 configuration | method: 'ppp'
| address: 'unknown'
| prefix: '0'
| gateway: 'unknown'
| DNS: none
-------------------------
IPv6 configuration | method: 'unknown'
-------------------------
Stats | Duration: '89'
| Bytes received: 'N/A'
| Bytes transmitted: 'N/A'



admin@staglinux-v3:~$ ip a
1: lo: <LOOPBACK,UP,LOWER_UP> mtu 65536 qdisc noqueue state UNKNOWN group default qlen 1
link/loopback 00:00:00:00:00:00 brd 00:00:00:00:00:00
inet 127.0.0.1/8 scope host lo
valid_lft forever preferred_lft forever
inet6 ::1/128 scope host
valid_lft forever preferred_lft forever
2: eth1: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP group default qlen 1000
link/ether 00:a0:10:01:a9:8c brd ff:ff:ff:ff:ff:ff
inet 172.16.64.91/24 brd 172.16.64.255 scope global dynamic eth1
valid_lft 690131sec preferred_lft 690131sec
inet6 fe80::8cce:5f64:7901:f31/64 scope link
valid_lft forever preferred_lft forever
3: eth0: <BROADCAST,MULTICAST,UP,LOWER_UP> mtu 1500 qdisc mq state UP group default qlen 1000
link/ether 00:a0:10:01:a7:21 brd ff:ff:ff:ff:ff:ff
inet 10.0.3.11/24 brd 10.0.3.255 scope global eth0
valid_lft forever preferred_lft forever
inet6 fe80::10f7:e47:8ee3:aa93/64 scope link
valid_lft forever preferred_lft forever
4: can0: <NOARP,UP,LOWER_UP,ECHO> mtu 16 qdisc pfifo_fast state UNKNOWN group default qlen 10
link/can
5: can1: <NOARP,UP,LOWER_UP,ECHO> mtu 16 qdisc pfifo_fast state UNKNOWN group default qlen 10
link/can





Aktueller Stand:
Network Manager darf die ETHs ebenfalls kontrollieren (macht keinen Unterschied, ob es geht oder nicht)

[Grivel]

Ok das PPP Problem ist gelöst. Berechtigungen :)

Tja jetzt wird die Verbindung aber immernoch nicht aufgebaut :(

[Lokadamus]

Kannst du mal das Datum anpassen. Wir haben nicht den 7. November, sondern erst den 21. Oktober. :|

Wie sieht ein neues Logfile aus?

[HeldImZelt]

Nov 07 16:37:09 staglinux-v3 NetworkManager[579]: <warn> [1478536629.0226] (ttyACM3): No agents were available for this request.

Anscheinend kann er kein Eingabefenster öffnen.
Vielleicht hilft das: https://wiki.archlinux.org/index.php/NetworkManager#VPN_not_working_in_GNOME

Testweise die Verbindung manuell herstellen.

Edit: Es wäre hilfreich zu wissen, welche 'Desktop Environment' (KDE, Gnome, XFCE...) Du benutzt.

[sei laut]

HeldImZelt könnte recht haben mit seiner Antwort.

Nov 07 16:42:22 staglinux-v3 NetworkManager[579]: <warn> [1478536942.5057] modem-broadband[ttyACM3]: failed to enable modem: GDBus.Error:org.freedesktop.ModemManager1.Error.Core.InProgress: Cannot e

Kurzum: Die Logmeldungen sind nicht vollständig. Gibts das auch in komplett?

[Grivel]

Vielen Dank für die Antworten.

Habe vergessen hier herein zu schauen.

Also:

Uhrzeiten - ja, die resettet sich bei jedem Stromverlust, ich bin das zu faul zu ändern - gibt allerlei Warnings aber ist nicht relevant für den Fehler.
Ich habe keine GUI am Start, das Gerät ist in der Industrie im Hintergrund für allerlei benutzt - da wurde aus "Optimierungszwecken" das GUI weggelassen.

Das Probem habe ich jetzt lustigerweise behoben, indem ich das Modem aus dem Legacy Modus in den 3G/4G Modus gezwungen habe, womit der serielle Port verschwunden ist und nur noch der "ursprüngliche" Port (net, nicht at )vorhanden war, damit konnte ich via dhlclient und einem kleinen Pythonscript (Pyserial + AT Commandsätze) eine Verbindung aufbauen, die anscheinend auch funktioniert - zumindest kann ich packages laden und pingen wie ich will...

Danke trotzdem. Ich sehe offen gestanden keinen Vorteil Network Manager und Modem Manager einzusetzen nach der Odysee die ich hatte... interessant finde ich nur, dass die Datenverbindung auch ohne APN Definition funktioniert.

[sei laut]

Liest du deine eigenen Log Meldungen? Den NetworkManager haben wir uns nicht herbei geraten, der ist wohl offensichtlich installiert. Zumindest der Process mit der ID 579 heißt so.

Nov 07 16:56:36 staglinux-v3 NetworkManager[579]: Plugin /usr/lib/pppd/2.4.7/nm-pppd-plugin.so loaded.
Nov 07 16:56:36 staglinux-v3 pppd[1133]: nm-ppp-plugin: (plugin_init): initializing
Nov 07 16:56:36 staglinux-v3 pppd[1133]: pppd 2.4.7 started by root, uid 0
Nov 07 16:56:36 staglinux-v3 pppd[1133]: nm-ppp-plugin: (nm_phasechange): status 3 / phase 'serial connection'
Nov 07 16:56:37 staglinux-v3 NetworkManager[579]: sh: 1: /etc/ppp/connect: Permission denied
Nov 07 16:56:37 staglinux-v3 NetworkManager[579]: Script /etc/ppp/connect finished (pid 1140), status = 0x7e
Nov 07 16:56:37 staglinux-v3 NetworkManager[579]: Connect script failed
Nov 07 16:56:37 staglinux-v3 pppd[1133]: nm-ppp-plugin: (nm_phasechange): status 1 / phase 'dead'

[Grivel]

Nochmal. Schon mein Nachtrag hat das Thema angesprochen. Das war ein Fehler, den ich als erstes korrigiert habe, nachdem ich es hier verfasst hatte.

Danach ich habe beschrieben wie ich das ganze ohne NM gelöst habe. Sehr primitiv. Nichts PPP. Nichts NM + PPP. Nichts APN. Simply USB Modem port genommen, mit diesem seriell kommuniziert, dann den Port als routing mit niedrigster Metric genommen und dann habe ich seltsamerweise Internet. Schnell, zuverlässig.

Das ganze Thema mit NM versucht Verbindung aufzubauen und jedes zweite File hat CHMOD Nachjustage benötigt damit der NM überhaupt zugreifen kann, hat mich erst dazu gebracht, mich selber hinzusetzen.
Also: Nochmals Danke - Problem behoben.

[Gast]

pass auf, dass du da nicht über kostenprobleme stolperst. fehlerhaft konfigurierte apns haben schon mehrfach dazu geführt, dass mobilertraffic nicht korrekt abgerechnet wurde.