Probleme mit docker bridge? Eintrag jede Minute im Syslog

[hansa]

Hallo Zusammen,

ich bekomme jede Minute den unten dargestellten Eintrag in der /var/log/syslog.

Nov 7 12:54:04 egw kernel: [ 961.574125] br-f3f3d4f657a6: port 2(veth20ec025) entered blocking state
Nov 7 12:54:04 egw kernel: [ 961.574129] br-f3f3d4f657a6: port 2(veth20ec025) entered disabled state
Nov 7 12:54:04 egw kernel: [ 961.574247] device veth20ec025 entered promiscuous mode
Nov 7 12:54:04 egw systemd-udevd[7825]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Nov 7 12:54:04 egw systemd-udevd[7825]: Using default interface naming scheme ‘v245’.
Nov 7 12:54:04 egw systemd-udevd[7825]: vetha13d06a: Could not generate persistent MAC: No data available
Nov 7 12:54:04 egw systemd-udevd[7826]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Nov 7 12:54:04 egw dockerd[1220]: time=“2021-11-07T12:54:04.351117108+01:00” level=info msg="No non-localhost DNS nameservers are left in resolv.conf. Using default external servers: [nameserver 8.8.8.8 nameserver 8.8.4.4]"
Nov 7 12:54:04 egw dockerd[1220]: time=“2021-11-07T12:54:04.351193845+01:00” level=info msg="IPv6 enabled; Adding default IPv6 external servers: [nameserver 2001:4860:4860::8888 nameserver 2001:4860:4860::8844]“
Nov 7 12:54:04 egw systemd-udevd[7826]: Using default interface naming scheme ‘v245’.
Nov 7 12:54:04 egw systemd-udevd[7826]: veth20ec025: Could not generate persistent MAC: No data available
Nov 7 12:54:04 egw systemd-networkd[582]: veth20ec025: Link UP
Nov 7 12:54:04 egw networkd-dispatcher[841]: WARNING:Unknown index 67 seen, reloading interface list
Nov 7 12:54:04 egw containerd[867]: time=“2021-11-07T12:54:04.376414694+01:00” level=info msg=“starting signal loop” namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/1964109b2a40a9a6fdd364db7ba7a1f000414dc34d008d81b264a30742092573 pid=7846
Nov 7 12:54:04 egw kernel: [ 961.878487] eth0: renamed from vetha13d06a
Nov 7 12:54:04 egw systemd-networkd[582]: veth20ec025: Gained carrier
Nov 7 12:54:04 egw kernel: [ 961.906212] br-f3f3d4f657a6: port 2(veth20ec025) entered blocking state
Nov 7 12:54:04 egw kernel: [ 961.906217] br-f3f3d4f657a6: port 2(veth20ec025) entered forwarding state
Nov 7 12:54:04 egw dockerd[1220]: time=“2021-11-07T12:54:04.842037842+01:00” level=info msg=“ignoring event” container=1964109b2a40a9a6fdd364db7ba7a1f000414dc34d008d81b264a30742092573 module=libcontainerd namespace=moby topic=/tasks/delete type=”*events.TaskDelete"
Nov 7 12:54:04 egw containerd[867]: time=“2021-11-07T12:54:04.842402810+01:00” level=info msg=“shim disconnected” id=1964109b2a40a9a6fdd364db7ba7a1f000414dc34d008d81b264a30742092573
Nov 7 12:54:04 egw containerd[867]: time=“2021-11-07T12:54:04.842509535+01:00” level=warning msg=“cleaning up after shim disconnected” id=1964109b2a40a9a6fdd364db7ba7a1f000414dc34d008d81b264a30742092573 namespace=moby
Nov 7 12:54:04 egw containerd[867]: time=“2021-11-07T12:54:04.842533090+01:00” level=info msg="cleaning up dead shim"
Nov 7 12:54:04 egw containerd[867]: time=“2021-11-07T12:54:04.857001233+01:00” level=warning msg="cleanup warnings time=“2021-11-07T12:54:04+01:00” level=info msg=“starting signal loop” namespace=moby pid=7967\n"
Nov 7 12:54:04 egw systemd-networkd[582]: veth20ec025: Lost carrier
Nov 7 12:54:04 egw kernel: [ 962.092539] br-f3f3d4f657a6: port 2(veth20ec025) entered disabled state
Nov 7 12:54:04 egw kernel: [ 962.092666] vetha13d06a: renamed from eth0
Nov 7 12:54:04 egw networkd-dispatcher[841]: WARNING:Unknown index 67 seen, reloading interface list
Nov 7 12:54:04 egw systemd-udevd[7878]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Nov 7 12:54:04 egw systemd-udevd[7878]: Using default interface naming scheme ‘v245’.
Nov 7 12:54:04 egw systemd-networkd[582]: veth20ec025: Link DOWN
Nov 7 12:54:04 egw kernel: [ 962.142201] br-f3f3d4f657a6: port 2(veth20ec025) entered disabled state
Nov 7 12:54:04 egw kernel: [ 962.144869] device veth20ec025 left promiscuous mode
Nov 7 12:54:04 egw kernel: [ 962.144876] br-f3f3d4f657a6: port 2(veth20ec025) entered disabled state
Nov 7 12:54:04 egw systemd-networkd[582]: rtnl: received neighbor for link ‘68’ we don’t know about, ignoring.
Nov 7 12:54:04 egw systemd-networkd[582]: rtnl: received neighbor for link ‘68’ we don’t know about, ignoring.
Nov 7 12:54:04 egw systemd-udevd[7878]: vetha13d06a: Failed to get link config: No such device
Nov 7 12:54:04 egw networkd-dispatcher[841]: ERROR:Unknown interface index 67 seen even after reload
Nov 7 12:54:04 egw networkd-dispatcher[841]: WARNING:Unknown index 67 seen, reloading interface list
Nov 7 12:54:04 egw networkd-dispatcher[841]: ERROR:Unknown interface index 67 seen even after reload
Nov 7 12:54:04 egw systemd[4270]: run-docker-netns-af0b7552816f.mount: Succeeded.
Nov 7 12:54:04 egw systemd[1]: run-docker-netns-af0b7552816f.mount: Succeeded.
Nov 7 12:54:05 egw systemd[4270]: var-lib-docker-containers-1964109b2a40a9a6fdd364db7ba7a1f000414dc34d008d81b264a30742092573-mounts-shm.mount: Succeeded.
Nov 7 12:54:05 egw systemd[4270]: var-lib-docker-overlay2-c684ea9148053dfa709dba6618adaaba4ca49c8ddbc94c23853834360104e0fc-merged.mount: Succeeded.
Nov 7 12:54:05 egw systemd[1]: var-lib-docker-containers-1964109b2a40a9a6fdd364db7ba7a1f000414dc34d008d81b264a30742092573-mounts-shm.mount: Succeeded.
Nov 7 12:54:05 egw systemd[1]: var-lib-docker-overlay2-c684ea9148053dfa709dba6618adaaba4ca49c8ddbc94c23853834360104e0fc-merged.mount: Succeeded.

