Attachment 11223 Details for Bug 55045 – bootup logfile of comment-2

bootup logfile of comment-2

named-fails-to-start.txt (text/plain), 18.52 KB, created by office on 2024-06-28 13:30:13 CEST

(hide)

Description:

Filename:

MIME Type:

Creator: office

Created: 2024-06-28 13:30:13 CEST

Size: 18.52 KB

patch

obsolete

>-- Logs begin at Sun 2024-05-05 21:05:13 CEST, end at Wed 2024-06-26 22:51:15 CEST. --
>Jun 26 22:35:01 drude univention-firewall[1068]: Starting Univention iptables --wait configuration...done.
>Jun 26 22:35:01 drude systemd[1]: Started LSB: Univention iptables --wait configuration.
>Jun 26 22:35:02 drude systemd[1]: systemd-fsckd.service: Succeeded.
>Jun 26 22:35:02 drude CRON[1356]: (root) CMD (/usr/share/univention-updater/updater-statistics > /dev/zero)
>Jun 26 22:35:02 drude CRON[1358]: (root) CMD (command -v debian-sa1 > /dev/null && debian-sa1 1 1)
>Jun 26 22:35:02 drude CRON[1360]: (root) CMD (/usr/share/univention-updater/enable-apache2-umc)
>Jun 26 22:35:02 drude CRON[1364]: (root) CMD (run-parts /usr/share/univention-monitoring-client/scripts/)
>Jun 26 22:35:02 drude CRON[1366]: (root) CMD (/usr/sbin/jitter 60 /usr/share/univention-samba4/scripts/sysvol-sync.sh >>/var/log/univention/sysvol-sync.log 2>&1)
>Jun 26 22:35:02 drude systemd[1]: Reloading.
>Jun 26 22:35:03 drude systemd[1]: dev-disk-by\x2dlabel-usershares.device: Dependency Before=network-online.target ignored (.device units cannot be delayed)
>Jun 26 22:35:03 drude systemd[1]: dev-disk-by\x2dlabel-usershares.device: Dependency Before=network.target ignored (.device units cannot be delayed)
>Jun 26 22:35:03 drude systemd[1]: dev-disk-by\x2dlabel-daten\x2dallgemein.device: Dependency Before=network-online.target ignored (.device units cannot be delayed)
>Jun 26 22:35:03 drude systemd[1]: dev-disk-by\x2dlabel-daten\x2dallgemein.device: Dependency Before=network.target ignored (.device units cannot be delayed)
>Jun 26 22:35:03 drude systemd[1]: /run/systemd/generator.late/univention-s4-connector.service:22: PIDFile= references path below legacy directory /var/run/, updating /var/run/univention-s4-connector â /run/univention-s4-connector; please update the unit file accordingly.
>Jun 26 22:35:03 drude systemd[1]: /lib/systemd/system/squid.service:7: PIDFile= references path below legacy directory /var/run/, updating /var/run/squid.pid â /run/squid.pid; please update the unit file accordingly.
>Jun 26 22:35:03 drude systemd[1]: /run/systemd/generator.late/slapd.service:20: PIDFile= references path below legacy directory /var/run/, updating /var/run/slapd/slapd.pid â /run/slapd/slapd.pid; please update the unit file accordingly.
>Jun 26 22:35:03 drude systemd[1]: /lib/systemd/system/rpc-statd.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/rpc.statd.pid â /run/rpc.statd.pid; please update the unit file accordingly.
>Jun 26 22:35:03 drude systemd[1]: /lib/systemd/system/nagios-nrpe-server.service:12: PIDFile= references path below legacy directory /var/run/, updating /var/run/nagios/nrpe.pid â /run/nagios/nrpe.pid; please update the unit file accordingly.
>Jun 26 22:35:03 drude systemd[1]: /lib/systemd/system/memcached.service:13: PIDFile= references path below legacy directory /var/run/, updating /var/run/memcached/memcached.pid â /run/memcached/memcached.pid; please update the unit file accordingly.
>Jun 26 22:35:03 drude systemd[1]: Starting Daily apt download activities...
>Jun 26 22:35:03 drude systemd[1]: univention-management-console-server.service: Main process exited, code=killed, status=15/TERM
>Jun 26 22:35:03 drude systemd[1]: univention-management-console-server.service: Succeeded.
>Jun 26 22:35:03 drude systemd[1]: Stopped Univention Management Console Server.
>Jun 26 22:35:03 drude systemd[1]: Starting Univention Management Console Server...
>Jun 26 22:35:09 drude slapd[1445]: WARNING: No dynamic config support for overlay translog.
>Jun 26 22:35:09 drude slapd[1445]: WARNING: No dynamic config support for overlay shadowbind.
>Jun 26 22:35:11 drude nmbd[1066]: Starting NetBIOS name server: nmbd.
>Jun 26 22:35:11 drude systemd[1]: Started LSB: Samba NetBIOS nameserver (nmbd).
>Jun 26 22:35:11 drude samba-ad-dc[1056]: Starting nmbd (via systemctl): nmbd.service.
>Jun 26 22:35:12 drude slapd[1445]: slapd starting
>Jun 26 22:35:12 drude slapd[1070]: Starting ldap server(s): slapd ...done.
>Jun 26 22:35:12 drude ldapsearch[1493]: DIGEST-MD5 common mech free
>Jun 26 22:35:13 drude samba-ad-dc[1056]: Starting Samba AD DC server: samba.
>Jun 26 22:35:13 drude systemd[1]: Started LSB: Samba daemons for the AD DC.
>Jun 26 22:35:13 drude systemd[1]: Starting BIND Domain Name Server with samba4 backend...
>Jun 26 22:35:15 drude slapd[1070]: Checking Schema ID: ...done.
>Jun 26 22:35:15 drude systemd[1]: Started LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol).
>Jun 26 22:35:15 drude systemd[1]: Starting LSB: Univention Directory Policy...
>Jun 26 22:35:15 drude systemd[1]: Starting Univention Directory Notifier Daemon...
>Jun 26 22:35:15 drude systemd[1]: Starting LSB: Univention S4 Connector...
>Jun 26 22:35:16 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:16 drude named[1505]: starting BIND 9.11.5-P4-5.1+deb10u11A~5.0.7.202405211119-Univention (Extended Support Version) <id:998753c>
>Jun 26 22:35:16 drude named[1505]: running on Linux x86_64 4.19.0-26-amd64 #1 SMP Debian 4.19.304-1 (2024-01-09)
>Jun 26 22:35:16 drude named[1505]: built with '--build=x86_64-linux-gnu' '--prefix=/usr' '--includedir=/usr/include' '--mandir=/usr/share/man' '--infodir=/usr/share/info' '--sysconfdir=/etc' '--localstatedir=/var' '--disable-silent-rules' '--libdir=/usr/lib/x86_64-linux-gnu' '--libexecdir=/usr/lib/x86_64-linux-gnu' '--disable-maintainer-mode' '--disable-dependency-tracking' '--libdir=/usr/lib/x86_64-linux-gnu' '--sysconfdir=/etc/bind' '--with-python=python3' '--localstatedir=/' '--enable-threads' '--enable-largefile' '--with-libtool' '--enable-shared' '--with-dlz-dlopen' '--enable-static' '--with-gost=no' '--with-openssl=/usr' '--with-gssapi=/usr' '--disable-isc-spnego' '--with-libidn2' '--with-libjson=/usr' '--with-lmdb=/usr' '--with-gnu-ld' '--with-geoip=/usr' '--with-atf=no' '--enable-ipv6' '--enable-rrl' '--enable-filter-aaaa' '--enable-native-pkcs11' '--with-pkcs11=/usr/lib/softhsm/libsofthsm2.so' '--with-randomdev=/dev/urandom' '--enable-dnstap' '--with-cmocka' 'build_alias=x86_64-linux-gnu' 'CFLAGS=-g -O2 -fdebug-prefix-map=/var/build/temp/tmp7ua8zvi6/bind9-9.11.5.P4+dfsg=. -fstack-protector-strong -Wformat -Werror=format-security -fno-strict-aliasing -fno-delete-null-pointer-checks -DNO_VERSION_DATE -DDIG_SIGCHASE' 'LDFLAGS=-Wl,-z,relro -Wl,-z,now' 'CPPFLAGS=-Wdate-time -D_FORTIFY_SOURCE=2'
>Jun 26 22:35:16 drude named[1505]: running as: named -c /etc/bind/named.conf.samba4 -f -d 0
>Jun 26 22:35:16 drude named[1505]: compiled by GCC 8.3.0
>Jun 26 22:35:16 drude named[1505]: compiled with OpenSSL version: OpenSSL 1.1.1n  15 Mar 2022
>Jun 26 22:35:16 drude named[1505]: linked to OpenSSL version: OpenSSL 1.1.1n  15 Mar 2022
>Jun 26 22:35:16 drude named[1505]: compiled with libxml2 version: 2.9.4
>Jun 26 22:35:16 drude named[1505]: linked to libxml2 version: 20904
>Jun 26 22:35:16 drude named[1505]: compiled with libjson-c version: 0.12.1
>Jun 26 22:35:16 drude named[1505]: linked to libjson-c version: 0.12.1
>Jun 26 22:35:16 drude named[1505]: threads support is enabled
>Jun 26 22:35:16 drude named[1505]: ----------------------------------------------------
>Jun 26 22:35:16 drude named[1505]: BIND 9 is maintained by Internet Systems Consortium,
>Jun 26 22:35:16 drude named[1505]: Inc. (ISC), a non-profit 501(c)(3) public-benefit
>Jun 26 22:35:16 drude named[1505]: corporation.  Support and training for BIND 9 are
>Jun 26 22:35:16 drude named[1505]: available at https://www.isc.org/support
>Jun 26 22:35:16 drude named[1505]: ----------------------------------------------------
>Jun 26 22:35:16 drude named[1505]: adjusted limit on open files from 524288 to 1048576
>Jun 26 22:35:16 drude named[1505]: found 3 CPUs, using 3 worker threads
>Jun 26 22:35:16 drude named[1505]: using 2 UDP listeners per interface
>Jun 26 22:35:16 drude named[1505]: using up to 4096 sockets
>Jun 26 22:35:17 drude named[1505]: loading configuration from '/etc/bind/named.conf.samba4'
>Jun 26 22:35:17 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:18 drude named[1505]: reading built-in trust anchors from file '/etc/bind/bind.keys'
>Jun 26 22:35:18 drude named[1505]: GeoIP Country (IPv4) (type 1) DB not available
>Jun 26 22:35:18 drude named[1505]: GeoIP Country (IPv6) (type 12) DB not available
>Jun 26 22:35:18 drude named[1505]: GeoIP City (IPv4) (type 2) DB not available
>Jun 26 22:35:18 drude named[1505]: GeoIP City (IPv4) (type 6) DB not available
>Jun 26 22:35:18 drude named[1505]: GeoIP City (IPv6) (type 30) DB not available
>Jun 26 22:35:18 drude named[1505]: GeoIP City (IPv6) (type 31) DB not available
>Jun 26 22:35:18 drude named[1505]: GeoIP Region (type 3) DB not available
>Jun 26 22:35:18 drude named[1505]: GeoIP Region (type 7) DB not available
>Jun 26 22:35:18 drude named[1505]: GeoIP ISP (type 4) DB not available
>Jun 26 22:35:18 drude named[1505]: GeoIP Org (type 5) DB not available
>Jun 26 22:35:18 drude named[1505]: GeoIP AS (type 9) DB not available
>Jun 26 22:35:18 drude named[1505]: GeoIP Domain (type 11) DB not available
>Jun 26 22:35:18 drude named[1505]: GeoIP NetSpeed (type 10) DB not available
>Jun 26 22:35:18 drude named[1505]: using default UDP/IPv4 port range: [32768, 60999]
>Jun 26 22:35:18 drude named[1505]: using default UDP/IPv6 port range: [32768, 60999]
>Jun 26 22:35:18 drude named[1505]: listening on IPv6 interfaces, port 53
>Jun 26 22:35:18 drude named[1505]: listening on IPv4 interface lo, 127.0.0.1#53
>Jun 26 22:35:18 drude named[1505]: listening on IPv4 interface ens3, 172.16.10.32#53
>Jun 26 22:35:18 drude named[1505]: generating session key for dynamic DNS
>Jun 26 22:35:18 drude named[1505]: sizing zone task pool based on 1 zones
>Jun 26 22:35:18 drude named[1505]: Loading 'samba4.zone' using driver dlopen
>Jun 26 22:35:18 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:19 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:20 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:21 drude systemd[1]: Started Univention Directory Notifier Daemon.
>Jun 26 22:35:21 drude systemd[1]: Starting Univention Directory Listener Daemon...
>Jun 26 22:35:21 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:22 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:22 drude systemd[1]: Started Univention Directory Listener Daemon.
>Jun 26 22:35:23 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:24 drude systemd[1]: apt-daily.service: Succeeded.
>Jun 26 22:35:24 drude systemd[1]: Started Daily apt download activities.
>Jun 26 22:35:24 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:25 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:26 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:27 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:28 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:29 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:30 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:31 drude ldapsearch[1709]: DIGEST-MD5 common mech free
>Jun 26 22:35:31 drude ldapsearch[1710]: DIGEST-MD5 common mech free
>Jun 26 22:35:31 drude ldapsearch[1711]: DIGEST-MD5 common mech free
>Jun 26 22:35:31 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:32 drude univention-directory-policy[1517]: Applying Univention Directory Policy...done.
>Jun 26 22:35:32 drude systemd[1]: Started LSB: Univention Directory Policy.
>Jun 26 22:35:32 drude systemd[1]: univention-self-service-invitation.service: Service RestartSec=1min expired, scheduling restart.
>Jun 26 22:35:32 drude systemd[1]: univention-self-service-invitation.service: Scheduled restart job, restart counter is at 1.
>Jun 26 22:35:32 drude systemd[1]: Stopped Univention self service invitation daemon.
>Jun 26 22:35:32 drude systemd[1]: Started Univention self service invitation daemon.
>Jun 26 22:35:32 drude systemd[1]: univention-self-service-invitation.service: Main process exited, code=exited, status=1/FAILURE
>Jun 26 22:35:32 drude systemd[1]: univention-self-service-invitation.service: Failed with result 'exit-code'.
>Jun 26 22:35:32 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:34 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:35 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:36 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:37 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:38 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:39 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:40 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:41 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:42 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:43 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:44 drude samba4[1506]: rndc: connect failed: 127.0.0.1#953: connection refused
>Jun 26 22:35:44 drude dockerd[1084]: time="2024-06-26T22:35:44.470199033+02:00" level=info msg="Starting up"
>Jun 26 22:35:45 drude systemd[1]: bind9.service: Control process exited, code=exited, status=124/n/a
>Jun 26 22:35:45 drude dockerd[1084]: time="2024-06-26T22:35:45.339408491+02:00" level=info msg="parsed scheme: \"unix\"" module=grpc
>Jun 26 22:35:45 drude dockerd[1084]: time="2024-06-26T22:35:45.339445330+02:00" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
>Jun 26 22:35:45 drude dockerd[1084]: time="2024-06-26T22:35:45.339474084+02:00" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///run/containerd/containerd.sock  <nil> 0 <nil>}] <nil> <nil>}" module=grpc
>Jun 26 22:35:45 drude dockerd[1084]: time="2024-06-26T22:35:45.339487921+02:00" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
>Jun 26 22:35:45 drude dockerd[1084]: time="2024-06-26T22:35:45.463530695+02:00" level=info msg="parsed scheme: \"unix\"" module=grpc
>Jun 26 22:35:45 drude dockerd[1084]: time="2024-06-26T22:35:45.463664027+02:00" level=info msg="scheme \"unix\" not registered, fallback to default scheme" module=grpc
>Jun 26 22:35:45 drude dockerd[1084]: time="2024-06-26T22:35:45.463701228+02:00" level=info msg="ccResolverWrapper: sending update to cc: {[{unix:///run/containerd/containerd.sock  <nil> 0 <nil>}] <nil> <nil>}" module=grpc
>Jun 26 22:35:45 drude dockerd[1084]: time="2024-06-26T22:35:45.463719001+02:00" level=info msg="ClientConn switching balancer to \"pick_first\"" module=grpc
>Jun 26 22:35:46 drude systemd[1]: var-lib-docker-overlay2-check\x2doverlayfs\x2dsupport2430203902-merged.mount: Succeeded.
>Jun 26 22:35:54 drude named[1505]: samba_dlz: started for DN DC=intern,DC=colvistec,DC=de
>Jun 26 22:35:54 drude named[1505]: samba_dlz: starting configure
>Jun 26 22:35:54 drude univention-s4-connector[1521]: Starting Univention S4 Connector: univention-s4-connector.
>Jun 26 22:35:54 drude systemd[1]: univention-s4-connector.service: Supervising process 2115 which is not our child. We'll most likely not notice when it exits.
>Jun 26 22:35:54 drude systemd[1]: Started LSB: Univention S4 Connector.
>Jun 26 22:35:55 drude named[1505]: samba_dlz: trying partition 'CN=MicrosoftDNS,DC=DomainDnsZones,DC=intern,DC=colvistec,DC=de'
>Jun 26 22:35:55 drude named[1505]: samba_dlz: configured writeable zone '8.16.172.in-addr.arpa'
>Jun 26 22:35:55 drude named[1505]: samba_dlz: trying partition 'CN=MicrosoftDNS,DC=DomainDnsZones,DC=intern,DC=colvistec,DC=de'
>Jun 26 22:35:55 drude named[1505]: samba_dlz: configured writeable zone '10.16.172.in-addr.arpa'
>Jun 26 22:35:55 drude named[1505]: samba_dlz: trying partition 'CN=MicrosoftDNS,DC=DomainDnsZones,DC=intern,DC=colvistec,DC=de'
>Jun 26 22:35:55 drude named[1505]: samba_dlz: configured writeable zone '178.168.192.in-addr.arpa'
>Jun 26 22:35:55 drude named[1505]: samba_dlz: trying partition 'CN=MicrosoftDNS,DC=DomainDnsZones,DC=intern,DC=colvistec,DC=de'
>Jun 26 22:35:55 drude named[1505]: samba_dlz: configured writeable zone '9.16.172.in-addr.arpa'
>Jun 26 22:35:55 drude named[1505]: samba_dlz: trying partition 'CN=MicrosoftDNS,DC=DomainDnsZones,DC=intern,DC=colvistec,DC=de'
>Jun 26 22:35:55 drude named[1505]: samba_dlz: configured writeable zone '16.16.172.in-addr.arpa'
>Jun 26 22:35:55 drude named[1505]: samba_dlz: trying partition 'CN=MicrosoftDNS,DC=DomainDnsZones,DC=intern,DC=colvistec,DC=de'
>Jun 26 22:35:55 drude named[1505]: samba_dlz: configured writeable zone 'intern.colvistec.de'
>Jun 26 22:35:55 drude named[1505]: samba_dlz: trying partition 'CN=MicrosoftDNS,DC=ForestDnsZones,DC=intern,DC=colvistec,DC=de'
>Jun 26 22:35:55 drude named[1505]: samba_dlz: configured writeable zone '_msdcs.intern.colvistec.de'
>Jun 26 22:35:55 drude named[1505]: none:106: 'max-cache-size 90%' - setting to 5308MB (out of 5898MB)
>Jun 26 22:35:55 drude named[1505]: ../../../bin/named/client.c:3516: unexpected error:
>Jun 26 22:35:55 drude named[1505]: isc_socket_accept() failed: shutting down
>Jun 26 22:35:55 drude named[1505]: ../../../bin/named/client.c:3516: unexpected error:
>Jun 26 22:35:55 drude named[1505]: isc_socket_accept() failed: shutting down
>Jun 26 22:35:55 drude named[1505]: ../../../bin/named/client.c:3516: unexpected error:
>Jun 26 22:35:55 drude named[1505]: isc_socket_accept() failed: shutting down
>Jun 26 22:35:55 drude named[1505]: loading configuration: shutting down
>Jun 26 22:35:55 drude named[1505]: exiting (due to fatal error)
>Jun 26 22:35:55 drude systemd[1]: bind9.service: Main process exited, code=exited, status=1/FAILURE
>Jun 26 22:35:55 drude systemd[1]: bind9.service: Failed with result 'exit-code'.
>Jun 26 22:35:55 drude systemd[1]: Failed to start BIND Domain Name Server with samba4 backend.
>Jun 26 22:35:55 drude systemd[1]: Reached target Host and Network Name Lookups.
>Jun 26 22:35:56 drude systemd[1]: Started Nagios Remote Plugin Executor.
>Jun 26 22:35:56 drude systemd[1]: Starting LSB: Univention Updater...
>Jun 26 22:35:56 drude systemd[1]: Started Bareos File Daemon service.
>Jun 26 22:35:56 drude systemd[1]: Started Bareos Director Daemon service.
>Jun 26 22:35:56 drude systemd[1]: Starting Squid Web Proxy Server...
>Jun 26 22:35:56 drude systemd[1]: Starting Postfix Mail Transport Agent (instance -)...

Actions: View

Attachments on bug 55045: 11223