-- Logs begin at Wed 2026-02-25 16:51:19 EET, end at Wed 2026-02-25 16:53:15 EET. -- Feb 25 16:52:00 volumio2 volumio[1299]: info: ::Roon Bridge has successfully started Feb 25 16:52:00 volumio2 volumio[1299]: info: squeezelite reloaded Feb 25 16:52:00 volumio2 volroon[1761]: 00:00:00.560 Debug: PathForResource, filename: ../.update Feb 25 16:52:00 volumio2 volumio[1299]: info: minidlna: Found miniDLNA version 1.2.1 Feb 25 16:52:00 volumio2 volumio[1299]: info: minidlna: Starting minidlna.service Feb 25 16:52:00 volumio2 volroon[1761]: 00:00:00.594 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/../.update Feb 25 16:52:00 volumio2 volroon[1761]: 00:00:00.600 Debug: PathForResource, filename: RAATServer Feb 25 16:52:00 volumio2 volroon[1761]: 00:00:00.600 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/RAATServer Feb 25 16:52:00 volumio2 volroon[1761]: 00:00:00.604 Debug: PathForResource, filename: RoonBridgeHelper Feb 25 16:52:00 volumio2 volroon[1761]: 00:00:00.604 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/RoonBridgeHelper Feb 25 16:52:00 volumio2 volroon[1761]: Initializing Feb 25 16:52:00 volumio2 sudo[1808]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart squeezelite Feb 25 16:52:00 volumio2 sudo[1808]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:00 volumio2 volumio[1299]: info: logitechmediaserver started Feb 25 16:52:00 volumio2 volroon[1761]: 00:00:00.690 Info: Starting /data/plugins/music_service/volroon/RoonBridge/Bridge/RoonBridgeHelper Feb 25 16:52:00 volumio2 sudo[1814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start minidlna.service Feb 25 16:52:00 volumio2 sudo[1814]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:00 volumio2 systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Feb 25 16:52:00 volumio2 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Feb 25 16:52:00 volumio2 sudo[1808]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:00 volumio2 volroon[1761]: 00:00:00.767 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/volroon/RoonBridge/Bridge/RAATServer Feb 25 16:52:00 volumio2 systemd[1]: Started MiniDLNA lightweight DLNA/UPnP-AV server. Feb 25 16:52:00 volumio2 systemd[1]: squeezelite.service: Succeeded. Feb 25 16:52:00 volumio2 sudo[1814]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:00 volumio2 volumio[1299]: info: MPD Permissions set Feb 25 16:52:00 volumio2 volumio[1299]: info: MPD Permissions set Feb 25 16:52:00 volumio2 volumio[1299]: info: Upmpdcli Daemon Started Feb 25 16:52:00 volumio2 volumio-remote-updater[767]: [2026-02-25 16:52:00] [connect] Successful connection Feb 25 16:52:00 volumio2 volroon[1761]: Not Running (.o) Feb 25 16:52:00 volumio2 volumio[1299]: info: camilladsp spawned new process with pid 1841, instance 1, run: true Feb 25 16:52:00 volumio2 volumio[1299]: info: camilladsp service started and running in background, instance 1 Feb 25 16:52:00 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:00 volumio2 systemd[1]: squeezelite.service: Service RestartSec=100ms expired, scheduling restart. Feb 25 16:52:00 volumio2 systemd[1]: squeezelite.service: Scheduled restart job, restart counter is at 1. Feb 25 16:52:00 volumio2 systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Feb 25 16:52:00 volumio2 systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Feb 25 16:52:00 volumio2 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Feb 25 16:52:00 volumio2 volumio[1299]: cannot open device 'volumioHw': Device or resource busy Feb 25 16:52:00 volumio2 volumio[1299]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Feb 25 16:52:00 volumio2 volumio[1299]: cannot open device 'volumioHw': Device or resource busy Feb 25 16:52:00 volumio2 jellyfin[1751]: [16:52:00] [INF] [1] Main: Jellyfin version: 10.9.6 Feb 25 16:52:00 volumio2 volumio[1299]: info: FusionDsp loaded Feb 25 16:52:00 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 16:52:01 volumio2 jellyfin[1751]: [16:52:00] [INF] [1] Main: Environment Variables: ["[JELLYFIN_LOG_DIR, /opt/jellyfin/log]"] Feb 25 16:52:01 volumio2 jellyfin[1751]: [16:52:01] [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"] Feb 25 16:52:01 volumio2 jellyfin[1751]: [16:52:01] [INF] [1] Main: Operating system: Debian GNU/Linux 10 (buster) Feb 25 16:52:01 volumio2 jellyfin[1751]: [16:52:01] [INF] [1] Main: Architecture: X64 Feb 25 16:52:01 volumio2 jellyfin[1751]: [16:52:01] [INF] [1] Main: 64-Bit Process: True Feb 25 16:52:01 volumio2 jellyfin[1751]: [16:52:01] [INF] [1] Main: User Interactive: True Feb 25 16:52:01 volumio2 jellyfin[1751]: [16:52:01] [INF] [1] Main: Processor count: 2 Feb 25 16:52:01 volumio2 sudo[1861]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Feb 25 16:52:01 volumio2 sudo[1861]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:01 volumio2 volumio[1299]: info: FusionDsp - Reporting Fusion DSP Enabled Feb 25 16:52:01 volumio2 jellyfin[1751]: [16:52:01] [INF] [1] Main: Program data path: /opt/jellyfin/data Feb 25 16:52:01 volumio2 jellyfin[1751]: [16:52:01] [INF] [1] Main: Log directory path: /opt/jellyfin/log Feb 25 16:52:01 volumio2 jellyfin[1751]: [16:52:01] [INF] [1] Main: Config directory path: /opt/jellyfin/config Feb 25 16:52:01 volumio2 jellyfin[1751]: [16:52:01] [INF] [1] Main: Cache path: /opt/jellyfin/cache Feb 25 16:52:01 volumio2 jellyfin[1751]: [16:52:01] [INF] [1] Main: Web resources path: /opt/jellyfin/jellyfin_10.9.6/jellyfin-web Feb 25 16:52:01 volumio2 jellyfin[1751]: [16:52:01] [INF] [1] Main: Application directory: /opt/jellyfin/jellyfin_10.9.6/ Feb 25 16:52:01 volumio2 volumio-time-update[775]: volumio-time-update-util: Date not found in response Feb 25 16:52:01 volumio2 volumio-time-update[775]: volumio-time-update-util: Retrying in 5 seconds... Feb 25 16:52:01 volumio2 volumio[1299]: info: Adding Signal Path Element [object Object] Feb 25 16:52:01 volumio2 volumio[1299]: info: Adding fusiondspeq DSP Signal Path Element Feb 25 16:52:01 volumio2 volumio[1299]: info: FusionDsp - ---- installed callbackRead Feb 25 16:52:01 volumio2 volumio[1299]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 25 16:52:01 volumio2 systemd[1]: Started FusionDsp Daemon. Feb 25 16:52:01 volumio2 sudo[1861]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:01 volumio2 volumio[1299]: info: MPD running with PID1737 Feb 25 16:52:01 volumio2 volumio[1299]: ,establishing connection Feb 25 16:52:01 volumio2 volumio[1299]: info: minidlna: systemctl start minidlna.service succeeded. Feb 25 16:52:01 volumio2 volumio[1299]: info: squeezelite started Feb 25 16:52:01 volumio2 volumio[1299]: info: VolumeController:: Volume=100 Mute =false Feb 25 16:52:01 volumio2 volumio[1299]: info: CoreCommandRouter::volumioGetState Feb 25 16:52:01 volumio2 volumio[1299]: info: CorePlayQueue::getTrack 0 Feb 25 16:52:01 volumio2 volumio[1299]: info: CoreStateMachine::pushState Feb 25 16:52:01 volumio2 volumio[1299]: info: CorePlayQueue::getTrack 0 Feb 25 16:52:01 volumio2 volumio[1299]: info: CoreCommandRouter::volumioPushState Feb 25 16:52:01 volumio2 volumio[1299]: info: [squeezelite_mc] Server discovery started Feb 25 16:52:01 volumio2 volumio[1299]: info: [squeezelite_mc] Player finder started Feb 25 16:52:01 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:01 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 16:52:01 volumio2 volumio[1299]: info: [squeezelite_mc] Executing aplay -D hw:5 --nonblock -f MPEG /dev/zero 2>&1 || true Feb 25 16:52:01 volumio2 volumio[1299]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Feb 25 16:52:01 volumio2 volroon[1761]: Running Feb 25 16:52:01 volumio2 sudo[1875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Feb 25 16:52:01 volumio2 sudo[1875]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:01 volumio2 volumio[1299]: info: camilladsp spawned new process with pid 1876, instance 1, run: true Feb 25 16:52:01 volumio2 volumio[1299]: info: Spotify config file written Feb 25 16:52:01 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. Feb 25 16:52:02 volumio2 volumio[1299]: Upnp client error: Error: This socket has been ended by the other party Feb 25 16:52:02 volumio2 sudo[1875]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:02 volumio2 sudo[1885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 25 16:52:02 volumio2 sudo[1885]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:02 volumio2 systemd[1]: Started go-librespot Daemon. Feb 25 16:52:02 volumio2 sudo[1885]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:02 volumio2 go-librespot[1888]: Librespot-go daemon starting... Feb 25 16:52:02 volumio2 go-librespot[1888]: time="2026-02-25T16:52:02+02:00" level=info msg="generated new device id: 2046b58e9f6298851c10ae09effea427e398a2bc" Feb 25 16:52:02 volumio2 volumio[1299]: error: MPD error: The expression evaluated to a falsy value: Feb 25 16:52:02 volumio2 volumio[1299]: assert.ok(self.idling) Feb 25 16:52:02 volumio2 go-librespot[1888]: time="2026-02-25T16:52:02+02:00" level=debug msg="stored credentials found for 31jxmlwjw63hm3cpzjfc7mx4zev4" Feb 25 16:52:02 volumio2 volumio[1299]: error: The expression evaluated to a falsy value: Feb 25 16:52:02 volumio2 volumio[1299]: assert.ok(self.idling) Feb 25 16:52:02 volumio2 volumio[1299]: error: MPD error: The expression evaluated to a falsy value: Feb 25 16:52:02 volumio2 volumio[1299]: assert.ok(self.idling) Feb 25 16:52:02 volumio2 volumio[1299]: error: The expression evaluated to a falsy value: Feb 25 16:52:02 volumio2 volumio[1299]: assert.ok(self.idling) Feb 25 16:52:02 volumio2 volumio-remote-updater[767]: [2026-02-25 16:52:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1772031120 101 Feb 25 16:52:02 volumio2 volumio[1299]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Feb 25 16:52:02 volumio2 volumio[1299]: info: [squeezelite_mc] Card 5 supports the following ALSA formats: ["S16_LE"] Feb 25 16:52:02 volumio2 volumio[1299]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"basic","playerName":"volumio2","dsdFormat":null,"card":"5","mixerType":"Hardware","mixer":"Speaker"} Feb 25 16:52:02 volumio2 volumio[1299]: 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 Feb 25 16:52:02 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:02 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:02 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:52:02 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 25 16:52:03 volumio2 sudo[1899]: 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 Feb 25 16:52:03 volumio2 sudo[1899]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:03 volumio2 sudo[1899]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:03 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:03 volumio2 volumio[1299]: info: No need to fix Spotify hosts Feb 25 16:52:03 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:03 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:52:03 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 25 16:52:03 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:03 volumio2 jellyfin[1751]: [16:52:03] [INF] [1] Emby.Server.Implementations.AppBase.BaseConfigurationManager: Setting cache path: /opt/jellyfin/cache Feb 25 16:52:03 volumio2 sudo[1914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 25 16:52:03 volumio2 sudo[1914]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:03 volumio2 systemd[1]: Started MPD Monitor Service. Feb 25 16:52:03 volumio2 sudo[1914]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:03 volumio2 mpd_monitor.sh[1916]: MPD Monitor Service: Starting MPD Monitor Service Feb 25 16:52:03 volumio2 volumio[1299]: error: updateQueue error: null Feb 25 16:52:03 volumio2 volumio[1299]: info: [squeezelite_mc] Executing alsactl -L -R nrestore Feb 25 16:52:03 volumio2 volroon[1761]: 00:00:00.167 Warn: get lock file path: /tmp/.rnbhgem0- Feb 25 16:52:03 volumio2 sudo[1922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/alsactl -L -R nrestore Feb 25 16:52:03 volumio2 sudo[1922]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:03 volumio2 sudo[1926]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 25 16:52:03 volumio2 jellyfin[1751]: [16:52:03] [INF] [1] Emby.Server.Implementations.ApplicationHost: Loading assemblies Feb 25 16:52:03 volumio2 sudo[1926]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:03 volumio2 volumio[1299]: info: Successfully started MPD Monitor Feb 25 16:52:03 volumio2 volumio[1299]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Feb 25 16:52:04 volumio2 sudo[1922]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:04 volumio2 systemd[1]: Stopping MPD Monitor Service... Feb 25 16:52:04 volumio2 systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Feb 25 16:52:04 volumio2 systemd[1]: mpd_monitor.service: Succeeded. Feb 25 16:52:04 volumio2 systemd[1]: Stopped MPD Monitor Service. Feb 25 16:52:04 volumio2 systemd[1]: Started MPD Monitor Service. Feb 25 16:52:04 volumio2 sudo[1926]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:04 volumio2 mpd_monitor.sh[1929]: MPD Monitor Service: Starting MPD Monitor Service Feb 25 16:52:04 volumio2 volumio[1299]: info: Starting Shairport Sync Feb 25 16:52:04 volumio2 sudo[1932]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Feb 25 16:52:04 volumio2 sudo[1932]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:04 volumio2 volumio[1299]: info: Starting Shairport Sync Feb 25 16:52:04 volumio2 sudo[1932]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:04 volumio2 sudo[1936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 16:52:04 volumio2 sudo[1936]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:04 volumio2 volumio[1299]: info: Starting Shairport Sync Feb 25 16:52:04 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 25 16:52:04 volumio2 systemd[1]: shairport-sync.service: Succeeded. Feb 25 16:52:04 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 25 16:52:04 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 25 16:52:04 volumio2 sudo[1936]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:04 volumio2 sudo[1939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 16:52:04 volumio2 sudo[1939]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:04 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 25 16:52:04 volumio2 systemd[1]: shairport-sync.service: Succeeded. Feb 25 16:52:04 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 25 16:52:04 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 25 16:52:04 volumio2 sudo[1939]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:04 volumio2 volumio[1299]: info: Successfully started MPD Monitor Feb 25 16:52:04 volumio2 sudo[1944]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 16:52:04 volumio2 sudo[1944]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:04 volumio2 volumio[1299]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/systemd/squeezelite.service.template.out /etc/systemd/system/squeezelite.service Feb 25 16:52:04 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 25 16:52:04 volumio2 systemd[1]: shairport-sync.service: Succeeded. Feb 25 16:52:04 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 25 16:52:04 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 25 16:52:04 volumio2 sudo[1944]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:04 volumio2 volumio[1299]: info: Shairport-Sync Started Feb 25 16:52:04 volumio2 volumio[1299]: Error adding Membership: Error: addMembership EINVAL Feb 25 16:52:04 volumio2 volumio[1299]: info: Shairport-Sync Started Feb 25 16:52:04 volumio2 volumio[1299]: info: Shairport-Sync Started Feb 25 16:52:04 volumio2 volumio[1299]: info: Asound.conf file unchanged, so no further update is needed Feb 25 16:52:05 volumio2 volumio[1299]: info: Output device has changed, restarting MPD Feb 25 16:52:05 volumio2 sudo[1961]: 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 Feb 25 16:52:05 volumio2 sudo[1961]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:05 volumio2 sudo[1961]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:05 volumio2 sudo[1977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 16:52:05 volumio2 sudo[1977]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:05 volumio2 sudo[1977]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:05 volumio2 volumio[1299]: info: Output device has changed, restarting Shairport Sync Feb 25 16:52:05 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:05 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:52:05 volumio2 sudo[1981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 16:52:05 volumio2 sudo[1981]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:05 volumio2 systemd[1]: Stopping Music Player Daemon... Feb 25 16:52:05 volumio2 volumio[1299]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 16:52:05 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Feb 25 16:52:05 volumio2 systemd[1]: mpd.service: Succeeded. Feb 25 16:52:05 volumio2 systemd[1]: Stopped Music Player Daemon. Feb 25 16:52:05 volumio2 systemd[1]: Starting Music Player Daemon... Feb 25 16:52:05 volumio2 jellyfin[1751]: [16:52:05] [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"] Feb 25 16:52:05 volumio2 jellyfin[1751]: [16:52:05] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Defined LAN exclusions: [] Feb 25 16:52:05 volumio2 jellyfin[1751]: [16:52:05] [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"] Feb 25 16:52:05 volumio2 jellyfin[1751]: [16:52:05] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered interface addresses: ["127.0.0.1", "192.168.1.51"] Feb 25 16:52:05 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:05 volumio2 volumio[1299]: info: volroon::Output device has changed Feb 25 16:52:05 volumio2 volumio[1299]: info: Preparing to generate the ALSA configuration file Feb 25 16:52:05 volumio2 jellyfin[1751]: [16:52:05] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Bind Addresses ["0.0.0.0"] Feb 25 16:52:05 volumio2 jellyfin[1751]: [16:52:05] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Remote IP filter is Allowlist Feb 25 16:52:05 volumio2 jellyfin[1751]: [16:52:05] [INF] [1] Jellyfin.Networking.Manager.NetworkManager: Filtered subnets: [] Feb 25 16:52:05 volumio2 volumio[1299]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Feb 25 16:52:05 volumio2 sudo[1996]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 16:52:05 volumio2 sudo[1996]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:05 volumio2 sudo[1996]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:05 volumio2 volumio[1299]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 25 16:52:05 volumio2 volumio[1299]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 25 16:52:05 volumio2 volumio[1299]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 25 16:52:05 volumio2 volumio[1299]: info: Reading ALSA contributions from plugins. Feb 25 16:52:05 volumio2 volumio[1299]: info: MPD Permissions set Feb 25 16:52:05 volumio2 volumio[1299]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl daemon-reload || true Feb 25 16:52:05 volumio2 sudo[2008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Feb 25 16:52:05 volumio2 sudo[2008]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:06 volumio2 sudo[2008]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:06 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:06 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:06 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:06 volumio2 volumio-time-update[775]: volumio-time-update-util: Fetching time from Volumio... Feb 25 16:52:06 volumio2 sudo[2018]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Feb 25 16:52:06 volumio2 sudo[2018]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:06 volumio2 systemd[1]: Reloading. Feb 25 16:52:06 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:52:06 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 25 16:52:06 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:06 volumio2 volumio[1299]: info: go-librespot daemon successfully initialized Feb 25 16:52:06 volumio2 volumio[1299]: info: Starting Shairport Sync Feb 25 16:52:06 volumio2 volumio[1299]: info: Asound.conf file unchanged, so no further update is needed Feb 25 16:52:06 volumio2 volumio[1299]: info: Output device has changed, restarting MPD Feb 25 16:52:06 volumio2 sudo[2043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 16:52:06 volumio2 sudo[2043]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:06 volumio2 sudo[2049]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 16:52:06 volumio2 sudo[2049]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:06 volumio2 volumio[1299]: info: Output device has changed, restarting Shairport Sync Feb 25 16:52:06 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:06 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:52:06 volumio2 sudo[2049]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:06 volumio2 sudo[2055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 16:52:06 volumio2 sudo[2055]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:07 volumio2 volumio[1299]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 16:52:07 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Feb 25 16:52:07 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. Feb 25 16:52:07 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:07 volumio2 volumio[1299]: info: volroon::Output device has changed Feb 25 16:52:07 volumio2 volumio[1299]: info: Preparing to generate the ALSA configuration file Feb 25 16:52:07 volumio2 volumio[1299]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Feb 25 16:52:07 volumio2 volumio[1299]: error: MPD error: Error: This socket has been ended by the other party Feb 25 16:52:07 volumio2 volumio[1299]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 25 16:52:07 volumio2 volumio[1299]: error: MPD error: Error: This socket has been ended by the other party Feb 25 16:52:07 volumio2 volumio[1299]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 25 16:52:07 volumio2 volumio[1299]: error: MPD error: Error: This socket has been ended by the other party Feb 25 16:52:07 volumio2 volumio[1299]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 25 16:52:07 volumio2 volumio[1299]: error: MPD error: Error: This socket has been ended by the other party Feb 25 16:52:07 volumio2 volumio[1299]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 25 16:52:07 volumio2 volumio[1299]: error: MPD error: Error: This socket has been ended by the other party Feb 25 16:52:07 volumio2 volumio[1299]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 25 16:52:07 volumio2 volumio[1299]: error: MPD error: Error: This socket has been ended by the other party Feb 25 16:52:07 volumio2 volumio[1299]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 25 16:52:07 volumio2 volumio[1299]: error: MPD error: Error: This socket has been ended by the other party Feb 25 16:52:07 volumio2 volumio[1299]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 25 16:52:07 volumio2 volumio[1299]: error: MPD error: Error: This socket has been ended by the other party Feb 25 16:52:07 volumio2 volumio[1299]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 25 16:52:07 volumio2 volumio[1299]: error: MPD error: Error: This socket has been ended by the other party Feb 25 16:52:07 volumio2 volumio[1299]: error: This socket has been ended by the other party {"code":"EPIPE"} Feb 25 16:52:07 volumio2 volumio[1299]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Feb 25 16:52:07 volumio2 volumio[1299]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 25 16:52:07 volumio2 volumio[1299]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 25 16:52:07 volumio2 volumio[1299]: info: Reading ALSA contributions from plugins. Feb 25 16:52:07 volumio2 sudo[2099]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Feb 25 16:52:07 volumio2 volumio[1299]: info: MPD Permissions set Feb 25 16:52:07 volumio2 sudo[2099]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:07 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:07 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. Feb 25 16:52:07 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:52:07 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 25 16:52:07 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:07 volumio2 volumio[1299]: info: Starting Shairport Sync Feb 25 16:52:07 volumio2 volumio[1299]: info: Asound.conf file unchanged, so no further update is needed Feb 25 16:52:07 volumio2 volumio[1299]: info: Output device has changed, restarting MPD Feb 25 16:52:07 volumio2 sudo[2124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 16:52:07 volumio2 sudo[2124]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:08 volumio2 sudo[2018]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:08 volumio2 sudo[2129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 25 16:52:08 volumio2 sudo[2129]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:08 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. Feb 25 16:52:08 volumio2 sudo[2129]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:08 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 25 16:52:08 volumio2 systemd[1]: shairport-sync.service: Succeeded. Feb 25 16:52:08 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 25 16:52:08 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 25 16:52:08 volumio2 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 25 16:52:08 volumio2 sudo[2099]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:08 volumio2 sudo[2043]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:08 volumio2 volroon[1761]: 00:00:08.770 Warn: exception starting raatserver: System.Net.Sockets.SocketException (0x80004005): Connection refused Feb 25 16:52:08 volumio2 volroon[1761]: at System.Net.Sockets.TcpClient..ctor (System.String hostname, System.Int32 port) [0x0006d] in <08911f3881ec4a92acc8fdc4d13b73bf>:0 Feb 25 16:52:08 volumio2 volroon[1761]: at Sooloos.RAATServer.ConnectOrStartAndWaitForExit (System.String path, System.String args, System.Action`1[T] status, Base.ChildProcess& p) [0x00165] in <5529660cb9214b5a92e49e1607b30d50>:0 Feb 25 16:52:08 volumio2 systemd[1]: mpd.service: Succeeded. Feb 25 16:52:08 volumio2 systemd[1]: Stopped Music Player Daemon. Feb 25 16:52:08 volumio2 sudo[2124]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:08 volumio2 volumio[1299]: info: Output device has changed, restarting Shairport Sync Feb 25 16:52:08 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:08 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:52:08 volumio2 volroon[1761]: Not Running (.o) Feb 25 16:52:08 volumio2 systemd[1]: Starting Music Player Daemon... Feb 25 16:52:08 volumio2 volroon[1761]: 00:00:08.869 Debug: PathForResource, filename: ../.update Feb 25 16:52:08 volumio2 volroon[1761]: 00:00:08.870 Debug: PathForResource, candidate: /data/plugins/music_service/volroon/RoonBridge/Bridge/../.update Feb 25 16:52:08 volumio2 sudo[2134]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 25 16:52:08 volumio2 sudo[2134]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:08 volumio2 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 25 16:52:08 volumio2 systemd[1]: mpd.service: Succeeded. Feb 25 16:52:08 volumio2 systemd[1]: Stopped Music Player Daemon. Feb 25 16:52:08 volumio2 systemd[1]: Starting Music Player Daemon... Feb 25 16:52:08 volumio2 volumio[1299]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 25 16:52:08 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAplayInfo Feb 25 16:52:08 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:08 volumio2 volumio[1299]: info: volroon::Output device has changed Feb 25 16:52:08 volumio2 sudo[2143]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 25 16:52:08 volumio2 sudo[2143]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:08 volumio2 sudo[2143]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:08 volumio2 volumio[1299]: info: MPD Permissions set Feb 25 16:52:08 volumio2 volumio[1299]: info: Shairport-Sync Started Feb 25 16:52:08 volumio2 volumio[1299]: info: Shairport-Sync Started Feb 25 16:52:08 volumio2 volumio[1299]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 25 16:52:08 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:08 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:08 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:08 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:08 volumio2 sudo[2180]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 25 16:52:08 volumio2 sudo[2180]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:09 volumio2 sudo[2180]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:09 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:52:09 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 25 16:52:09 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:09 volumio2 volumio[1299]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl stop squeezelite || true Feb 25 16:52:09 volumio2 volumio[1299]: info: Starting Shairport Sync Feb 25 16:52:09 volumio2 sudo[2209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop squeezelite Feb 25 16:52:09 volumio2 sudo[2209]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:09 volumio2 volumio[1299]: info: [jellyfin_server] Executing /usr/bin/sudo /bin/systemctl status jellyfin || true Feb 25 16:52:09 volumio2 systemd[1]: Stopping Squeezelite lightweight headless Squeezebox emulator... Feb 25 16:52:09 volumio2 sudo[2217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 25 16:52:09 volumio2 sudo[2217]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:09 volumio2 volumio[1299]: info: Initializing connection to go-librespot Websocket Feb 25 16:52:09 volumio2 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 25 16:52:09 volumio2 systemd[1]: shairport-sync.service: Succeeded. Feb 25 16:52:09 volumio2 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 25 16:52:09 volumio2 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 25 16:52:09 volumio2 systemd[1]: squeezelite.service: Succeeded. Feb 25 16:52:09 volumio2 sudo[2223]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status jellyfin Feb 25 16:52:09 volumio2 systemd[1]: Stopped Squeezelite lightweight headless Squeezebox emulator. Feb 25 16:52:09 volumio2 sudo[2217]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:09 volumio2 sudo[2223]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:09 volumio2 volumio[1299]: info: Shairport-Sync Started Feb 25 16:52:09 volumio2 sudo[2209]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:09 volumio2 sudo[2223]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:10 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:10 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 25 16:52:10 volumio2 volumio[1299]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 25 16:52:10 volumio2 sudo[2280]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 25 16:52:10 volumio2 sudo[2280]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:10 volumio2 volroon[1761]: 00:00:10.878 Info: ConnectOrStartAndWaitForExit RAATServer, path: /data/plugins/music_service/volroon/RoonBridge/Bridge/RAATServer Feb 25 16:52:10 volumio2 sudo[2280]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:10 volumio2 volumio[1299]: info: [squeezelite_mc] Executing rm /tmp/squeezelite.log Feb 25 16:52:10 volumio2 volroon[1761]: Running Feb 25 16:52:10 volumio2 sudo[2289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /tmp/squeezelite.log Feb 25 16:52:10 volumio2 sudo[2289]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:10 volumio2 sudo[2289]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:10 volumio2 volumio[1299]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl start squeezelite || true Feb 25 16:52:10 volumio2 sudo[2294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start squeezelite Feb 25 16:52:10 volumio2 sudo[2294]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:10 volumio2 volumio[1299]: 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 Feb 25 16:52:10 volumio2 systemd[1]: Starting Squeezelite lightweight headless Squeezebox emulator... Feb 25 16:52:10 volumio2 systemd[1]: Started Squeezelite lightweight headless Squeezebox emulator. Feb 25 16:52:10 volumio2 sudo[2294]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:10 volumio2 mpd[2163]: Feb 25 16:52 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 25 16:52:10 volumio2 volumio[1299]: info: camilladsp spawned new process with pid 2302, instance 1, run: true Feb 25 16:52:11 volumio2 volumio[1299]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 25 16:52:11 volumio2 sudo[2313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 25 16:52:11 volumio2 sudo[2313]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:11 volumio2 sudo[2313]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:11 volumio2 volumio[1299]: Upnp client error: Error: This socket has been ended by the other party Feb 25 16:52:11 volumio2 systemd[1]: Started Music Player Daemon. Feb 25 16:52:11 volumio2 sudo[2134]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:11 volumio2 sudo[2055]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:11 volumio2 sudo[1981]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:11 volumio2 volumio[1299]: error: MPD error: The expression evaluated to a falsy value: Feb 25 16:52:11 volumio2 volumio[1299]: assert.ok(self.idling) Feb 25 16:52:11 volumio2 volumio[1299]: error: The expression evaluated to a falsy value: Feb 25 16:52:11 volumio2 volumio[1299]: assert.ok(self.idling) Feb 25 16:52:11 volumio2 volumio[1299]: error: MPD error: The expression evaluated to a falsy value: Feb 25 16:52:11 volumio2 volumio[1299]: assert.ok(self.idling) Feb 25 16:52:11 volumio2 volumio[1299]: error: The expression evaluated to a falsy value: Feb 25 16:52:11 volumio2 volumio[1299]: assert.ok(self.idling) Feb 25 16:52:11 volumio2 volumio[1299]: error: updateQueue error: null Feb 25 16:52:12 volumio2 volumio[1299]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 25 16:52:12 volumio2 sudo[2321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 25 16:52:12 volumio2 sudo[2321]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:12 volumio2 sudo[2321]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:12 volumio2 volumio[1299]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 25 16:52:12 volumio2 sudo[2324]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 25 16:52:12 volumio2 sudo[2324]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:12 volumio2 sudo[2324]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:13 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 16:52:13 volumio2 volumio[1299]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 25 16:52:13 volumio2 sudo[2330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 25 16:52:13 volumio2 sudo[2330]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:13 volumio2 sudo[2330]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:14 volumio2 volumio[1299]: info: [squeezelite_mc] Executing /usr/bin/sudo /bin/systemctl status squeezelite || true Feb 25 16:52:14 volumio2 sudo[2333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl status squeezelite Feb 25 16:52:14 volumio2 sudo[2333]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:14 volumio2 sudo[2333]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:14 volumio2 sudo[2336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 25 16:52:14 volumio2 sudo[2336]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:15 volumio2 systemd[1]: Stopping MPD Monitor Service... Feb 25 16:52:15 volumio2 systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Feb 25 16:52:15 volumio2 systemd[1]: mpd_monitor.service: Succeeded. Feb 25 16:52:15 volumio2 sudo[2339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 25 16:52:15 volumio2 systemd[1]: Stopped MPD Monitor Service. Feb 25 16:52:15 volumio2 systemd[1]: Started MPD Monitor Service. Feb 25 16:52:15 volumio2 mpd_monitor.sh[2340]: MPD Monitor Service: Starting MPD Monitor Service Feb 25 16:52:15 volumio2 sudo[2336]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:15 volumio2 sudo[2339]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:15 volumio2 systemd[1]: Stopping MPD Monitor Service... Feb 25 16:52:15 volumio2 systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Feb 25 16:52:15 volumio2 systemd[1]: mpd_monitor.service: Succeeded. Feb 25 16:52:15 volumio2 systemd[1]: Stopped MPD Monitor Service. Feb 25 16:52:15 volumio2 systemd[1]: Started MPD Monitor Service. Feb 25 16:52:15 volumio2 mpd_monitor.sh[2355]: MPD Monitor Service: Starting MPD Monitor Service Feb 25 16:52:15 volumio2 sudo[2339]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:15 volumio2 volumio[1299]: info: Successfully started MPD Monitor Feb 25 16:52:15 volumio2 volumio[1299]: info: Successfully started MPD Monitor Feb 25 16:52:15 volumio2 sudo[2354]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Feb 25 16:52:15 volumio2 sudo[2354]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:52:15 volumio2 systemd[1]: Stopping MPD Monitor Service... Feb 25 16:52:15 volumio2 systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Feb 25 16:52:15 volumio2 systemd[1]: mpd_monitor.service: Succeeded. Feb 25 16:52:15 volumio2 systemd[1]: Stopped MPD Monitor Service. Feb 25 16:52:15 volumio2 systemd[1]: Started MPD Monitor Service. Feb 25 16:52:15 volumio2 volumio[1299]: 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 Feb 25 16:52:15 volumio2 mpd_monitor.sh[2368]: MPD Monitor Service: Starting MPD Monitor Service Feb 25 16:52:15 volumio2 sudo[2354]: pam_unix(sudo:session): session closed for user root Feb 25 16:52:15 volumio2 volumio[1299]: info: Successfully started MPD Monitor Feb 25 16:52:15 volumio2 volumio[1299]: info: An error occurred while refreshing Spotify Token Error: getaddrinfo ENOTFOUND oauth-performer.prod.vlmapi.io Feb 25 16:52:15 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 25 16:52:15 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 25 16:52:15 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 25 16:52:15 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 25 16:52:16 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 16:52:17 volumio2 ntpd[1310]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::volumioGetVisibleSources Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::volumioGetState Feb 25 16:52:17 volumio2 volumio[1299]: info: CorePlayQueue::getTrack 0 Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 25 16:52:17 volumio2 volumio[1299]: info: Received Get System Info Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 16:52:17 volumio2 volumio[1299]: info: Discovery: Getting this device information Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::volumioGetState Feb 25 16:52:17 volumio2 volumio[1299]: info: CorePlayQueue::getTrack 0 Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 16:52:17 volumio2 volumio[1709]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::volumioGetState Feb 25 16:52:17 volumio2 volumio[1299]: info: CorePlayQueue::getTrack 0 Feb 25 16:52:17 volumio2 volumio[1299]: info: Listing playlists Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::volumioGetState Feb 25 16:52:17 volumio2 volumio[1299]: info: CorePlayQueue::getTrack 0 Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 16:52:17 volumio2 volumio[1299]: info: Received Get System Info Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 16:52:17 volumio2 volumio[1299]: info: Discovery: Getting this device information Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::volumioGetState Feb 25 16:52:17 volumio2 volumio[1299]: info: CorePlayQueue::getTrack 0 Feb 25 16:52:17 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 16:52:17 volumio2 volumio[1709]: .............++++ Feb 25 16:52:18 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 25 16:52:18 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Feb 25 16:52:18 volumio2 volumio[1299]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 25 16:52:19 volumio2 volumio[1709]: ....................................................++++ Feb 25 16:52:19 volumio2 volumio[1709]: e is 65537 (0x010001) Feb 25 16:52:19 volumio2 volumio[1709]: writing RSA key Feb 25 16:52:19 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Feb 25 16:52:20 volumio2 go-librespot[1888]: time="2026-02-25T16:52:20+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" Feb 25 16:52:20 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 16:52:20 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 16:52:23 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 16:52:23 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 25 16:52:23 volumio2 systemd[1]: Stopped go-librespot Daemon. Feb 25 16:52:23 volumio2 systemd[1]: Started go-librespot Daemon. Feb 25 16:52:23 volumio2 go-librespot[2474]: Librespot-go daemon starting... Feb 25 16:52:23 volumio2 go-librespot[2474]: time="2026-02-25T16:52:23+02:00" level=info msg="generated new device id: 158b76aa6b91da850a525c3dc59af7c72af02402" Feb 25 16:52:23 volumio2 go-librespot[2474]: time="2026-02-25T16:52:23+02:00" level=debug msg="stored credentials found for 31jxmlwjw63hm3cpzjfc7mx4zev4" Feb 25 16:52:24 volumio2 volumio-time-update[775]: volumio-time-update-util: Date not found in response Feb 25 16:52:24 volumio2 volumio-time-update[775]: volumio-time-update-util: Retrying in 5 seconds... Feb 25 16:52:25 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Feb 25 16:52:27 volumio2 volumio[1299]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.886&uuid=ed70a44c887eefde0eab7e68ff9950f7" http://updates.volumio.org/downloader-v1/track-device Feb 25 16:52:27 volumio2 volumio[1299]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 25 16:52:27 volumio2 volumio[1299]: Dload Upload Total Spent Left Speed Feb 25 16:52:27 volumio2 volumio[1299]: [1.5K blob data] Feb 25 16:52:27 volumio2 volumio[1299]: retrying in 5 seconds, trial 0 Feb 25 16:52:27 volumio2 volumio[1299]: info: Volumio Calling Home Feb 25 16:52:27 volumio2 volumio[1299]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Feb 25 16:52:27 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:52:27 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 25 16:52:27 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:52:27 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Feb 25 16:52:29 volumio2 volumio-time-update[775]: volumio-time-update-util: Fetching time from Volumio... Feb 25 16:52:29 volumio2 volumio[1299]: error: Plugin music_service ytcr failed to complete 'onStart' in a timely fashion Feb 25 16:52:29 volumio2 volumio[1299]: info: ------------------------------------------- Feb 25 16:52:29 volumio2 volumio[1299]: info: ----- MyVolumio plugins startup ---- Feb 25 16:52:29 volumio2 volumio[1299]: info: ------------------------------------------- Feb 25 16:52:29 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 25 16:52:29 volumio2 volumio[1299]: info: [jellyfin-poller] Polled http://192.168.1.51:8096/web/index.html#/home.htm: offline Feb 25 16:52:31 volumio2 volumio[1299]: 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"} Feb 25 16:52:31 volumio2 jellyfin[1751]: [16:52:31] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: TMDb 10.9.6.0 Feb 25 16:52:31 volumio2 jellyfin[1751]: [16:52:31] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: Studio Images 10.9.6.0 Feb 25 16:52:31 volumio2 jellyfin[1751]: [16:52:31] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: OMDb 10.9.6.0 Feb 25 16:52:31 volumio2 jellyfin[1751]: [16:52:31] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: MusicBrainz 10.9.6.0 Feb 25 16:52:31 volumio2 jellyfin[1751]: [16:52:31] [INF] [1] Emby.Server.Implementations.Plugins.PluginManager: Loaded plugin: AudioDB 10.9.6.0 Feb 25 16:52:32 volumio2 jellyfin[1751]: [16:52:32] [INF] [1] Main: Kestrel is listening on 0.0.0.0 Feb 25 16:52:32 volumio2 volumio[1299]: info: [squeezelite_mc] Notification listener started Feb 25 16:52:32 volumio2 volumio[1299]: info: [squeezelite_mc] Getting players connected to volumio2 (192.168.1.51) Feb 25 16:52:32 volumio2 volumio[1299]: 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"}}] Feb 25 16:52:32 volumio2 volumio[1299]: 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"}} Feb 25 16:52:32 volumio2 jellyfin[1751]: [16:52:32] [INF] [1] Jellyfin.Networking.PortForwardingHost: Starting NAT discovery Feb 25 16:52:32 volumio2 volumio[1299]: info: Discovery: adding 01a5e55c-dda8-49b3-ab3b-5c5bd763a7d0 Feb 25 16:52:32 volumio2 volumio[1299]: info: Discovery: Found device Volumio2 Feb 25 16:52:32 volumio2 volumio[1299]: info: CoreCommandRouter::volumioGetState Feb 25 16:52:32 volumio2 volumio[1299]: info: CorePlayQueue::getTrack 0 Feb 25 16:52:33 volumio2 volumio[1299]: (node:1299) UnhandledPromiseRejectionWarning: TypeError: Cannot read property '0' of undefined Feb 25 16:52:33 volumio2 volumio[1299]: at PlayerStatusMonitor._PlayerStatusMonitor_parsePlayerStatusResult (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:180:37) Feb 25 16:52:33 volumio2 volumio[1299]: at PlayerStatusMonitor._PlayerStatusMonitor_getStatusAndEmit (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:118:129) Feb 25 16:52:33 volumio2 volumio[1299]: at runMicrotasks () Feb 25 16:52:33 volumio2 volumio[1299]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 16:52:33 volumio2 volumio[1299]: at async PlayerStatusMonitor.start (/data/plugins/music_service/squeezelite_mc/dist/lib/PlayerStatusMonitor.js:47:9) Feb 25 16:52:33 volumio2 volumio[1299]: at async PlayerFinder. (/data/plugins/music_service/squeezelite_mc/dist/index.js:686:13) Feb 25 16:52:33 volumio2 volumio[1299]: (Use `node --trace-warnings ...` to show where the warning was created) Feb 25 16:52:33 volumio2 volumio[1299]: (node:1299) 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) Feb 25 16:52:33 volumio2 volumio[1299]: (node:1299) [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. Feb 25 16:52:34 volumio2 jellyfin[1751]: [16:52:34] [WRN] [1] Microsoft.AspNetCore.StaticFiles.StaticFileMiddleware: The WebRootPath was not found: /wwwroot. Static files may be unavailable. Feb 25 16:52:34 volumio2 jellyfin[1751]: [16:52:34] [INF] [1] Emby.Server.Implementations.ApplicationHost: Running startup tasks Feb 25 16:52:34 volumio2 jellyfin[1751]: [16:52:34] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Створити Trickplay-зображення set to fire at 2026-02-26 03:00:00.000 +02:00, which is 10:07:25.0444344 from now. Feb 25 16:52:34 volumio2 jellyfin[1751]: [16:52:34] [INF] [1] Emby.Server.Implementations.ScheduledTasks.TaskManager: Daily trigger for Створити ескізи розділів set to fire at 2026-02-26 02:00:00.000 +02:00, which is 09:07:25.0208660 from now. Feb 25 16:52:35 volumio2 ntpd[1310]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 25 16:52:35 volumio2 jellyfin[1751]: [16:52:35] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Found ffmpeg version 6.0.1 Feb 25 16:52:35 volumio2 jellyfin[1751]: [16:52:35] [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"] Feb 25 16:52:35 volumio2 jellyfin[1751]: [16:52:35] [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"] Feb 25 16:52:35 volumio2 jellyfin[1751]: [16:52:35] [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"] Feb 25 16:52:36 volumio2 jellyfin[1751]: [16:52:36] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: Available hwaccel types: ["cuda", "vaapi", "qsv", "drm", "opencl", "vulkan"] Feb 25 16:52:38 volumio2 jellyfin[1751]: [16:52:38] [INF] [12] Emby.Server.Implementations.ScheduledTasks.TaskManager: Очистити колекції і списки відтворення Completed after 0 minute(s) and 0 seconds Feb 25 16:52:38 volumio2 jellyfin[1751]: [16:52:38] [INF] [1] MediaBrowser.MediaEncoding.Encoder.MediaEncoder: FFmpeg: /usr/lib/jellyfin-ffmpeg/ffmpeg Feb 25 16:52:38 volumio2 jellyfin[1751]: [16:52:38] [INF] [1] Emby.Server.Implementations.ApplicationHost: ServerId: 84aa2766180e43ba989ef1e637e024d0 Feb 25 16:52:38 volumio2 jellyfin[1751]: [16:52:38] [INF] [1] Emby.Server.Implementations.ApplicationHost: Core startup complete Feb 25 16:52:38 volumio2 jellyfin[1751]: [16:52:38] [INF] [1] Main: Startup complete 0:00:39.2355439 Feb 25 16:52:39 volumio2 jellyfin[1751]: [16:52:39] [INF] [8] Emby.Server.Implementations.IO.LibraryMonitor: Watching directory /data/INTERNAL/Музыка Feb 25 16:52:41 volumio2 go-librespot[2474]: time="2026-02-25T16:52:41+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" Feb 25 16:52:41 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 16:52:41 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 16:52:45 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 16:52:45 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 25 16:52:45 volumio2 systemd[1]: Stopped go-librespot Daemon. Feb 25 16:52:45 volumio2 systemd[1]: Started go-librespot Daemon. Feb 25 16:52:45 volumio2 go-librespot[2556]: Librespot-go daemon starting... Feb 25 16:52:45 volumio2 go-librespot[2556]: time="2026-02-25T16:52:45+02:00" level=info msg="generated new device id: 96447d529d6d2cdb236c1fb23eb7ed71480d2613" Feb 25 16:52:45 volumio2 go-librespot[2556]: time="2026-02-25T16:52:45+02:00" level=debug msg="stored credentials found for 31jxmlwjw63hm3cpzjfc7mx4zev4" Feb 25 16:52:47 volumio2 volumio-time-update[775]: volumio-time-update-util: Date not found in response Feb 25 16:52:47 volumio2 volumio-time-update[775]: volumio-time-update-util: Retrying in 5 seconds... Feb 25 16:52:49 volumio2 volumio[1299]: error: MyVolumio Plugin failed to start in a timely fashion Feb 25 16:52:49 volumio2 volumio[1299]: [Metrics] CommandRouter: 67s 317.71ms Feb 25 16:52:49 volumio2 volumio[1299]: info: CoreCommandRouter::volumiosetStartupVolume Feb 25 16:52:49 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 25 16:52:49 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:52:49 volumio2 volumio[1299]: info: CoreCommandRouter::Close All Modals sent Feb 25 16:52:49 volumio2 volumio[1299]: info: CoreCommandRouter::Close All Modals sent Feb 25 16:52:49 volumio2 volumio[1299]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 25 16:52:49 volumio2 volumio[1299]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 25 16:52:49 volumio2 volumio[1299]: info: camilladsp stopping service pid 2302... Feb 25 16:52:49 volumio2 volumio[1299]: info: camilladsp service terminated, instance 1 Feb 25 16:52:49 volumio2 volumio[1299]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 25 16:52:49 volumio2 volumio[1299]: info: camilladsp service started and running in background, instance 1 Feb 25 16:52:49 volumio2 volumio[1299]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Feb 25 16:52:49 volumio2 volumio[1299]: info: FusionDsp - ---- read samplerate from file: 44100 Feb 25 16:52:49 volumio2 volumio[1299]: info: FusionDsp - If filter freq >samplerate/2 then disable it Feb 25 16:52:49 volumio2 volumio[1299]: info: camilladsp spawned new process with pid 2583, instance 1, run: true Feb 25 16:52:50 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 25 16:52:50 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Feb 25 16:52:50 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 25 16:52:50 volumio2 volumio[1299]: error: [yt-cast-receiver] (YouTube) Failed to obtain lounge token with screen Id from stored MDX context (j51v9n67ksj9hqu32hdn234fmt): Feb 25 16:52:50 volumio2 volumio[1299]: (ConnectionError) (YouTube) Connection error in getting lounge token Feb 25 16:52:50 volumio2 volumio[1299]: Error info: { Feb 25 16:52:50 volumio2 volumio[1299]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Feb 25 16:52:50 volumio2 volumio[1299]: } Feb 25 16:52:50 volumio2 volumio[1299]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo ENOTFOUND www.youtube.com Feb 25 16:52:50 volumio2 volumio[1299]: Stack trace: Feb 25 16:52:50 volumio2 volumio[1299]: ConnectionError: (YouTube) Connection error in getting lounge token Feb 25 16:52:50 volumio2 volumio[1299]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Feb 25 16:52:50 volumio2 volumio[1299]: at runMicrotasks () Feb 25 16:52:50 volumio2 volumio[1299]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 16:52:50 volumio2 volumio[1299]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Feb 25 16:52:50 volumio2 volumio[1299]: at async Promise.all (index 0) Feb 25 16:52:50 volumio2 volumio[1299]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Feb 25 16:52:50 volumio2 volumio[1299]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Feb 25 16:52:50 volumio2 volumio[1299]: Going to generate fresh screen Id and try again... Feb 25 16:52:51 volumio2 volumio[1299]: error: [yt-cast-receiver] (YouTube Music) Failed to obtain lounge token with screen Id from stored MDX context (2tjsutri6h9rdpbnh7s3r3aaiv): Feb 25 16:52:51 volumio2 volumio[1299]: (ConnectionError) (YouTube Music) Connection error in getting lounge token Feb 25 16:52:51 volumio2 volumio[1299]: Error info: { Feb 25 16:52:51 volumio2 volumio[1299]: url: 'https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch' Feb 25 16:52:51 volumio2 volumio[1299]: } Feb 25 16:52:51 volumio2 volumio[1299]: --->(FetchError) request to https://www.youtube.com/api/lounge/pairing/get_lounge_token_batch failed, reason: getaddrinfo ENOTFOUND www.youtube.com Feb 25 16:52:51 volumio2 volumio[1299]: Stack trace: Feb 25 16:52:51 volumio2 volumio[1299]: ConnectionError: (YouTube Music) Connection error in getting lounge token Feb 25 16:52:51 volumio2 volumio[1299]: at Session._Session_getLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:435:15) Feb 25 16:52:51 volumio2 volumio[1299]: at runMicrotasks () Feb 25 16:52:51 volumio2 volumio[1299]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 25 16:52:51 volumio2 volumio[1299]: at async Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/Session.js:131:31) Feb 25 16:52:51 volumio2 volumio[1299]: at async Promise.all (index 1) Feb 25 16:52:51 volumio2 volumio[1299]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/app/YouTubeApp.js:110:13) Feb 25 16:52:51 volumio2 volumio[1299]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/cjs/lib/YouTubeCastReceiver.js:91:13) Feb 25 16:52:51 volumio2 volumio[1299]: Going to generate fresh screen Id and try again... Feb 25 16:52:52 volumio2 volumio-time-update[775]: volumio-time-update-util: Fetching time from Volumio... Feb 25 16:52:53 volumio2 ntpd[1310]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 25 16:52:54 volumio2 volumio[1299]: info: camilladsp spawned new process with pid 2597, instance 1, run: true Feb 25 16:52:55 volumio2 volumio[1299]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.886&uuid=ed70a44c887eefde0eab7e68ff9950f7" http://updates.volumio.org/downloader-v1/track-device Feb 25 16:52:55 volumio2 volumio[1299]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 25 16:52:55 volumio2 volumio[1299]: Dload Upload Total Spent Left Speed Feb 25 16:52:55 volumio2 volumio[1299]: [1.5K blob data] Feb 25 16:52:55 volumio2 volumio[1299]: retrying in 5 seconds, trial 1 Feb 25 16:52:55 volumio2 volumio[1299]: info: Volumio Calling Home Feb 25 16:52:56 volumio2 jellyfin[1751]: [16:52:56] [ERR] [8] Emby.Server.Implementations.Updates.InstallationManager: An error occurred while accessing the plugin manifest: https://repo.jellyfin.org/files/plugin/manifest.json Feb 25 16:52:56 volumio2 jellyfin[1751]: System.Net.Http.HttpRequestException: Unknown socket error (repo.jellyfin.org:443) Feb 25 16:52:56 volumio2 jellyfin[1751]: ---> System.Net.Sockets.SocketException (0xFFFDFFFE): Unknown socket error Feb 25 16:52:56 volumio2 jellyfin[1751]: at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.ThrowException(SocketError error, CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: at System.Net.Sockets.Socket.AwaitableSocketAsyncEventArgs.System.Threading.Tasks.Sources.IValueTaskSource.GetResult(Int16 token) Feb 25 16:52:56 volumio2 jellyfin[1751]: at System.Net.Sockets.Socket.g__WaitForConnectWithCancellation|285_0(AwaitableSocketAsyncEventArgs saea, ValueTask connectTask, CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: at Jellyfin.Networking.HappyEyeballs.HttpClientExtension.AttemptConnection(AddressFamily addressFamily, SocketsHttpConnectionContext context, CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: at Jellyfin.Networking.HappyEyeballs.HttpClientExtension.OnConnect(SocketsHttpConnectionContext context, CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: at System.Net.Http.HttpConnectionPool.ConnectToTcpHostAsync(String host, Int32 port, HttpRequestMessage initialRequest, Boolean async, CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: --- End of inner exception stack trace --- Feb 25 16:52:56 volumio2 jellyfin[1751]: at System.Net.Http.HttpConnectionPool.ConnectToTcpHostAsync(String host, Int32 port, HttpRequestMessage initialRequest, Boolean async, CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: at System.Net.Http.HttpConnectionPool.ConnectAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: at System.Net.Http.HttpConnectionPool.CreateHttp11ConnectionAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: at System.Net.Http.HttpConnectionPool.AddHttp11ConnectionAsync(QueueItem queueItem) Feb 25 16:52:56 volumio2 jellyfin[1751]: at System.Threading.Tasks.TaskCompletionSourceWithCancellation`1.WaitWithCancellationAsync(CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: at System.Net.Http.HttpConnectionPool.SendWithVersionDetectionAndRetryAsync(HttpRequestMessage request, Boolean async, Boolean doRequestAuth, CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: at System.Net.Http.RedirectHandler.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: at System.Net.Http.DecompressionHandler.SendAsync(HttpRequestMessage request, Boolean async, CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: at Microsoft.Extensions.Http.Logging.LoggingHttpMessageHandler.g__Core|5_0(HttpRequestMessage request, Boolean useAsync, CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: at Microsoft.Extensions.Http.Logging.LoggingScopeHttpMessageHandler.g__Core|5_0(HttpRequestMessage request, Boolean useAsync, CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: at System.Net.Http.HttpClient.g__Core|83_0(HttpRequestMessage request, HttpCompletionOption completionOption, CancellationTokenSource cts, Boolean disposeCts, CancellationTokenSource pendingRequestsCts, CancellationToken originalCancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: 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) Feb 25 16:52:56 volumio2 jellyfin[1751]: at Emby.Server.Implementations.Updates.InstallationManager.GetPackages(String manifestName, String manifest, Boolean filterIncompatible, CancellationToken cancellationToken) Feb 25 16:52:56 volumio2 jellyfin[1751]: [16:52:56] [INF] [8] Emby.Server.Implementations.ScheduledTasks.TaskManager: Оновити плагіни Completed after 0 minute(s) and 18 seconds Feb 25 16:52:56 volumio2 volumio[1299]: info: BOOT COMPLETED Feb 25 16:53:00 volumio2 volumio[1299]: info: [jellyfin-poller] Polled http://192.168.1.51:8096/web/index.html#/home.htm: offline (system info unavailable) Feb 25 16:53:03 volumio2 go-librespot[2556]: time="2026-02-25T16:53:03+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" Feb 25 16:53:03 volumio2 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 16:53:03 volumio2 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 16:53:06 volumio2 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Feb 25 16:53:06 volumio2 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 25 16:53:06 volumio2 systemd[1]: Stopped go-librespot Daemon. Feb 25 16:53:06 volumio2 systemd[1]: Started go-librespot Daemon. Feb 25 16:53:06 volumio2 go-librespot[2641]: Librespot-go daemon starting... Feb 25 16:53:06 volumio2 go-librespot[2641]: time="2026-02-25T16:53:06+02:00" level=info msg="generated new device id: 996b9b6df0dbfce60d24b12a947a8ee0f827fafb" Feb 25 16:53:06 volumio2 go-librespot[2641]: time="2026-02-25T16:53:06+02:00" level=debug msg="stored credentials found for 31jxmlwjw63hm3cpzjfc7mx4zev4" Feb 25 16:53:07 volumio2 volumio[1299]: info: Volumio called home Feb 25 16:53:08 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Feb 25 16:53:08 volumio2 volumio[1299]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 25 16:53:08 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Feb 25 16:53:09 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Feb 25 16:53:09 volumio2 go-librespot[2641]: time="2026-02-25T16:53:09+02:00" level=debug msg="new websocket client" Feb 25 16:53:09 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Feb 25 16:53:09 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Feb 25 16:53:09 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 25 16:53:09 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Feb 25 16:53:09 volumio2 volumio[1299]: info: Connection to go-librespot Websocket established Feb 25 16:53:09 volumio2 volumio[1299]: info: FusionDsp - {"Reload":{"result":"Ok"}} Feb 25 16:53:09 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Feb 25 16:53:09 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Feb 25 16:53:09 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Feb 25 16:53:09 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Feb 25 16:53:09 volumio2 go-librespot[2641]: time="2026-02-25T16:53:09+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]" Feb 25 16:53:09 volumio2 go-librespot[2641]: time="2026-02-25T16:53:09+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]" Feb 25 16:53:09 volumio2 go-librespot[2641]: time="2026-02-25T16:53:09+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]" Feb 25 16:53:09 volumio2 go-librespot[2641]: time="2026-02-25T16:53:09+02:00" level=debug msg="zeroconf server listening on port 36925" Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Feb 25 16:53:09 volumio2 volumio[1299]: info: Adding plugin bluetooth to MyMusic Plugins Feb 25 16:53:09 volumio2 volumio[1299]: info: Adding plugin multiroom to MyMusic Plugins Feb 25 16:53:09 volumio2 volumio[1299]: info: Adding plugin metavolumio to MyMusic Plugins Feb 25 16:53:09 volumio2 volumio[1299]: info: Adding plugin cd_controller to MyMusic Plugins Feb 25 16:53:09 volumio2 volumio[1299]: info: Adding plugin qobuzconnect to MyMusic Plugins Feb 25 16:53:09 volumio2 volumio[1299]: info: Adding plugin smart_inputs to MyMusic Plugins Feb 25 16:53:09 volumio2 volumio[1299]: info: Adding plugin tidalconnect to MyMusic Plugins Feb 25 16:53:09 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Feb 25 16:53:09 volumio2 go-librespot[2641]: time="2026-02-25T16:53:09+02:00" level=debug msg="obtained new client token: AAApXyLABwY5hgJZmiFFk0ntVgeTLqjVcjfeaPoCnrAWXeTT5eSj9Ju9CSt4urMQ+gmJeCtbSbiMNWEDUWylocptKoi4VLwnipq3d1hgEFi6PU61iKejZ5QGTak+U7/ZMfCSUnJOQhyzgZlTiGSa9NdqLbKzbewnt4JC1zaiC9Bk9cpSb4JQRXVBUQrsS0P4R2v+GM0aDWm8O71V/9gpIRde57OG9nh5MUxy4Kvw+48u0Pdj9RTKDCp9gQW/GGI=" Feb 25 16:53:09 volumio2 go-librespot[2641]: time="2026-02-25T16:53:09+02:00" level=info msg="connected to ap-gew1.spotify.com:4070" Feb 25 16:53:09 volumio2 go-librespot[2641]: time="2026-02-25T16:53:09+02:00" level=debug msg="completed keyexchange" Feb 25 16:53:10 volumio2 volumio-time-update[775]: volumio-time-update-util: Date not found in response Feb 25 16:53:10 volumio2 volumio-time-update[775]: volumio-time-update-util: Retrying in 5 seconds... Feb 25 16:53:10 volumio2 go-librespot[2641]: time="2026-02-25T16:53:10+02:00" level=debug msg="completed challenge" Feb 25 16:53:10 volumio2 go-librespot[2641]: time="2026-02-25T16:53:10+02:00" level=debug msg="authenticated as 31jxmlwjw63hm3cpzjfc7mx4zev4" Feb 25 16:53:10 volumio2 go-librespot[2641]: time="2026-02-25T16:53:10+02:00" level=debug msg="authenticated as 31jxmlwjw63hm3cpzjfc7mx4zev4" Feb 25 16:53:10 volumio2 go-librespot[2641]: time="2026-02-25T16:53:10+02:00" level=debug msg="dealer connection opened" Feb 25 16:53:10 volumio2 go-librespot[2641]: time="2026-02-25T16:53:10+02:00" level=debug msg="initializing zeroconf session, username: 31jxmlwjw63hm3cpzjfc7mx4zev4" Feb 25 16:53:10 volumio2 go-librespot[2641]: time="2026-02-25T16:53:10+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 25 16:53:10 volumio2 go-librespot[2641]: time="2026-02-25T16:53:10+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 25 16:53:10 volumio2 go-librespot[2641]: time="2026-02-25T16:53:10+02:00" level=debug msg="received connection id: YTcwY2QxNDctZWZkMi00MWVhLThhZmMtNzM3ODU4M2JjYThlK2RlYWxlcit0Y3A6Ly8wYWNhNDBlZS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArRTVFMTM0RkI3N0VEMjExMUUyM0MzQUREODFDQTBFQTU0NTE0NThGQzg1OTBCRjYxMzFCQjQ0MzdBQ0QzM0JERQ==" Feb 25 16:53:11 volumio2 go-librespot[2641]: time="2026-02-25T16:53:11+02:00" level=debug msg="put connect state because NEW_DEVICE" Feb 25 16:53:11 volumio2 go-librespot[2641]: time="2026-02-25T16:53:11+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 25 16:53:11 volumio2 go-librespot[2641]: time="2026-02-25T16:53:11+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 25 16:53:11 volumio2 go-librespot[2641]: time="2026-02-25T16:53:11+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 25 16:53:11 volumio2 go-librespot[2641]: time="2026-02-25T16:53:11+02:00" level=debug msg="autoplay enabled: false" Feb 25 16:53:11 volumio2 volumio[1299]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Feb 25 16:53:11 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Feb 25 16:53:11 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:53:11 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:53:11 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 25 16:53:11 volumio2 volumio[1299]: info: Starting MyVolumio Remote Streaming Endpoints Feb 25 16:53:11 volumio2 volumio[1299]: info: MyVolumio login type: Token Feb 25 16:53:11 volumio2 volumio[1299]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Feb 25 16:53:11 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Feb 25 16:53:11 volumio2 ntpd[1310]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 25 16:53:11 volumio2 ntpd[1310]: Soliciting pool server 31.133.97.13 Feb 25 16:53:11 volumio2 ntpd[1310]: Soliciting pool server 79.142.192.4 Feb 25 16:53:11 volumio2 ntpd[1310]: Soliciting pool server 46.173.175.211 Feb 25 16:53:11 volumio2 ntpd[1310]: Soliciting pool server 91.236.251.31 Feb 25 16:53:11 volumio2 ntpd[1310]: Soliciting pool server 188.191.239.24 Feb 25 16:53:11 volumio2 ntpd[1310]: Soliciting pool server 91.231.182.17 Feb 25 16:53:11 volumio2 volumio[1299]: info: Starting Streaming Service Transparent Proxy Feb 25 16:53:12 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Feb 25 16:53:12 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Feb 25 16:53:12 volumio2 volumio[1299]: info: Streaming services startup Feb 25 16:53:12 volumio2 volumio[1299]: info: Starting Streaming Daemon Feb 25 16:53:12 volumio2 sudo[2663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 16:53:12 volumio2 sudo[2663]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:53:12 volumio2 sudo[2663]: pam_unix(sudo:session): session closed for user root Feb 25 16:53:12 volumio2 volumio[1299]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Feb 25 16:53:12 volumio2 volumio[1299]: info: Getting Spotify volume Feb 25 16:53:12 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 25 16:53:12 volumio2 volumio[1299]: info: Received Get System Info Feb 25 16:53:12 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Feb 25 16:53:12 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Feb 25 16:53:12 volumio2 volumio[1299]: info: Discovery: Getting this device information Feb 25 16:53:12 volumio2 volumio[1299]: info: CoreCommandRouter::volumioGetState Feb 25 16:53:12 volumio2 volumio[1299]: info: CorePlayQueue::getTrack 0 Feb 25 16:53:12 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Feb 25 16:53:12 volumio2 volumio[1299]: error: Cannot start Volumio Streaming Daemon Feb 25 16:53:12 volumio2 volumio[1299]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 25 16:53:12 volumio2 volumio[1299]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 25 16:53:12 volumio2 volumio[1299]: info: Spotify volume: 100 Feb 25 16:53:12 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Feb 25 16:53:12 volumio2 volumio[1299]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Feb 25 16:53:12 volumio2 volumio[1299]: info: CoreCommandRouter::volumioGetState Feb 25 16:53:12 volumio2 volumio[1299]: info: CorePlayQueue::getTrack 0 Feb 25 16:53:12 volumio2 volumio[1299]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Feb 25 16:53:12 volumio2 volumio[1299]: info: [yt-cast-receiver] DIAL server listening on port 8098 Feb 25 16:53:12 volumio2 volumio[1299]: error: MyVolumio Custom Token format not valid, refreshing it Feb 25 16:53:12 volumio2 volumio[1299]: STREAMING PROXY: Starting server on port 3245 Feb 25 16:53:12 volumio2 volumio[1299]: Node JS runtime: 14 Feb 25 16:53:12 volumio2 ntpd[1310]: Soliciting pool server 94.158.46.150 Feb 25 16:53:12 volumio2 ntpd[1310]: Soliciting pool server 91.236.251.14 Feb 25 16:53:12 volumio2 ntpd[1310]: Soliciting pool server 91.236.251.35 Feb 25 16:53:13 volumio2 volumio[1299]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Feb 25 16:53:13 volumio2 volumio[1299]: info: MyVolumio login type: Token Feb 25 16:53:13 volumio2 volumio[1299]: info: MyVolumio login type: Token Feb 25 16:53:13 volumio2 volumio[1299]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Feb 25 16:53:13 volumio2 ntpd[1310]: Soliciting pool server 162.159.200.123 Feb 25 16:53:13 volumio2 ntpd[1310]: Soliciting pool server 91.236.251.34 Feb 25 16:53:13 volumio2 ntpd[1310]: Soliciting pool server 91.236.251.24 Feb 25 16:53:14 volumio2 ntpd[1310]: Soliciting pool server 2a02:c6c1:c:207::13 Feb 25 16:53:15 volumio2 volumio[1299]: info: MyVolumio token set successfully Feb 25 16:53:15 volumio2 volumio[1299]: info: MYVOLUMIO: Adding device Feb 25 16:53:15 volumio2 volumio[1299]: info: MYVOLUMIO: Evaluating Server Feb 25 16:53:15 volumio2 volumio-time-update[775]: volumio-time-update-util: Fetching time from Volumio... Feb 25 16:53:15 volumio2 volumio-time-update[775]: volumio-time-update-util: Setting system time to: 2026-02-25 16:53:15 Feb 25 16:53:15 volumio2 sudo[2688]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2026-02-25 16:53:15 Feb 25 16:53:15 volumio2 sudo[2688]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:53:15 volumio2 dbus-daemon[772]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.18' (uid=0 pid=2689 comm="timedatectl set-time 2026-02-25 16:53:15 ") Feb 25 16:53:15 volumio2 systemd[1]: Starting Time & Date Service... Feb 25 16:53:15 volumio2 dbus-daemon[772]: [system] Successfully activated service 'org.freedesktop.timedate1' Feb 25 16:53:15 volumio2 systemd[1]: Started Time & Date Service. Feb 25 16:53:15 volumio2 systemd-timedated[2696]: Changed local time to Wed Feb 25 16:53:15 2026 Feb 25 16:53:15 volumio2 sudo[2688]: pam_unix(sudo:session): session closed for user root Feb 25 16:53:15 volumio2 volumio-time-update[775]: volumio-time-update-util: System time updated successfully. Feb 25 16:53:15 volumio2 systemd[1]: Started Volumio Time Update Utility. Feb 25 16:53:15 volumio2 systemd[1]: Reached target Multi-User System. Feb 25 16:53:15 volumio2 systemd[1]: Reached target Graphical Interface. Feb 25 16:53:15 volumio2 systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 25 16:53:15 volumio2 systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Feb 25 16:53:15 volumio2 systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 25 16:53:15 volumio2 systemd[1]: Startup finished in 5.494s (firmware) + 2.710s (loader) + 9.438s (kernel) + 1min 56.325s (userspace) = 2min 13.969s. Feb 25 16:53:15 volumio2 volumio[1299]: info: MyVolumio status changed Feb 25 16:53:15 volumio2 volumio[1299]: info: Streaming services startup Feb 25 16:53:15 volumio2 volumio[1299]: info: Starting Streaming Daemon Feb 25 16:53:15 volumio2 volumio[1299]: info: Removing browser output: myVolumio user plan is not superstar Feb 25 16:53:15 volumio2 volumio[1299]: info: Removing audio output: Feb 25 16:53:15 volumio2 volumio[1299]: info: Stoppping Tunnel 1 Feb 25 16:53:15 volumio2 sudo[2718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 25 16:53:15 volumio2 sudo[2718]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:53:15 volumio2 sudo[2718]: pam_unix(sudo:session): session closed for user root Feb 25 16:53:15 volumio2 volumio[1299]: error: Failed to ping endpoint us2.myvolumio.org : unknown error Feb 25 16:53:15 volumio2 volumio[1299]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 16:53:15 volumio2 sudo[2721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Feb 25 16:53:15 volumio2 sudo[2721]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 25 16:53:15 volumio2 volumio[1299]: Error: Unable to resolve or reject the same promise twice Feb 25 16:53:15 volumio2 volumio[1299]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Feb 25 16:53:15 volumio2 volumio[1299]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32367) Feb 25 16:53:15 volumio2 volumio[1299]: at Socket.emit (events.js:400:28) Feb 25 16:53:15 volumio2 volumio[1299]: at addChunk (internal/streams/readable.js:293:12) Feb 25 16:53:15 volumio2 volumio[1299]: at readableAddChunk (internal/streams/readable.js:267:9) Feb 25 16:53:15 volumio2 volumio[1299]: at Socket.Readable.push (internal/streams/readable.js:206:10) Feb 25 16:53:15 volumio2 volumio[1299]: at Pipe.onStreamRead (internal/stream_base_commons.js:188:23) Feb 25 16:53:15 volumio2 volumio[1299]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 16:53:15 volumio2 sudo[2721]: pam_unix(sudo:session): session closed for user root Feb 25 16:53:15 volumio2 sudo[2732]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-25 16:52 Feb 25 16:53:15 volumio2 sudo[2732]: 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 06:18:30 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="33f9369b544b4488ff4979d2ee3813a5"