-- Logs begin at Thu 2025-12-11 06:14:17 EET, end at Thu 2025-12-11 06:16:12 EET. -- Dec 11 06:15:00 volumio2 systemd[1]: Started FusionDsp Daemon. Dec 11 06:15:00 volumio2 sudo[1866]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:00 volumio2 volumio[1276]: Upnp client error: Error: This socket has been ended by the other party Dec 11 06:15:00 volumio2 volumio[1276]: info: Shairport-Sync Started Dec 11 06:15:00 volumio2 volumio[1276]: Error adding Membership: Error: addMembership EINVAL Dec 11 06:15:00 volumio2 volumio[1276]: info: Shairport-Sync Started Dec 11 06:15:00 volumio2 volumio[1276]: info: Shairport-Sync Started Dec 11 06:15:00 volumio2 volumio[1276]: info: [squeezelite_mc] Card 5 supports the following ALSA formats: ["S16_LE"] Dec 11 06:15:00 volumio2 volumio[1276]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio2","dsdFormat":null,"card":"5","mixerType":"Hardware","mixer":"Speaker"} Dec 11 06:15:00 volumio2 volumio[1276]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Dec 11 06:15:00 volumio2 volumio[1276]: info: Asound.conf file unchanged, so no further update is needed Dec 11 06:15:00 volumio2 volumio[1276]: info: Output device has changed, restarting MPD Dec 11 06:15:00 volumio2 volumio[1276]: info: Output device has changed, restarting Shairport Sync Dec 11 06:15:00 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:00 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 06:15:00 volumio2 sudo[1886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 06:15:00 volumio2 sudo[1886]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:00 volumio2 volumio[1276]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 06:15:00 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Dec 11 06:15:00 volumio2 systemd[1]: Stopping Music Player Daemon... Dec 11 06:15:00 volumio2 sudo[1884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 06:15:00 volumio2 sudo[1884]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:00 volumio2 sudo[1884]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:00 volumio2 systemd[1]: mpd.service: Succeeded. Dec 11 06:15:00 volumio2 systemd[1]: Stopped Music Player Daemon. Dec 11 06:15:00 volumio2 systemd[1]: Starting Music Player Daemon... Dec 11 06:15:00 volumio2 sudo[1882]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Dec 11 06:15:00 volumio2 sudo[1882]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:00 volumio2 sudo[1882]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:00 volumio2 sudo[1893]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 06:15:00 volumio2 sudo[1893]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:00 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:00 volumio2 volumio[1276]: info: volroon::Output device has changed Dec 11 06:15:00 volumio2 sudo[1893]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 11 06:15:00 volumio2 sudo[1893]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:00 volumio2 volumio[1276]: info: Preparing to generate the ALSA configuration file Dec 11 06:15:00 volumio2 volumio[1276]: info: MPD running with PID1720 Dec 11 06:15:00 volumio2 volumio[1276]: ,establishing connection Dec 11 06:15:00 volumio2 volumio[1276]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Dec 11 06:15:00 volumio2 volumio[1276]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 11 06:15:00 volumio2 volumio[1276]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 11 06:15:00 volumio2 volumio[1276]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 11 06:15:00 volumio2 volumio[1276]: info: Reading ALSA contributions from plugins. Dec 11 06:15:00 volumio2 volumio[1276]: error: MPD error: Error: write EPIPE Dec 11 06:15:00 volumio2 sudo[1901]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Dec 11 06:15:00 volumio2 sudo[1901]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:00 volumio2 volumio[1276]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Dec 11 06:15:00 volumio2 sudo[1901]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:01 volumio2 volumio[1276]: error: MPD error: The expression evaluated to a falsy value: Dec 11 06:15:01 volumio2 volumio[1276]: assert.ok(self.idling) Dec 11 06:15:01 volumio2 volumio[1276]: error: The expression evaluated to a falsy value: Dec 11 06:15:01 volumio2 volumio[1276]: assert.ok(self.idling) Dec 11 06:15:01 volumio2 volumio[1276]: error: MPD error: Error: write EPIPE Dec 11 06:15:01 volumio2 volumio[1276]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Dec 11 06:15:01 volumio2 volumio[1276]: error: MPD error: The expression evaluated to a falsy value: Dec 11 06:15:01 volumio2 volumio[1276]: assert.ok(self.idling) Dec 11 06:15:01 volumio2 volumio[1276]: error: The expression evaluated to a falsy value: Dec 11 06:15:01 volumio2 volumio[1276]: assert.ok(self.idling) Dec 11 06:15:01 volumio2 volumio[1276]: info: MPD Permissions set Dec 11 06:15:01 volumio2 volumio[1276]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Dec 11 06:15:01 volumio2 volumio[1276]: error: Upnp client error: Error: This socket has been ended by the other party Dec 11 06:15:01 volumio2 volumio[1276]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 11 06:15:01 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:01 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:01 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:01 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:01 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:01 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:01 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:01 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:01 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 06:15:01 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 11 06:15:01 volumio2 sudo[1907]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Dec 11 06:15:01 volumio2 sudo[1907]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:01 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:01 volumio2 sudo[1907]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:01 volumio2 volroon[1775]: 00:00:00.024 Warn: get lock file path: /tmp/.rnbgem0- Dec 11 06:15:01 volumio2 volumio[1276]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Dec 11 06:15:01 volumio2 volumio[1276]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Dec 11 06:15:01 volumio2 volumio[1276]: info: Starting Shairport Sync Dec 11 06:15:01 volumio2 sudo[1931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Dec 11 06:15:01 volumio2 sudo[1931]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:01 volumio2 sudo[1933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Dec 11 06:15:01 volumio2 sudo[1933]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:01 volumio2 sudo[1931]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:02 volumio2 volumio[1276]: info: go-librespot daemon successfully initialized Dec 11 06:15:02 volumio2 sudo[1933]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:02 volumio2 volumio[1276]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Dec 11 06:15:02 volumio2 sudo[1938]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 06:15:02 volumio2 sudo[1938]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:02 volumio2 volumio[1276]: info: Asound.conf file unchanged, so no further update is needed Dec 11 06:15:02 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 11 06:15:02 volumio2 volumio[1276]: info: Output device has changed, restarting MPD Dec 11 06:15:02 volumio2 systemd[1]: shairport-sync.service: Succeeded. Dec 11 06:15:02 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 11 06:15:02 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 11 06:15:02 volumio2 sudo[1938]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:02 volumio2 sudo[1941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Dec 11 06:15:02 volumio2 sudo[1941]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:02 volumio2 systemd[1]: Reloading. Dec 11 06:15:02 volumio2 sudo[1947]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 06:15:02 volumio2 sudo[1947]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:02 volumio2 sudo[1947]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:02 volumio2 volumio[1276]: info: Output device has changed, restarting Shairport Sync Dec 11 06:15:02 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:02 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 06:15:02 volumio2 sudo[1951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 06:15:02 volumio2 sudo[1951]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:02 volumio2 volumio[1276]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 06:15:02 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Dec 11 06:15:02 volumio2 volroon[1775]: 00:00:01.082 Trace: [childprocess] using unix child process Dec 11 06:15:02 volumio2 mpd[1898]: Dec 11 06:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 06:15:02 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:02 volumio2 volumio[1276]: info: volroon::Output device has changed Dec 11 06:15:02 volumio2 volumio[1276]: info: Preparing to generate the ALSA configuration file Dec 11 06:15:02 volumio2 volumio-time-update[702]: volumio-time-update-util: Fetching time from Volumio... Dec 11 06:15:02 volumio2 volroon[1775]: 00:00:01.284 Debug: PathForResource, filename: ../.update Dec 11 06:15:02 volumio2 volumio[1276]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 11 06:15:02 volumio2 volumio[1276]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 11 06:15:02 volumio2 volumio[1276]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 11 06:15:02 volumio2 volumio[1276]: info: Reading ALSA contributions from plugins. Dec 11 06:15:02 volumio2 volumio[1276]: info: MPD Permissions set Dec 11 06:15:02 volumio2 volumio[1276]: info: Shairport-Sync Started Dec 11 06:15:02 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:02 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:02 volumio2 volroon[1775]: 00:00:01.489 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/../.update Dec 11 06:15:02 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 06:15:02 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 11 06:15:02 volumio2 volroon[1775]: 00:00:01.500 Debug: PathForResource, filename: RAATServer Dec 11 06:15:02 volumio2 volroon[1775]: 00:00:01.503 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/RAATServer Dec 11 06:15:02 volumio2 volroon[1775]: 00:00:01.504 Debug: PathForResource, filename: RoonBridgeHelper Dec 11 06:15:02 volumio2 volroon[1775]: 00:00:01.509 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/RoonBridgeHelper Dec 11 06:15:02 volumio2 volroon[1775]: Initializing Dec 11 06:15:02 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:02 volumio2 volumio[1276]: info: Starting Shairport Sync Dec 11 06:15:02 volumio2 volroon[1775]: 00:00:01.596 Info: Starting /data/plugins/music_service/volroon/RoonBridge/Bridge/RoonBridgeHelper Dec 11 06:15:03 volumio2 volumio[1276]: info: Asound.conf file unchanged, so no further update is needed Dec 11 06:15:03 volumio2 volumio[1276]: info: Output device has changed, restarting MPD Dec 11 06:15:03 volumio2 volumio[1276]: info: Output device has changed, restarting Shairport Sync Dec 11 06:15:03 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:03 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 06:15:03 volumio2 sudo[1988]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 06:15:03 volumio2 sudo[1988]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:03 volumio2 sudo[1990]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 11 06:15:03 volumio2 sudo[1990]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:03 volumio2 sudo[1992]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 11 06:15:03 volumio2 sudo[1992]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:03 volumio2 sudo[1990]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:03 volumio2 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 11 06:15:03 volumio2 volroon[1775]: 00:00:01.798 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/volroon/RoonBridge/Bridge/RAATServer Dec 11 06:15:03 volumio2 volumio[1276]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 11 06:15:03 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Dec 11 06:15:03 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:03 volumio2 volumio[1276]: info: volroon::Output device has changed Dec 11 06:15:03 volumio2 systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Dec 11 06:15:03 volumio2 volroon[1775]: Not Running (.o) Dec 11 06:15:03 volumio2 sudo[2015]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 06:15:03 volumio2 sudo[2015]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:03 volumio2 volumio[1276]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Dec 11 06:15:03 volumio2 systemd[1]: Started Music Player Daemon. Dec 11 06:15:03 volumio2 sudo[1941]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:03 volumio2 sudo[1886]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:03 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 11 06:15:03 volumio2 systemd[1]: Stopping Music Player Daemon... Dec 11 06:15:03 volumio2 systemd[1]: shairport-sync.service: Succeeded. Dec 11 06:15:03 volumio2 volumio[1276]: info: MPD Permissions set Dec 11 06:15:03 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 11 06:15:03 volumio2 volumio[1276]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 11 06:15:03 volumio2 sudo[2019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 06:15:03 volumio2 systemd[1]: mpd.service: Succeeded. Dec 11 06:15:03 volumio2 systemd[1]: Stopped Music Player Daemon. Dec 11 06:15:03 volumio2 sudo[2019]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:03 volumio2 sudo[2022]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Dec 11 06:15:03 volumio2 systemd[1]: Starting Music Player Daemon... Dec 11 06:15:03 volumio2 volumio[1276]: error: MPD error: Error: read ECONNRESET Dec 11 06:15:03 volumio2 volumio[1276]: error: Upnp client error: Error: This socket has been ended by the other party Dec 11 06:15:03 volumio2 sudo[2022]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:03 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 11 06:15:03 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:03 volumio2 sudo[1988]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:03 volumio2 sudo[2026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 11 06:15:03 volumio2 systemd[1]: Configuration file /etc/systemd/system/jellyfin.service is marked world-inaccessible. This has no effect as configuration data is accessible via APIs without restrictions. Proceeding anyway. Dec 11 06:15:03 volumio2 sudo[2026]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:04 volumio2 sudo[2022]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:04 volumio2 sudo[2024]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 11 06:15:04 volumio2 sudo[2024]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:04 volumio2 sudo[2024]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:04 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 06:15:04 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 11 06:15:04 volumio2 volroon[1775]: Running Dec 11 06:15:04 volumio2 sudo[2026]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:04 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:04 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 06:15:04 volumio2 volumio[1276]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Dec 11 06:15:04 volumio2 volumio[1276]: info: Shairport-Sync Started Dec 11 06:15:04 volumio2 volumio[1276]: info: Starting Shairport Sync Dec 11 06:15:04 volumio2 sudo[2057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Dec 11 06:15:04 volumio2 sudo[2057]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:04 volumio2 systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Dec 11 06:15:04 volumio2 sudo[2061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 11 06:15:04 volumio2 sudo[2061]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:04 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 11 06:15:04 volumio2 systemd[1]: shairport-sync.service: Succeeded. Dec 11 06:15:04 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 11 06:15:04 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 11 06:15:04 volumio2 sudo[2061]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:04 volumio2 volumio[1276]: info: Shairport-Sync Started Dec 11 06:15:04 volumio2 systemd[1]: squeezelite.service: Succeeded. Dec 11 06:15:04 volumio2 systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Dec 11 06:15:04 volumio2 sudo[2057]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:04 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:04 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:04 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:04 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 11 06:15:04 volumio2 volumio[1276]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Dec 11 06:15:05 volumio2 sudo[2072]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Dec 11 06:15:05 volumio2 sudo[2072]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:05 volumio2 volumio[1276]: info: Initializing connection to go-librespot Websocket Dec 11 06:15:05 volumio2 sudo[2072]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:05 volumio2 volumio[1276]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 11 06:15:05 volumio2 sudo[2076]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 11 06:15:05 volumio2 sudo[2076]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:05 volumio2 sudo[2076]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:05 volumio2 volumio[1276]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Dec 11 06:15:05 volumio2 sudo[2080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Dec 11 06:15:05 volumio2 sudo[2080]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:05 volumio2 sudo[2080]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:05 volumio2 volumio[1276]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Dec 11 06:15:05 volumio2 volumio[1276]: error: MPD error: The expression evaluated to a falsy value: Dec 11 06:15:05 volumio2 volumio[1276]: assert.ok(self.idling) Dec 11 06:15:05 volumio2 volumio[1276]: error: The expression evaluated to a falsy value: Dec 11 06:15:05 volumio2 volumio[1276]: assert.ok(self.idling) Dec 11 06:15:05 volumio2 volumio[1276]: error: MPD error: The expression evaluated to a falsy value: Dec 11 06:15:05 volumio2 volumio[1276]: assert.ok(self.idling) Dec 11 06:15:05 volumio2 volumio[1276]: error: The expression evaluated to a falsy value: Dec 11 06:15:05 volumio2 volumio[1276]: assert.ok(self.idling) Dec 11 06:15:05 volumio2 sudo[2087]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Dec 11 06:15:05 volumio2 sudo[2087]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:05 volumio2 systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Dec 11 06:15:05 volumio2 jellyfin[1734]: [06:15:05] [INF] [1] Main: Jellyfin version: 10.9.6 Dec 11 06:15:05 volumio2 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Dec 11 06:15:06 volumio2 sudo[2087]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:06 volumio2 jellyfin[1734]: [06:15:06] [INF] [1] Main: Environment Variables: ["[JELLYFIN_LOG_DIR, /opt/jellyfin/log]"] Dec 11 06:15:06 volumio2 jellyfin[1734]: [06:15:06] [INF] [1] Main: Arguments: ["/opt/jellyfin/jellyfin_10.9.6/jellyfin.dll", "-d", "/opt/jellyfin/data", "-C", "/opt/jellyfin/cache", "-c", "/opt/jellyfin/config", "-l", "/opt/jellyfin/log", "--ffmpeg", "/usr/lib/jellyfin-ffmpeg/ffmpeg"] Dec 11 06:15:06 volumio2 jellyfin[1734]: [06:15:06] [INF] [1] Main: Operating system: Debian GNU/Linux 10 (buster) Dec 11 06:15:06 volumio2 jellyfin[1734]: [06:15:06] [INF] [1] Main: Architecture: X64 Dec 11 06:15:06 volumio2 jellyfin[1734]: [06:15:06] [INF] [1] Main: 64-Bit Process: True Dec 11 06:15:06 volumio2 jellyfin[1734]: [06:15:06] [INF] [1] Main: User Interactive: True Dec 11 06:15:06 volumio2 jellyfin[1734]: [06:15:06] [INF] [1] Main: Processor count: 2 Dec 11 06:15:06 volumio2 jellyfin[1734]: [06:15:06] [INF] [1] Main: Program data path: /opt/jellyfin/data Dec 11 06:15:06 volumio2 jellyfin[1734]: [06:15:06] [INF] [1] Main: Log directory path: /opt/jellyfin/log Dec 11 06:15:06 volumio2 jellyfin[1734]: [06:15:06] [INF] [1] Main: Config directory path: /opt/jellyfin/config Dec 11 06:15:06 volumio2 jellyfin[1734]: [06:15:06] [INF] [1] Main: Cache path: /opt/jellyfin/cache Dec 11 06:15:06 volumio2 jellyfin[1734]: [06:15:06] [INF] [1] Main: Web resources path: /opt/jellyfin/jellyfin_10.9.6/jellyfin-web Dec 11 06:15:06 volumio2 jellyfin[1734]: [06:15:06] [INF] [1] Main: Application directory: /opt/jellyfin/jellyfin_10.9.6/ Dec 11 06:15:06 volumio2 mpd[2040]: Dec 11 06:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 11 06:15:06 volumio2 volumio[1276]: info: camilladsp spawned new process with pid 2102, instance 1, run: true Dec 11 06:15:06 volumio2 volumio[1276]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 11 06:15:06 volumio2 sudo[2147]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 11 06:15:06 volumio2 sudo[2147]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:06 volumio2 sudo[2147]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:06 volumio2 volroon[1775]: 00:00:00.106 Warn: get lock file path: /tmp/.rnbhgem0- Dec 11 06:15:06 volumio2 sudo[2166]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 06:15:06 volumio2 sudo[2166]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:06 volumio2 systemd[1]: Started Music Player Daemon. Dec 11 06:15:06 volumio2 sudo[1951]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:06 volumio2 sudo[1992]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:06 volumio2 volumio[1276]: error: updateQueue error: null Dec 11 06:15:06 volumio2 volumio[1276]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 11 06:15:06 volumio2 systemd[1]: Started MPD Monitor Service. Dec 11 06:15:06 volumio2 sudo[2166]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:06 volumio2 sudo[2019]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:06 volumio2 sudo[2015]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:07 volumio2 volumio[1276]: info: Successfully started MPD Monitor Dec 11 06:15:07 volumio2 volumio[1276]: info: Successfully started MPD Monitor Dec 11 06:15:07 volumio2 volumio[1276]: info: Successfully started MPD Monitor Dec 11 06:15:07 volumio2 volumio[1276]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 11 06:15:07 volumio2 mpd_monitor.sh[2194]: MPD Monitor Service: Starting MPD Monitor Service Dec 11 06:15:07 volumio2 volumio[1691]: Generating RSA private key, 4096 bit long modulus (2 primes) Dec 11 06:15:07 volumio2 volumio[1276]: error: MPD error: The expression evaluated to a falsy value: Dec 11 06:15:07 volumio2 volumio[1276]: assert.ok(self.idling) Dec 11 06:15:07 volumio2 volumio[1276]: error: The expression evaluated to a falsy value: Dec 11 06:15:07 volumio2 volumio[1276]: assert.ok(self.idling) Dec 11 06:15:07 volumio2 volumio[1276]: error: updateQueue error: null Dec 11 06:15:07 volumio2 volumio[1276]: Upnp client error: Error: read ECONNRESET Dec 11 06:15:07 volumio2 sudo[2232]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 11 06:15:07 volumio2 sudo[2232]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:07 volumio2 sudo[2232]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:07 volumio2 volumio[1276]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 11 06:15:08 volumio2 sudo[2252]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 11 06:15:08 volumio2 sudo[2252]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:08 volumio2 sudo[2252]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:08 volumio2 volumio[1691]: .................................++++ Dec 11 06:15:08 volumio2 volumio[1276]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 11 06:15:08 volumio2 sudo[2301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 11 06:15:08 volumio2 sudo[2301]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:08 volumio2 volumio[1276]: error: updateQueue error: null Dec 11 06:15:08 volumio2 volumio[1276]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 11 06:15:08 volumio2 sudo[2301]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:09 volumio2 volumio[1691]: ...................++++ Dec 11 06:15:09 volumio2 volumio[1691]: e is 65537 (0x010001) Dec 11 06:15:09 volumio2 volumio[1691]: writing RSA key Dec 11 06:15:09 volumio2 jellyfin[1734]: [06:15:09] [INF] [1] Emby.Server.Implementations.AppBase.BaseConfigurationManager: Setting cache path: /opt/jellyfin/cache Dec 11 06:15:09 volumio2 volumio[1276]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Dec 11 06:15:09 volumio2 sudo[2317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Dec 11 06:15:09 volumio2 sudo[2317]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:09 volumio2 sudo[2317]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:10 volumio2 sudo[2322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 06:15:10 volumio2 sudo[2322]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:10 volumio2 systemd[1]: Stopping MPD Monitor Service... Dec 11 06:15:10 volumio2 systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 11 06:15:10 volumio2 systemd[1]: mpd_monitor.service: Succeeded. Dec 11 06:15:10 volumio2 systemd[1]: Stopped MPD Monitor Service. Dec 11 06:15:10 volumio2 sudo[2325]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 11 06:15:10 volumio2 sudo[2325]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:15:10 volumio2 systemd[1]: Started MPD Monitor Service. Dec 11 06:15:10 volumio2 mpd_monitor.sh[2327]: MPD Monitor Service: Starting MPD Monitor Service Dec 11 06:15:10 volumio2 sudo[2322]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:10 volumio2 volumio[1276]: info: Successfully started MPD Monitor Dec 11 06:15:10 volumio2 systemd[1]: Stopping MPD Monitor Service... Dec 11 06:15:10 volumio2 jellyfin[1734]: [06:15:10] [INF] [1] Emby.Server.Implementations.ApplicationHost: Loading assemblies Dec 11 06:15:10 volumio2 systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 11 06:15:10 volumio2 systemd[1]: mpd_monitor.service: Succeeded. Dec 11 06:15:10 volumio2 systemd[1]: Stopped MPD Monitor Service. Dec 11 06:15:10 volumio2 systemd[1]: Started MPD Monitor Service. Dec 11 06:15:10 volumio2 sudo[2325]: pam_unix(sudo:session): session closed for user root Dec 11 06:15:10 volumio2 volumio[1276]: info: Successfully started MPD Monitor Dec 11 06:15:10 volumio2 mpd_monitor.sh[2332]: MPD Monitor Service: Starting MPD Monitor Service Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 06:15:10 volumio2 volroon[1775]: 00:00:08.978 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Dec 11 06:15:10 volumio2 volroon[1775]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Dec 11 06:15:10 volumio2 volroon[1775]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Dec 11 06:15:10 volumio2 volroon[1775]: Not Running (.o) Dec 11 06:15:10 volumio2 volroon[1775]: 00:00:09.047 Debug: PathForResource, filename: ../.update Dec 11 06:15:10 volumio2 volroon[1775]: 00:00:09.048 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/../.update Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::volumioGetVisibleSources Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::volumioGetState Dec 11 06:15:10 volumio2 volumio[1276]: info: CorePlayQueue::getTrack 0 Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 11 06:15:10 volumio2 volumio[1276]: info: Received Get System Info Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 06:15:10 volumio2 volumio[1276]: info: Discovery: Getting this device information Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::volumioGetState Dec 11 06:15:10 volumio2 volumio[1276]: info: CorePlayQueue::getTrack 0 Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::volumioGetState Dec 11 06:15:10 volumio2 volumio[1276]: info: CorePlayQueue::getTrack 0 Dec 11 06:15:10 volumio2 volumio[1276]: info: Listing playlists Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 06:15:10 volumio2 volumio[1276]: info: Received Get System Info Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 06:15:10 volumio2 volumio[1276]: info: Discovery: Getting this device information Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::volumioGetState Dec 11 06:15:10 volumio2 volumio[1276]: info: CorePlayQueue::getTrack 0 Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 06:15:10 volumio2 volumio[1276]: info: CoreCommandRouter::volumioGetState Dec 11 06:15:10 volumio2 volumio[1276]: info: CorePlayQueue::getTrack 0 Dec 11 06:15:11 volumio2 jellyfin[1734]: [06:15:11] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN subnets: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"] Dec 11 06:15:11 volumio2 jellyfin[1734]: [06:15:11] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN exclusions: [] Dec 11 06:15:11 volumio2 jellyfin[1734]: [06:15:11] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Used LAN subnets: ["127.0.0.1/8", "10.0.0.0/8", "172.16.0.0/12", "192.168.0.0/16"] Dec 11 06:15:11 volumio2 jellyfin[1734]: [06:15:11] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered interface addresses: ["127.0.0.1", "192.168.1.51"] Dec 11 06:15:11 volumio2 jellyfin[1734]: [06:15:11] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Bind Addresses ["0.0.0.0"] Dec 11 06:15:11 volumio2 jellyfin[1734]: [06:15:11] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Remote IP filter is Allowlist Dec 11 06:15:11 volumio2 jellyfin[1734]: [06:15:11] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered subnets: [] Dec 11 06:15:11 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 11 06:15:11 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 11 06:15:11 volumio2 volumio[1276]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 11 06:15:12 volumio2 volroon[1775]: 00:00:11.049 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/volroon/RoonBridge/Bridge/RAATServer Dec 11 06:15:12 volumio2 volroon[1775]: Running Dec 11 06:15:13 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 11 06:15:13 volumio2 ntpd[1324]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Dec 11 06:15:14 volumio2 volumio[1276]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Dec 11 06:15:14 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 11 06:15:14 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 11 06:15:14 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 11 06:15:14 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 11 06:15:17 volumio2 go-librespot[1792]: time="2025-12-11T06:15:17+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 11 06:15:17 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 06:15:17 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 06:15:18 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 11 06:15:20 volumio2 volumio-time-update[702]: volumio-time-update-util: Date not found in response Dec 11 06:15:20 volumio2 volumio-time-update[702]: volumio-time-update-util: Retrying in 5 seconds... Dec 11 06:15:20 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 06:15:20 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 11 06:15:20 volumio2 systemd[1]: Stopped go-librespot Daemon. Dec 11 06:15:20 volumio2 systemd[1]: Started go-librespot Daemon. Dec 11 06:15:21 volumio2 go-librespot[2449]: Librespot-go daemon starting... Dec 11 06:15:21 volumio2 go-librespot[2449]: time="2025-12-11T06:15:21+02:00" level=info msg="generated new device id: 570f972ca772a7752fa1e1d928ce4fabd9cdc2ce" Dec 11 06:15:21 volumio2 go-librespot[2449]: time="2025-12-11T06:15:21+02:00" level=debug msg="stored credentials found for 31jxmlwjw63hm3cpzjfc7mx4zev4" Dec 11 06:15:25 volumio2 volumio-time-update[702]: volumio-time-update-util: Fetching time from Volumio... Dec 11 06:15:25 volumio2 volumio[1276]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.874&uuid=ed70a44c887eefde0eab7e68ff9950f7" http://updates.volumio.org/downloader-v1/track-device Dec 11 06:15:25 volumio2 volumio[1276]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 11 06:15:25 volumio2 volumio[1276]: Dload Upload Total Spent Left Speed Dec 11 06:15:25 volumio2 volumio[1276]: [1.5K blob data] Dec 11 06:15:25 volumio2 volumio[1276]: retrying in 5 seconds, trial 0 Dec 11 06:15:25 volumio2 volumio[1276]: info: Volumio Calling Home Dec 11 06:15:27 volumio2 volumio[1276]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Dec 11 06:15:27 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 06:15:27 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 11 06:15:27 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 06:15:27 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 11 06:15:27 volumio2 volumio[1276]: error: Plugin music_service ytcr failed to complete 'onStart' in a timely fashion Dec 11 06:15:27 volumio2 volumio[1276]: info: ------------------------------------------- Dec 11 06:15:27 volumio2 volumio[1276]: info: ----- MyVolumio plugins startup ---- Dec 11 06:15:27 volumio2 volumio[1276]: info: ------------------------------------------- Dec 11 06:15:27 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 11 06:15:28 volumio2 volumio[1276]: info: [jellyfin-poller] Polled http://192.168.1.51:8096/web/index.html#/home.htm: offline Dec 11 06:15:28 volumio2 volumio[1276]: info: [squeezelite_mc] Server discovered: {"ip":"192.168.1.51","name":"volumio2","ver":"8.5.2","uuid":"2177359a-299b-470a-aaa3-3cf6cb2ca85c","jsonPort":"9000","cliPort":"9090"} Dec 11 06:15:29 volumio2 jellyfin[1734]: [06:15:29] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TMDb 10.9.6.0 Dec 11 06:15:29 volumio2 jellyfin[1734]: [06:15:29] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Studio Images 10.9.6.0 Dec 11 06:15:29 volumio2 jellyfin[1734]: [06:15:29] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: OMDb 10.9.6.0 Dec 11 06:15:29 volumio2 jellyfin[1734]: [06:15:29] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: MusicBrainz 10.9.6.0 Dec 11 06:15:29 volumio2 jellyfin[1734]: [06:15:29] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AudioDB 10.9.6.0 Dec 11 06:15:29 volumio2 jellyfin[1734]: [06:15:29] [INF] [1] Main: Kestrel is listening on 0.0.0.0 Dec 11 06:15:29 volumio2 volumio[1276]: info: [squeezelite_mc] Notification listener started Dec 11 06:15:29 volumio2 volumio[1276]: info: [squeezelite_mc] Getting players connected to volumio2 (192.168.1.51) Dec 11 06:15:30 volumio2 volumio[1276]: info: [squeezelite_mc] Players connected to volumio2 (192.168.1.51): [{"id":"c0:4b:24:d6:1b:da","uuid":null,"ip":"192.168.1.51","name":"volumio2","server":{"ip":"192.168.1.51","name":"volumio2","ver":"8.5.2","uuid":"2177359a-299b-470a-aaa3-3cf6cb2ca85c","jsonPort":"9000","cliPort":"9090"}}] Dec 11 06:15:30 volumio2 volumio[1276]: info: [squeezelite_mc] Player found: {"id":"c0:4b:24:d6:1b:da","uuid":null,"ip":"192.168.1.51","name":"volumio2","server":{"ip":"192.168.1.51","name":"volumio2","ver":"8.5.2","uuid":"2177359a-299b-470a-aaa3-3cf6cb2ca85c","jsonPort":"9000","cliPort":"9090"}} Dec 11 06:15:30 volumio2 jellyfin[1734]: [06:15:30] [INF] [1] Jellyfin.Networking.PortForwardingHost: Starting NAT discovery Dec 11 06:15:31 volumio2 volumio[1276]: (node:1276) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined Dec 11 06:15:31 volumio2 volumio[1276]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37) Dec 11 06:15:31 volumio2 volumio[1276]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129) Dec 11 06:15:31 volumio2 volumio[1276]: at runMicrotasks () Dec 11 06:15:31 volumio2 volumio[1276]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Dec 11 06:15:31 volumio2 volumio[1276]: at async PlayerStatusMonitor.start (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:47:9) Dec 11 06:15:31 volumio2 volumio[1276]: at async PlayerFinder. (/data/plugins/music_service/squeezelite_mc/dist/index.js:686:13) Dec 11 06:15:31 volumio2 volumio[1276]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 11 06:15:31 volumio2 volumio[1276]: (node:1276) UnhandledPromiseRejectionWarning: Unhandled promise rejection. This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). To terminate the node process on unhandled promise rejection, use the CLI flag `--unhandled-rejections=strict` (see https://nodejs.org/api/cli.html#cli_unhandled_rejections_mode). (rejection id: 3) Dec 11 06:15:31 volumio2 volumio[1276]: (node:1276) [DEP0018] DeprecationWarning: Unhandled promise rejections are deprecated. In the future, promise rejections that are not handled will terminate the Node.js process with a non-zero exit code. Dec 11 06:15:31 volumio2 volumio[1276]: info: Discovery: adding 01a5e55c-dda8-49b3-ab3b-5c5bd763a7d0 Dec 11 06:15:31 volumio2 volumio[1276]: info: Discovery: Found device Volumio2 Dec 11 06:15:31 volumio2 volumio[1276]: info: CoreCommandRouter::volumioGetState Dec 11 06:15:31 volumio2 volumio[1276]: info: CorePlayQueue::getTrack 0 Dec 11 06:15:31 volumio2 ntpd[1324]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Dec 11 06:15:32 volumio2 jellyfin[1734]: [06:15:32] [WRN] [1] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The WebRootPath was not found: /wwwroot. Static files may be unavailable. Dec 11 06:15:32 volumio2 jellyfin[1734]: [06:15:32] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks Dec 11 06:15:33 volumio2 jellyfin[1734]: [06:15:33] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Створити Trickplay-зображення set to fire at 2025-12-12 03:00:00.000 +02:00, which is 20:44:26.9907810 from now. Dec 11 06:15:33 volumio2 jellyfin[1734]: [06:15:33] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Створити ескізи розділів set to fire at 2025-12-12 02:00:00.000 +02:00, which is 19:44:26.9543632 from now. Dec 11 06:15:33 volumio2 jellyfin[1734]: [06:15:33] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version 6.0.1 Dec 11 06:15:34 volumio2 jellyfin[1734]: [06:15:34] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available decoders: ["libdav1d", "av1", "av1_cuvid", "av1_qsv", "h264", "h264_qsv", "h264_cuvid", "hevc", "hevc_qsv", "hevc_cuvid", "mpeg2video", "mpeg2_qsv", "mpeg2_cuvid", "mpeg4", "mpeg4_cuvid", "msmpeg4", "vc1_qsv", "vc1_cuvid", "vp8", "libvpx", "vp8_cuvid", "vp8_qsv", "vp9", "libvpx-vp9", "vp9_cuvid", "vp9_qsv", "aac", "ac3", "dca", "flac", "mp3", "truehd"] Dec 11 06:15:34 volumio2 jellyfin[1734]: [06:15:34] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available encoders: ["libsvtav1", "av1_nvenc", "av1_qsv", "av1_amf", "av1_vaapi", "libx264", "h264_amf", "h264_nvenc", "h264_qsv", "h264_v4l2m2m", "h264_vaapi", "libx265", "hevc_amf", "hevc_nvenc", "hevc_qsv", "hevc_vaapi", "mjpeg_qsv", "mjpeg_vaapi", "mpeg4", "msmpeg4", "libvpx", "libvpx-vp9", "aac", "libfdk_aac", "ac3", "alac", "dca", "flac", "libmp3lame", "libopus", "truehd", "libvorbis", "srt"] Dec 11 06:15:34 volumio2 jellyfin[1734]: [06:15:34] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available filters: ["deinterlace_qsv", "deinterlace_vaapi", "hwupload_cuda", "hwupload_vaapi", "libplacebo", "overlay_opencl", "overlay_qsv", "overlay_vaapi", "overlay_vulkan", "overlay_cuda", "procamp_vaapi", "scale_cuda", "scale_opencl", "scale_qsv", "scale_vaapi", "scale_vulkan", "tonemap_cuda", "tonemap_opencl", "tonemap_vaapi", "vpp_qsv", "yadif_cuda", "zscale", "alphasrc"] Dec 11 06:15:35 volumio2 jellyfin[1734]: [06:15:35] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available hwaccel types: ["cuda", "vaapi", "qsv", "drm", "opencl", "vulkan"] Dec 11 06:15:36 volumio2 jellyfin[1734]: [06:15:36] [INF] [10] Emby.Server.Implementations.ScheduledTasks.TaskManager: Очистити колекції і списки відтворення Completed after 0 minute(s) and 0 seconds Dec 11 06:15:36 volumio2 jellyfin[1734]: [06:15:36] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: FFmpeg: /usr/lib/jellyfin-ffmpeg/ffmpeg Dec 11 06:15:36 volumio2 jellyfin[1734]: [06:15:36] [INF] [1] Emby.Server.Implementations.ApplicationHost: ServerId: 84aa2766180e43ba989ef1e637e024d0 Dec 11 06:15:36 volumio2 jellyfin[1734]: [06:15:36] [INF] [1] Emby.Server.Implementations.ApplicationHost: Core startup complete Dec 11 06:15:36 volumio2 jellyfin[1734]: [06:15:36] [INF] [1] Main: Startup complete 0:00:34.8563284 Dec 11 06:15:39 volumio2 go-librespot[2449]: time="2025-12-11T06:15:39+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 11 06:15:39 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 06:15:39 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 06:15:40 volumio2 jellyfin[1734]: [06:15:40] [INF] [8] Emby.Server.Implementations.IO.LibraryMonitor: Watching directory /data/INTERNAL/Музыка Dec 11 06:15:42 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 06:15:42 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Dec 11 06:15:42 volumio2 systemd[1]: Stopped go-librespot Daemon. Dec 11 06:15:42 volumio2 systemd[1]: Started go-librespot Daemon. Dec 11 06:15:42 volumio2 go-librespot[2542]: Librespot-go daemon starting... Dec 11 06:15:42 volumio2 go-librespot[2542]: time="2025-12-11T06:15:42+02:00" level=info msg="generated new device id: bf1a1351b39c76503ca285565282213367871091" Dec 11 06:15:42 volumio2 go-librespot[2542]: time="2025-12-11T06:15:42+02:00" level=debug msg="stored credentials found for 31jxmlwjw63hm3cpzjfc7mx4zev4" Dec 11 06:15:43 volumio2 volumio-time-update[702]: volumio-time-update-util: Date not found in response Dec 11 06:15:43 volumio2 volumio-time-update[702]: volumio-time-update-util: Retrying in 5 seconds... Dec 11 06:15:47 volumio2 volumio[1276]: error: MyVolumio Plugin failed to start in a timely fashion Dec 11 06:15:47 volumio2 volumio[1276]: [Metrics] CommandRouter: 69s 172.30ms Dec 11 06:15:47 volumio2 volumio[1276]: info: CoreCommandRouter::volumiosetStartupVolume Dec 11 06:15:47 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 11 06:15:47 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 06:15:47 volumio2 volumio[1276]: info: CoreCommandRouter::Close All Modals sent Dec 11 06:15:47 volumio2 volumio[1276]: info: CoreCommandRouter::Close All Modals sent Dec 11 06:15:47 volumio2 volumio[1276]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 11 06:15:47 volumio2 volumio[1276]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 11 06:15:47 volumio2 volumio[1276]: info: camilladsp stopping service pid 2102... Dec 11 06:15:47 volumio2 volumio[1276]: info: camilladsp service terminated, instance 1 Dec 11 06:15:47 volumio2 volumio[1276]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 06:15:47 volumio2 volumio[1276]: info: camilladsp service started and running in background, instance 1 Dec 11 06:15:47 volumio2 volumio[1276]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 11 06:15:47 volumio2 volumio[1276]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 11 06:15:47 volumio2 volumio[1276]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 11 06:15:47 volumio2 volumio[1276]: info: camilladsp spawned new process with pid 2555, instance 1, run: true Dec 11 06:15:48 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 11 06:15:48 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 11 06:15:48 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 11 06:15:48 volumio2 volumio-time-update[702]: volumio-time-update-util: Fetching time from Volumio... Dec 11 06:15:49 volumio2 volumio[1276]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (148rht5qeutavdhj9c7i9thi2s): Dec 11 06:15:49 volumio2 volumio[1276]: (ConnectionError) (YouTube) Connection error in getting lounge token Dec 11 06:15:49 volumio2 volumio[1276]: Error info: { Dec 11 06:15:49 volumio2 volumio[1276]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Dec 11 06:15:49 volumio2 volumio[1276]: } Dec 11 06:15:49 volumio2 volumio[1276]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo ENOTFOUND www.youtube.com Dec 11 06:15:49 volumio2 volumio[1276]: Stack trace: Dec 11 06:15:49 volumio2 volumio[1276]: ConnectionError: (YouTube) Connection error in getting lounge token Dec 11 06:15:49 volumio2 volumio[1276]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Dec 11 06:15:49 volumio2 volumio[1276]: at runMicrotasks () Dec 11 06:15:49 volumio2 volumio[1276]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Dec 11 06:15:49 volumio2 volumio[1276]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Dec 11 06:15:49 volumio2 volumio[1276]: at async Promise.all (index 0) Dec 11 06:15:49 volumio2 volumio[1276]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Dec 11 06:15:49 volumio2 volumio[1276]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Dec 11 06:15:49 volumio2 volumio[1276]: Going to generate fresh screen Id and try again... Dec 11 06:15:50 volumio2 ntpd[1324]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Dec 11 06:15:50 volumio2 volumio[1276]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (k90co0br5t6rsoddmr3gdau43v): Dec 11 06:15:50 volumio2 volumio[1276]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Dec 11 06:15:50 volumio2 volumio[1276]: Error info: { Dec 11 06:15:50 volumio2 volumio[1276]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Dec 11 06:15:50 volumio2 volumio[1276]: } Dec 11 06:15:50 volumio2 volumio[1276]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo ENOTFOUND www.youtube.com Dec 11 06:15:50 volumio2 volumio[1276]: Stack trace: Dec 11 06:15:50 volumio2 volumio[1276]: ConnectionError: (YouTube Music) Connection error in getting lounge token Dec 11 06:15:50 volumio2 volumio[1276]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Dec 11 06:15:50 volumio2 volumio[1276]: at runMicrotasks () Dec 11 06:15:50 volumio2 volumio[1276]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Dec 11 06:15:50 volumio2 volumio[1276]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Dec 11 06:15:50 volumio2 volumio[1276]: at async Promise.all (index 1) Dec 11 06:15:50 volumio2 volumio[1276]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Dec 11 06:15:50 volumio2 volumio[1276]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Dec 11 06:15:50 volumio2 volumio[1276]: Going to generate fresh screen Id and try again... Dec 11 06:15:53 volumio2 volumio[1276]: info: camilladsp spawned new process with pid 2586, instance 1, run: true Dec 11 06:15:54 volumio2 volumio[1276]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.874&uuid=ed70a44c887eefde0eab7e68ff9950f7" http://updates.volumio.org/downloader-v1/track-device Dec 11 06:15:54 volumio2 volumio[1276]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 11 06:15:54 volumio2 volumio[1276]: Dload Upload Total Spent Left Speed Dec 11 06:15:54 volumio2 volumio[1276]: [1.5K blob data] Dec 11 06:15:54 volumio2 volumio[1276]: retrying in 5 seconds, trial 1 Dec 11 06:15:54 volumio2 volumio[1276]: info: Volumio Calling Home Dec 11 06:15:54 volumio2 jellyfin[1734]: [06:15:54] [ERR] [10] Emby.Server.Implementations.Updates.InstallationManager: An error occurred while accessing the plugin manifest: https://repo.jellyfin.org/files/plugin/manifest.json Dec 11 06:15:54 volumio2 jellyfin[1734]: System.Net.Http.HttpRequestException: Unknown socket error (repo.jellyfin.org:443) Dec 11 06:15:54 volumio2 jellyfin[1734]: ---> System.Net.Sockets.SocketException (0xFFFDFFFE): Unknown socket error Dec 11 06:15:54 volumio2 jellyfin[1734]: at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource.GetResult(Int16 token) Dec 11 06:15:54 volumio2 jellyfin[1734]: at System.Net.Sockets.Socket.g__WaitForConnectWithCancellation|285_0(AwaitableSocketAsyncEventArgs saea, ValueTask connectTask, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at Jellyfin.Networking.HappyEyeballs.HttpClientExtension.AttemptConnection(AddressFamily addressFamily, SocketsHttpConnectionContext context, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at Jellyfin.Networking.HappyEyeballs.HttpClientExtension.OnConnect(SocketsHttpConnectionContext context, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at System.Net.Http.HttpConnectionPool.ConnectToTcpHostAsync(String host, Int32 port, HttpRequestMessage initialRequest, Boolean async, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: --- End of inner exception stack trace --- Dec 11 06:15:54 volumio2 jellyfin[1734]: at System.Net.Http.HttpConnectionPool.ConnectToTcpHostAsync(String host, Int32 port, HttpRequestMessage initialRequest, Boolean async, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at System.Net.Http.HttpConnectionPool.ConnectAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at System.Net.Http.HttpConnectionPool.CreateHttp11ConnectionAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at System.Net.Http.HttpConnectionPool.AddHttp11ConnectionAsync(QueueItem queueItem) Dec 11 06:15:54 volumio2 jellyfin[1734]: at System.Threading.Tasks.TaskCompletionSourceWithCancellation`1.WaitWithCancellationAsync(CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at System.Net.Http.HttpConnectionPool.SendWithVersionDetectionAndRetryAsync(HttpRequestMessage request, Boolean async, Boolean doRequestAuth, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at System.Net.Http.RedirectHandler.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at System.Net.Http.DecompressionHandler.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at Microsoft.Extensions.Http.Logging.LoggingHttpMessageHandler.g__Core|5_0(HttpRequestMessage request, Boolean useAsync, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at Microsoft.Extensions.Http.Logging.LoggingScopeHttpMessageHandler.g__Core|5_0(HttpRequestMessage request, Boolean useAsync, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at System.Net.Http.HttpClient.g__Core|83_0(HttpRequestMessage request, HttpCompletionOption completionOption, CancellationTokenSource cts, Boolean disposeCts, CancellationTokenSource pendingRequestsCts, CancellationToken originalCancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at System.Net.Http.Json.HttpClientJsonExtensions.g__Core|12_0[TValue,TJsonOptions](HttpClient client, Task`1 responseTask, Boolean usingResponseHeadersRead, CancellationTokenSource linkedCTS, Func`4 deserializeMethod, TJsonOptions jsonOptions, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: at Emby.Server.Implementations.Updates.InstallationManager.GetPackages(String manifestName, String manifest, Boolean filterIncompatible, CancellationToken cancellationToken) Dec 11 06:15:54 volumio2 jellyfin[1734]: [06:15:54] [INF] [10] Emby.Server.Implementations.ScheduledTasks.TaskManager: Оновити плагіни Completed after 0 minute(s) and 18 seconds Dec 11 06:15:54 volumio2 volumio[1276]: info: BOOT COMPLETED Dec 11 06:15:58 volumio2 volumio[1276]: info: [jellyfin-poller] Polled http://192.168.1.51:8096/web/index.html#/home.htm: offline (system info unavailable) Dec 11 06:16:00 volumio2 go-librespot[2542]: time="2025-12-11T06:16:00+02:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Dec 11 06:16:00 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 11 06:16:00 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 11 06:16:03 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Dec 11 06:16:03 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Dec 11 06:16:03 volumio2 systemd[1]: Stopped go-librespot Daemon. Dec 11 06:16:03 volumio2 systemd[1]: Started go-librespot Daemon. Dec 11 06:16:03 volumio2 go-librespot[2614]: Librespot-go daemon starting... Dec 11 06:16:03 volumio2 go-librespot[2614]: time="2025-12-11T06:16:03+02:00" level=info msg="generated new device id: b09e66378a0f509df3c7efa4220d32d110591f9a" Dec 11 06:16:03 volumio2 go-librespot[2614]: time="2025-12-11T06:16:03+02:00" level=debug msg="stored credentials found for 31jxmlwjw63hm3cpzjfc7mx4zev4" Dec 11 06:16:06 volumio2 volumio[1276]: info: Volumio called home Dec 11 06:16:06 volumio2 go-librespot[2614]: time="2025-12-11T06:16:06+02:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Dec 11 06:16:06 volumio2 go-librespot[2614]: time="2025-12-11T06:16:06+02:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Dec 11 06:16:06 volumio2 go-librespot[2614]: time="2025-12-11T06:16:06+02:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Dec 11 06:16:06 volumio2 go-librespot[2614]: time="2025-12-11T06:16:06+02:00" level=debug msg="zeroconf server listening on port 46349" Dec 11 06:16:06 volumio2 go-librespot[2614]: time="2025-12-11T06:16:06+02:00" level=debug msg="obtained new client token: AAAt0AqkTH3efrhiY9PltqnxyZmlfKY4yUwWxhv/ng08QgOZHC5lwajrsQYSRaW/7ipX4Q9cqev5lUbbQPVszd4uSSM2Q3PIul5L4Badmug1o6QGgxq3888OeUbR8/nW2D0zLgs2n5+COSuMZj8X2EAgR8OQK9Btzcndh0Wgs4O7RRGhNeL5eK1Z3yb4I9L01Ui+cAW0XOVogngI8sYlIIeCAR7fSBjjIzGpJOMSA+hX6ldGZilMRChX6FAj" Dec 11 06:16:06 volumio2 volumio-time-update[702]: volumio-time-update-util: Date not found in response Dec 11 06:16:06 volumio2 volumio-time-update[702]: volumio-time-update-util: Retrying in 5 seconds... Dec 11 06:16:06 volumio2 go-librespot[2614]: time="2025-12-11T06:16:06+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Dec 11 06:16:06 volumio2 go-librespot[2614]: time="2025-12-11T06:16:06+02:00" level=debug msg="completed keyexchange" Dec 11 06:16:07 volumio2 go-librespot[2614]: time="2025-12-11T06:16:07+02:00" level=debug msg="completed challenge" Dec 11 06:16:07 volumio2 go-librespot[2614]: time="2025-12-11T06:16:07+02:00" level=debug msg="authenticated as 31jxmlwjw63hm3cpzjfc7mx4zev4" Dec 11 06:16:07 volumio2 go-librespot[2614]: time="2025-12-11T06:16:07+02:00" level=debug msg="authenticated as 31jxmlwjw63hm3cpzjfc7mx4zev4" Dec 11 06:16:07 volumio2 go-librespot[2614]: time="2025-12-11T06:16:07+02:00" level=debug msg="dealer connection opened" Dec 11 06:16:07 volumio2 go-librespot[2614]: time="2025-12-11T06:16:07+02:00" level=debug msg="initializing zeroconf session, username: 31jxmlwjw63hm3cpzjfc7mx4zev4" Dec 11 06:16:07 volumio2 go-librespot[2614]: time="2025-12-11T06:16:07+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Dec 11 06:16:07 volumio2 go-librespot[2614]: time="2025-12-11T06:16:07+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Dec 11 06:16:07 volumio2 go-librespot[2614]: time="2025-12-11T06:16:07+02:00" level=debug msg="autoplay enabled: false" Dec 11 06:16:07 volumio2 go-librespot[2614]: time="2025-12-11T06:16:07+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Dec 11 06:16:07 volumio2 go-librespot[2614]: time="2025-12-11T06:16:07+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Dec 11 06:16:07 volumio2 go-librespot[2614]: time="2025-12-11T06:16:07+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Dec 11 06:16:07 volumio2 go-librespot[2614]: time="2025-12-11T06:16:07+02:00" level=debug msg="received connection id: ODUzMWJhNGYtNmMwNy00ZmQ3LWE5ZjEtYzI4NTM0ODk3MjgzK2RlYWxlcit0Y3A6Ly8wYWNhNDE1Ny5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArOTczNzUzMTdGN0YyMjA3QjcyRkIwQ0VFODVFQkQ0ODQ5MzZENzQ1NjFFNDk3Mzc5RkU3MUM4OUM4NTczNjE4Qw==" Dec 11 06:16:07 volumio2 go-librespot[2614]: time="2025-12-11T06:16:07+02:00" level=debug msg="put connect state because NEW_DEVICE" Dec 11 06:16:07 volumio2 go-librespot[2614]: time="2025-12-11T06:16:07+02:00" level=debug msg="new websocket client" Dec 11 06:16:07 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 11 06:16:07 volumio2 volumio[1276]: info: Connection to go-librespot Websocket established Dec 11 06:16:07 volumio2 volumio[1276]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 11 06:16:07 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 11 06:16:07 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 11 06:16:07 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 11 06:16:07 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 11 06:16:07 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Dec 11 06:16:07 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 11 06:16:07 volumio2 volumio[1276]: info: FusionDsp - {"Reload":{"result":"Ok"}} Dec 11 06:16:08 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Dec 11 06:16:08 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Dec 11 06:16:08 volumio2 ntpd[1324]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Dec 11 06:16:08 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Dec 11 06:16:08 volumio2 ntpd[1324]: Soliciting pool server 130.255.135.221 Dec 11 06:16:08 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Dec 11 06:16:08 volumio2 ntpd[1324]: Soliciting pool server 91.236.251.234 Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 11 06:16:08 volumio2 volumio[1276]: info: Adding plugin bluetooth to MyMusic Plugins Dec 11 06:16:08 volumio2 volumio[1276]: info: Adding plugin multiroom to MyMusic Plugins Dec 11 06:16:08 volumio2 volumio[1276]: info: Adding plugin metavolumio to MyMusic Plugins Dec 11 06:16:08 volumio2 volumio[1276]: info: Adding plugin cd_controller to MyMusic Plugins Dec 11 06:16:08 volumio2 volumio[1276]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 11 06:16:08 volumio2 volumio[1276]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 11 06:16:08 volumio2 volumio[1276]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 11 06:16:08 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 11 06:16:08 volumio2 ntpd[1324]: Soliciting pool server 91.236.251.31 Dec 11 06:16:08 volumio2 ntpd[1324]: Soliciting pool server 31.28.161.68 Dec 11 06:16:08 volumio2 ntpd[1324]: Soliciting pool server 79.142.192.4 Dec 11 06:16:09 volumio2 volumio[1276]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 11 06:16:09 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 11 06:16:09 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 06:16:09 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 06:16:09 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 11 06:16:09 volumio2 volumio[1276]: info: Starting MyVolumio Remote Streaming Endpoints Dec 11 06:16:09 volumio2 volumio[1276]: info: MyVolumio login type: Token Dec 11 06:16:09 volumio2 volumio[1276]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 11 06:16:09 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 11 06:16:09 volumio2 ntpd[1324]: Soliciting pool server 91.236.251.24 Dec 11 06:16:09 volumio2 ntpd[1324]: Soliciting pool server 91.210.190.13 Dec 11 06:16:10 volumio2 volumio[1276]: info: Starting Streaming Service Transparent Proxy Dec 11 06:16:10 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 11 06:16:10 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 11 06:16:10 volumio2 volumio[1276]: info: Streaming services startup Dec 11 06:16:10 volumio2 volumio[1276]: info: Starting Streaming Daemon Dec 11 06:16:10 volumio2 sudo[2640]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 11 06:16:10 volumio2 sudo[2640]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:16:10 volumio2 sudo[2640]: pam_unix(sudo:session): session closed for user root Dec 11 06:16:10 volumio2 volumio[1276]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 11 06:16:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 11 06:16:10 volumio2 volumio[1276]: info: Received Get System Info Dec 11 06:16:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 11 06:16:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 11 06:16:10 volumio2 volumio[1276]: info: Discovery: Getting this device information Dec 11 06:16:10 volumio2 volumio[1276]: info: CoreCommandRouter::volumioGetState Dec 11 06:16:10 volumio2 volumio[1276]: info: CorePlayQueue::getTrack 0 Dec 11 06:16:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 11 06:16:10 volumio2 volumio[1276]: error: Cannot start Volumio Streaming Daemon Dec 11 06:16:10 volumio2 volumio[1276]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 11 06:16:10 volumio2 volumio[1276]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 11 06:16:10 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Dec 11 06:16:10 volumio2 volumio[1276]: error: MyVolumio Custom Token format not valid, refreshing it Dec 11 06:16:10 volumio2 ntpd[1324]: Soliciting pool server 109.110.82.19 Dec 11 06:16:10 volumio2 ntpd[1324]: Soliciting pool server 217.156.67.138 Dec 11 06:16:10 volumio2 volumio[1276]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 11 06:16:10 volumio2 volumio[1276]: info: MyVolumio login type: Token Dec 11 06:16:10 volumio2 volumio[1276]: info: Getting Spotify volume Dec 11 06:16:10 volumio2 volumio[1276]: info: Spotify volume: 100 Dec 11 06:16:10 volumio2 volumio[1276]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Dec 11 06:16:11 volumio2 volumio[1276]: info: CoreCommandRouter::volumioGetState Dec 11 06:16:11 volumio2 volumio[1276]: info: CorePlayQueue::getTrack 0 Dec 11 06:16:11 volumio2 volumio[1276]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Dec 11 06:16:11 volumio2 volumio[1276]: STREAMING PROXY: Starting server on port 3245 Dec 11 06:16:11 volumio2 volumio[1276]: Node JS runtime: 14 Dec 11 06:16:11 volumio2 volumio[1276]: info: [yt-cast-receiver] DIAL server listening on port 8098 Dec 11 06:16:11 volumio2 volumio[1276]: info: MyVolumio login type: Token Dec 11 06:16:11 volumio2 volumio[1276]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 11 06:16:11 volumio2 ntpd[1324]: Soliciting pool server 2a05:b00::52:83cd:7400 Dec 11 06:16:11 volumio2 volumio-time-update[702]: volumio-time-update-util: Fetching time from Volumio... Dec 11 06:16:11 volumio2 volumio[1276]: info: MyVolumio token set successfully Dec 11 06:16:11 volumio2 volumio[1276]: info: MYVOLUMIO: Adding device Dec 11 06:16:11 volumio2 volumio[1276]: info: MYVOLUMIO: Evaluating Server Dec 11 06:16:12 volumio2 volumio-time-update[702]: volumio-time-update-util: Setting system time to: 2025-12-11 06:16:12 Dec 11 06:16:12 volumio2 sudo[2673]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-12-11 06:16:12 Dec 11 06:16:12 volumio2 sudo[2673]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:16:12 volumio2 dbus-daemon[689]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.18' (uid=0 pid=2674 comm="timedatectl set-time 2025-12-11 06:16:12 ") Dec 11 06:16:12 volumio2 systemd[1]: Starting Time & Date Service... Dec 11 06:16:12 volumio2 dbus-daemon[689]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 11 06:16:12 volumio2 systemd[1]: Started Time & Date Service. Dec 11 06:16:12 volumio2 systemd-timedated[2676]: Changed local time to Thu Dec 11 06:16:12 2025 Dec 11 06:16:12 volumio2 sudo[2673]: pam_unix(sudo:session): session closed for user root Dec 11 06:16:12 volumio2 volumio-time-update[702]: volumio-time-update-util: System time updated successfully. Dec 11 06:16:12 volumio2 systemd[1]: Started Volumio Time Update Utility. Dec 11 06:16:12 volumio2 systemd[1]: Reached target Multi-User System. Dec 11 06:16:12 volumio2 systemd[1]: Reached target Graphical Interface. Dec 11 06:16:12 volumio2 systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 11 06:16:12 volumio2 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 11 06:16:12 volumio2 systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 11 06:16:12 volumio2 systemd[1]: Startup finished in 5.380s (firmware) + 2.590s (loader) + 8.050s (kernel) + 1min 55.407s (userspace) = 2min 11.429s. Dec 11 06:16:12 volumio2 volumio[1276]: info: MyVolumio status changed Dec 11 06:16:12 volumio2 volumio[1276]: info: Streaming services startup Dec 11 06:16:12 volumio2 volumio[1276]: info: Starting Streaming Daemon Dec 11 06:16:12 volumio2 volumio[1276]: info: Removing browser output: myVolumio user plan is not superstar Dec 11 06:16:12 volumio2 volumio[1276]: info: Removing audio output: Dec 11 06:16:12 volumio2 volumio[1276]: info: Stoppping Tunnel 1 Dec 11 06:16:12 volumio2 sudo[2696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 11 06:16:12 volumio2 sudo[2696]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:16:12 volumio2 volumio[1276]: error: Failed to ping endpoint eu4.myvolumio.org : unknown error Dec 11 06:16:12 volumio2 volumio[1276]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 06:16:12 volumio2 sudo[2699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 11 06:16:12 volumio2 volumio[1276]: Error: Unable to resolve or reject the same promise twice Dec 11 06:16:12 volumio2 volumio[1276]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 11 06:16:12 volumio2 volumio[1276]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) Dec 11 06:16:12 volumio2 volumio[1276]: at Socket.emit (events.js:400:28) Dec 11 06:16:12 volumio2 volumio[1276]: at addChunk (internal/streams/readable.js:293:12) Dec 11 06:16:12 volumio2 volumio[1276]: at readableAddChunk (internal/streams/readable.js:267:9) Dec 11 06:16:12 volumio2 volumio[1276]: at Socket.Readable.push (internal/streams/readable.js:206:10) Dec 11 06:16:12 volumio2 volumio[1276]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Dec 11 06:16:12 volumio2 volumio[1276]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 11 06:16:12 volumio2 sudo[2699]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 11 06:16:12 volumio2 sudo[2696]: pam_unix(sudo:session): session closed for user root Dec 11 06:16:12 volumio2 sudo[2699]: pam_unix(sudo:session): session closed for user root Dec 11 06:16:12 volumio2 sudo[2710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-11 06:15 Dec 11 06:16:12 volumio2 sudo[2710]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="502750e5d54ecf269d25125b622bfabc030fb553" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed 12 Nov 2025 07:31:13 PM CET" VOLUMIO_VERSION="3.874" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="674d725e3f749f2a23785c9f94a28aa3"