System unstable after upgrade to bullseye

I run a Pioneer-FreedomBox-HSK behind a Router (AVM Fritzbox 7390). The Pioneer is mounted and connected to a Bay-SSD. I moved the file system to the ssd as described here.
I have to HDD’s connected to the USB-Slots, which are formatted as a RAID 1 (Also described in the post linked above).
I have installed bepasty, calibre, mindlna, radicale, samba and synthing. I also run a gitea service on the box (Outside of freedombox)

I bought / ordered it on 2021-01-06
About: You are running Debian GNU/Linux 11 (bullseye) and FreedomBox version 21.7. FreedomBox is up to date.

The system ran smoothly until a couple of days before when it upgraded to bullseye. Since than the box reboots rather randomly every other day and is not reachable anymore over the network until I unplug power and power it on again.

At the moment I have no clue where to start looking. I disabled auto-update and auto-update to next stable release but to no avail. I also had issues with the syslog service not running anymore. I reinstalled and reenabled rsyslog.service again, but I have the feeling, that it misses a lot of entries, as there are huge gaps in the timestamps.

Where may I start looking for possible issues?

Regards Oliver

1 Like

This is intentional as single log with journald is better on SD cards instead of multiple files and copies. Please use systemd-journald instead. To see the logs, run:

$ journalctl 

Various units and date ranges can be filtered, see man journalctl.

My own primary FreedomBox Olimex Pioneer hardware went to twice after the upgrade. Each time it seem to have lasted around 3 days. Logs show nothing unusual, just regular messages and then suddenly nothing until next reboot.

I have now connected a USB serial console to the machine and started it. This is a good way to debug further. If there are any kernel messages such as crashes we should be able to see them on this console. If I find anything, I will post here.

1 Like

I scanned through my logs and found a number of odd/ suspicious entries:

sudo journalctl -q

# The log starts on Jul 13 with a number of records, then:
# Oddly the recorded timestamp is quite different from the next one in the log
Jul 13 17:29:42 freedombox systemd-timesyncd[350]: System clock time unset or jumped backwards, restoring from recorded timestamp: Sat 2021-08-28 04:47:02 UTC
Aug 18 02:00:52 freedombox kernel: Booting Linux on physical CPU 0x0

# pam-abl started to throw errors
Aug 18 03:39:36 freedombox pam-abl[6793]: BDB0087 DB_RUNRECOVERY: Fatal error, run database recovery (-30973) while starting transaction
Aug 18 03:39:36 freedombox pam-abl[6793]: BDB0087 DB_RUNRECOVERY: Fatal error, run database recovery (-30973) while starting the transaction to record_attempt.

# Switching dates???
Aug 18 04:18:38 freedombox sshd[8401]: pam_unix(sshd:auth): authentication failure; logname= uid=0 euid=0 tty=ssh ruser= rhost=78.192.6.4
Jul 13 17:29:38 freedombox systemd-timesyncd[370]: System clock time unset or jumped backwards, restoring from recorded timestamp: Wed 2021-08-18 04:18:39 UTC
Aug 18 04:18:40 freedombox sshd[8401]: Failed password for invalid user conta from 78.192.6.4 port 46970 ssh2

# A huge time gap
Aug 18 04:39:37 freedombox sshd[9527]: Disconnected from invalid user suporte 139.59.244.237 port 43338 [preauth]
Aug 18 09:00:29 freedombox systemd-timesyncd[370]: Initial synchronization to time server 144.91.116.85:123 (0.debian.pool.ntp.org).

# last log entry
Aug 18 18:38:52 freedombox gitea-zblesk[383]: 2021/08/18 18:38:52 Completed GET /user/events 200 OK in 10.600166ms
# I repowered the system
Aug 19 16:37:21 freedombox kernel: Booting Linux on physical CPU 0x0

# last log entry
Aug 19 16:38:39 freedombox syncthing[958]: [M7FML] INFO: Ready to synchronize "Default Folder" (default) (sendreceive)
# Again, I pulled the power plug and repowered
Aug 19 17:55:08 freedombox systemd-timesyncd[372]: Initial synchronization to time server 193.141.27.1:123 (0.debian.pool.ntp.org).

# Again, same scenario
Aug 20 16:54:37 freedombox sshd[19543]: pam_unix(sshd:auth): authentication failure; logname= uid=0 euid=0 tty=ssh ruser= rhost=24.166.58.59
Aug 22 16:37:12 freedombox kernel: Booting Linux on physical CPU 0x0

Aug 22 16:38:27 freedombox systemd[1]: Started Session 1 of user oliver.
# time gap
Aug 22 19:24:00 freedombox systemd-timesyncd[372]: Initial synchronization to time server 144.76.59.37:123 (0.debian.pool.ntp.org).

