Jürgen Bausa
2022-06-24 20:20:01 UTC
Ich bin diese Woche mit dem ICE gefahren und musste feststellen, dass
ich mich mit meinem Laptop (Xiaomi Air 12 unter Bullseye AMD64 mit
NetworkManager, Intel Corporation Wireless 8260 (rev 3a)) nicht ins ICE-
wlan einloggen kann, obwohl wlan mit allen anderen Access Points
einwandfrei läuft. Es hatte früher auch im ICE immer geklappt (zuletzt
Anfang Mai). Auch diesmal hatten andere Geräte und Betriebssysteme
(Android, MacOS, Windows, ...) keine Probleme. Es ging sogar mit meinem
Laptop, nachdem ich die Recue-partition (puppy linux) gebootet hatte.
Ich vermute daher, die Bahn hat irgendwas im wlan geändert was mit
Linux/NM nicht harmoniert. Kennt jemand das Problem?
Hier die Symptome. Es traten zwei unterschiedliche Fälle auf:
Die Verbindung wird gestartet, aber es wird keine IP bezogen. Das
NM-Applet zeigt dann dauerhaft "Netwerkadresse wird bezogen ..." an.
Oder es wird eine IP bezogen, ich kann auch kurz die Landing Page der
Bahn sehen, ein Internet-Zugriff funktioniert aber nicht.
Im syslog finde ich dann folgendes, was sich andauernd widerholt.
Offenbar werden dabei eher zufällig verschiedene Accesspoints
(c:51:0e:56:32:70, 3c:51:0e:56:32:80, 6c:31:0e:71:26:40,
6c:31:0e:71:26:10) verwendet.
Jun 23 15:44:48 lina wpa_supplicant[700]: wlp1s0: SME: Trying to
authenticate with 3c:51:0e:56:32:80 (SSID='WIFIonICE' freq=5220 MHz)
Jun 23 15:44:48 lina kernel: [ 1001.260466] wlp1s0: authenticate with
3c:51:0e:56:32:80
Jun 23 15:44:48 lina wpa_supplicant[700]: wlp1s0: Trying to associate
with 3c:51:0e:56:32:80 (SSID='WIFIonICE' freq=5220 MHz)
Jun 23 15:44:48 lina kernel: [ 1001.276000] wlp1s0: send auth to
3c:51:0e:56:32:80 (try 1/3)
Jun 23 15:44:48 lina kernel: [ 1001.277723] wlp1s0: authenticated
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.8781]
device (wlp1s0): supplicant interface state: scanning -> authenticating
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.8782]
device (p2p-dev-wlp1s0): supplicant management interface state: scanning
-> authenticating
Jun 23 15:44:48 lina kernel: [ 1001.278649] wlp1s0: associate with
3c:51:0e:56:32:80 (try 1/3)
Jun 23 15:44:48 lina kernel: [ 1001.280182] wlp1s0: RX AssocResp from
3c:51:0e:56:32:80 (capab=0x101 status=0 aid=15)
Jun 23 15:44:48 lina wpa_supplicant[700]: wlp1s0: Associated with
3c:51:0e:56:32:80
Jun 23 15:44:48 lina kernel: [ 1001.282921] wlp1s0: associated
Jun 23 15:44:48 lina wpa_supplicant[700]: wlp1s0: CTRL-EVENT-CONNECTED -
Connection to 3c:51:0e:56:32:80 completed [id=0 id_str=]
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.8877]
device (wlp1s0): supplicant interface state: authenticating -> associating
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.8880]
device (p2p-dev-wlp1s0): supplicant management interface state:
authenticating -> associating
Jun 23 15:44:48 lina wpa_supplicant[700]: wlp1s0:
CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jun 23 15:44:48 lina wpa_supplicant[700]: wlp1s0:
CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-63 noise=9999 txrate=360000
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.9138]
device (wlp1s0): supplicant interface state: associating -> completed
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.9139]
device (p2p-dev-wlp1s0): supplicant management interface state:
associating -> completed
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.9140]
device (wlp1s0): DHCPv4 lease renewal requested
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.9334]
dhcp4 (wlp1s0): canceled DHCP transaction
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.9335]
dhcp4 (wlp1s0): state changed unknown -> done
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.9349]
dhcp4 (wlp1s0): activation: beginning transaction (timeout in 45 seconds)
Jun 23 15:44:53 lina kernel: [ 1006.011129] wlp1s0: deauthenticated from
3c:51:0e:56:32:80 (Reason: 2=PREV_AUTH_NOT_VALID)
Jun 23 15:44:53 lina wpa_supplicant[700]: wlp1s0:
CTRL-EVENT-DISCONNECTED bssid=3c:51:0e:56:32:80 reason=2
Jun 23 15:44:53 lina NetworkManager[684]: <info> [1655991893.6648]
device (wlp1s0): supplicant interface state: completed -> disconnected
Jun 23 15:44:53 lina NetworkManager[684]: <info> [1655991893.6649]
device (p2p-dev-wlp1s0): supplicant management interface state:
completed -> disconnected
Jun 23 15:44:53 lina NetworkManager[684]: <info> [1655991893.7652]
device (wlp1s0): supplicant interface state: disconnected -> scanning
Jun 23 15:44:53 lina NetworkManager[684]: <info> [1655991893.7653]
device (p2p-dev-wlp1s0): supplicant management interface state:
disconnected -> scanning
Und unter messages folgendes:
Jun 23 15:44:48 lina kernel: [ 1001.260466] wlp1s0: authenticate with
3c:51:0e:56:32:80
Jun 23 15:44:48 lina kernel: [ 1001.276000] wlp1s0: send auth to
3c:51:0e:56:32:80 (try 1/3)
Jun 23 15:44:48 lina kernel: [ 1001.277723] wlp1s0: authenticated
Jun 23 15:44:48 lina kernel: [ 1001.278649] wlp1s0: associate with
3c:51:0e:56:32:80 (try 1/3)
Jun 23 15:44:48 lina kernel: [ 1001.280182] wlp1s0: RX AssocResp from
3c:51:0e:56:32:80 (capab=0x101 status=0 aid=15)
Jun 23 15:44:48 lina kernel: [ 1001.282921] wlp1s0: associated
Jun 23 15:44:53 lina kernel: [ 1006.011129] wlp1s0: deauthenticated from
3c:51:0e:56:32:80 (Reason: 2=PREV_AUTH_NOT_VALID)
Jun 23 15:44:53 lina kernel: [ 1006.272658] wlp1s0: authenticate with
3c:51:0e:56:32:70
Jun 23 15:44:53 lina kernel: [ 1006.280671] wlp1s0: send auth to
3c:51:0e:56:32:70 (try 1/3)
Jun 23 15:44:53 lina kernel: [ 1006.281964] wlp1s0: authenticated
Jun 23 15:44:53 lina kernel: [ 1006.286565] wlp1s0: associate with
3c:51:0e:56:32:70 (try 1/3)
Jun 23 15:44:53 lina kernel: [ 1006.288147] wlp1s0: RX AssocResp from
3c:51:0e:56:32:70 (capab=0x421 status=0 aid=2)
Jun 23 15:44:53 lina kernel: [ 1006.291097] wlp1s0: associated
Jun 23 15:44:59 lina kernel: [ 1011.710363] wlp1s0: deauthenticated from
3c:51:0e:56:32:70 (Reason: 2=PREV_AUTH_NOT_VALID)
Jun 23 15:45:00 lina kernel: [ 1012.461190] wlp1s0: authenticate with
3c:51:0e:56:32:80
Jun 23 15:45:00 lina kernel: [ 1012.469909] wlp1s0: send auth to
3c:51:0e:56:32:80 (try 1/3)
Jun 23 15:45:00 lina kernel: [ 1012.472610] wlp1s0: authenticated
Jun 23 15:45:00 lina kernel: [ 1012.478569] wlp1s0: associate with
3c:51:0e:56:32:80 (try 1/3)
Jun 23 15:45:00 lina kernel: [ 1012.481100] wlp1s0: RX AssocResp from
3c:51:0e:56:32:80 (capab=0x101 status=0 aid=15)
Jun 23 15:45:00 lina kernel: [ 1012.500335] wlp1s0: associated
Jun 23 15:45:04 lina kernel: [ 1017.210937] wlp1s0: deauthenticated from
3c:51:0e:56:32:80 (Reason: 2=PREV_AUTH_NOT_VALID)
Jürgen
ich mich mit meinem Laptop (Xiaomi Air 12 unter Bullseye AMD64 mit
NetworkManager, Intel Corporation Wireless 8260 (rev 3a)) nicht ins ICE-
wlan einloggen kann, obwohl wlan mit allen anderen Access Points
einwandfrei läuft. Es hatte früher auch im ICE immer geklappt (zuletzt
Anfang Mai). Auch diesmal hatten andere Geräte und Betriebssysteme
(Android, MacOS, Windows, ...) keine Probleme. Es ging sogar mit meinem
Laptop, nachdem ich die Recue-partition (puppy linux) gebootet hatte.
Ich vermute daher, die Bahn hat irgendwas im wlan geändert was mit
Linux/NM nicht harmoniert. Kennt jemand das Problem?
Hier die Symptome. Es traten zwei unterschiedliche Fälle auf:
Die Verbindung wird gestartet, aber es wird keine IP bezogen. Das
NM-Applet zeigt dann dauerhaft "Netwerkadresse wird bezogen ..." an.
Oder es wird eine IP bezogen, ich kann auch kurz die Landing Page der
Bahn sehen, ein Internet-Zugriff funktioniert aber nicht.
Im syslog finde ich dann folgendes, was sich andauernd widerholt.
Offenbar werden dabei eher zufällig verschiedene Accesspoints
(c:51:0e:56:32:70, 3c:51:0e:56:32:80, 6c:31:0e:71:26:40,
6c:31:0e:71:26:10) verwendet.
Jun 23 15:44:48 lina wpa_supplicant[700]: wlp1s0: SME: Trying to
authenticate with 3c:51:0e:56:32:80 (SSID='WIFIonICE' freq=5220 MHz)
Jun 23 15:44:48 lina kernel: [ 1001.260466] wlp1s0: authenticate with
3c:51:0e:56:32:80
Jun 23 15:44:48 lina wpa_supplicant[700]: wlp1s0: Trying to associate
with 3c:51:0e:56:32:80 (SSID='WIFIonICE' freq=5220 MHz)
Jun 23 15:44:48 lina kernel: [ 1001.276000] wlp1s0: send auth to
3c:51:0e:56:32:80 (try 1/3)
Jun 23 15:44:48 lina kernel: [ 1001.277723] wlp1s0: authenticated
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.8781]
device (wlp1s0): supplicant interface state: scanning -> authenticating
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.8782]
device (p2p-dev-wlp1s0): supplicant management interface state: scanning
-> authenticating
Jun 23 15:44:48 lina kernel: [ 1001.278649] wlp1s0: associate with
3c:51:0e:56:32:80 (try 1/3)
Jun 23 15:44:48 lina kernel: [ 1001.280182] wlp1s0: RX AssocResp from
3c:51:0e:56:32:80 (capab=0x101 status=0 aid=15)
Jun 23 15:44:48 lina wpa_supplicant[700]: wlp1s0: Associated with
3c:51:0e:56:32:80
Jun 23 15:44:48 lina kernel: [ 1001.282921] wlp1s0: associated
Jun 23 15:44:48 lina wpa_supplicant[700]: wlp1s0: CTRL-EVENT-CONNECTED -
Connection to 3c:51:0e:56:32:80 completed [id=0 id_str=]
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.8877]
device (wlp1s0): supplicant interface state: authenticating -> associating
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.8880]
device (p2p-dev-wlp1s0): supplicant management interface state:
authenticating -> associating
Jun 23 15:44:48 lina wpa_supplicant[700]: wlp1s0:
CTRL-EVENT-SUBNET-STATUS-UPDATE status=0
Jun 23 15:44:48 lina wpa_supplicant[700]: wlp1s0:
CTRL-EVENT-SIGNAL-CHANGE above=1 signal=-63 noise=9999 txrate=360000
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.9138]
device (wlp1s0): supplicant interface state: associating -> completed
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.9139]
device (p2p-dev-wlp1s0): supplicant management interface state:
associating -> completed
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.9140]
device (wlp1s0): DHCPv4 lease renewal requested
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.9334]
dhcp4 (wlp1s0): canceled DHCP transaction
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.9335]
dhcp4 (wlp1s0): state changed unknown -> done
Jun 23 15:44:48 lina NetworkManager[684]: <info> [1655991888.9349]
dhcp4 (wlp1s0): activation: beginning transaction (timeout in 45 seconds)
Jun 23 15:44:53 lina kernel: [ 1006.011129] wlp1s0: deauthenticated from
3c:51:0e:56:32:80 (Reason: 2=PREV_AUTH_NOT_VALID)
Jun 23 15:44:53 lina wpa_supplicant[700]: wlp1s0:
CTRL-EVENT-DISCONNECTED bssid=3c:51:0e:56:32:80 reason=2
Jun 23 15:44:53 lina NetworkManager[684]: <info> [1655991893.6648]
device (wlp1s0): supplicant interface state: completed -> disconnected
Jun 23 15:44:53 lina NetworkManager[684]: <info> [1655991893.6649]
device (p2p-dev-wlp1s0): supplicant management interface state:
completed -> disconnected
Jun 23 15:44:53 lina NetworkManager[684]: <info> [1655991893.7652]
device (wlp1s0): supplicant interface state: disconnected -> scanning
Jun 23 15:44:53 lina NetworkManager[684]: <info> [1655991893.7653]
device (p2p-dev-wlp1s0): supplicant management interface state:
disconnected -> scanning
Und unter messages folgendes:
Jun 23 15:44:48 lina kernel: [ 1001.260466] wlp1s0: authenticate with
3c:51:0e:56:32:80
Jun 23 15:44:48 lina kernel: [ 1001.276000] wlp1s0: send auth to
3c:51:0e:56:32:80 (try 1/3)
Jun 23 15:44:48 lina kernel: [ 1001.277723] wlp1s0: authenticated
Jun 23 15:44:48 lina kernel: [ 1001.278649] wlp1s0: associate with
3c:51:0e:56:32:80 (try 1/3)
Jun 23 15:44:48 lina kernel: [ 1001.280182] wlp1s0: RX AssocResp from
3c:51:0e:56:32:80 (capab=0x101 status=0 aid=15)
Jun 23 15:44:48 lina kernel: [ 1001.282921] wlp1s0: associated
Jun 23 15:44:53 lina kernel: [ 1006.011129] wlp1s0: deauthenticated from
3c:51:0e:56:32:80 (Reason: 2=PREV_AUTH_NOT_VALID)
Jun 23 15:44:53 lina kernel: [ 1006.272658] wlp1s0: authenticate with
3c:51:0e:56:32:70
Jun 23 15:44:53 lina kernel: [ 1006.280671] wlp1s0: send auth to
3c:51:0e:56:32:70 (try 1/3)
Jun 23 15:44:53 lina kernel: [ 1006.281964] wlp1s0: authenticated
Jun 23 15:44:53 lina kernel: [ 1006.286565] wlp1s0: associate with
3c:51:0e:56:32:70 (try 1/3)
Jun 23 15:44:53 lina kernel: [ 1006.288147] wlp1s0: RX AssocResp from
3c:51:0e:56:32:70 (capab=0x421 status=0 aid=2)
Jun 23 15:44:53 lina kernel: [ 1006.291097] wlp1s0: associated
Jun 23 15:44:59 lina kernel: [ 1011.710363] wlp1s0: deauthenticated from
3c:51:0e:56:32:70 (Reason: 2=PREV_AUTH_NOT_VALID)
Jun 23 15:45:00 lina kernel: [ 1012.461190] wlp1s0: authenticate with
3c:51:0e:56:32:80
Jun 23 15:45:00 lina kernel: [ 1012.469909] wlp1s0: send auth to
3c:51:0e:56:32:80 (try 1/3)
Jun 23 15:45:00 lina kernel: [ 1012.472610] wlp1s0: authenticated
Jun 23 15:45:00 lina kernel: [ 1012.478569] wlp1s0: associate with
3c:51:0e:56:32:80 (try 1/3)
Jun 23 15:45:00 lina kernel: [ 1012.481100] wlp1s0: RX AssocResp from
3c:51:0e:56:32:80 (capab=0x101 status=0 aid=15)
Jun 23 15:45:00 lina kernel: [ 1012.500335] wlp1s0: associated
Jun 23 15:45:04 lina kernel: [ 1017.210937] wlp1s0: deauthenticated from
3c:51:0e:56:32:80 (Reason: 2=PREV_AUTH_NOT_VALID)
Jürgen