und wieder von vorne, aber mit anderen Bridges…

Nov 7 12:55:04 egw kernel: [ 1022.085853] br-f3f3d4f657a6: port 2(veth59d255b) entered blocking state
Nov 7 12:55:04 egw kernel: [ 1022.085856] br-f3f3d4f657a6: port 2(veth59d255b) entered disabled state
Nov 7 12:55:04 egw kernel: [ 1022.085919] device veth59d255b entered promiscuous mode
Nov 7 12:55:04 egw systemd-udevd[8051]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Nov 7 12:55:04 egw dockerd[1220]: time=“2021-11-07T12:55:04.862868655+01:00” level=info msg="No non-localhost DNS nameservers are left in resolv.conf. Using default external servers: [nameserver 8.8.8.8 nameserver 8.8.4.4]"
Nov 7 12:55:04 egw dockerd[1220]: time=“2021-11-07T12:55:04.862920852+01:00” level=info msg="IPv6 enabled; Adding default IPv6 external servers: [nameserver 2001:4860:4860::8888 nameserver 2001:4860:4860::8844]"
Nov 7 12:55:04 egw systemd-udevd[8051]: Using default interface naming scheme ‘v245’.
Nov 7 12:55:04 egw systemd-udevd[8051]: vethc12b51e: Could not generate persistent MAC: No data available
Nov 7 12:55:04 egw systemd-udevd[8052]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Nov 7 12:55:04 egw systemd-udevd[8052]: Using default interface naming scheme ‘v245’.
Nov 7 12:55:04 egw systemd-udevd[8052]: veth59d255b: Could not generate persistent MAC: No data available
Nov 7 12:55:04 egw networkd-dispatcher[841]: WARNING:Unknown index 69 seen, reloading interface list
Nov 7 12:55:04 egw systemd-networkd[582]: veth59d255b: Link UP
Nov 7 12:55:04 egw containerd[867]: time=“2021-11-07T12:55:04.889510247+01:00” level=info msg=“starting signal loop” namespace=moby path=/run/containerd/io.containerd.runtime.v2.task/moby/1964109b2a40a9a6fdd364db7ba7a1f000414dc34d008d81b264a30742092573 pid=8072
Nov 7 12:55:05 egw kernel: [ 1022.390714] eth0: renamed from vethc12b51e
Nov 7 12:55:05 egw systemd-networkd[582]: veth59d255b: Gained carrier
Nov 7 12:55:05 egw kernel: [ 1022.409326] br-f3f3d4f657a6: port 2(veth59d255b) entered blocking state
Nov 7 12:55:05 egw kernel: [ 1022.409330] br-f3f3d4f657a6: port 2(veth59d255b) entered forwarding state
Nov 7 12:55:05 egw containerd[867]: time=“2021-11-07T12:55:05.343147143+01:00” level=info msg=“shim disconnected” id=1964109b2a40a9a6fdd364db7ba7a1f000414dc34d008d81b264a30742092573
Nov 7 12:55:05 egw containerd[867]: time=“2021-11-07T12:55:05.343217795+01:00” level=warning msg=“cleaning up after shim disconnected” id=1964109b2a40a9a6fdd364db7ba7a1f000414dc34d008d81b264a30742092573 namespace=moby
Nov 7 12:55:05 egw containerd[867]: time=“2021-11-07T12:55:05.343233394+01:00” level=info msg=“cleaning up dead shim"
Nov 7 12:55:05 egw dockerd[1220]: time=“2021-11-07T12:55:05.343312160+01:00” level=info msg=“ignoring event” container=1964109b2a40a9a6fdd364db7ba7a1f000414dc34d008d81b264a30742092573 module=libcontainerd namespace=moby topic=/tasks/delete type=”*events.TaskDelete"
Nov 7 12:55:05 egw containerd[867]: time=“2021-11-07T12:55:05.355889926+01:00” level=warning msg="cleanup warnings time=“2021-11-07T12:55:05+01:00” level=info msg=“starting signal loop” namespace=moby pid=8199\n"
Nov 7 12:55:05 egw systemd-networkd[582]: veth59d255b: Lost carrier
Nov 7 12:55:05 egw kernel: [ 1022.590195] br-f3f3d4f657a6: port 2(veth59d255b) entered disabled state
Nov 7 12:55:05 egw kernel: [ 1022.590307] vethc12b51e: renamed from eth0
Nov 7 12:55:05 egw networkd-dispatcher[841]: WARNING:Unknown index 69 seen, reloading interface list
Nov 7 12:55:05 egw systemd-udevd[8094]: ethtool: autonegotiation is unset or enabled, the speed and duplex are not writable.
Nov 7 12:55:05 egw systemd-udevd[8094]: Using default interface naming scheme ‘v245’.
Nov 7 12:55:05 egw kernel: [ 1022.632554] br-f3f3d4f657a6: port 2(veth59d255b) entered disabled state
Nov 7 12:55:05 egw systemd-networkd[582]: veth59d255b: Link DOWN
Nov 7 12:55:05 egw kernel: [ 1022.635083] device veth59d255b left promiscuous mode
Nov 7 12:55:05 egw kernel: [ 1022.635093] br-f3f3d4f657a6: port 2(veth59d255b) entered disabled state
Nov 7 12:55:05 egw systemd-networkd[582]: rtnl: received neighbor for link ‘70’ we don’t know about, ignoring.
Nov 7 12:55:05 egw systemd-networkd[582]: rtnl: received neighbor for link ‘70’ we don’t know about, ignoring.
Nov 7 12:55:05 egw systemd-udevd[8094]: vethc12b51e: Failed to get link config: No such device
Nov 7 12:55:05 egw networkd-dispatcher[841]: ERROR:Unknown interface index 69 seen even after reload
Nov 7 12:55:05 egw networkd-dispatcher[841]: WARNING:Unknown index 69 seen, reloading interface list
Nov 7 12:55:05 egw networkd-dispatcher[841]: ERROR:Unknown interface index 69 seen even after reload
Nov 7 12:55:05 egw systemd[4270]: run-docker-netns-35cf7758a9a1.mount: Succeeded.
Nov 7 12:55:05 egw systemd[1]: run-docker-netns-35cf7758a9a1.mount: Succeeded.
Nov 7 12:55:05 egw systemd[4270]: var-lib-docker-containers-1964109b2a40a9a6fdd364db7ba7a1f000414dc34d008d81b264a30742092573-mounts-shm.mount: Succeeded.
Nov 7 12:55:05 egw systemd[4270]: var-lib-docker-overlay2-c684ea9148053dfa709dba6618adaaba4ca49c8ddbc94c23853834360104e0fc-merged.mount: Succeeded.
Nov 7 12:55:05 egw systemd[1]: var-lib-docker-containers-1964109b2a40a9a6fdd364db7ba7a1f000414dc34d008d81b264a30742092573-mounts-shm.mount: Succeeded.
Nov 7 12:55:05 egw systemd[1]: var-lib-docker-overlay2-c684ea9148053dfa709dba6618adaaba4ca49c8ddbc94c23853834360104e0fc-merged.mount: Succeeded.
Nov 7 12:55:32 egw systemd[1]: Starting Ubuntu Advantage Timer for running repeated jobs…
Nov 7 12:55:32 egw systemd[1]: ua-timer.service: Succeeded.
Nov 7 12:55:32 egw systemd[1]: Finished Ubuntu Advantage Timer for running repeated jobs.