# An error during auto update I guess
Aug 25 07:00:50 freedombox /usr/bin/plinth[11987]: Error running setup - PackageException(error_string="Error during installation", error_details="dpkg: error: dpkg frontend lock was locked by another process with pid 11372

# last log entry
Aug 26 22:37:22 freedombox sshd[23705]: Disconnected from invalid user admin 164.90.134.223 port 52398 [preauth]
# Repowering
Aug 27 09:22:20 freedombox kernel: Booting Linux on physical CPU 0x0

# last log entry
Aug 28 04:50:18 freedombox sshd[14557]: pam_unix(sshd:auth): authentication failure; logname= uid=0 euid=0 tty=ssh ruser= rhost=2.181.126.23
# Repowering
Aug 28 09:44:47 freedombox systemd-timesyncd[350]: Initial synchronization to time server 176.221.42.125:123 (0.debian.pool.ntp.org).

# First occurence of many pam-abl errors of the same type. Eventually I removed the complete directory in /var/lib/abl to initialize new databases, now the errors are gone
Aug 28 09:51:26 freedombox pam-abl[2473]: Resource temporarily unavailable (11) while opening the database environment
Aug 28 09:51:26 freedombox pam-abl[2473]: Resource temporarily unavailable (11) while Creating database environment.
Aug 28 09:51:26 freedombox pam-abl[2473]: The database environment could not be opened

So far, my box is running since 3 days without noticeable problems.

1 Like

I think, the Pioneer board does not have a battery backed RTC clock. So, the time might jump and when the machine gets connected to the Internet, the time is set properly using the NTP protocol by systemd-timesyncd. So, this may be okay.

This is not serious. When logging into a console, pam-abl module is run by PAM. It is used to ensure that too many failures will result in an temporary ban on the user account. Since network login attempts are protected differently, this is not serious.

Adversaries on the Internet keep trying to login via SSH by trying known usernames and guessing passwords. We have fail2ban that will impose temporary bans on these attempts when certain number of attempts fail. This is not a problem unless you have week password and have not disabled password authentication yet.

Last entry but like in my case, it does not seem serious.

This can happen if FreedomBox tries a package operation when apt is busy. This is harmless as it will try again later.

My machine too is stable. Let’s keep watching. Nice work so far.

1 Like

Adversaries on the Internet keep trying to login via SSH by trying known usernames and guessing passwords. We have fail2ban that will impose temporary bans on these attempts when certain number of attempts fail. This is not a problem unless you have week password and have not disabled password authentication yet.

Meanwhile I disabled password authentication, but thank you anyway for hinting.

My FreedomBox hung today. And I saw the following on the serial console. It looks like there is not enough memory for all the services I am running on my FreedomBox (I run quite a few). I will try trimming them a bit and try again.

[218575.169685] systemd[1]: Failed to start Journal Service.
[218673.885821] systemd[1]: Failed to start Journal Service.
[218768.222955] systemd[1]: Failed to start Journal Service.
[219009.404160] systemd[1]: Failed to start Journal Service.
[219113.418769] systemd[1]: Failed to start Journal Service.
[219211.965658] systemd[1]: Failed to start Journal Service.
[219491.164682] systemd[1]: Failed to start Journal Service.
[219584.282928] systemd[1]: Failed to start Journal Service.
[282024.855801] systemd[1]: Failed to start Journal Service.
[282127.806916] systemd[1]: Failed to start Journal Service.
[282329.369764] systemd[1]: Failed to start Journal Service.
[282502.910094] INFO: task systemd:1 blocked for more than 120 seconds.
[282502.917029]       Tainted: G         C  E     5.10.0-8-armmp-lpae #1 Debian 5.10.46-4
[282502.925061] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[282667.948641] systemd[1]: Failed to start Journal Service.
[282793.442973] systemd[1]: Failed to start Journal Service.
[282988.381403] systemd[1]: Failed to start Journal Service.
[283333.734162] systemd[1]: Failed to start Journal Service.
[283512.250056] systemd[1]: Failed to start Journal Service.
[283590.372255] INFO: task (php):4306 blocked for more than 120 seconds.
[283590.378988]       Tainted: G         C  E     5.10.0-8-armmp-lpae #1 Debian 5.10.46-4
[283590.387025] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[283590.399213] INFO: task cron:4350 blocked for more than 120 seconds.
[283590.405705]       Tainted: G         C  E     5.10.0-8-armmp-lpae #1 Debian 5.10.46-4
[283590.413735] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[283590.425760] INFO: task cron:4351 blocked for more than 120 seconds.
[283590.432236]       Tainted: G         C  E     5.10.0-8-armmp-lpae #1 Debian 5.10.46-4
[283590.440253] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[283952.862894] INFO: task tar:4376 blocked for more than 120 seconds.
[283952.869390]       Tainted: G         C  E     5.10.0-8-armmp-lpae #1 Debian 5.10.46-4
[283952.877461] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[284073.692893] INFO: task nscd:370 blocked for more than 120 seconds.
[284073.699414]       Tainted: G         C  E     5.10.0-8-armmp-lpae #1 Debian 5.10.46-4
[284073.707433] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[284073.719503] INFO: task tar:4376 blocked for more than 241 seconds.
[284073.725854]       Tainted: G         C  E     5.10.0-8-armmp-lpae #1 Debian 5.10.46-4
[284073.733841] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[284276.689264] systemd[1]: Failed to start Journal Service.
[284436.186446] INFO: task cron:4460 blocked for more than 120 seconds.
[284436.193099]       Tainted: G         C  E     5.10.0-8-armmp-lpae #1 Debian 5.10.46-4
[284436.201227] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[284557.027062] INFO: task btrfs-transacti:180 blocked for more than 120 seconds.
[284557.034547]       Tainted: G         C  E     5.10.0-8-armmp-lpae #1 Debian 5.10.46-4
[284557.042597] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[284557.053072] INFO: task nscd:370 blocked for more than 120 seconds.
[284557.059475]       Tainted: G         C  E     5.10.0-8-armmp-lpae #1 Debian 5.10.46-4
[284557.067516] "echo 0 > /proc/sys/kernel/hung_task_timeout_secs" disables this message.
[289968.573976] Out of memory: Killed process 11040 (tmux: server) total-vm:106136kB, anon-rss:103112kB, file-rss:8kB, shmem-rss:0kB, UID:10001 pgtables:232kB oom_score_adj:0
[290159.168768] systemd[1]: Failed to start Journal Service.

You can also use zram to create a compressed part in RAM which serves as swap. I had the same problem with hard freeze when ram is full and zram help a lot.

the package is named zram-tools.

1 Like

FreedomBox includes zram-tools from v21.5, so all installations on Debian Bullseye should have it.

2 Likes

Thanks for the info, I missed it on the announcement (and already had it configured at the time)

my freedombox is no longer accessible , always after a day or 3, I then pull the plug and restart

Could you please share the list of services you are running? Also could you try disabling some of the services?

Bepasty ,Cockpit, ejabberd, jsxc, mediawiki ,minetest,mumble, Radical,Roundcube,Syncthing, and searx
Hi I do not have much knowledge
I will uninstall ejabberd, jsxc, mediawiki ,minetest,mumble, Radical,Roundcube, and then wait and see ,these are not really in use yet

sorry right now only bepasty, share, minetest searx and syncthing running. I can not switch of JSXC and Tor

Tor can be switched off by unchecking the “Enable tor” checkbox and submitting. JSXC is just a browser app.

Unfortunately, also here my pioneer box became unavailable again. And I have difficulties to see what’s the reason for that. Only thing, a couple of days before I added calibre to my active services:
Bepasty, Calibre, Cockpit, Roundcube, Samba, Syncthing. And gitea, which I installed separately. Quite a short list and before the dist-upgrade everything went smoothly for months.
That’s a bit frustrating. When I am home, I may restart the box but as it just happened, I was offroad for a week and soon after leaving the box became unreachable. On Sept. 21, according to journalctl, it booted six times between 6:17 and 6:25, then, 10 min later, no more log entries until I disconnected and reconnected the power 5 days later.
I can post here only 32000 characters max, so I will split my journal log in two pieces:

Sep 21 06:09:08 freedombox systemd[1]: Starting Clean php session files...
Sep 21 06:09:10 freedombox systemd[1]: phpsessionclean.service: Succeeded.
Sep 21 06:09:10 freedombox systemd[1]: Finished Clean php session files.
Sep 21 06:09:10 freedombox systemd[1]: phpsessionclean.service: Consumed 1.664s CPU time.
Sep 21 06:11:58 freedombox sshd[9374]: Received disconnect from 222.186.42.137 port 15232:11:  [preauth]
Sep 21 06:11:58 freedombox sshd[9374]: Disconnected from authenticating user root 222.186.42.137 port 15232 [preauth]
Sep 21 06:12:43 freedombox sshd[9376]: error: kex_exchange_identification: Connection closed by remote host
Sep 21 06:12:43 freedombox sshd[9376]: Connection closed by 45.133.1.12 port 56368
Sep 21 06:13:27 freedombox sshd[9377]: Unable to negotiate with 45.133.1.12 port 51544: no matching key exchange method found. Their offer: diffie-hellman-group14-sha1,diffie-hellman-group-exchange-sha1,diffie-hellman-group1-sha1 [preauth]
Sep 21 06:16:29 freedombox sshd[9381]: Received disconnect from 222.187.254.41 port 38222:11:  [preauth]
Sep 21 06:16:29 freedombox sshd[9381]: Disconnected from authenticating user root 222.187.254.41 port 38222 [preauth]
Sep 21 06:17:01 freedombox CRON[9384]: pam_unix(cron:session): session opened for user root(uid=0) by (uid=0)
Sep 21 06:17:01 freedombox CRON[9386]: (root) CMD (   cd / && run-parts --report /etc/cron.hourly)
Sep 21 06:17:01 freedombox CRON[9384]: pam_unix(cron:session): session closed for user root
Sep 21 06:17:27 freedombox sshd[9389]: error: kex_exchange_identification: Connection closed by remote host
Sep 21 06:17:27 freedombox sshd[9389]: Connection closed by 209.141.62.151 port 50766
-- Boot 0e3522b11fe94868a4a257728aa27a75 --
Jul 13 17:29:37 freedombox systemd-timesyncd[351]: System clock time unset or jumped backwards, restoring from recorded timestamp: Tue 2021-09-21 06:18:47 UTC
Sep 21 06:18:58 freedombox kernel: zram: Added device: zram0
Sep 21 06:18:58 freedombox kernel: zram0: detected capacity change from 0 to 488325120
Sep 21 06:18:58 freedombox kernel: Adding 476876k swap on /dev/zram0.  Priority:100 extents:1 across:476876k SSFS
Sep 21 06:18:58 freedombox kernel: sun4i-drm display-engine: [drm] Cannot find any crtc or sizes
Sep 21 06:18:58 freedombox haveged[350]: haveged: ver: 1.9.14; arch: generic; vend: ; build: (gcc 10.2.1 CTV); collect: 128K
Sep 21 06:18:58 freedombox haveged[350]: haveged: cpu: (VC); data: 16K (D); inst: 16K (D); idx: 12/40; sz: 15006/57790
Sep 21 06:18:58 freedombox haveged[350]: haveged: tot tests(BA8): A:1/1 B:1/1 continuous tests(B):  last entropy estimate 7.9996
Sep 21 06:18:58 freedombox haveged[350]: haveged: fills: 0, generated: 0
Sep 21 06:18:47 freedombox avahi-daemon[363]: Found user 'avahi' (UID 109) and group 'avahi' (GID 115).
Sep 21 06:18:47 freedombox systemd[1]: Started Network Time Synchronization.
Sep 21 06:18:47 freedombox avahi-daemon[363]: Successfully dropped root privileges.
-- Boot e1b9af3f877f4b06893d4429080da29d --
Sep 21 06:18:58 freedombox systemd[1]: Starting Daily apt upgrade and clean activities...
-- Boot 0e3522b11fe94868a4a257728aa27a75 --
Sep 21 06:18:47 freedombox systemd[1]: Reached target System Initialization.
Sep 21 06:18:47 freedombox avahi-daemon[363]: avahi-daemon 0.8 starting up.
Sep 21 06:18:47 freedombox systemd[1]: Started Take snapper snapshot of root on boot.
Sep 21 06:18:47 freedombox nscd[371]: 371 monitoring file `/etc/passwd` (1)
Sep 21 06:18:47 freedombox systemd[1]: Started Daily Cleanup of Snapper Snapshots.
Sep 21 06:18:47 freedombox nscd[371]: 371 monitoring directory `/etc` (2)
Sep 21 06:18:47 freedombox systemd[1]: Started Daily Cleanup of Temporary Directories.
Sep 21 06:18:47 freedombox nscd[371]: 371 monitoring file `/etc/group` (3)
Sep 21 06:18:47 freedombox systemd[1]: Reached target System Time Set.
Sep 21 06:18:47 freedombox nscd[371]: 371 monitoring directory `/etc` (2)
Sep 21 06:18:47 freedombox systemd[1]: Reached target System Time Synchronized.
Sep 21 06:18:47 freedombox nscd[371]: 371 monitoring file `/etc/hosts` (4)
Sep 21 06:18:47 freedombox systemd[1]: Started Daily apt download activities.
Sep 21 06:18:47 freedombox nscd[371]: 371 monitoring directory `/etc` (2)
Sep 21 06:18:47 freedombox systemd[1]: apt-daily-upgrade.timer: Not using persistent file timestamp Tue 2021-09-21 06:18:58 UTC as it is in the future.
Sep 21 06:18:58 freedombox gitea-zblesk[366]: 2021/09/21 06:18:53 cmd/web.go:82:runWeb() [I] Starting Gitea on PID: 366
Sep 21 06:18:58 freedombox gitea-zblesk[366]: 2021/09/21 06:18:53 ...dules/setting/git.go:101:newGit() [I] Git Version: 2.30.2, Wire Protocol Version 2 Enabled
Sep 21 06:18:58 freedombox gitea-zblesk[366]: 2021/09/21 06:18:53 cmd/web.go:126:runWeb() [I] Global init
Sep 21 06:18:58 freedombox gitea-zblesk[366]: 2021/09/21 06:18:53 ...dules/setting/git.go:101:newGit() [I] Git Version: 2.30.2, Wire Protocol Version 2 Enabled
Sep 21 06:18:58 freedombox gitea-zblesk[366]: 2021/09/21 06:18:53 routers/init.go:134:GlobalInit() [T] AppPath: /usr/local/bin/gitea
Sep 21 06:18:58 freedombox gitea-zblesk[366]: 2021/09/21 06:18:53 routers/init.go:135:GlobalInit() [T] AppWorkPath: /var/lib/gitea
Sep 21 06:18:58 freedombox gitea-zblesk[366]: 2021/09/21 06:18:53 routers/init.go:136:GlobalInit() [T] Custom path: /var/lib/gitea/custom
Sep 21 06:18:58 freedombox gitea-zblesk[366]: 2021/09/21 06:18:53 routers/init.go:137:GlobalInit() [T] Log path: /var/log/gitea
Sep 21 06:18:58 freedombox gitea-zblesk[366]: 2021/09/21 06:18:53 routers/init.go:49:checkRunMode() [I] Run Mode: Prod
Sep 21 06:18:47 freedombox nscd[371]: 371 monitoring file `/etc/resolv.conf` (5)
Sep 21 06:18:47 freedombox systemd[1]: Started Daily apt upgrade and clean activities.
Sep 21 06:18:47 freedombox nscd[371]: 371 monitoring directory `/etc` (2)
Sep 21 06:18:47 freedombox systemd[1]: Started Run certbot twice daily.
Sep 21 06:18:48 freedombox nscd[371]: 371 monitoring file `/etc/services` (6)
Sep 21 06:18:47 freedombox systemd[1]: Started Periodic ext4 Online Metadata Check for All Filesystems.
Sep 21 06:18:48 freedombox nscd[371]: 371 monitoring directory `/etc` (2)
Sep 21 06:18:47 freedombox systemd[1]: Started Daily exim4-base housekeeping.
Sep 21 06:18:48 freedombox nscd[371]: 371 disabled inotify-based monitoring for file `/etc/netgroup': No such file or directory
Sep 21 06:18:47 freedombox systemd[1]: Started Daily rotation of log files.
Sep 21 06:18:58 freedombox zramswap[399]: <13>Sep 21 06:18:49 root: Starting Zram
Sep 21 06:18:48 freedombox nscd[371]: 371 stat failed for file `/etc/netgroup'; will try again later: No such file or directory
Sep 21 06:18:47 freedombox systemd[1]: Started Daily man-db regeneration.
Sep 21 06:18:58 freedombox zramswap[418]: Setting up swapspace version 1, size = 465.7 MiB (488321024 bytes)
Sep 21 06:18:58 freedombox zramswap[418]: no label, UUID=828bd963-94ab-4bfb-a9cd-be150d230b72
Sep 21 06:18:49 freedombox rsyslogd[370]: imuxsock: Acquired UNIX socket '/run/systemd/journal/syslog' (fd 3) from systemd.  [v8.2102.0]
Sep 21 06:18:47 freedombox systemd[1]: Started Clean PHP session files every 30 mins.
Sep 21 06:18:49 freedombox rsyslogd[370]: [origin software="rsyslogd" swVersion="8.2102.0" x-pid="370" x-info="https://www.rsyslog.com"] start
Sep 21 06:18:47 freedombox systemd[1]: Started Timeline of Snapper Snapshots.
Sep 21 06:18:49 freedombox avahi-daemon[363]: Successfully called chroot().
Sep 21 06:18:47 freedombox systemd[1]: Listening on Avahi mDNS/DNS-SD Stack Activation Socket.
Sep 21 06:18:49 freedombox avahi-daemon[363]: Successfully dropped remaining capabilities.
Sep 21 06:18:47 freedombox systemd[1]: Starting Cockpit Web Service Socket.
Sep 21 06:18:49 freedombox avahi-daemon[363]: Loading service file /services/domain.service.
Sep 21 06:18:47 freedombox systemd[1]: Listening on D-Bus System Message Bus Socket.
Sep 21 06:18:49 freedombox avahi-daemon[363]: Loading service file /services/freedombox.service.
Sep 21 06:18:47 freedombox systemd[1]: Listening on Cockpit Web Service Socket.
Sep 21 06:18:49 freedombox avahi-daemon[363]: Loading service file /services/sftp-ssh.service.
Sep 21 06:18:47 freedombox systemd[1]: Reached target Sockets.
Sep 21 06:18:49 freedombox avahi-daemon[363]: Loading service file /services/ssh.service.
Sep 21 06:18:47 freedombox systemd[1]: Reached target Basic System.
Sep 21 06:18:49 freedombox avahi-daemon[363]: Loading service file /services/xmpp-server.service.
Sep 21 06:18:47 freedombox systemd[1]: Starting Avahi mDNS/DNS-SD Stack...
Sep 21 06:18:49 freedombox dbus-daemon[364]: [system] Activating systemd to hand-off: service name='org.opensuse.Snapper' unit='snapperd.service' requested by ':1.2' (uid=0 pid=372 comm="/usr/bin/snapper --config root create --cleanup-al")
Sep 21 06:18:47 freedombox systemd[1]: Started D-Bus System Message Bus.
Sep 21 06:18:49 freedombox avahi-daemon[363]: Joining mDNS multicast group on interface lo.IPv6 with address ::1.
Sep 21 06:18:47 freedombox systemd[1]: Starting Remove Stale Online ext4 Metadata Check Snapshots...
Sep 21 06:18:49 freedombox avahi-daemon[363]: New relevant interface lo.IPv6 for mDNS.
Sep 21 06:18:47 freedombox systemd[1]: Condition check resulted in getty on tty2-tty6 if dbus and logind are not available being skipped.
Sep 21 06:18:49 freedombox avahi-daemon[363]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1.
Sep 21 06:18:47 freedombox systemd[1]: Started Gitea service.
Sep 21 06:18:49 freedombox avahi-daemon[363]: New relevant interface lo.IPv4 for mDNS.
Sep 21 06:18:47 freedombox systemd[1]: Starting Name Service Cache Daemon...
Sep 21 06:18:49 freedombox avahi-daemon[363]: Network interface enumeration completed.
Sep 21 06:18:47 freedombox systemd[1]: Starting Authorization Manager...
Sep 21 06:18:49 freedombox avahi-daemon[363]: Registering new address record for ::1 on lo.*.
Sep 21 06:18:47 freedombox systemd[1]: Starting System Logging Service...
Sep 21 06:18:49 freedombox avahi-daemon[363]: Registering new address record for 127.0.0.1 on lo.IPv4.
Sep 21 06:18:47 freedombox systemd[1]: Starting Take snapper snapshot of root on boot...
Sep 21 06:18:49 freedombox polkitd[369]: started daemon version 0.105 using authority implementation `local' version `0.105'
Sep 21 06:18:49 freedombox systemd[1]: Starting User Login Management...
Sep 21 06:18:49 freedombox dbus-daemon[364]: [system] Successfully activated service 'org.freedesktop.systemd1'
Sep 21 06:18:49 freedombox systemd[1]: Starting Disk Manager...
Sep 21 06:18:49 freedombox root[399]: Starting Zram
Sep 21 06:18:49 freedombox systemd[1]: Starting WPA supplicant...
Sep 21 06:18:49 freedombox wpa_supplicant[396]: Successfully initialized wpa_supplicant
Sep 21 06:18:49 freedombox systemd[1]: Starting Linux zramswap setup...
Sep 21 06:18:50 freedombox dbus-daemon[364]: [system] Successfully activated service 'org.opensuse.Snapper'
Sep 21 06:18:49 freedombox systemd[1]: Started System Logging Service.
Sep 21 06:18:50 freedombox avahi-daemon[363]: Server startup complete. Host name is freedombox.local. Local service cookie is 2915011619.
Sep 21 06:18:49 freedombox systemd[1]: Started Name Service Cache Daemon.
Sep 21 06:18:51 freedombox avahi-daemon[363]: Service "freedombox" (/services/xmpp-server.service) successfully established.
Sep 21 06:18:49 freedombox systemd[1]: Started Avahi mDNS/DNS-SD Stack.
Sep 21 06:18:51 freedombox avahi-daemon[363]: Service "freedombox" (/services/ssh.service) successfully established.
Sep 21 06:18:49 freedombox systemd[1]: Started Authorization Manager.
Sep 21 06:18:51 freedombox avahi-daemon[363]: Service "freedombox SFTP server" (/services/sftp-ssh.service) successfully established.
Sep 21 06:18:49 freedombox systemd[1]: Starting Modem Manager...
Sep 21 06:18:51 freedombox avahi-daemon[363]: Service "freedombox" (/services/freedombox.service) successfully established.
Sep 21 06:18:49 freedombox systemd[1]: Starting firewalld - dynamic firewall daemon...
Sep 21 06:18:51 freedombox avahi-daemon[363]: Service "freedombox" (/services/domain.service) successfully established.
Sep 21 06:18:49 freedombox systemd[1]: Starting DBus interface for snapper...
Sep 21 06:18:51 freedombox ModemManager[401]: <info>  ModemManager (version 1.14.12) starting in system bus...
Sep 21 06:18:49 freedombox systemd[1]: Started WPA supplicant.
Sep 21 06:18:55 freedombox ModemManager[401]: <info>  [base-manager] couldn't check support for device '/sys/devices/platform/soc/1c50000.ethernet': not supported by any plugin
Sep 21 06:18:49 freedombox udisksd[395]: udisks daemon version 2.9.2 starting
Sep 21 06:18:50 freedombox systemd[1]: Started DBus interface for snapper.
Sep 21 06:18:50 freedombox systemd[1]: Finished Linux zramswap setup.
Sep 21 06:18:51 freedombox systemd-logind[391]: New seat seat0.
Sep 21 06:18:51 freedombox systemd-logind[391]: Watching system buttons on /dev/input/event0 (axp20x-pek)
Sep 21 06:18:51 freedombox systemd[1]: Started User Login Management.
Sep 21 06:18:52 freedombox systemd[1]: Started Modem Manager.
Sep 21 06:18:52 freedombox udisksd[395]: Error performing housekeeping for drive /org/freedesktop/UDisks2/drives/Hitachi_HDS721010CLA330_JP2911N1068PRV: Error updating SMART data: sk_disk_smart_read_data: Operation not supported (udisks-error-quark, 0)
Sep 21 06:18:52 freedombox systemd[1]: Started Disk Manager.
Sep 21 06:18:52 freedombox udisksd[395]: Cleaning up mount point /media/root/Nas (device 8:16 is not mounted)
Sep 21 06:18:52 freedombox udisksd[395]: Acquired the name org.freedesktop.UDisks2 on the system message bus
Sep 21 06:18:53 freedombox systemd[1]: e2scrub_reap.service: Succeeded.
Sep 21 06:18:53 freedombox systemd[1]: Finished Remove Stale Online ext4 Metadata Check Snapshots.
Sep 21 06:18:58 freedombox systemd[1]: snapper-boot.service: Succeeded.
Sep 21 06:18:58 freedombox systemd[1]: Finished Take snapper snapshot of root on boot.
Sep 21 06:19:00 freedombox kernel: vcc3v0: disabling
Sep 21 06:19:00 freedombox kernel: vcc5v0: disabling
Sep 21 06:19:00 freedombox kernel: usb0-vbus: disabling
Sep 21 06:19:00 freedombox kernel: vddio-csi0: disabling
Sep 21 06:19:00 freedombox kernel: vddio-csi1: disabling
Sep 21 06:19:02 freedombox systemd[1]: Started firewalld - dynamic firewall daemon.
Sep 21 06:19:02 freedombox systemd[1]: Reached target Network (Pre).
Sep 21 06:19:02 freedombox systemd[1]: Starting Network Manager...
Sep 21 06:19:02 freedombox systemd[1]: Starting Raise network interfaces...
-- Boot e1b9af3f877f4b06893d4429080da29d --
Sep 21 06:19:03 freedombox systemd[1]: apt-daily-upgrade.service: Succeeded.
Sep 21 06:19:03 freedombox systemd[1]: Finished Daily apt upgrade and clean activities.
Sep 21 06:19:03 freedombox systemd[1]: apt-daily-upgrade.service: Consumed 3.975s CPU time.

Continuing above message. Second part of journal log:

-- Boot e1b9af3f877f4b06893d4429080da29d --
Sep 21 06:19:03 freedombox systemd[1]: apt-daily-upgrade.service: Succeeded.
Sep 21 06:19:03 freedombox systemd[1]: Finished Daily apt upgrade and clean activities.
Sep 21 06:19:03 freedombox systemd[1]: apt-daily-upgrade.service: Consumed 3.975s CPU time.
-- Boot 0e3522b11fe94868a4a257728aa27a75 --
Sep 21 06:19:03 freedombox NetworkManager[453]: <info>  [1632205143.3555] NetworkManager (version 1.30.0) is starting... (for the first time)
Sep 21 06:19:03 freedombox NetworkManager[453]: <info>  [1632205143.3561] Read config: /etc/NetworkManager/NetworkManager.conf (lib: no-mac-addr-change.conf)
Sep 21 06:19:03 freedombox systemd[1]: Started Network Manager.
Sep 21 06:19:03 freedombox NetworkManager[453]: <info>  [1632205143.4302] bus-manager: acquired D-Bus service "org.freedesktop.NetworkManager"
Sep 21 06:19:03 freedombox systemd[1]: Starting Network Manager Wait Online...
Sep 21 06:19:03 freedombox NetworkManager[453]: <info>  [1632205143.5408] manager[0x197c020]: monitoring kernel firmware directory '/lib/firmware'.
Sep 21 06:19:03 freedombox NetworkManager[453]: <info>  [1632205143.5438] monitoring ifupdown state file '/run/network/ifstate'.
Sep 21 06:19:03 freedombox dbus-daemon[364]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.11' (uid=0 pid=453 comm="/usr/sbin/NetworkManager --no-daemon ")
Sep 21 06:19:03 freedombox systemd[1]: Starting Hostname Service...
Sep 21 06:19:03 freedombox systemd[1]: Finished Raise network interfaces.
Sep 21 06:19:03 freedombox systemd[1]: Reached target Network.
Sep 21 06:19:03 freedombox systemd[1]: Starting calibre Content Server...
Sep 21 06:19:03 freedombox nscd[371]: 371 monitoring file `/etc/passwd` (1)
Sep 21 06:19:03 freedombox nscd[371]: 371 monitoring directory `/etc` (2)
Sep 21 06:19:03 freedombox nscd[371]: 371 monitoring file `/etc/group` (3)
Sep 21 06:19:03 freedombox nscd[371]: 371 monitoring directory `/etc` (2)
Sep 21 06:19:03 freedombox systemd[1]: Starting Fail2Ban Service...
Sep 21 06:19:03 freedombox systemd[1]: Starting The PHP 7.4 FastCGI Process Manager...
Sep 21 06:19:03 freedombox systemd[1]: Started FreedomBox Service (Plinth).
Sep 21 06:19:04 freedombox systemd[1]: Condition check resulted in fast remote file copy program daemon being skipped.
Sep 21 06:19:04 freedombox systemd[1]: Starting OpenBSD Secure Shell server...
Sep 21 06:19:04 freedombox systemd[1]: Starting Permit User Sessions...
Sep 21 06:19:04 freedombox systemd[1]: Started Unattended Upgrades Shutdown.
Sep 21 06:19:04 freedombox systemd[1]: Started Fail2Ban Service.
Sep 21 06:19:04 freedombox systemd[1]: Finished Permit User Sessions.
Sep 21 06:19:04 freedombox systemd[1]: Started Getty on tty1.
Sep 21 06:19:04 freedombox systemd[1]: Started Serial Getty on ttyS0.
Sep 21 06:19:04 freedombox systemd[1]: Reached target Login Prompts.
Sep 21 06:19:05 freedombox sshd[525]: Server listening on 0.0.0.0 port 22.
Sep 21 06:19:05 freedombox sshd[525]: Server listening on :: port 22.
Sep 21 06:19:05 freedombox systemd[1]: Started OpenBSD Secure Shell server.
Sep 21 06:19:06 freedombox systemd[1]: Started calibre Content Server.
Sep 21 06:19:06 freedombox dbus-daemon[364]: [system] Successfully activated service 'org.freedesktop.hostname1'
Sep 21 06:19:06 freedombox systemd[1]: Started Hostname Service.
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.1281] hostname: hostname: using hostnamed
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.1285] hostname: hostname changed from (none) to "freedombox"
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.1328] dns-mgr[0x19758d8]: init: dns=default,systemd-resolved rc-manager=resolvconf (auto)
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.2652] manager[0x197c020]: rfkill: Wi-Fi hardware radio set enabled
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.2657] manager[0x197c020]: rfkill: WWAN hardware radio set enabled
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.3305] Loaded device plugin: NMAtmManager (/usr/lib/arm-linux-gnueabihf/NetworkManager/1.30.0/libnm-device-plugin-adsl.so)
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.5191] Loaded device plugin: NMBluezManager (/usr/lib/arm-linux-gnueabihf/NetworkManager/1.30.0/libnm-device-plugin-bluetooth.so)
Sep 21 06:19:06 freedombox systemd[1]: Listening on Load/Save RF Kill Switch Status /dev/rfkill Watch.
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.6259] Loaded device plugin: NMTeamFactory (/usr/lib/arm-linux-gnueabihf/NetworkManager/1.30.0/libnm-device-plugin-team.so)
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.6621] Loaded device plugin: NMWifiFactory (/usr/lib/arm-linux-gnueabihf/NetworkManager/1.30.0/libnm-device-plugin-wifi.so)
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.6744] Loaded device plugin: NMWwanFactory (/usr/lib/arm-linux-gnueabihf/NetworkManager/1.30.0/libnm-device-plugin-wwan.so)
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.7099] manager: rfkill: Wi-Fi enabled by radio killswitch; enabled by state file
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.7190] manager: rfkill: WWAN enabled by radio killswitch; enabled by state file
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.7294] manager: Networking is enabled by state file
Sep 21 06:19:06 freedombox dbus-daemon[364]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.11' (uid=0 pid=453 comm="/usr/sbin/NetworkManager --no-daemon ")
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.7424] dhcp-init: Using DHCP client 'internal'
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.7946] settings: Loaded settings plugin: ifupdown ("/usr/lib/arm-linux-gnueabihf/NetworkManager/1.30.0/libnm-settings-plugin-ifupdown.so")
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.8004] settings: Loaded settings plugin: keyfile (internal)
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.8068] ifupdown: management mode: unmanaged
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.8173] ifupdown:       interface-parser: parsing file /etc/network/interfaces
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.8253] ifupdown:       interface-parser: source line includes interfaces file(s) /etc/network/interfaces.d
Sep 21 06:19:06 freedombox NetworkManager[453]: <info>  [1632205146.8295] ifupdown:       interface-parser: finished parsing file /etc/network/interfaces
Sep 21 06:19:06 freedombox systemd[1]: Starting Network Manager Script Dispatcher Service...
Sep 21 06:19:07 freedombox NetworkManager[453]: <info>  [1632205147.0065] device (lo): carrier: link connected
Sep 21 06:19:07 freedombox NetworkManager[453]: <info>  [1632205147.0274] manager: (lo): new Generic device (/org/freedesktop/NetworkManager/Devices/1)
Sep 21 06:19:07 freedombox NetworkManager[453]: <info>  [1632205147.0981] device (eth0): driver '(null)' does not support carrier detection.
Sep 21 06:19:07 freedombox NetworkManager[453]: <info>  [1632205147.1149] device (eth0): driver 'sun7i-dwmac' does not support carrier detection.
Sep 21 06:19:07 freedombox NetworkManager[453]: <info>  [1632205147.1386] manager: (eth0): new Ethernet device (/org/freedesktop/NetworkManager/Devices/2)
Sep 21 06:19:07 freedombox NetworkManager[453]: <info>  [1632205147.2627] device (eth0): state change: unmanaged -> unavailable (reason 'managed', sys-iface-state: 'external')
Sep 21 06:19:07 freedombox dbus-daemon[364]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Sep 21 06:19:07 freedombox systemd[1]: Started Network Manager Script Dispatcher Service.
Sep 21 06:19:07 freedombox kernel: sun7i-dwmac 1c50000.ethernet eth0: PHY [stmmac-0:01] driver [RTL8211E Gigabit Ethernet] (irq=POLL)
Sep 21 06:19:07 freedombox kernel: sun7i-dwmac 1c50000.ethernet eth0: No Safety Features support found
Sep 21 06:19:07 freedombox kernel: sun7i-dwmac 1c50000.ethernet eth0: RX IPC Checksum Offload disabled
Sep 21 06:19:07 freedombox kernel: sun7i-dwmac 1c50000.ethernet eth0: No MAC Management Counters available
Sep 21 06:19:07 freedombox kernel: sun7i-dwmac 1c50000.ethernet eth0: PTP not supported by HW
Sep 21 06:19:07 freedombox kernel: sun7i-dwmac 1c50000.ethernet eth0: configuring for phy/rgmii link mode
Sep 21 06:19:07 freedombox NetworkManager[453]: <info>  [1632205147.7743] modem-manager: ModemManager available
Sep 21 06:19:08 freedombox systemd[1]: Started The PHP 7.4 FastCGI Process Manager.
Sep 21 06:19:08 freedombox nscd[371]: 371 checking for monitored file `/etc/netgroup': No such file or directory
Sep 21 06:19:11 freedombox kernel: sun7i-dwmac 1c50000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off
Sep 21 06:19:11 freedombox kernel: IPv6: ADDRCONF(NETDEV_CHANGE): eth0: link becomes ready
Sep 21 06:19:11 freedombox NetworkManager[453]: <info>  [1632205151.4728] device (eth0): carrier: link connected
Sep 21 06:19:11 freedombox NetworkManager[453]: <info>  [1632205151.4861] device (eth0): state change: unavailable -> disconnected (reason 'carrier-changed', sys-iface-state: 'managed')
Sep 21 06:19:11 freedombox NetworkManager[453]: <info>  [1632205151.5160] policy: auto-activating connection 'FreedomBox WAN' (231a0b9f-cfc4-4efe-9908-b356fedb3e18)
Sep 21 06:19:11 freedombox NetworkManager[453]: <info>  [1632205151.5390] device (eth0): Activation: starting connection 'FreedomBox WAN' (231a0b9f-cfc4-4efe-9908-b356fedb3e18)
Sep 21 06:19:11 freedombox NetworkManager[453]: <info>  [1632205151.5485] device (eth0): state change: disconnected -> prepare (reason 'none', sys-iface-state: 'managed')
Sep 21 06:19:11 freedombox NetworkManager[453]: <info>  [1632205151.5823] manager: NetworkManager state is now CONNECTING
Sep 21 06:19:11 freedombox NetworkManager[453]: <info>  [1632205151.6005] device (eth0): state change: prepare -> config (reason 'none', sys-iface-state: 'managed')
Sep 21 06:19:13 freedombox fail2ban-server[522]: Server ready
Sep 21 06:19:14 freedombox systemd[1]: systemd-fsckd.service: Succeeded.
Sep 21 06:19:17 freedombox NetworkManager[453]: <info>  [1632205157.1891] device (eth0): state change: config -> ip-config (reason 'none', sys-iface-state: 'managed')
Sep 21 06:19:17 freedombox NetworkManager[453]: <info>  [1632205157.1956] dhcp4 (eth0): activation: beginning transaction (timeout in 45 seconds)
Sep 21 06:19:17 freedombox avahi-daemon[363]: Joining mDNS multicast group on interface eth0.IPv6 with address fe80::3c30:54c4:c1ef:ac81.
Sep 21 06:19:17 freedombox avahi-daemon[363]: New relevant interface eth0.IPv6 for mDNS.
Sep 21 06:19:17 freedombox avahi-daemon[363]: Registering new address record for fe80::3c30:54c4:c1ef:ac81 on eth0.*.
Sep 21 06:19:17 freedombox NetworkManager[453]: <info>  [1632205157.2627] dhcp4 (eth0): state changed unknown -> bound, address=192.168.178.44
Sep 21 06:19:17 freedombox avahi-daemon[363]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.178.44.
Sep 21 06:19:17 freedombox avahi-daemon[363]: New relevant interface eth0.IPv4 for mDNS.
Sep 21 06:19:17 freedombox avahi-daemon[363]: Registering new address record for 192.168.178.44 on eth0.IPv4.
Sep 21 06:19:17 freedombox NetworkManager[453]: <info>  [1632205157.2951] device (eth0): state change: ip-config -> ip-check (reason 'none', sys-iface-state: 'managed')
Sep 21 06:19:17 freedombox NetworkManager[453]: <info>  [1632205157.3646] device (eth0): state change: ip-check -> secondaries (reason 'none', sys-iface-state: 'managed')
Sep 21 06:19:17 freedombox NetworkManager[453]: <info>  [1632205157.3747] device (eth0): state change: secondaries -> activated (reason 'none', sys-iface-state: 'managed')
Sep 21 06:19:17 freedombox NetworkManager[453]: <info>  [1632205157.4030] manager: NetworkManager state is now CONNECTED_LOCAL
Sep 21 06:19:17 freedombox NetworkManager[453]: <info>  [1632205157.4592] manager: NetworkManager state is now CONNECTED_SITE
Sep 21 06:19:17 freedombox NetworkManager[453]: <info>  [1632205157.4653] policy: set 'FreedomBox WAN' (eth0) as default for IPv4 routing and DNS
Sep 21 06:19:17 freedombox dbus-daemon[364]: [system] Activating via systemd: service name='org.freedesktop.resolve1' unit='dbus-org.freedesktop.resolve1.service' requested by ':1.11' (uid=0 pid=453 comm="/usr/sbin/NetworkManager --no-daemon ")
Sep 21 06:19:17 freedombox NetworkManager[453]: <info>  [1632205157.4847] dns-mgr: Writing DNS information to /sbin/resolvconf
Sep 21 06:19:17 freedombox dbus-daemon[364]: [system] Activation via systemd failed for unit 'dbus-org.freedesktop.resolve1.service': Unit dbus-org.freedesktop.resolve1.service not found.
Sep 21 06:19:17 freedombox systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Sep 21 06:19:18 freedombox nscd[371]: 371 ignored inotify event for `/etc/resolv.conf` (file exists)
Sep 21 06:19:18 freedombox nscd[371]: 371 ignored inotify event for `/etc/resolv.conf` (file exists)
Sep 21 06:19:18 freedombox NetworkManager[453]: <info>  [1632205158.2691] device (eth0): Activation: successful, device activated.
Sep 21 06:19:18 freedombox dbus-daemon[364]: [system] Activating via systemd: service name='org.freedesktop.nm_dispatcher' unit='dbus-org.freedesktop.nm-dispatcher.service' requested by ':1.11' (uid=0 pid=453 comm="/usr/sbin/NetworkManager --no-daemon ")
Sep 21 06:19:18 freedombox NetworkManager[453]: <info>  [1632205158.2981] manager: NetworkManager state is now CONNECTED_GLOBAL
Sep 21 06:19:18 freedombox NetworkManager[453]: <info>  [1632205158.3326] manager: startup complete
Sep 21 06:19:18 freedombox systemd[1]: Starting Network Manager Script Dispatcher Service...
Sep 21 06:19:18 freedombox systemd[1]: Finished Network Manager Wait Online.
Sep 21 06:19:18 freedombox systemd[1]: Reached target Network is Online.
Sep 21 06:19:18 freedombox systemd[1]: Starting Cockpit motd updater service...
Sep 21 06:19:18 freedombox systemd[1]: Starting LSB: ez-ipupdate client for dynamic DNS services...
Sep 21 06:19:18 freedombox systemd[1]: Starting Samba NMB Daemon...
Sep 21 06:19:18 freedombox systemd[1]: Starting Performance Metrics Collector Daemon...
Sep 21 06:19:18 freedombox dbus-daemon[364]: [system] Successfully activated service 'org.freedesktop.nm_dispatcher'
Sep 21 06:19:18 freedombox systemd[1]: Starting LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol)...
Sep 21 06:19:18 freedombox sshd[640]: Invalid user oliver from 192.168.178.58 port 60692
Sep 21 06:19:18 freedombox systemd[1]: Starting LSB: Start/stop uWSGI server instance(s)...
Sep 21 06:19:19 freedombox ez-ipupdate[649]: Starting Dynamic DNS client: no .conf file in /etc/ez-ipupdate.
Sep 21 06:19:19 freedombox systemd[1]: Started LSB: ez-ipupdate client for dynamic DNS services.
Sep 21 06:19:19 freedombox sshd[640]: Connection closed by invalid user oliver 192.168.178.58 port 60692 [preauth]
Sep 21 06:19:19 freedombox systemd[1]: Started Network Manager Script Dispatcher Service.
Sep 21 06:19:19 freedombox NetworkManager[453]: <info>  [1632205159.3560] dhcp6 (eth0): activation: beginning transaction (timeout in 45 seconds)
Sep 21 06:19:19 freedombox avahi-daemon[363]: Leaving mDNS multicast group on interface eth0.IPv6 with address fe80::3c30:54c4:c1ef:ac81.
Sep 21 06:19:19 freedombox avahi-daemon[363]: Joining mDNS multicast group on interface eth0.IPv6 with address 2003:cc:3f2a:5300:2f:a00a:27df:b9ab.
Sep 21 06:19:19 freedombox avahi-daemon[363]: Registering new address record for 2003:cc:3f2a:5300:2f:a00a:27df:b9ab on eth0.*.
Sep 21 06:19:19 freedombox avahi-daemon[363]: Withdrawing address record for fe80::3c30:54c4:c1ef:ac81 on eth0.
Sep 21 06:19:19 freedombox NetworkManager[453]: <info>  [1632205159.4625] policy: set 'FreedomBox WAN' (eth0) as default for IPv6 routing and DNS
Sep 21 06:19:19 freedombox NetworkManager[453]: <info>  [1632205159.4702] dns-mgr: Writing DNS information to /sbin/resolvconf
Sep 21 06:19:19 freedombox systemd[1]: cockpit-motd.service: Succeeded.
Sep 21 06:19:19 freedombox systemd[1]: Finished Cockpit motd updater service.
Sep 21 06:19:20 freedombox slapd[734]: @(#) $OpenLDAP: slapd 2.4.57+dfsg-3 (May 15 2021 23:03:34) $
                                               Debian OpenLDAP Maintainers <pkg-openldap-devel@lists.alioth.debian.org>
Sep 21 06:19:20 freedombox NetworkManager[453]: <info>  [1632205160.9563] dhcp6 (eth0): state changed unknown -> bound
Sep 21 06:19:21 freedombox slapd[772]: slapd starting
Sep 21 06:19:21 freedombox slapd[659]: Starting OpenLDAP: slapd.
Sep 21 06:19:21 freedombox systemd[1]: Started LSB: OpenLDAP standalone server (Lightweight Directory Access Protocol).
Sep 21 06:19:21 freedombox systemd[1]: Starting LSB: LDAP connection daemon...
Sep 21 06:19:22 freedombox kernel: nf_conntrack: default automatic helper assignment has been turned off for security reasons and CT-based  firewall rule not found. Use the iptables CT target to attach helpers instead.
Sep 21 06:19:22 freedombox systemd[1]: Started Samba NMB Daemon.
Sep 21 06:19:22 freedombox systemd[1]: Starting Samba SMB Daemon...
Sep 21 06:19:22 freedombox nslcd[863]: version 0.9.11 starting
Sep 21 06:19:22 freedombox nslcd[863]: accepting connections
Sep 21 06:19:22 freedombox nslcd[784]: Starting LDAP connection daemon: nslcd.
Sep 21 06:19:22 freedombox systemd[1]: Started LSB: LDAP connection daemon.
Sep 21 06:19:22 freedombox systemd[1]: Starting The Apache HTTP Server...
Sep 21 06:19:22 freedombox systemd[1]: Started Regular background program processing daemon.
Sep 21 06:19:22 freedombox systemd[1]: Starting LSB: exim Mail Transport Agent...
Sep 21 06:19:22 freedombox cron[873]: (CRON) INFO (pidfile fd = 3)
Sep 21 06:19:22 freedombox systemd[1]: Started Syncthing - Open Source Continuous File Synchronization for syncthing.
Sep 21 06:19:22 freedombox cron[873]: (CRON) INFO (Running @reboot jobs)
Sep 21 06:19:24 freedombox syncthing[881]: [start] INFO: syncthing v1.12.1-ds1 "Fermium Flea" (go1.15.9 linux-arm) debian@debian 2021-07-23 20:27:51 UTC
Sep 21 06:19:25 freedombox uwsgi[666]: Starting app server(s): uwsgi -> . done.
Sep 21 06:19:25 freedombox systemd[1]: Started LSB: Start/stop uWSGI server instance(s).
Sep 21 06:19:26 freedombox systemd[1]: Started The Apache HTTP Server.
Sep 21 06:19:27 freedombox systemd[1]: Started Performance Metrics Collector Daemon.
Sep 21 06:19:27 freedombox systemd[1]: Starting Performance Metrics Inference Engine...
Sep 21 06:19:27 freedombox systemd[1]: Starting Performance Metrics Archive Logger...
Sep 21 06:19:27 freedombox systemd[1]: Starting Proxy for Performance Metrics Collector Daemon...
Sep 21 06:19:30 freedombox sshd[1307]: Invalid user oliver from 2003:cc:3f2a:5300:dec2:d5f3:39a:e70d port 37048
Sep 21 06:19:30 freedombox systemd[1]: Started Samba SMB Daemon.
Sep 21 06:19:30 freedombox sshd[1307]: Connection closed by invalid user oliver 2003:cc:3f2a:5300:dec2:d5f3:39a:e70d port 37048 [preauth]
Sep 21 06:19:31 freedombox exim4[877]: Starting MTA: exim4.
Sep 21 06:19:31 freedombox systemd[1]: Started LSB: exim Mail Transport Agent.
Sep 21 06:19:31 freedombox systemd[1]: NetworkManager-dispatcher.service: Succeeded.
Sep 21 06:19:33 freedombox systemd[1]: Started Proxy for Performance Metrics Collector Daemon.
Sep 21 06:19:36 freedombox systemd[1]: systemd-hostnamed.service: Succeeded.
Sep 21 06:19:41 freedombox systemd[1]: Started Performance Metrics Inference Engine.
Sep 21 06:19:41 freedombox systemd[1]: Started Half-hourly check of PMIE instances.
Sep 21 06:19:41 freedombox systemd[1]: Started Daily processing of PMIE logs.
Sep 21 06:19:42 freedombox systemd[1]: Condition check resulted in Check PMIE instances are running being skipped.
Sep 21 06:19:43 freedombox syncthing[881]: [M7FML] INFO: My ID: M7FMLVP-WYPHUUM-AR6CLPD-FQYBVDV-KWWGHAR-A5BTF4X-CN5OJYK-ZUNRAAV
Sep 21 06:19:43 freedombox pcp-pmie[1801]: High 1-minute load average 6.6load@freedombox
Sep 21 06:19:44 freedombox syncthing[881]: [M7FML] INFO: Single thread SHA256 performance is 2.6 MB/s using crypto/sha256 (2.3 MB/s using minio/sha256-simd).
-- Boot e1b9af3f877f4b06893d4429080da29d --
Sep 21 06:25:01 freedombox CRON[9454]: pam_unix(cron:session): session opened for user pcp(uid=998) by (uid=0)
Sep 21 06:25:01 freedombox CRON[9456]: (pcp) CMD ( /usr/lib/pcp/bin/pmlogger_check -C)
Sep 21 06:25:01 freedombox CRON[9453]: pam_unix(cron:session): session opened for user root(uid=0) by (uid=0)
Sep 21 06:25:01 freedombox CRON[9459]: (root) CMD (test -x /usr/sbin/anacron || ( cd / && run-parts --report /etc/cron.daily ))
Sep 21 06:25:02 freedombox systemd[1]: Condition check resulted in Check pmlogger instances are running being skipped.
Sep 21 06:25:02 freedombox cracklib[9534]: no dictionary update necessary.
Sep 21 06:25:03 freedombox su[9597]: (to nobody) root on none
Sep 21 06:25:03 freedombox su[9597]: pam_unix(su:session): session opened for user nobody(uid=65534) by (uid=0)
Sep 21 06:25:03 freedombox systemd[1]: Created slice User Slice of UID 65534.
Sep 21 06:25:03 freedombox systemd[1]: Starting User Runtime Directory /run/user/65534...
Sep 21 06:25:03 freedombox systemd[1]: Finished User Runtime Directory /run/user/65534.
Sep 21 06:25:03 freedombox systemd[1]: Starting User Manager for UID 65534...
Sep 21 06:25:04 freedombox systemd[9640]: pam_unix(systemd-user:session): session opened for user nobody(uid=65534) by (uid=0)
Sep 21 06:25:04 freedombox systemd[9682]: gpgconf: error running '/usr/lib/gnupg/scdaemon': probably not installed
Sep 21 06:25:05 freedombox systemd[9640]: Queued start job for default target Main User Target.
Sep 21 06:25:05 freedombox systemd[9640]: Created slice User Application Slice.
Sep 21 06:25:05 freedombox systemd[9640]: Reached target Paths.
Sep 21 06:25:05 freedombox systemd[9640]: Reached target Timers.
Sep 21 06:25:05 freedombox systemd[9640]: Starting D-Bus User Message Bus Socket.
Sep 21 06:25:05 freedombox systemd[9640]: Listening on GnuPG network certificate management daemon.
Sep 21 06:25:05 freedombox systemd[9640]: Listening on GnuPG cryptographic agent and passphrase cache (access for web browsers).
Sep 21 06:25:05 freedombox systemd[9640]: Listening on GnuPG cryptographic agent and passphrase cache (restricted).
Sep 21 06:25:05 freedombox systemd[9640]: Listening on GnuPG cryptographic agent (ssh-agent emulation).
Sep 21 06:25:05 freedombox systemd[9640]: Listening on GnuPG cryptographic agent and passphrase cache.
Sep 21 06:25:05 freedombox systemd[9640]: Listening on debconf communication socket.
Sep 21 06:25:05 freedombox systemd[9640]: Listening on D-Bus User Message Bus Socket.
Sep 21 06:25:05 freedombox systemd[9640]: Reached target Sockets.
Sep 21 06:25:05 freedombox systemd[9640]: Reached target Basic System.
Sep 21 06:25:05 freedombox systemd[9640]: Reached target Main User Target.
Sep 21 06:25:05 freedombox systemd[9640]: Startup finished in 1.519s.
Sep 21 06:25:05 freedombox systemd[1]: Started User Manager for UID 65534.
Sep 21 06:25:05 freedombox systemd[1]: Started Session 1388 of user nobody.
Sep 21 06:25:05 freedombox su[9597]: pam_unix(su:session): session closed for user nobody
Sep 21 06:25:06 freedombox systemd[1]: session-1388.scope: Succeeded.
Sep 21 06:25:06 freedombox su[9822]: (to nobody) root on none
Sep 21 06:25:06 freedombox su[9822]: pam_unix(su:session): session opened for user nobody(uid=65534) by (uid=0)
Sep 21 06:25:06 freedombox systemd[1]: Started Session 1388 of user nobody.
Sep 21 06:25:06 freedombox su[9822]: pam_unix(su:session): session closed for user nobody
Sep 21 06:25:06 freedombox systemd[1]: session-1388.scope: Succeeded.
Sep 21 06:25:07 freedombox su[9872]: (to nobody) root on none
Sep 21 06:25:07 freedombox su[9872]: pam_unix(su:session): session opened for user nobody(uid=65534) by (uid=0)
Sep 21 06:25:07 freedombox systemd[1]: Started Session 1388 of user nobody.
Sep 21 06:25:08 freedombox CRON[9454]: pam_unix(cron:session): session closed for user pcp
Sep 21 06:28:01 freedombox CRON[9937]: pam_unix(cron:session): session opened for user pcp(uid=998) by (uid=0)
Sep 21 06:28:01 freedombox CRON[9939]: (pcp) CMD ( /usr/lib/pcp/bin/pmie_check -C)
Sep 21 06:28:02 freedombox systemd[1]: Condition check resulted in Check PMIE instances are running being skipped.
Sep 21 06:28:03 freedombox CRON[9937]: pam_unix(cron:session): session closed for user pcp
Sep 21 06:28:44 freedombox sshd[10099]: Received disconnect from 221.131.165.33 port 58119:11:  [preauth]
Sep 21 06:28:44 freedombox sshd[10099]: Disconnected from authenticating user root 221.131.165.33 port 58119 [preauth]
Sep 21 06:29:25 freedombox pcp-pmie[1906]: Severe demand for real memory 7.9pgsout/s@freedombox
Sep 21 06:30:01 freedombox CRON[10103]: pam_unix(cron:session): session opened for user pcp(uid=998) by (uid=0)
Sep 21 06:30:01 freedombox CRON[10102]: pam_unix(cron:session): session opened for user pcp(uid=998) by (uid=0)
Sep 21 06:30:01 freedombox CRON[10106]: (pcp) CMD ( /usr/lib/pcp/bin/pmlogger_daily -p)
Sep 21 06:30:01 freedombox CRON[10107]: (pcp) CMD ( /usr/lib/pcp/bin/pmlogger_daily_report -o /var/log/pcp/sa -p)
Sep 21 06:30:02 freedombox CRON[10103]: pam_unix(cron:session): session closed for user pcp
Sep 21 06:30:02 freedombox systemd[1]: Condition check resulted in Poll log processing being skipped.
Sep 21 06:30:02 freedombox CRON[10102]: pam_unix(cron:session): session closed for user pcp
Sep 21 06:30:38 freedombox udisksd[380]: Error performing housekeeping for drive /org/freedesktop/UDisks2/drives/Hitachi_HDS721010CLA330_JP2911N1068PRV: Error updating SMART data: sk_disk_smart_read_data: Operation not supported (udisks-error-quark, 0)
Sep 21 06:31:42 freedombox sshd[10193]: Received disconnect from 221.181.185.159 port 49944:11:  [preauth]
Sep 21 06:31:42 freedombox sshd[10193]: Disconnected from authenticating user root 221.181.185.159 port 49944 [preauth]
Sep 21 06:35:28 freedombox su[9872]: pam_unix(su:session): session closed for user nobody
Sep 21 06:35:29 freedombox systemd[1]: session-1388.scope: Succeeded.
Sep 21 06:35:29 freedombox systemd[1]: session-1388.scope: Consumed 6min 38.877s CPU time.
Sep 21 06:35:39 freedombox systemd[1]: Stopping User Manager for UID 65534...
Sep 21 06:35:39 freedombox systemd[9640]: Stopped target Main User Target.
Sep 21 06:35:39 freedombox systemd[9640]: Stopped target Basic System.
Sep 21 06:35:39 freedombox systemd[9640]: Stopped target Paths.
-- Boot 0e3522b11fe94868a4a257728aa27a75 --
Sep 26 20:57:37 freedombox systemd-timesyncd[351]: Initial synchronization to time server 159.69.25.180:123 (0.debian.pool.ntp.org).

In all of the hangs so far, we haven’t seen any sign of something wrong with kernel (in the form panic message notices on the console) or with applications (except for out-of-memory on two occasions). This leads me to believe this could be kernel+hardware related.

server keeps running :slight_smile: after 7 days
with
bepasty, sharing,jsxc, mintest search synthing

2 days ago, I installed the matrix synapse chat server.
Today, my system booted 4 times (according to journalctl --boot … – comments) from 12:19 'til 12:28 for no apparent reasons, than half an hour later no more entries in journal until I repowered the box at around 18:00
Again, no conspicuous messages …
So my feeling is, the less services, the more stable the system runs, which makes it quite useless :frowning:

It’s not the case. I tried with only Matrix and WireGuard running, and it’s the same. Hanging every second day. It’s very frustrating. I can’t believe that there is no solution for that problem yet. After all, that’s the pioneer edition, it’s kind a symbol of the system, and it’s not working. I think I waited enough for some solution. I’m wandering to go back to previous actually stable release (buster) or to get another hardware (e.g. Raspberry Pi)…or just go to another server OS (e.g. OMV).