Plex loses connection to OMV 6 while streaming from raspberry pi

Hi, a new guy here.

I´m having connection issues with my OMV 6/Plex/raspi 4 setup. The setup is the following:

- OMV 6.9.6-2 (shaitan) as of yesterday (but the problem is annoying for weeks or even months already)

- current plex version

- Raspberry Pi 4 4 GB connected via LAN to my vodafone router

- always up to date regarding sudo apt-get update / upgrade on the RPi4

- 18 TB HDD connected via SATA USB 3 adapter to raspberry (externally powered)

The issue that I have is that while streaming, Plex loses the connection to OMV. When plex doesnt have a connection my windows PC cannot connect to the HDD either. A couple of months ago this happened mybe twice a month but by now its happening almost everyday. Plex cant connect and I have to wait for the connection to be reestablised something between 30 seconds and 5 minutes.

While checking the omv sys logs I saw that at the exact times when plex / OMV was having issues, the clock was reset. These logs are exemplary and always the same when I checked them when something went wrong. Snapshot of the OMV sys logs below:

Quote

7.11.2023, 21:55:20

collectd[939]: Filter subsystem: Built-in target `write': Dispatching value to all write plugins failed with status -1.

7.11.2023, 21:55:20

collectd[939]: rrdcached plugin: rrdc_update (/var/lib/rrdcached/db/localhost/df-root/df_complex-used.rrd, [1699390520.276261:13843304448.000000], 1) failed: rrdcached@unix:/run/rrdcached.sock: illegal attempt to update using time 1699390520.276261 when last update time is 1699390520.276580 (minimum one second step) (status=-1)

7.11.2023, 21:55:20

collectd[939]: Filter subsystem: Built-in target `write': Dispatching value to all write plugins failed with status -1.

7.11.2023, 21:55:20

collectd[939]: rrdcached plugin: rrdc_update (/var/lib/rrdcached/db/localhost/rrdcached/counter-journal-rotates.rrd, [1699390520.279660:0], 1) failed: rrdcached@unix:/run/rrdcached.sock: illegal attempt to update using time 1699390520.279660 when last update time is 1699390520.279792 (minimum one second step) (status=-1)

7.11.2023, 21:55:20

collectd[939]: rrdcached plugin: Successfully reconnected to RRDCacheD at unix:/run/rrdcached.sock

7.11.2023, 21:55:20

collectd[939]: Filter subsystem: Built-in target `write': Some write plugin is back to normal operation. `write' succeeded.

7.11.2023, 21:55:20

wsdd.py[896]: WARNING: no interface given, using all interfaces

7.11.2023, 21:55:20

collectd[939]: rrdcached plugin: rrdc_update (/var/lib/rrdcached/db/localhost/df-root/df_complex-reserved.rrd, [1699390520.276246:1273524224.000000], 1) failed: rrdcached@unix:/run/rrdcached.sock: illegal attempt to update using time 1699390520.276246 when last update time is 1699390520.276579 (minimum one second step) (status=-1)

7.11.2023, 21:55:20

collectd[939]: Filter subsystem: Built-in target `write': Some write plugin is back to normal operation. `write' succeeded.

7.11.2023, 21:55:20

collectd[939]: rrdcached plugin: Successfully reconnected to RRDCacheD at unix:/run/rrdcached.sock

7.11.2023, 21:55:20