Die EGW selbst scheint davon nicht beeinträchtigt. Auf der Weboberfläche fallen mir keine Fehler auf.

System Ubuntu LTS 20.04 in ESXi VM. Die EGW Container sind die einzigen Container auf der Maschine.

Dank und Gruß,
Hansa

[StefanU]

Hi Hansa.

Ist da neu? (Updates gemacht?)

Was meint ein
docker ps -a
? Wird da vielleicht ein Container ständig neu gestartet?

Stefan

[hansa]

Hi Stefan,

Der Fehler ist schon länger drin. Hatte ich bisher ignoriert, da die Container normal laufen und ich es nicht schnell durch googeln lösen könnte.
Heute war mir jedoch aufgefallen, dass der Fehler aber weitere Auswirkungen hat. Das logfile des cloud-init war auf 500mb angewachsen, da der kein logrotate hat und wohl auch jede Minute getriggert wurde (sieht man im geposteten Log nicht, da ich den da schon ausgeschalteten hatte).
Ein Test-Update auf auf Ubuntu 21.04 hat den Fehler auch nicht beseitigt.

Ich frage mich, welcher Job dafür sorgt, dass das genau alle 60s Auftritt.

Ich teste morgen Mal, was passiert, wenn man die einzelnen Container ausschaltet… Vielleicht finde ich so raus, welcher es verursacht.

