Log messages for Open-ID-Connector / Bind

Hi@all,

I’m looking at the log files right now and I’m finding messages here that I can’t explain.

On the UCS (Master / 192.168.24.4 / srv01.mylocal.lan) the Open-ID-Connector (for Kopano-Meet) is installed.

On the UCS (Member / 192.168.24.6 / com01.mylocal.lan) Kopano + Meet is installed.

If I now execute the join on the member:

univention-run-join-scripts --force

I get “Done” on the member for all steps.
But in the logfile of the master some error messages appear with Open-ID and Docker Network Device:

Jan 26 10:21:49 srv01 sshd[14788]: Accepted keyboard-interactive/pam for Administrator from 192.168.24.6 port 34740 ssh2
Jan 26 10:21:49 srv01 sshd[14788]: pam_unix(sshd:session): session opened for user Administrator by (uid=0)
Jan 26 10:21:50 srv01 sshd[14798]: Received disconnect from 192.168.24.6 port 34740:11: disconnected by user
Jan 26 10:21:50 srv01 sshd[14798]: Disconnected from 192.168.24.6 port 34740
Jan 26 10:21:50 srv01 sshd[14788]: pam_unix(sshd:session): session closed for user Administrator
Jan 26 10:22:03 srv01 sshd[14856]: Accepted keyboard-interactive/pam for com01$ from 192.168.24.6 port 34770 ssh2
Jan 26 10:22:03 srv01 sshd[14856]: pam_unix(sshd:session): session opened for user com01$ by (uid=0)
Jan 26 10:22:04 srv01 sshd[14866]: Received disconnect from 192.168.24.6 port 34770:11: disconnected by user
Jan 26 10:22:04 srv01 sshd[14866]: Disconnected from 192.168.24.6 port 34770
Jan 26 10:22:04 srv01 sshd[14856]: pam_unix(sshd:session): session closed for user com01$
Jan 26 10:22:59 srv01 systemd[1]: Stopping Nagios Remote Plugin Executor...
Jan 26 10:22:59 srv01 nrpe[13027]: Caught SIGTERM - shutting down...
Jan 26 10:22:59 srv01 nrpe[13027]: Daemon shutdown
Jan 26 10:22:59 srv01 systemd[1]: Stopped Nagios Remote Plugin Executor.
Jan 26 10:22:59 srv01 systemd[1]: Started Nagios Remote Plugin Executor.
Jan 26 10:22:59 srv01 nrpe[14985]: Starting up daemon
Jan 26 10:22:59 srv01 nrpe[14985]: Server listening on 0.0.0.0 port 5666.
Jan 26 10:22:59 srv01 nrpe[14985]: Server listening on :: port 5666.
Jan 26 10:22:59 srv01 nrpe[14985]: Listening for connections on port 5666
Jan 26 10:22:59 srv01 nrpe[14985]: Allowing connections from: srv01.gehr.local
Jan 26 10:23:08 srv01 systemd[1]: Stopping LSB: Start the Container for openid-connect-provider...
Jan 26 10:23:10 srv01 containerd[1120]: time="2021-01-26T10:23:10.420698519+01:00" level=info msg="shim reaped" id=244f1657ded420209e8f8c9e0ad42032cb6bd05fdc4f05575399514d7025482c
Jan 26 10:23:10 srv01 dockerd[1694]: time="2021-01-26T10:23:10.430705906+01:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
Jan 26 10:23:10 srv01 kernel: docker0: port 1(vethf628348) entered disabled state
Jan 26 10:23:10 srv01 kernel: veth494abeb: renamed from eth0
Jan 26 10:23:10 srv01 kernel: docker0: port 1(vethf628348) entered disabled state
Jan 26 10:23:10 srv01 kernel: device vethf628348 left promiscuous mode
Jan 26 10:23:10 srv01 kernel: docker0: port 1(vethf628348) entered disabled state
Jan 26 10:23:11 srv01 docker-app-openid-connect-provider[15006]: Stopping openid-connect-provider Container 244f1657ded420209e8f8c9e0ad42032cb6bd05fdc4f05575399514d7025482c ....
Jan 26 10:23:12 srv01 systemd[1]: Stopped LSB: Start the Container for openid-connect-provider.
Jan 26 10:23:12 srv01 systemd[1]: Starting LSB: Start the Container for openid-connect-provider...
Jan 26 10:23:13 srv01 ntpd[1311]: Deleting interface #14 docker0, 172.17.42.1#123, interface stats: received=0, sent=0, dropped=0, active_time=323 secs
Jan 26 10:23:13 srv01 ntpd[1311]: Deleting interface #15 docker0, fe80::42:b9ff:fe91:bf35%3#123, interface stats: received=0, sent=0, dropped=0, active_time=323 secs
Jan 26 10:23:13 srv01 ntpd[1311]: Deleting interface #17 vethf628348, fe80::88d:5ff:fece:ec38%9#123, interface stats: received=0, sent=0, dropped=0, active_time=321 secs
Jan 26 10:23:13 srv01 kernel: docker0: port 1(veth7d389fc) entered blocking state
Jan 26 10:23:13 srv01 kernel: docker0: port 1(veth7d389fc) entered disabled state
Jan 26 10:23:13 srv01 kernel: device veth7d389fc entered promiscuous mode
Jan 26 10:23:13 srv01 kernel: IPv6: ADDRCONF(NETDEV_UP): veth7d389fc: link is not ready
Jan 26 10:23:13 srv01 systemd-udevd[15485]: Could not generate persistent MAC address for veth94eb064: No such file or directory
Jan 26 10:23:13 srv01 systemd-udevd[15486]: Could not generate persistent MAC address for veth7d389fc: No such file or directory
Jan 26 10:23:13 srv01 containerd[1120]: time="2021-01-26T10:23:13.465167276+01:00" level=info msg="shim containerd-shim started" address="/containerd-shim/moby/244f1657ded420209e8f8c9e0ad42032cb6bd05fdc4f05575399514d7025482c/shim.sock" debug=false pid=15534
Jan 26 10:23:13 srv01 kernel: eth0: renamed from veth94eb064
Jan 26 10:23:13 srv01 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth7d389fc: link becomes ready
Jan 26 10:23:13 srv01 kernel: docker0: port 1(veth7d389fc) entered blocking state
Jan 26 10:23:13 srv01 kernel: docker0: port 1(veth7d389fc) entered forwarding state
Jan 26 10:23:14 srv01 docker-app-openid-connect-provider[15426]: Starting openid-connect-provider Container 244f1657ded420209e8f8c9e0ad42032cb6bd05fdc4f05575399514d7025482c ....
Jan 26 10:23:14 srv01 systemd[1]: Started LSB: Start the Container for openid-connect-provider.
Jan 26 10:23:15 srv01 ntpd[1311]: Listen normally on 18 docker0 172.17.42.1:123
Jan 26 10:23:15 srv01 ntpd[1311]: Listen normally on 19 docker0 [fe80::42:b9ff:fe91:bf35%3]:123
Jan 26 10:23:15 srv01 ntpd[1311]: bind(27) AF_INET6 fe80::9c9f:a4ff:fe76:efcd%11#123 flags 0x11 failed: Cannot assign requested address
Jan 26 10:23:15 srv01 ntpd[1311]: unable to create socket on veth7d389fc (20) for fe80::9c9f:a4ff:fe76:efcd%11#123
Jan 26 10:23:15 srv01 ntpd[1311]: failed to init interface for address fe80::9c9f:a4ff:fe76:efcd%11
Jan 26 10:23:15 srv01 systemd[1]: Stopping LSB: Start the Container for openid-connect-provider...
Jan 26 10:23:17 srv01 ntpd[1311]: Listen normally on 21 veth7d389fc [fe80::9c9f:a4ff:fe76:efcd%11]:123
Jan 26 10:23:17 srv01 containerd[1120]: time="2021-01-26T10:23:17.683807995+01:00" level=info msg="shim reaped" id=244f1657ded420209e8f8c9e0ad42032cb6bd05fdc4f05575399514d7025482c
Jan 26 10:23:17 srv01 dockerd[1694]: time="2021-01-26T10:23:17.693836184+01:00" level=info msg="ignoring event" module=libcontainerd namespace=moby topic=/tasks/delete type="*events.TaskDelete"
Jan 26 10:23:17 srv01 kernel: docker0: port 1(veth7d389fc) entered disabled state
Jan 26 10:23:17 srv01 kernel: veth94eb064: renamed from eth0
Jan 26 10:23:17 srv01 kernel: docker0: port 1(veth7d389fc) entered disabled state
Jan 26 10:23:17 srv01 kernel: device veth7d389fc left promiscuous mode
Jan 26 10:23:17 srv01 kernel: docker0: port 1(veth7d389fc) entered disabled state
Jan 26 10:23:18 srv01 docker-app-openid-connect-provider[15669]: Stopping openid-connect-provider Container 244f1657ded420209e8f8c9e0ad42032cb6bd05fdc4f05575399514d7025482c ....
Jan 26 10:23:19 srv01 systemd[1]: Stopped LSB: Start the Container for openid-connect-provider.
Jan 26 10:23:19 srv01 systemd[1]: Starting LSB: Start the Container for openid-connect-provider...
Jan 26 10:23:20 srv01 ntpd[1311]: Deleting interface #18 docker0, 172.17.42.1#123, interface stats: received=0, sent=0, dropped=0, active_time=5 secs
Jan 26 10:23:20 srv01 ntpd[1311]: Deleting interface #19 docker0, fe80::42:b9ff:fe91:bf35%3#123, interface stats: received=0, sent=0, dropped=0, active_time=5 secs
Jan 26 10:23:20 srv01 ntpd[1311]: Deleting interface #21 veth7d389fc, fe80::9c9f:a4ff:fe76:efcd%11#123, interface stats: received=0, sent=0, dropped=0, active_time=3 secs
Jan 26 10:23:20 srv01 systemd-udevd[16139]: Could not generate persistent MAC address for veth9357fd5: No such file or directory
Jan 26 10:23:20 srv01 kernel: docker0: port 1(veth30f6576) entered blocking state
Jan 26 10:23:20 srv01 kernel: docker0: port 1(veth30f6576) entered disabled state
Jan 26 10:23:20 srv01 kernel: device veth30f6576 entered promiscuous mode
Jan 26 10:23:20 srv01 kernel: IPv6: ADDRCONF(NETDEV_UP): veth30f6576: link is not ready
Jan 26 10:23:20 srv01 systemd-udevd[16140]: Could not generate persistent MAC address for veth30f6576: No such file or directory
Jan 26 10:23:20 srv01 containerd[1120]: time="2021-01-26T10:23:20.679600781+01:00" level=info msg="shim containerd-shim started" address="/containerd-shim/moby/244f1657ded420209e8f8c9e0ad42032cb6bd05fdc4f05575399514d7025482c/shim.sock" debug=false pid=16187
Jan 26 10:23:20 srv01 kernel: eth0: renamed from veth9357fd5
Jan 26 10:23:20 srv01 kernel: IPv6: ADDRCONF(NETDEV_CHANGE): veth30f6576: link becomes ready
Jan 26 10:23:20 srv01 kernel: docker0: port 1(veth30f6576) entered blocking state
Jan 26 10:23:20 srv01 kernel: docker0: port 1(veth30f6576) entered forwarding state
Jan 26 10:23:22 srv01 docker-app-openid-connect-provider[16077]: Starting openid-connect-provider Container 244f1657ded420209e8f8c9e0ad42032cb6bd05fdc4f05575399514d7025482c ....
Jan 26 10:23:22 srv01 systemd[1]: Started LSB: Start the Container for openid-connect-provider.
Jan 26 10:23:22 srv01 ntpd[1311]: Listen normally on 22 docker0 172.17.42.1:123
Jan 26 10:23:22 srv01 ntpd[1311]: Listen normally on 23 docker0 [fe80::42:b9ff:fe91:bf35%3]:123
Jan 26 10:23:22 srv01 ntpd[1311]: bind(27) AF_INET6 fe80::586f:25ff:fee7:1675%13#123 flags 0x11 failed: Cannot assign requested address
Jan 26 10:23:22 srv01 ntpd[1311]: unable to create socket on veth30f6576 (24) for fe80::586f:25ff:fee7:1675%13#123
Jan 26 10:23:22 srv01 ntpd[1311]: failed to init interface for address fe80::586f:25ff:fee7:1675%13
Jan 26 10:23:24 srv01 ntpd[1311]: Listen normally on 25 veth30f6576 [fe80::586f:25ff:fee7:1675%13]:123
Jan 26 10:23:38 srv01 systemd[1]: Starting Cleanup of Temporary Directories...
Jan 26 10:23:38 srv01 systemd[1]: Started Cleanup of Temporary Directories.
Jan 26 10:24:11 srv01 slapd[1615]: slap_global_control: unrecognized control: 1.3.6.1.4.1.42.2.27.8.5.1
Jan 26 10:24:11 srv01 slapd[1615]: get_filter: conn 1906 unknown attribute type=sudoHost (17)
Jan 26 10:24:11 srv01 slapd[1615]: get_ssa: conn 1906 unknown attribute type=sudoHost (17)
Jan 26 10:24:11 srv01 slapd[1615]: get_ssa: conn 1906 unknown attribute type=sudoHost (17)
Jan 26 10:24:11 srv01 slapd[1615]: get_ssa: conn 1906 unknown attribute type=sudoHost (17)
Jan 26 10:24:11 srv01 slapd[1615]: get_ssa: conn 1906 unknown attribute type=sudoHost (17)
Jan 26 10:24:11 srv01 slapd[1615]: get_ssa: conn 1906 unknown attribute type=sudoHost (17)
Jan 26 10:25:01 srv01 CRON[16476]: pam_unix(cron:session): session opened for user root by (uid=0)
Jan 26 10:25:01 srv01 CRON[16477]: pam_unix(cron:session): session opened for user root by (uid=0)
Jan 26 10:25:01 srv01 CRON[16484]: (root) CMD (/usr/sbin/jitter 60 /usr/share/univention-samba4/scripts/sysvol-sync.sh >>/var/log/univention/sysvol-sync.log 2>&1)
Jan 26 10:25:01 srv01 CRON[16487]: (root) CMD (if [ -x /usr/bin/mrtg ] && [ -r /etc/mrtg.cfg ] && [ -d "$(grep '^[[:space:]]*[^#]*[[:space:]]*WorkDir' /etc/mrtg.cfg | awk '{ print $NF }')" ]; then mkdir -p /var/log/mrtg ; env LANG=C /usr/bin/mrtg /etc/mrtg.cfg 2>&1 | tee -a /var/log/mrtg/mrtg.log ; fi)
Jan 26 10:25:01 srv01 CRON[16476]: pam_unix(cron:session): session closed for user root
Jan 26 10:25:17 srv01 CRON[16477]: pam_unix(cron:session): session closed for user root
Jan 26 10:27:28 srv01 named[1443]: DNS format error from 192.168.24.254#53 resolving list-manage.com.multi.uribl.com/A for client 192.168.24.6#44173: non-improving referral
Jan 26 10:27:28 srv01 named[1443]: FORMERR resolving 'list-manage.com.multi.uribl.com/A/IN': 192.168.24.254#53
Jan 26 10:27:28 srv01 named[1443]: DNS format error from 192.168.24.254#53 resolving campaign-archive.com.multi.uribl.com/A for client 192.168.24.6#44173: non-improving referral

I made usc-sso available from external or changed the domain to ucs-sso.external.de. As described here:
UCS Single Sign On

Is it possibly related to that?

with best
sven