collectd[939]: Filter subsystem: Built-in target `write': Dispatching value to all write plugins failed with status -1.

7.11.2023, 21:55:21

systemd[1]: phpsessionclean.service: Succeeded.

7.11.2023, 21:55:21

systemd[1]: Started The PHP 7.4 FastCGI Process Manager.

7.11.2023, 21:55:20

chronyd[901]: Selected source 94.16.114.254 (pool.ntp.org)

7.11.2023, 21:55:20

chronyd[901]: System clock was stepped by 2228.358641 seconds

7.11.2023, 21:55:20

collectd[939]: rrdcached plugin: Successfully reconnected to RRDCacheD at unix:/run/rrdcached.sock

7.11.2023, 21:55:20

collectd[939]: rrdcached plugin: Successfully reconnected to RRDCacheD at unix:/run/rrdcached.sock

7.11.2023, 21:55:20

collectd[939]: rrdcached plugin: rrdc_update (/var/lib/rrdcached/db/localhost/cpu-1/cpu-interrupt.rrd, [1699390520.274992:0], 1) failed: rrdcached@unix:/run/rrdcached.sock: illegal attempt to update using time 1699390520.274992 when last update time is 1699390520.275371 (minimum one second step) (status=-1)

7.11.2023, 21:55:20

systemd[1]: Starting Clean php session files...

7.11.2023, 21:55:20

collectd[939]: Filter subsystem: Built-in target `write': Dispatching value to all write plugins failed with status -1.

7.11.2023, 21:55:20

collectd[939]: Filter subsystem: Built-in target `write': Some write plugin is back to normal operation. `write' succeeded.

7.11.2023, 21:55:20

collectd[939]: rrdcached plugin: rrdc_update (/var/lib/rrdcached/db/localhost/cpu-0/cpu-softirq.rrd, [1699390520.275002:6], 1) failed: rrdcached@unix:/run/rrdcached.sock: illegal attempt to update using time 1699390520.275002 when last update time is 1699390520.275381 (minimum one second step) (status=-1)

7.11.2023, 21:55:20

collectd[939]: Filter subsystem: Built-in target `write': Dispatching value to all write plugins failed with status -1.

7.11.2023, 21:55:20

collectd[939]: Filter subsystem: Built-in target `write': Some write plugin is back to normal operation. `write' succeeded.

7.11.2023, 21:55:20

collectd[939]: rrdcached plugin: Successfully reconnected to RRDCacheD at unix:/run/rrdcached.sock

7.11.2023, 21:55:20

chronyd[901]: System clock wrong by 2228.358641 seconds

7.11.2023, 21:55:30

systemd[1]: plexmediaserver.service: Scheduled restart job, restart counter is at 1.

7.11.2023, 21:55:30

systemd[1]: Starting Plex Media Server...

7.11.2023, 21:55:30

systemd[1]: Stopped Plex Media Server.

7.11.2023, 21:55:30

collectd[939]: Filter subsystem: Built-in target `write': Some write plugin is back to normal operation. `write' succeeded.

7.11.2023, 21:55:30

systemd[1]: Started Plex Media Server.

7.11.2023, 21:55:28

collectd[939]: Not sleeping because the next interval is 2218.359 seconds in the past!

7.11.2023, 21:55:27

smbd[1035]: daemon_ready: daemon 'smbd' finished starting up and ready to serve connections

7.11.2023, 21:55:27

smbd[1035]: [2023/11/07 21:55:27.749267, 0] ../../lib/util/become_daemon.c:135(daemon_ready)

7.11.2023, 21:55:27

systemd[1]: Started Samba SMB Daemon.

7.11.2023, 21:55:27

systemd[1]: Finished Remove Stale Online ext4 Metadata Check Snapshots.

7.11.2023, 21:55:27

systemd[1]: e2scrub_reap.service: Succeeded.

7.11.2023, 21:55:25

Plex Media Server[902]: Plex Media Server is already running. Will not start...

7.11.2023, 21:55:25

systemd[1]: plexmediaserver.service: Main process exited, code=exited, status=1/FAILURE

7.11.2023, 21:55:25

systemd[1]: plexmediaserver.service: Failed with result 'exit-code'.

7.11.2023, 21:55:21

systemd[1]: Finished Clean php session files.7.11.2023, 21:57:06

kernel: [ 185.348837] BTRFS warning (device sda): failed to load free space cache for block group 9888110477312, rebuilding it now

7.11.2023, 21:57:06

kernel: [ 185.348798] BTRFS warning (device sda): block group 9888110477312 has wrong amount of free space

7.11.2023, 21:56:10

CRON[1290]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi)

7.11.2023, 21:55:51

systemd[1]: Startup finished in 4.221s (kernel) + 1min 45.457s (userspace) = 1min 49.679s.

