-- Logs begin at Wed 2025-09-17 15:07:43 CEST, end at Wed 2025-09-17 15:10:30 CEST. -- Sep 17 15:09:07 volumio systemd-timedated[1283]: Changed local time to Wed Sep 17 15:09:07 2025 Sep 17 15:09:07 volumio sudo[1281]: pam_unix(sudo:session): session closed for user root Sep 17 15:09:07 volumio volumio-time-update[814]: volumio-time-update-util: System time updated successfully. Sep 17 15:09:07 volumio systemd[1]: Started Volumio Time Update Utility. Sep 17 15:09:07 volumio volumio[1117]: info: MYVOLUMIO Environment detected Sep 17 15:09:07 volumio ntpd[1106]: Soliciting pool server 129.250.35.250 Sep 17 15:09:07 volumio ntpd[1106]: Soliciting pool server 172.232.44.156 Sep 17 15:09:07 volumio ntpd[1106]: Soliciting pool server 82.64.230.205 Sep 17 15:09:08 volumio volumio[1117]: info: Plugin folders cleanup Sep 17 15:09:08 volumio volumio[1117]: info: Scanning into folder /volumio/app/plugins/ Sep 17 15:09:08 volumio volumio[1117]: info: Scanning category audio_interface Sep 17 15:09:08 volumio volumio[1117]: info: Scanning category miscellanea Sep 17 15:09:08 volumio volumio[1117]: info: Scanning category music_service Sep 17 15:09:08 volumio volumio[1117]: info: Scanning category plugins.json Sep 17 15:09:08 volumio volumio[1117]: info: Scanning category system_controller Sep 17 15:09:08 volumio volumio[1117]: info: Scanning category user_interface Sep 17 15:09:08 volumio volumio[1117]: info: Scanning into folder /data/plugins/ Sep 17 15:09:08 volumio volumio[1117]: info: Scanning category audio_interface Sep 17 15:09:08 volumio volumio[1117]: info: Scanning category music_service Sep 17 15:09:08 volumio volumio[1117]: info: Scanning category system_controller Sep 17 15:09:08 volumio volumio[1117]: info: Scanning category system_hardware Sep 17 15:09:08 volumio volumio[1117]: info: Scanning category user_interface Sep 17 15:09:08 volumio volumio[1117]: info: Plugin folders cleanup completed Sep 17 15:09:08 volumio volumio[1117]: info: ------------------------------------------- Sep 17 15:09:08 volumio volumio[1117]: info: ----- Core plugins startup ---- Sep 17 15:09:08 volumio volumio[1117]: info: ------------------------------------------- Sep 17 15:09:08 volumio volumio[1117]: info: Loading plugins from folder /volumio/app/plugins/ Sep 17 15:09:08 volumio volumio[1117]: info: Adding plugin upnp to MyMusic Plugins Sep 17 15:09:08 volumio volumio[1117]: info: Adding plugin airplay_emulation to MyMusic Plugins Sep 17 15:09:08 volumio volumio[1117]: info: Adding plugin upnp_browser to MyMusic Plugins Sep 17 15:09:08 volumio volumio[1117]: info: Loading plugins from folder /data/plugins/ Sep 17 15:09:08 volumio volumio[1117]: info: Loading plugin "system"... Sep 17 15:09:08 volumio volumio[1117]: info: Loading plugin "appearance"... Sep 17 15:09:08 volumio ntpd[1106]: Soliciting pool server 172.232.63.219 Sep 17 15:09:08 volumio ntpd[1106]: Soliciting pool server 164.132.166.29 Sep 17 15:09:08 volumio ntpd[1106]: Soliciting pool server 176.137.36.37 Sep 17 15:09:08 volumio ntpd[1106]: Soliciting pool server 37.59.63.125 Sep 17 15:09:09 volumio nmbd[903]: [2025/09/17 15:09:09.096889, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 17 15:09:09 volumio nmbd[903]: daemon_ready: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Sep 17 15:09:09 volumio systemd[1]: Started Samba NMB Daemon. Sep 17 15:09:09 volumio systemd[1]: Starting Samba Winbind Daemon... Sep 17 15:09:09 volumio winbindd[1324]: [2025/09/17 15:09:09.389818, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Sep 17 15:09:09 volumio winbindd[1324]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Sep 17 15:09:09 volumio winbindd[1324]: [2025/09/17 15:09:09.399049, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 17 15:09:09 volumio systemd[1]: Started Samba Winbind Daemon. Sep 17 15:09:09 volumio winbindd[1324]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Sep 17 15:09:09 volumio ntpd[1106]: Soliciting pool server 129.151.225.244 Sep 17 15:09:09 volumio ntpd[1106]: Soliciting pool server 45.140.164.53 Sep 17 15:09:09 volumio systemd[1]: Starting Samba SMB Daemon... Sep 17 15:09:10 volumio smbd[1335]: [2025/09/17 15:09:10.004492, 0] ../lib/util/become_daemon.c:138(daemon_ready) Sep 17 15:09:10 volumio systemd[1]: Started Samba SMB Daemon. Sep 17 15:09:10 volumio smbd[1335]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Sep 17 15:09:10 volumio systemd[1]: Reached target Multi-User System. Sep 17 15:09:10 volumio systemd[1]: Reached target Graphical Interface. Sep 17 15:09:10 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 17 15:09:10 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 17 15:09:10 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 17 15:09:10 volumio systemd[1]: Startup finished in 8.063s (kernel) + 54.025s (userspace) = 1min 2.089s. Sep 17 15:09:10 volumio volumio[1117]: info: Loading plugin "network"... Sep 17 15:09:10 volumio volumio[1117]: info: Refreshing Cached IP Addresses Sep 17 15:09:10 volumio sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 17 15:09:10 volumio sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 15:09:10 volumio sudo[1347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 17 15:09:10 volumio sudo[1345]: pam_unix(sudo:session): session closed for user root Sep 17 15:09:10 volumio sudo[1347]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 15:09:10 volumio sudo[1347]: pam_unix(sudo:session): session closed for user root Sep 17 15:09:10 volumio volumio[1117]: info: Loading plugin "services"... Sep 17 15:09:10 volumio volumio[1117]: info: Loading plugin "alsa_controller"... Sep 17 15:09:10 volumio sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Sep 17 15:09:10 volumio sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 15:09:10 volumio ntpd[1106]: Soliciting pool server 54.36.61.42 Sep 17 15:09:10 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 15:09:10 volumio volumio[1117]: info: Loading plugin "wizard"... Sep 17 15:09:10 volumio volumio[1117]: info: Loading plugin "networkfs"... Sep 17 15:09:10 volumio sudo[1357]: pam_unix(sudo:session): session closed for user root Sep 17 15:09:10 volumio volumio[1117]: info: Starting Udev Watcher for removable devices Sep 17 15:09:10 volumio volumio[1117]: info: Ignoring mount for partition: boot Sep 17 15:09:10 volumio volumio[1117]: info: Ignoring mount for partition: volumio Sep 17 15:09:10 volumio volumio[1117]: info: Ignoring mount for partition: volumio_data Sep 17 15:09:10 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 17 15:09:10 volumio volumio[1117]: info: Loading plugin "volumio_command_line_client"... Sep 17 15:09:10 volumio sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //Littlesoft.fr/music /mnt/NAS/littlsoft Sep 17 15:09:10 volumio sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 15:09:10 volumio volumio[1117]: info: Loading plugin "upnp"... Sep 17 15:09:10 volumio volumio[1117]: info: [1758114550690] Starting Upmpd Daemon Sep 17 15:09:10 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 17 15:09:10 volumio volumio[1117]: info: Loading plugin "my_music"... Sep 17 15:09:10 volumio volumio[1117]: info: Loading plugin "mpd"... Sep 17 15:09:10 volumio kernel: FS-Cache: Loaded Sep 17 15:09:10 volumio kernel: Key type dns_resolver registered Sep 17 15:09:10 volumio kernel: Key type cifs.spnego registered Sep 17 15:09:10 volumio kernel: Key type cifs.idmap registered Sep 17 15:09:10 volumio kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Sep 17 15:09:10 volumio kernel: CIFS: Attempting to mount //Littlesoft.fr/music Sep 17 15:09:11 volumio volumio[1117]: info: Loading plugin "upnp_browser"... Sep 17 15:09:11 volumio ntpd[1106]: Soliciting pool server 2a0c:8881:5:3b::1 Sep 17 15:09:11 volumio volumio[1117]: info: Loading plugin "alarm-clock"... Sep 17 15:09:11 volumio volumio[1117]: info: Loading plugin "airplay_emulation"... Sep 17 15:09:11 volumio volumio[1117]: info: Starting Shairport Sync Sep 17 15:09:11 volumio volumio[1117]: info: Loading plugin "last_100"... Sep 17 15:09:11 volumio volumio[1117]: info: Loading plugin "webradio"... Sep 17 15:09:11 volumio dbus-daemon[807]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.9' (uid=0 pid=1306 comm="/usr/lib/chromium/chromium --show-component-extens") Sep 17 15:09:11 volumio systemd[1]: Starting Bluetooth service... Sep 17 15:09:11 volumio volumio[1117]: info: Loading plugin "i2s_dacs"... Sep 17 15:09:11 volumio volumio[1117]: info: I2S DAC not set, start Auto-detection Sep 17 15:09:11 volumio volumio[1117]: info: Loading plugin "volumiodiscovery"... Sep 17 15:09:11 volumio volumio[1117]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 17 15:09:11 volumio volumio[1117]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 15:09:11 volumio volumio[1117]: *** WARNING *** For more information see Sep 17 15:09:11 volumio node[1117]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Sep 17 15:09:11 volumio volumio[1117]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 17 15:09:11 volumio volumio[1117]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 15:09:11 volumio volumio[1117]: *** WARNING *** For more information see Sep 17 15:09:11 volumio node[1117]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 15:09:11 volumio node[1117]: *** WARNING *** For more information see Sep 17 15:09:11 volumio node[1117]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Sep 17 15:09:11 volumio node[1117]: *** WARNING *** Please fix your application to use the native API of Avahi! Sep 17 15:09:11 volumio node[1117]: *** WARNING *** For more information see Sep 17 15:09:11 volumio volumio[1117]: info: Applying required configuration parameters for plugin volumiodiscovery Sep 17 15:09:11 volumio volumio[1117]: info: Discovery: Started advertising with name: Volumio Sep 17 15:09:11 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 17 15:09:11 volumio volumio[1117]: info: Plugin mixcloud is not enabled Sep 17 15:09:11 volumio volumio[1117]: info: Loading plugin "spop"... Sep 17 15:09:11 volumio bluetoothd[1405]: Bluetooth daemon 5.50 Sep 17 15:09:11 volumio kernel: Bluetooth: Core ver 2.22 Sep 17 15:09:11 volumio kernel: NET: Registered PF_BLUETOOTH protocol family Sep 17 15:09:11 volumio kernel: Bluetooth: HCI device and connection manager initialized Sep 17 15:09:11 volumio kernel: Bluetooth: HCI socket layer initialized Sep 17 15:09:11 volumio kernel: Bluetooth: L2CAP socket layer initialized Sep 17 15:09:11 volumio kernel: Bluetooth: SCO socket layer initialized Sep 17 15:09:11 volumio bluetoothd[1405]: Unknown key AutoConnectTimeout for group General in /etc/bluetooth/main.conf Sep 17 15:09:11 volumio dbus-daemon[807]: [system] Successfully activated service 'org.bluez' Sep 17 15:09:11 volumio systemd[1]: Started Bluetooth service. Sep 17 15:09:11 volumio bluetoothd[1405]: Starting SDP server Sep 17 15:09:11 volumio bluetoothd[1405]: Excluding (cli) sap Sep 17 15:09:11 volumio bluetoothd[1405]: Bluetooth management interface 1.22 initialized Sep 17 15:09:11 volumio dbus-daemon[807]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.12' (uid=0 pid=1405 comm="/usr/lib/bluetooth/bluetoothd --noplugin=sap ") Sep 17 15:09:11 volumio kernel: Bluetooth: BNEP (Ethernet Emulation) ver 1.3 Sep 17 15:09:11 volumio kernel: Bluetooth: BNEP filters: protocol multicast Sep 17 15:09:11 volumio kernel: Bluetooth: BNEP socket layer initialized Sep 17 15:09:12 volumio systemd[1]: Starting Hostname Service... Sep 17 15:09:12 volumio dbus-daemon[807]: [system] Successfully activated service 'org.freedesktop.hostname1' Sep 17 15:09:12 volumio systemd[1]: Started Hostname Service. Sep 17 15:09:12 volumio volumio-remote-updater[804]: [2025-09-17 15:09:12] [connect] Successful connection Sep 17 15:09:15 volumio volumio[1117]: info: Loading plugin "autostart"... Sep 17 15:09:15 volumio volumio[1117]: info: Applying required configuration parameters for plugin autostart Sep 17 15:09:15 volumio volumio[1117]: info: AutoStart - onVolumioStart - read config.json Sep 17 15:09:15 volumio volumio[1117]: info: Loading plugin "outputs"... Sep 17 15:09:15 volumio volumio[1117]: info: Loading plugin "albumart"... Sep 17 15:09:15 volumio volumio[1117]: info: Plugin example_plugin is not enabled Sep 17 15:09:15 volumio volumio[1117]: info: Loading plugin "inputs"... Sep 17 15:09:15 volumio volumio[1117]: info: Loading plugin "updater_comm"... Sep 17 15:09:15 volumio volumio[1117]: Forking 3 albumart workers Sep 17 15:09:00 volumio volumio[1117]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Sep 17 15:09:00 volumio volumio[1117]: info: Updating MyVolumio device info Sep 17 15:09:00 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 15:09:00 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 15:09:00 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 15:09:00 volumio volumio[1117]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Sep 17 15:09:02 volumio sudo[2039]: pam_unix(sudo:session): session closed for user root Sep 17 15:09:02 volumio volumio[1117]: info: Cannot mount NAS littlsoft at system boot, trial number 2 ,retrying in 5 seconds Sep 17 15:09:02 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 17 15:09:02 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Sep 17 15:09:02 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 17 15:09:02 volumio volumio[1117]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 17 15:09:02 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 17 15:09:02 volumio volumio[1117]: info: Received Get System Version Sep 17 15:09:02 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 15:09:02 volumio volumio[1117]: info: Received Get System Info Sep 17 15:09:02 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 17 15:09:02 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 17 15:09:02 volumio volumio[1117]: info: Discovery: Getting this device information Sep 17 15:09:02 volumio volumio[1117]: info: CoreCommandRouter::volumioGetState Sep 17 15:09:02 volumio volumio[1117]: info: CorePlayQueue::getTrack 0 Sep 17 15:09:02 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 17 15:09:02 volumio volumio[1117]: info: AutoStart - Plugin is starting Sep 17 15:09:02 volumio volumio[1117]: info: CoreCommandRouter::volumioGetQueue Sep 17 15:09:02 volumio volumio[1117]: info: CoreStateMachine::getQueue Sep 17 15:09:02 volumio volumio[1117]: info: CorePlayQueue::getQueue Sep 17 15:09:02 volumio volumio[1117]: info: AutoStart - start playing Sep 17 15:09:02 volumio volumio[1117]: info: AutoStart - start playing with no specific position Sep 17 15:09:02 volumio volumio[1117]: info: CoreCommandRouter::volumioPlay Sep 17 15:09:02 volumio volumio[1117]: info: CoreStateMachine::play index 0 Sep 17 15:09:02 volumio volumio[1117]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 15:09:02 volumio volumio[1117]: info: CoreStateMachine::stop Sep 17 15:09:02 volumio volumio[1117]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 15:09:02 volumio volumio[1117]: info: CoreStateMachine::play index undefined Sep 17 15:09:02 volumio volumio[1117]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 17 15:09:02 volumio volumio[1117]: info: CorePlayQueue::getTrack 0 Sep 17 15:09:02 volumio volumio[1117]: info: CoreStateMachine::startPlaybackTimer Sep 17 15:09:02 volumio volumio[1117]: info: CorePlayQueue::getTrack 0 Sep 17 15:09:02 volumio volumio[1117]: info: [1758114542735] ControllerSpotify::clearAddPlayTrack Sep 17 15:09:02 volumio volumio[1117]: info: Sending Spotify command with payload to local API: /player/play Sep 17 15:09:02 volumio go-librespot[1677]: time="2025-09-17T15:09:02+02:00" level=debug msg="resolved context of track" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:09:02 volumio go-librespot[1677]: time="2025-09-17T15:09:02+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:09:02 volumio go-librespot[1677]: time="2025-09-17T15:09:02+02:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:09:02 volumio go-librespot[1677]: time="2025-09-17T15:09:02+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 17 15:09:02 volumio go-librespot[1677]: time="2025-09-17T15:09:02+02:00" level=trace msg="emitting websocket event: will_play" Sep 17 15:09:02 volumio volumio[1117]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:2nOZaGwMYt07nXtIeYbEiu","uri":"spotify:track:2nOZaGwMYt07nXtIeYbEiu","play_origin":"go-librespot"}} Sep 17 15:09:02 volumio go-librespot[1677]: time="2025-09-17T15:09:02+02:00" level=debug msg="selected format OGG_VORBIS_320 (d4373ad4f0c4d584fea1678567467751869874a3)" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:09:02 volumio go-librespot[1677]: time="2025-09-17T15:09:02+02:00" level=debug msg="requested aes key for file d4373ad4f0c4d584fea1678567467751869874a3, gid: 2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:09:02 volumio go-librespot[1677]: time="2025-09-17T15:09:02+02:00" level=trace msg="found 2 cdn urls" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:09:02 volumio systemd[1]: systemd-timedated.service: Succeeded. Sep 17 15:09:03 volumio go-librespot[1677]: time="2025-09-17T15:09:03+02:00" level=debug msg="fetched first chunk of 14, total size is 7089552 bytes" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:09:03 volumio go-librespot[1677]: time="2025-09-17T15:09:03+02:00" level=debug msg="created new output device" Sep 17 15:09:03 volumio go-librespot[1677]: time="2025-09-17T15:09:03+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" Sep 17 15:09:03 volumio volumio[1117]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 17 15:09:03 volumio volumio[1117]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 17 15:09:03 volumio volumio[1117]: info: camilladsp stopping service pid 1771... Sep 17 15:09:03 volumio go-librespot[1677]: time="2025-09-17T15:09:03+02:00" level=info msg="loaded track \"Comportement\" (paused: false, position: 0ms, duration: 172000ms, prefetched: false)" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:09:03 volumio volumio[1117]: info: camilladsp service terminated, instance 1 Sep 17 15:09:03 volumio volumio[1117]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 17 15:09:03 volumio volumio[1117]: info: camilladsp service started and running in background, instance 1 Sep 17 15:09:03 volumio volumio[1117]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 17 15:09:03 volumio volumio[1117]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 17 15:09:03 volumio volumio[1117]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 17 15:09:03 volumio volumio[1117]: error: FusionDsp - WebSocket error: [object Object] Sep 17 15:09:03 volumio go-librespot[1677]: time="2025-09-17T15:09:03+02:00" level=debug msg="fetched chunk 1/13, size: 524288" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:09:03 volumio go-librespot[1677]: time="2025-09-17T15:09:03+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 17 15:09:03 volumio go-librespot[1677]: time="2025-09-17T15:09:03+02:00" level=trace msg="scheduling prefetch in 142s" Sep 17 15:09:03 volumio go-librespot[1677]: time="2025-09-17T15:09:03+02:00" level=trace msg="emitting websocket event: metadata" Sep 17 15:09:03 volumio volumio[1117]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2nOZaGwMYt07nXtIeYbEiu","name":"Comportement","artist_names":["Aya Nakamura"],"album_name":"Journal intime","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02d46bd7cfa437851c2eb13e86","position":0,"duration":172000,"release_date":"year:2017 month:8 day:25","track_number":10,"disc_number":1}} Sep 17 15:09:03 volumio go-librespot[1677]: time="2025-09-17T15:09:03+02:00" level=debug msg="fetched chunk 2/13, size: 524288" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:09:03 volumio go-librespot[1677]: time="2025-09-17T15:09:03+02:00" level=debug msg="fetched chunk 3/13, size: 524288" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:09:03 volumio go-librespot[1677]: time="2025-09-17T15:09:03+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Sep 17 15:09:03 volumio go-librespot[1677]: time="2025-09-17T15:09:03+02:00" level=trace msg="emitting websocket event: playing" Sep 17 15:09:03 volumio volumio[1117]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:track:2nOZaGwMYt07nXtIeYbEiu","uri":"spotify:track:2nOZaGwMYt07nXtIeYbEiu","resume":false,"play_origin":"go-librespot"}} Sep 17 15:09:03 volumio volumio[1117]: SPOTIFY: PUSH STATE SPOTIFY Sep 17 15:09:03 volumio volumio[1117]: SPOTIFY: {"status":"play","service":"spop","title":"Comportement","artist":"Aya Nakamura","album":"Journal intime","albumart":"https://i.scdn.co/image/ab67616d00001e02d46bd7cfa437851c2eb13e86","uri":"spotify:track:2nOZaGwMYt07nXtIeYbEiu","trackType":"spotify","seek":0,"duration":172,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 17 15:09:03 volumio volumio[1117]: info: CoreCommandRouter::servicePushState Sep 17 15:09:03 volumio volumio[1117]: info: CorePlayQueue::getTrack 0 Sep 17 15:09:03 volumio volumio[1117]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Comportement","artist":"Aya Nakamura","album":"Journal intime","albumart":"https://i.scdn.co/image/ab67616d00001e02d46bd7cfa437851c2eb13e86","uri":"spotify:track:2nOZaGwMYt07nXtIeYbEiu","trackType":"spotify","seek":0,"duration":172,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 17 15:09:03 volumio volumio[1117]: verbose: CURRENT POSITION 0 Sep 17 15:09:03 volumio volumio[1117]: info: CoreStateMachine::syncState stateService play Sep 17 15:09:03 volumio volumio[1117]: info: CoreStateMachine::syncState currentStatus stop Sep 17 15:09:03 volumio volumio[1117]: SPOTIFY: PUSH STATE SPOTIFY Sep 17 15:09:03 volumio volumio[1117]: SPOTIFY: {"status":"play","service":"spop","title":"Comportement","artist":"Aya Nakamura","album":"Journal intime","albumart":"https://i.scdn.co/image/ab67616d00001e02d46bd7cfa437851c2eb13e86","uri":"spotify:track:2nOZaGwMYt07nXtIeYbEiu","trackType":"spotify","seek":0,"duration":172,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 17 15:09:03 volumio volumio[1117]: info: CoreCommandRouter::servicePushState Sep 17 15:09:03 volumio volumio[1117]: info: CorePlayQueue::getTrack 0 Sep 17 15:09:03 volumio volumio[1117]: verbose: STATE SERVICE {"status":"play","service":"spop","title":"Comportement","artist":"Aya Nakamura","album":"Journal intime","albumart":"https://i.scdn.co/image/ab67616d00001e02d46bd7cfa437851c2eb13e86","uri":"spotify:track:2nOZaGwMYt07nXtIeYbEiu","trackType":"spotify","seek":0,"duration":172,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Sep 17 15:09:03 volumio volumio[1117]: verbose: CURRENT POSITION 0 Sep 17 15:09:03 volumio volumio[1117]: info: CoreStateMachine::syncState stateService play Sep 17 15:09:03 volumio volumio[1117]: info: CoreStateMachine::syncState currentStatus play Sep 17 15:09:03 volumio volumio[1117]: info: Received an update from plugin. extracting info from payload Sep 17 15:09:03 volumio volumio[1117]: info: CoreStateMachine::pushState Sep 17 15:09:03 volumio volumio[1117]: info: CorePlayQueue::getTrack 0 Sep 17 15:09:03 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 17 15:09:03 volumio volumio[1117]: info: CoreCommandRouter::volumioPushState Sep 17 15:09:03 volumio volumio[1117]: info: CoreStateMachine::pushState Sep 17 15:09:03 volumio volumio[1117]: info: CorePlayQueue::getTrack 0 Sep 17 15:09:03 volumio volumio[1117]: info: CoreCommandRouter::volumioPushState Sep 17 15:09:03 volumio volumio[1117]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Sep 17 15:09:03 volumio volumio[1117]: SPOTIFY: RECEIVED VOLUMIO VOLUME 49 Sep 17 15:09:06 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 17 15:09:06 volumio volumio[1117]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Sep 17 15:09:06 volumio volumio[1117]: info: CoreCommandRouter::volumioGetState Sep 17 15:09:06 volumio volumio[1117]: info: CorePlayQueue::getTrack 0 Sep 17 15:09:07 volumio sudo[2176]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //Littlesoft.fr/music /mnt/NAS/littlsoft Sep 17 15:09:07 volumio sudo[2176]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 15:09:07 volumio kernel: CIFS: Attempting to mount //Littlesoft.fr/music Sep 17 15:09:08 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Sep 17 15:09:11 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 15:09:11 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 15:09:11 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 17 15:09:11 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 17 15:09:11 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 17 15:09:11 volumio volumio[1117]: info: CoreCommandRouter::volumioGetBrowseSources Sep 17 15:09:11 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 15:09:12 volumio volumio[1117]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 17 15:09:12 volumio volumio[1117]: info: Completed starting MyVolumio Plugin Sep 17 15:09:12 volumio volumio[1117]: [Metrics] CommandRouter: 39s 721.27ms Sep 17 15:09:12 volumio volumio[1117]: info: CoreCommandRouter::volumiosetStartupVolume Sep 17 15:09:12 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 17 15:09:12 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 15:09:12 volumio volumio[1117]: info: CoreCommandRouter::Close All Modals sent Sep 17 15:09:12 volumio volumio[1117]: info: CoreCommandRouter::Close All Modals sent Sep 17 15:09:12 volumio volumio[1117]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 17 15:09:12 volumio volumio[1117]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 17 15:09:12 volumio volumio[1117]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 17 15:09:12 volumio volumio[1117]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Sep 17 15:09:12 volumio volumio[1117]: info: FusionDsp - ---- read samplerate from file: 44100 Sep 17 15:09:12 volumio volumio[1117]: info: FusionDsp - If filter freq >samplerate/2 then disable it Sep 17 15:09:12 volumio volumio[1117]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 17 15:09:12 volumio volumio[1117]: info: FusionDsp - {"Reload":{"result":"Ok"}} Sep 17 15:09:12 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 15:09:12 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 17 15:09:12 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 17 15:09:12 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 17 15:09:12 volumio volumio-remote-updater[804]: No test mode Sep 17 15:09:12 volumio volumio-remote-updater[804]: No alpha test mode Sep 17 15:09:12 volumio volumio[1117]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 17 15:09:12 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 17 15:09:13 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 17 15:09:13 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 17 15:09:13 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 17 15:09:13 volumio go-librespot[1677]: time="2025-09-17T15:09:13+02:00" level=trace msg="sent dealer ping" Sep 17 15:09:13 volumio go-librespot[1677]: time="2025-09-17T15:09:13+02:00" level=trace msg="received dealer pong" Sep 17 15:09:17 volumio sudo[2176]: pam_unix(sudo:session): session closed for user root Sep 17 15:09:17 volumio volumio[1117]: info: Cannot mount NAS littlsoft at system boot, trial number 3 ,retrying in 5 seconds Sep 17 15:09:17 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 17 15:09:17 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Sep 17 15:09:18 volumio go-librespot[1677]: time="2025-09-17T15:09:18+02:00" level=debug msg="fetched chunk 4/13, size: 524288" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:09:19 volumio volumio[1117]: info: BOOT COMPLETED Sep 17 15:09:22 volumio sudo[2210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //Littlesoft.fr/music /mnt/NAS/littlsoft Sep 17 15:09:22 volumio sudo[2210]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 15:09:22 volumio kernel: CIFS: Attempting to mount //Littlesoft.fr/music Sep 17 15:09:32 volumio go-librespot[1677]: time="2025-09-17T15:09:32+02:00" level=debug msg="fetched chunk 5/13, size: 524288" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:09:33 volumio sudo[2210]: pam_unix(sudo:session): session closed for user root Sep 17 15:09:33 volumio volumio[1117]: info: Cannot mount NAS littlsoft at system boot, trial number 4 ,retrying in 5 seconds Sep 17 15:09:33 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 17 15:09:33 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Sep 17 15:09:38 volumio sudo[2246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //Littlesoft.fr/music /mnt/NAS/littlsoft Sep 17 15:09:38 volumio sudo[2246]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 15:09:38 volumio kernel: CIFS: Attempting to mount //Littlesoft.fr/music Sep 17 15:09:43 volumio go-librespot[1677]: time="2025-09-17T15:09:43+02:00" level=trace msg="sent dealer ping" Sep 17 15:09:43 volumio go-librespot[1677]: time="2025-09-17T15:09:43+02:00" level=trace msg="received dealer pong" Sep 17 15:09:45 volumio go-librespot[1677]: time="2025-09-17T15:09:45+02:00" level=debug msg="fetched chunk 6/13, size: 524288" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:09:48 volumio sudo[2246]: pam_unix(sudo:session): session closed for user root Sep 17 15:09:48 volumio volumio[1117]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Sep 17 15:09:48 volumio kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Sep 17 15:09:48 volumio kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Sep 17 15:09:58 volumio go-librespot[1677]: time="2025-09-17T15:09:58+02:00" level=debug msg="fetched chunk 7/13, size: 524288" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:10:04 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 17 15:10:04 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 17 15:10:08 volumio volumio[1117]: info: Enabling plugin mixcloud Sep 17 15:10:08 volumio volumio[1117]: info: Loading plugin "mixcloud"... Sep 17 15:10:08 volumio volumio[1117]: info: PLUGIN START: mixcloud Sep 17 15:10:08 volumio volumio[1117]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 17 15:10:08 volumio volumio[1117]: info: [1758114608813] CoreMusicLibrary::Adding element Mixcloud Sep 17 15:10:08 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 17 15:10:08 volumio volumio[1117]: Cannot find translation for source 80s80s Radio Sep 17 15:10:08 volumio volumio[1117]: Cannot find translation for source Spotify Sep 17 15:10:08 volumio volumio[1117]: Cannot find translation for source Mixcloud Sep 17 15:10:08 volumio volumio[1117]: info: Done. Sep 17 15:10:09 volumio volumio[1117]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 17 15:10:10 volumio go-librespot[1677]: time="2025-09-17T15:10:10+02:00" level=debug msg="fetched chunk 8/13, size: 524288" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:10:12 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 17 15:10:12 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 17 15:10:13 volumio go-librespot[1677]: time="2025-09-17T15:10:13+02:00" level=trace msg="sent dealer ping" Sep 17 15:10:13 volumio go-librespot[1677]: time="2025-09-17T15:10:13+02:00" level=trace msg="received dealer pong" Sep 17 15:10:19 volumio volumio[1117]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ferrum_streaming_control_technology/volumio/buster/amd64 Sep 17 15:10:20 volumio volumio[1117]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/ferrum_streaming_control_technology/volumio/buster/amd64 Sep 17 15:10:20 volumio volumio[1117]: info: Folder /tmp/plugins removed Sep 17 15:10:20 volumio volumio[1117]: info: Check plugin dependencies Sep 17 15:10:20 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 17 15:10:20 volumio volumio[1117]: info: Checking if plugin already exists Sep 17 15:10:20 volumio volumio[1117]: info: Rename folder Sep 17 15:10:20 volumio volumio[1117]: info: Folder /tmp/downloaded_plugin.zip removed Sep 17 15:10:20 volumio volumio[1117]: info: Move to category Sep 17 15:10:21 volumio volumio[1117]: info: Checking if install.sh is present Sep 17 15:10:21 volumio volumio[1117]: info: Executing install.sh Sep 17 15:10:21 volumio sudo[2374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/user_interface/ferrum_streaming_control_technology/install.sh Sep 17 15:10:21 volumio sudo[2374]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 17 15:10:21 volumio sudo[2374]: pam_unix(sudo:session): session closed for user root Sep 17 15:10:21 volumio volumio[1117]: info: Install script completed Sep 17 15:10:21 volumio volumio[1117]: info: Adding reference to registry Sep 17 15:10:21 volumio volumio[1117]: info: Done installing plugin. Sep 17 15:10:21 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 17 15:10:21 volumio volumio[1117]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 17 15:10:21 volumio volumio[1117]: info: Installing ferrum streaming control technology Dependencies Sep 17 15:10:21 volumio volumio[1117]: Plugin install end detected on script Sep 17 15:10:21 volumio volumio[1117]: info: Folder /tmp/plugins removed Sep 17 15:10:21 volumio volumio[1117]: info: Folder /tmp/downloaded_plugin.zip removed Sep 17 15:10:21 volumio volumio[1117]: info: Folder /data/temp removed Sep 17 15:10:22 volumio volumio[1117]: error: Failed to check for new versions for plugin Ferrum Streaming Control Technology: Error: Invalid argument not valid semver ('' received) Sep 17 15:10:22 volumio volumio[1117]: info: Enabling plugin ferrum_streaming_control_technology Sep 17 15:10:22 volumio volumio[1117]: info: Loading plugin "ferrum_streaming_control_technology"... Sep 17 15:10:22 volumio volumio[1117]: error: !!!! WARNING !!!! Sep 17 15:10:22 volumio volumio[1117]: error: The plugin user_interface/ferrum_streaming_control_technology failed to load, setting it to stopped. Error: Error: Cannot find module '@hemspzoo/fsct-lib-linux-x64-gnu' Sep 17 15:10:22 volumio volumio[1117]: Require stack: Sep 17 15:10:22 volumio volumio[1117]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Sep 17 15:10:22 volumio volumio[1117]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Sep 17 15:10:22 volumio volumio[1117]: - /volumio/app/pluginmanager.js Sep 17 15:10:22 volumio volumio[1117]: - /volumio/app/index.js Sep 17 15:10:22 volumio volumio[1117]: - /volumio/index.js Sep 17 15:10:22 volumio volumio[1117]: error: Stack trace: Error: Cannot find module '@hemspzoo/fsct-lib-linux-x64-gnu' Sep 17 15:10:22 volumio volumio[1117]: Require stack: Sep 17 15:10:22 volumio volumio[1117]: - /data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js Sep 17 15:10:22 volumio volumio[1117]: - /data/plugins/user_interface/ferrum_streaming_control_technology/index.js Sep 17 15:10:22 volumio volumio[1117]: - /volumio/app/pluginmanager.js Sep 17 15:10:22 volumio volumio[1117]: - /volumio/app/index.js Sep 17 15:10:22 volumio volumio[1117]: - /volumio/index.js Sep 17 15:10:22 volumio volumio[1117]: at Function.Module._resolveFilename (internal/modules/cjs/loader.js:931:15) Sep 17 15:10:22 volumio volumio[1117]: at Function.Module._load (internal/modules/cjs/loader.js:774:27) Sep 17 15:10:22 volumio volumio[1117]: at Module.require (internal/modules/cjs/loader.js:1003:19) Sep 17 15:10:22 volumio volumio[1117]: at require (internal/modules/cjs/helpers.js:107:18) Sep 17 15:10:22 volumio volumio[1117]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/node_modules/@hemspzoo/fsct-lib/index.js:190:31) Sep 17 15:10:22 volumio volumio[1117]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Sep 17 15:10:22 volumio volumio[1117]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Sep 17 15:10:22 volumio volumio[1117]: at Module.load (internal/modules/cjs/loader.js:979:32) Sep 17 15:10:22 volumio volumio[1117]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Sep 17 15:10:22 volumio volumio[1117]: at Module.require (internal/modules/cjs/loader.js:1003:19) Sep 17 15:10:22 volumio volumio[1117]: at require (internal/modules/cjs/helpers.js:107:18) Sep 17 15:10:22 volumio volumio[1117]: at Object. (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:25:118) Sep 17 15:10:22 volumio volumio[1117]: at Module._compile (internal/modules/cjs/loader.js:1114:14) Sep 17 15:10:22 volumio volumio[1117]: at Object.Module._extensions..js (internal/modules/cjs/loader.js:1143:10) Sep 17 15:10:22 volumio volumio[1117]: at Module.load (internal/modules/cjs/loader.js:979:32) Sep 17 15:10:22 volumio volumio[1117]: at Function.Module._load (internal/modules/cjs/loader.js:819:12) Sep 17 15:10:22 volumio volumio[1117]: error: !!!! WARNING !!!! Sep 17 15:10:22 volumio volumio[1117]: info: Done. Sep 17 15:10:23 volumio go-librespot[1677]: time="2025-09-17T15:10:23+02:00" level=debug msg="fetched chunk 9/13, size: 524288" uri="spotify:track:2nOZaGwMYt07nXtIeYbEiu" Sep 17 15:10:30 volumio volumio[1117]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/now_playing/volumio/buster/amd64 Sep 17 15:10:30 volumio volumio[1117]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 15:10:30 volumio volumio[1117]: TypeError: Cannot read property 'broadcastMessage' of null Sep 17 15:10:30 volumio volumio[1117]: at /volumio/app/index.js:1534:32 Sep 17 15:10:30 volumio volumio[1117]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Sep 17 15:10:30 volumio volumio[1117]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Sep 17 15:10:30 volumio volumio[1117]: at CoreCommandRouter.broadcastMessage (/volumio/app/index.js:1532:13) Sep 17 15:10:30 volumio volumio[1117]: at PluginManager.pushMessage (/volumio/app/pluginmanager.js:1594:20) Sep 17 15:10:30 volumio volumio[1117]: at PluginManager.installPlugin (/volumio/app/pluginmanager.js:776:8) Sep 17 15:10:30 volumio volumio[1117]: at CoreCommandRouter.installPlugin (/volumio/app/index.js:1453:22) Sep 17 15:10:30 volumio volumio[1117]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1209:47) Sep 17 15:10:30 volumio volumio[1117]: at Socket.emit (events.js:400:28) Sep 17 15:10:30 volumio volumio[1117]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Sep 17 15:10:30 volumio volumio[1117]: at processTicksAndRejections (internal/process/task_queues.js:77:11) Sep 17 15:10:30 volumio volumio[1117]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 17 15:10:30 volumio sudo[2399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-17 15:09 Sep 17 15:10:30 volumio sudo[2399]: 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="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"