Gruß,
Hansa

[StefanU]

Ich teste morgen Mal, was passiert, wenn man die einzelnen Container ausschaltet… Vielleicht finde ich so raus, welcher es verursacht.

Werf doch erst einmal einen Blick auf Docker mit:

Was meint ein
docker ps -a
? Wird da vielleicht ein Container ständig neu gestartet?

Hilft oft schon…

Stefan

[hansa]

11:20 root@egw:[~]: docker ps -a
CONTAINER ID   IMAGE                                     COMMAND                  CREATED         STATUS                           PORTS                      NAMES
d43fa862fc23   rocketchat/rocket.chat:latest             "docker-entrypoint.s…"   6 hours ago     Up 17 minutes                    127.0.0.1:3000->3000/tcp   rocketchat
de9f5f1b2755   nginx:stable-alpine                       "/docker-entrypoint.…"   26 hours ago    Up 23 hours                      127.0.0.1:8080->80/tcp     egroupware-nginx
1523aebd4f34   phpswoole/swoole:latest                   "/entrypoint.sh"         26 hours ago    Up 23 hours                                                 egroupware-push
b09e38a41425   egroupware/egroupware:21.1                "/entrypoint.sh php-…"   26 hours ago    Up 23 hours                      9000/tcp                   egroupware
56f4200e7756   busybox                                   "/bin/true"              26 hours ago    Exited (0) 26 hours ago                                     egroupware-db
92fc98aec9a4   quay.io/egroupware/collabora-key:stable   "/bin/sh -c 'bash st…"   26 hours ago    Up 23 hours                      127.0.0.1:9980->9980/tcp   collabora-key
bea811acd5c1   containrrr/watchtower                     "/watchtower --sched…"   5 months ago    Up 23 hours                      8080/tcp                   egroupware-watchtower
1964109b2a40   mongo:4.0                                 "docker-entrypoint.s…"   9 months ago    Restarting (14) 34 seconds ago                              rocketchat-mongo
29d8698cda57   0712bd00d695                              "docker-entrypoint.s…"   23 months ago   Exited (1) 19 months ago                                    egroupwarerocketchat_mongo-init-replica_1

Ist der ‘mongo:4.0’ der Übertäter?

Als Datenbank benutze ich einen mysqld auf dem Server direkt (nicht in einem Container)…

[hansa]

Treffer!
docker stop 1964109b2a40

Hab den Rocketchat deinstalliert, da ich ihn nicht benötige.

Wahrscheinlich hatte ich den in der Vergangenheit nicht vollständig konfiguriert.

Danke für die Hilfe.
Problem ist für mich damit gelöst.

Gruß,
Hansa