7.11.2023, 21:55:51

systemd[1]: Finished Beep after system start.

7.11.2023, 21:55:51

systemd[1]: openmediavault-beep-up.service: Succeeded.

7.11.2023, 21:55:50

systemd[1]: Reached target Login Prompts.

7.11.2023, 21:55:50

systemd[1]: Reached target Multi-User System.

7.11.2023, 21:55:50

systemd[1]: Starting Beep after system start...

7.11.2023, 21:55:50

systemd[1]: Finished Generate the prelogin message.

7.11.2023, 21:55:50

systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.

7.11.2023, 21:55:50

systemd[1]: Finished Update UTMP about System Runlevel Changes.

7.11.2023, 21:55:50

systemd[1]: Starting Update UTMP about System Runlevel Changes...

7.11.2023, 21:55:45

systemd[1]: systemd-hostnamed.service: Succeeded.

7.11.2023, 21:55:44

monit[867]: 'RaspiNAS' Monit 5.27.2 started

7.11.2023, 22:00:16

openmediavault-check_btrfs_errors: Checking file system mounted at /srv/dev-disk-by-uuid-3694b700-02bd-46ea-999e-ffad60d35121 [UUID=3694b700-02bd-46ea-999e-ffad60d35121] for errors ...

7.11.2023, 22:00:16

openmediavault-check_btrfs_errors: Performing an error check on Btrfs file systems.

7.11.2023, 22:00:15

anacron[1530]: Updated timestamp for job `cron.daily' to 2023-11-07

7.11.2023, 22:00:15

anacron[914]: Job `cron.daily' started

7.11.2023, 22:00:02

CRON[1408]: (root) CMD (/usr/sbin/omv-mkrrdgraph >/dev/null 2>&1)

Display More

NTP Server is configured for the clock

My assumption is that the NTP server is somehow not working and the raspberry is pulling the time at random point in time, causing connection issues with the HDD. When the time is corrected, OMV reconnects the drive and I can continue streaming again.

What logs / terminal printout could i provide that could lead to an answer?

Kind regards from Germany,

Oliver

These are the logs from yesterday. OMV didn´t find the HDD for about two minutes. after that it reconnected automatically.

Nov  9 21:59:59 raspinas chronyd[901]: Selected source 5.1.73.2 (pool.ntp.org)
Nov  9 21:59:59 raspinas chronyd[901]: System clock wrong by 2503.460821 seconds
Nov  9 21:59:59 raspinas chronyd[901]: System clock was stepped by 2503.460821 seconds
Nov  9 21:59:59 raspinas collectd[939]: rrdcached plugin: Successfully reconnected to RRDCacheD at unix:/run/rrdcached.sock
Nov  9 21:59:59 raspinas collectd[939]: rrdcached plugin: rrdc_update (/var/lib/rrdcached/db/localhost/memory/memory-cached.rrd, [1699563599.807584:3092819968.000000], 1) failed: rrdcached@unix:/run/rrdcached.sock: illegal attempt to update using time 1699563599.807584 when last update time is 1699563599.808287 (minimum one second step) (status=-1)
Nov  9 21:59:59 raspinas collectd[939]: Filter subsystem: Built-in target `write': Dispatching value to all write plugins failed with status -1.
Nov  9 21:59:59 raspinas systemd[1]: Starting Clean php session files...
Nov  9 21:59:59 raspinas collectd[939]: Filter subsystem: Built-in target `write': Some write plugin is back to normal operation. `write' succeeded.
Nov  9 22:00:00 raspinas wsdd.py[896]: WARNING: no interface given, using all interfaces
Nov  9 22:00:00 raspinas systemd[1]: Started The PHP 7.4 FastCGI Process Manager.
Nov  9 22:00:00 raspinas systemd[1]: phpsessionclean.service: Succeeded.
Nov  9 22:00:00 raspinas systemd[1]: Finished Clean php session files.
Nov  9 22:00:07 raspinas collectd[939]: Not sleeping because the next interval is 2493.461 seconds in the past!
Nov  9 22:00:11 raspinas smbd[1049]: [2023/11/09 22:00:11.761517,  0] ../../lib/util/become_daemon.c:135(daemon_ready)
Nov  9 22:00:11 raspinas systemd[1]: Started Samba SMB Daemon.
Nov  9 22:00:11 raspinas smbd[1049]:   daemon_ready: daemon 'smbd' finished starting up and ready to serve connections
Nov  9 22:00:11 raspinas systemd[1]: e2scrub_reap.service: Succeeded.
Nov  9 22:00:11 raspinas systemd[1]: Finished Remove Stale Online ext4 Metadata Check Snapshots.
Nov  9 22:00:24 raspinas monit[867]: 'RaspiNAS' Monit 5.27.2 started
Nov  9 22:00:24 raspinas systemd[1]: systemd-hostnamed.service: Succeeded.
Nov  9 22:00:46 raspinas systemd[1]: Finished Generate the prelogin message.
Nov  9 22:00:46 raspinas systemd[1]: Reached target Login Prompts.
Nov  9 22:00:46 raspinas systemd[1]: Reached target Multi-User System.
Nov  9 22:00:46 raspinas systemd[1]: Starting Beep after system start...
Nov  9 22:00:46 raspinas systemd[1]: Starting Update UTMP about System Runlevel Changes...
Nov  9 22:00:46 raspinas systemd[1]: systemd-update-utmp-runlevel.service: Succeeded.
Nov  9 22:00:46 raspinas systemd[1]: Finished Update UTMP about System Runlevel Changes.
Nov  9 22:00:46 raspinas CRON[1250]: (root) CMD (/usr/sbin/omv-mkrrdgraph >/dev/null 2>&1)
Nov  9 22:00:46 raspinas systemd[1]: openmediavault-beep-up.service: Succeeded.
Nov  9 22:00:46 raspinas systemd[1]: Finished Beep after system start.
Nov  9 22:00:46 raspinas systemd[1]: Startup finished in 4.252s (kernel) + 2min 5.092s (userspace) = 2min 9.345s.
Nov  9 22:00:47 raspinas CRON[1259]: (root) CMD ([ -x /etc/init.d/anacron ] && if [ ! -d /run/systemd/system ]; then /usr/sbin/invoke-rc.d anacron start >/dev/null; fi)
Nov  9 22:01:26 raspinas kernel: [  169.340308] BTRFS warning (device sda): block group 9888110477312 has wrong amount of free space
Nov  9 22:01:26 raspinas kernel: [  169.340346] BTRFS warning (device sda): failed to load free space cache for block group 9888110477312, rebuilding it now
Nov  9 22:04:55 raspinas anacron[914]: Job `cron.daily' started
Nov  9 22:04:55 raspinas anacron[1527]: Updated timestamp for job `cron.daily' to 2023-11-09
Nov  9 22:04:55 raspinas openmediavault-check_btrfs_errors: Performing an error check on Btrfs file systems.
Nov  9 22:04:55 raspinas openmediavault-check_btrfs_errors: Checking file system mounted at /srv/dev-disk-by-uuid-3694b700-02bd-46ea-999e-ffad60d35121 [UUID=3694b700-02bd-46ea-999e-ffad60d35121] for errors ...
Nov  9 22:04:55 raspinas kernel: [  378.391179] BTRFS info (device sda): device stats zeroed by btrfs (1562)
Nov  9 22:04:55 raspinas openmediavault-check_btrfs_errors: No errors found on file system mounted at /srv/dev-disk-by-uuid-3694b700-02bd-46ea-999e-ffad60d35121 [UUID=3694b700-02bd-46ea-999e-ffad60d35121].
Nov  9 22:04:55 raspinas openmediavault-check_ssl_cert_expiry: Perform a check for expired SSL certificates.
Nov  9 22:09:01 raspinas CRON[1739]: (root) CMD (  [ -x /usr/lib/php/sessionclean ] && if [ ! -d /run/systemd/system ]; then /usr/lib/php/sessionclean; fi)

Nevermind, i did a fresh install of OMV and Plex and now it seems to work properly again