-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-05-31 12:33:45 CEST. -- May 31 12:33:21 hifiberry systemd-timedated[1039]: Changed local time to Sat May 31 12:33:21 2025 May 31 12:33:21 hifiberry sudo[1037]: pam_unix(sudo:session): session closed for user root May 31 12:33:21 hifiberry volumio-time-update[606]: volumio-time-update-util: System time updated successfully. May 31 12:33:21 hifiberry systemd[1]: Starting Daily apt download activities... May 31 12:33:21 hifiberry systemd[1]: Started Volumio Time Update Utility. May 31 12:33:21 hifiberry wireless.js[617]: trying... May 31 12:33:21 hifiberry sudo[1056]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 31 12:33:21 hifiberry sudo[1056]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:21 hifiberry sudo[1056]: pam_unix(sudo:session): session closed for user root May 31 12:33:21 hifiberry dhcpcd[1006]: wlan0: leased 192.168.0.15 for 86400 seconds May 31 12:33:21 hifiberry avahi-daemon[598]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.0.15. May 31 12:33:21 hifiberry avahi-daemon[598]: New relevant interface wlan0.IPv4 for mDNS. May 31 12:33:21 hifiberry dhcpcd[1006]: wlan0: adding route to 192.168.0.0/24 May 31 12:33:21 hifiberry avahi-daemon[598]: Registering new address record for 192.168.0.15 on wlan0.IPv4. May 31 12:33:21 hifiberry dhcpcd[1006]: wlan0: adding default route via 192.168.0.1 May 31 12:33:21 hifiberry dhcpcd[1006]: wlan0: dhcp_envoption 81.0/3: Operation not supported May 31 12:33:22 hifiberry wireless.js[617]: trying... May 31 12:33:22 hifiberry sudo[1086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r May 31 12:33:22 hifiberry sudo[1086]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:22 hifiberry sudo[1086]: pam_unix(sudo:session): session closed for user root May 31 12:33:22 hifiberry wireless.js[617]: Connected to: ----draadlos May 31 12:33:22 hifiberry wireless.js[617]: ---- May 31 12:33:22 hifiberry sudo[1089]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 12:33:22 hifiberry sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:22 hifiberry sudo[1089]: pam_unix(sudo:session): session closed for user root May 31 12:33:22 hifiberry wireless.js[617]: ... joined AP, wlan0 IPv4 is 192.168.0.15, ipV6 is undefined May 31 12:33:22 hifiberry wireless.js[617]: It's done! AP May 31 12:33:22 hifiberry systemd[1]: Started Wireless Services. May 31 12:33:22 hifiberry kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled May 31 12:33:22 hifiberry systemd[1]: Started Volumio Backend Module. May 31 12:33:22 hifiberry systemd[1]: Started Volumio Cpu Tweaker. May 31 12:33:22 hifiberry systemd[1]: Reached target Multi-User System. May 31 12:33:22 hifiberry systemd[1]: Reached target Graphical Interface. May 31 12:33:22 hifiberry systemd[1]: Starting Update UTMP about System Runlevel Changes... May 31 12:33:22 hifiberry systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. May 31 12:33:22 hifiberry systemd[1]: Started Update UTMP about System Runlevel Changes. May 31 12:33:22 hifiberry volumio-cpu-tweak[1095]: Setting RT Priority for mpd May 31 12:33:22 hifiberry volumio-cpu-tweak[1095]: Setting MPD Affinity May 31 12:33:22 hifiberry volumio-cpu-tweak[1095]: pid 807's current affinity mask: f May 31 12:33:22 hifiberry volumio-cpu-tweak[1095]: pid 807's new affinity mask: 3 May 31 12:33:22 hifiberry volumio-cpu-tweak[1095]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input May 31 12:33:22 hifiberry volumio-cpu-tweak[1095]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance May 31 12:33:22 hifiberry systemd[1]: volumio_cpu_tweak.service: Succeeded. May 31 12:33:22 hifiberry ntpd[754]: Listen normally on 4 wlan0 192.168.0.15:123 May 31 12:33:22 hifiberry ntpd[754]: new interface(s) found: waking up resolver May 31 12:33:23 hifiberry volumio-remote-updater[602]: [2025-05-31 12:33:23] [info] asio async_connect error: system:111 (Connection refused) May 31 12:33:23 hifiberry volumio-remote-updater[602]: [2025-05-31 12:33:23] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) May 31 12:33:23 hifiberry volumio-remote-updater[602]: [2025-05-31 12:33:23] [error] handle_connect error: Underlying Transport Error May 31 12:33:24 hifiberry systemd[1]: apt-daily.service: Succeeded. May 31 12:33:24 hifiberry systemd[1]: Started Daily apt download activities. May 31 12:33:24 hifiberry systemd[1]: Starting Daily apt upgrade and clean activities... May 31 12:33:25 hifiberry volumio[1094]: info: ------------------------------------------- May 31 12:33:25 hifiberry volumio[1094]: info: ----- Volumio3 ---- May 31 12:33:25 hifiberry volumio[1094]: info: ------------------------------------------- May 31 12:33:25 hifiberry volumio[1094]: info: ----- System startup ---- May 31 12:33:25 hifiberry volumio[1094]: info: ------------------------------------------- May 31 12:33:26 hifiberry systemd[1]: apt-daily-upgrade.service: Succeeded. May 31 12:33:26 hifiberry systemd[1]: Started Daily apt upgrade and clean activities. May 31 12:33:26 hifiberry systemd[1]: Startup finished in 13.481s (kernel) + 21.835s (userspace) = 35.316s. May 31 12:33:26 hifiberry volumio[1094]: info: MYVOLUMIO Environment detected May 31 12:33:27 hifiberry volumio[1094]: info: Plugin folders cleanup May 31 12:33:27 hifiberry volumio[1094]: info: Scanning into folder /volumio/app/plugins/ May 31 12:33:27 hifiberry volumio[1094]: info: Scanning category audio_interface May 31 12:33:27 hifiberry volumio[1094]: info: Scanning category miscellanea May 31 12:33:27 hifiberry volumio[1094]: info: Scanning category music_service May 31 12:33:27 hifiberry volumio[1094]: info: Scanning category plugins.json May 31 12:33:27 hifiberry volumio[1094]: info: Scanning category system_controller May 31 12:33:27 hifiberry volumio[1094]: info: Scanning category user_interface May 31 12:33:27 hifiberry volumio[1094]: info: Scanning into folder /data/plugins/ May 31 12:33:27 hifiberry volumio[1094]: info: Scanning category music_service May 31 12:33:27 hifiberry volumio[1094]: info: Scanning category system_hardware May 31 12:33:27 hifiberry volumio[1094]: info: Plugin folders cleanup completed May 31 12:33:27 hifiberry volumio[1094]: info: ------------------------------------------- May 31 12:33:27 hifiberry volumio[1094]: info: ----- Core plugins startup ---- May 31 12:33:27 hifiberry volumio[1094]: info: ------------------------------------------- May 31 12:33:27 hifiberry volumio[1094]: info: Loading plugins from folder /volumio/app/plugins/ May 31 12:33:27 hifiberry volumio[1094]: info: Adding plugin upnp to MyMusic Plugins May 31 12:33:27 hifiberry volumio[1094]: info: Adding plugin airplay_emulation to MyMusic Plugins May 31 12:33:27 hifiberry volumio[1094]: info: Adding plugin upnp_browser to MyMusic Plugins May 31 12:33:27 hifiberry volumio[1094]: info: Loading plugins from folder /data/plugins/ May 31 12:33:27 hifiberry volumio[1094]: info: Loading plugin "system"... May 31 12:33:27 hifiberry volumio[1094]: info: Loading plugin "appearance"... May 31 12:33:28 hifiberry volumio[1094]: info: Loading plugin "network"... May 31 12:33:28 hifiberry volumio[1094]: info: Refreshing Cached IP Addresses May 31 12:33:28 hifiberry sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 12:33:28 hifiberry sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:28 hifiberry sudo[1203]: pam_unix(sudo:session): session closed for user root May 31 12:33:28 hifiberry sudo[1205]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 12:33:28 hifiberry sudo[1205]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:28 hifiberry sudo[1205]: pam_unix(sudo:session): session closed for user root May 31 12:33:28 hifiberry volumio[1094]: info: Loading plugin "services"... May 31 12:33:28 hifiberry volumio[1094]: info: Loading plugin "alsa_controller"... May 31 12:33:28 hifiberry sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan May 31 12:33:28 hifiberry sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:28 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 12:33:28 hifiberry volumio[1094]: info: Loading plugin "wizard"... May 31 12:33:28 hifiberry volumio[1094]: info: Loading plugin "networkfs"... May 31 12:33:29 hifiberry volumio[1094]: info: Starting Udev Watcher for removable devices May 31 12:33:29 hifiberry sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=hifiberry,password=hifiberry,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.0.73/Music /mnt/NAS/Music May 31 12:33:29 hifiberry sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:29 hifiberry volumio[1094]: info: Ignoring mount for partition: boot May 31 12:33:29 hifiberry volumio[1094]: info: Ignoring mount for partition: volumio May 31 12:33:29 hifiberry volumio[1094]: info: Ignoring mount for partition: volumio_data May 31 12:33:29 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 12:33:29 hifiberry volumio[1094]: info: Loading plugin "volumio_command_line_client"... May 31 12:33:29 hifiberry volumio[1094]: info: Loading plugin "upnp"... May 31 12:33:29 hifiberry volumio[1094]: info: [1748687609079] Starting Upmpd Daemon May 31 12:33:29 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 12:33:29 hifiberry volumio[1094]: info: Loading plugin "my_music"... May 31 12:33:29 hifiberry volumio[1094]: info: Loading plugin "mpd"... May 31 12:33:29 hifiberry kernel: Key type cifs.spnego registered May 31 12:33:29 hifiberry kernel: Key type cifs.idmap registered May 31 12:33:29 hifiberry 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. May 31 12:33:29 hifiberry kernel: CIFS: Attempting to mount //192.168.0.73/Music May 31 12:33:29 hifiberry kernel: cryptd: max_cpu_qlen set to 1000 May 31 12:33:29 hifiberry sudo[1229]: pam_unix(sudo:session): session closed for user root May 31 12:33:29 hifiberry sudo[1218]: pam_unix(sudo:session): session closed for user root May 31 12:33:29 hifiberry volumio[1094]: info: Loading plugin "upnp_browser"... May 31 12:33:30 hifiberry volumio[1094]: info: Loading plugin "alarm-clock"... May 31 12:33:30 hifiberry volumio[1094]: info: Loading plugin "airplay_emulation"... May 31 12:33:30 hifiberry volumio[1094]: info: Starting Shairport Sync May 31 12:33:30 hifiberry volumio[1094]: info: Loading plugin "last_100"... May 31 12:33:30 hifiberry volumio[1094]: info: Loading plugin "webradio"... May 31 12:33:30 hifiberry volumio[1094]: info: Loading plugin "i2s_dacs"... May 31 12:33:30 hifiberry volumio[1094]: info: Loading plugin "volumiodiscovery"... May 31 12:33:30 hifiberry volumio[1094]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 12:33:30 hifiberry volumio[1094]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 12:33:30 hifiberry volumio[1094]: *** WARNING *** For more information see May 31 12:33:30 hifiberry volumio[1094]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 12:33:30 hifiberry node[1094]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 31 12:33:30 hifiberry volumio[1094]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 12:33:30 hifiberry volumio[1094]: *** WARNING *** For more information see May 31 12:33:30 hifiberry node[1094]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 12:33:30 hifiberry node[1094]: *** WARNING *** For more information see May 31 12:33:30 hifiberry node[1094]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 31 12:33:30 hifiberry node[1094]: *** WARNING *** Please fix your application to use the native API of Avahi! May 31 12:33:30 hifiberry node[1094]: *** WARNING *** For more information see May 31 12:33:30 hifiberry volumio[1094]: info: Applying required configuration parameters for plugin volumiodiscovery May 31 12:33:30 hifiberry volumio[1094]: info: Discovery: Started advertising with name: hifiberry May 31 12:33:30 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 12:33:30 hifiberry volumio[1094]: info: Loading plugin "jellyfin"... May 31 12:33:32 hifiberry volumio[1094]: info: Loading plugin "spop"... May 31 12:33:33 hifiberry volumio-remote-updater[602]: [2025-05-31 12:33:33] [connect] Successful connection May 31 12:33:33 hifiberry volumio[1094]: info: Loading plugin "outputs"... May 31 12:33:33 hifiberry volumio[1094]: info: Loading plugin "albumart"... May 31 12:33:33 hifiberry volumio[1094]: info: Plugin example_plugin is not enabled May 31 12:33:33 hifiberry volumio[1094]: info: Loading plugin "inputs"... May 31 12:33:33 hifiberry volumio[1094]: info: Loading plugin "updater_comm"... May 31 12:33:34 hifiberry volumio[1094]: info: Plugin mpdemulation is not enabled May 31 12:33:34 hifiberry volumio[1094]: info: Loading plugin "rest_api"... May 31 12:33:34 hifiberry volumio[1094]: info: Loading plugin "websocket"... May 31 12:33:34 hifiberry volumio[1094]: info: Starting Socket.io Server version 2.3.0 May 31 12:33:34 hifiberry volumio[1094]: info: Loading plugin "serialampcontroller"... May 31 12:33:34 hifiberry volumio[1094]: Forking 3 albumart workers May 31 12:33:35 hifiberry volumio[1094]: info: Applying required configuration parameters for plugin serialampcontroller May 31 12:33:35 hifiberry volumio[1094]: info: Applying required configuration parameters for plugin serialampcontroller May 31 12:33:35 hifiberry volumio[1094]: info: Loading i18n strings for locale nl May 31 12:33:35 hifiberry volumio[1094]: Updating browse sources language May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:33:35 hifiberry volumio[1094]: Starting albumart workers May 31 12:33:35 hifiberry volumio[1094]: Starting albumart workers May 31 12:33:35 hifiberry volumio[1094]: Starting albumart workers May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::initPlayerControls May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 12:33:35 hifiberry volumio[1094]: Express server listening on port 3000 May 31 12:33:35 hifiberry volumio[1094]: [Metrics] WebUI: 11s 14.25ms May 31 12:33:35 hifiberry volumio[1094]: info: CoreStateMachine::resetVolumioState May 31 12:33:35 hifiberry volumio[1094]: info: CoreStateMachine::getcurrentVolume May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::volumioRetrievevolume May 31 12:33:35 hifiberry volumio[1094]: info: CoreStateMachine::pushState May 31 12:33:35 hifiberry volumio[1094]: info: CorePlayQueue::getTrack 0 May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::volumioPushState May 31 12:33:35 hifiberry volumio[1094]: info: Volumio Network Manager: Network status updated: 3 May 31 12:33:35 hifiberry volumio-remote-updater[602]: [2025-05-31 12:33:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748687613 101 May 31 12:33:35 hifiberry volumio[1094]: 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 May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::volumioGetState May 31 12:33:35 hifiberry volumio[1094]: info: CorePlayQueue::getTrack 0 May 31 12:33:35 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:33:36 hifiberry volumio[1094]: info: Reloading queue from file May 31 12:33:36 hifiberry volumio[1094]: info: Setting Device type: Raspberry PI May 31 12:33:36 hifiberry volumio[1094]: info: CoreStateMachine::setRepeat null single undefined May 31 12:33:36 hifiberry volumio[1094]: info: CoreStateMachine::pushState May 31 12:33:36 hifiberry volumio[1094]: info: CorePlayQueue::getTrack 0 May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::volumioPushState May 31 12:33:36 hifiberry volumio[1094]: info: CoreStateMachine::setRandom null May 31 12:33:36 hifiberry volumio[1094]: info: CoreStateMachine::pushState May 31 12:33:36 hifiberry volumio[1094]: info: CorePlayQueue::getTrack 0 May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::volumioPushState May 31 12:33:36 hifiberry volumio[1094]: info: Completed loading Core Plugins May 31 12:33:36 hifiberry volumio[1094]: info: Preparing to generate the ALSA configuration file May 31 12:33:36 hifiberry volumio[1094]: info: Asound.conf file unchanged, so no further update is needed May 31 12:33:36 hifiberry volumio[1094]: info: Output device has changed, restarting MPD May 31 12:33:36 hifiberry volumio[1094]: info: Output device has changed, restarting Shairport Sync May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:33:36 hifiberry sudo[1333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 12:33:36 hifiberry sudo[1333]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:36 hifiberry sudo[1333]: pam_unix(sudo:session): session closed for user root May 31 12:33:36 hifiberry sudo[1335]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 12:33:36 hifiberry sudo[1335]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:36 hifiberry volumio[1094]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 12:33:36 hifiberry volumio[1094]: info: ___________ START PLUGINS ___________ May 31 12:33:36 hifiberry systemd[1]: Stopping Music Player Daemon... May 31 12:33:36 hifiberry volumio[1094]: info: ControllerMpd::onStart: Initializing MPD May 31 12:33:36 hifiberry volumio[1094]: info: Creating MPD Configuration file May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 12:33:36 hifiberry sudo[1342]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 31 12:33:36 hifiberry volumio[1094]: info: [1748687616377] CoreMusicLibrary::Adding element Media Servers May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:33:36 hifiberry sudo[1342]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:36 hifiberry sudo[1342]: pam_unix(sudo:session): session closed for user root May 31 12:33:36 hifiberry sudo[1344]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 31 12:33:36 hifiberry sudo[1344]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:33:36 hifiberry volumio[1094]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:33:36 hifiberry volumio[1094]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 12:33:36 hifiberry volumio[1094]: info: [1748687616515] CoreMusicLibrary::Adding element Last_100 May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 12:33:36 hifiberry volumio[1094]: info: [1748687616520] CoreMusicLibrary::Adding element Webradio May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 12:33:36 hifiberry volumio[1094]: info: Initializing BBC Radios May 31 12:33:36 hifiberry systemd[1]: mpd.service: Succeeded. May 31 12:33:36 hifiberry systemd[1]: Stopped Music Player Daemon. May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 31 12:33:36 hifiberry volumio[1094]: info: Discovery: Getting this device information May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::volumioGetState May 31 12:33:36 hifiberry volumio[1094]: info: CorePlayQueue::getTrack 0 May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 12:33:36 hifiberry volumio[1094]: info: [1748687616684] CoreMusicLibrary::Adding element Jellyfin May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:33:36 hifiberry volumio[1094]: Cannot find translation for source Jellyfin May 31 12:33:36 hifiberry volumio[1094]: info: [jellyfin] Initialized plugin with device info: {"id":"3cd3f306-394a-440c-aab3-cad8fdfa19ef","host":"http://192.168.0.225","name":"hifiberry","type":"device","serviceName":"Volumio","state":{"status":"stop","volume":100,"mute":false,"artist":"Dire Straits","track":"Dire Straits - Heavy Fuel.mp3","albumart":"http://192.168.0.225/albumart?cacheid=270&web=Dire%20Straits//extralarge&path=%2Fmnt%2FNAS%2FMusic%2FDire%20Straits&metadata=false"}} May 31 12:33:36 hifiberry volumio[1094]: info: Creating Spotify config file May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:36 hifiberry systemd[1]: Starting Music Player Daemon... May 31 12:33:36 hifiberry volumio[1094]: info: [SERIALAMPCONTROLLER] loadI18nStrings: /data/plugins/system_hardware/serialampcontroller/i18n/strings_nl.json May 31 12:33:36 hifiberry volumio[1094]: info: [SERIALAMPCONTROLLER] loadI18nStrings: nl not found. Fallback to en May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:36 hifiberry volumio[1094]: info: [SERIALAMPCONTROLLER] onStart: previous ALSA config: "2" May 31 12:33:36 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards May 31 12:33:36 hifiberry sudo[1363]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 31 12:33:36 hifiberry sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:36 hifiberry sudo[1363]: pam_unix(sudo:session): session closed for user root May 31 12:33:36 hifiberry volumio[1094]: info: [SERIALAMPCONTROLLER] onStart: previous ALSA cards: [{"id":"0","alsacard":"b1","name":"HDMI Out"},{"id":"1","alsacard":"Headphones","name":"Headphones"},{"id":"2","alsacard":"sndrpihifiberry","name":"snd_rpi_hifiberry_digi"}] May 31 12:33:36 hifiberry volumio[1094]: info: [SERIALAMPCONTROLLER] loadAmpDefinitions: loaded AmpDefinitions: {"syncSave":true,"autosave":true,"autosaveDelay":1000,"saved":true,"atomicSave":false,"data":{"amps":[{"vendor":"Rotel","model":"A12","baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Coax1","Coax2","Opt1","Opt2","Aux1","Aux2","Tuner","Phono","USB","Bluetooth","PC-USB"],"sourceCmd":["cd!","coax1!","coax2!","opt1!","opt2!","aux1!","aux2!","tuner!","phono!","usb!","bluetooth!","pcusb!"],"sourceRespPostfix":["cd","coax1","coax2","opt1","opt2","aux1","aux2","tuner","phono","usb","bluetooth","pc_usb"],"delimiter":"$","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"vol_up!","volDown":"vol_dwn!","volValue":"vol_##!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"power?","reqSource":"source?","reqVolume":"volume?","reqMute":"mute?","reqModel":"model?"}},{"vendor":"Rotel","model":"A14","baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Coax1","Coax2","Opt1","Opt2","Aux1","Aux2","Tuner","Phono","USB","Bluetooth","PC-USB"],"sourceCmd":["cd!","coax1!","coax2!","opt1!","opt2!","aux1!","aux2!","tuner!","phono!","usb!","bluetooth!","pcusb!"],"sourceRespPostfix":["cd","coax1","coax2","opt1","opt2","aux1","aux2","tuner","phono","usb","bluetooth","pc_usb"],"delimiter":"$","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"vol_up!","volDown":"vol_dwn!","volValue":"vol_##!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"power?","reqSource":"source?","reqVolume":"volume?","reqMute":"mute?","reqModel":"model?"}},{"vendor":"Rotel","model":"A11","baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Aux1","Tuner","Phono","Bluetooth"],"sourceCmd":["cd!","aux1!","tuner!","phono!","bluetooth!"],"sourceRespPostfix":["cd","aux1","tuner","phono","bluetooth"],"delimiter":"$","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"vol_up!","volDown":"vol_dwn!","volValue":"vol_##!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"power?","reqSource":"source?","reqVolume":"volume?","reqMute":"mute?","reqModel":"model?"}},{"vendor":"Rotel","model":"RC-1570","baudRate":115200,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Coax1","Coax2","Opt1","Opt2","Aux1","Aux2","Tuner","Phono","USB","PC-USB","XLR"],"sourceCmd":["cd!","coax1!","coax2!","opt1!","opt2!","aux1!","aux2!","tuner!","phono!","usb!","pc_usb!","bal_xlr!"],"sourceRespPostfix":["cd","coax1","coax2","opt1","opt2","aux1","aux2","tuner","phono","usb","pc_usb","bal_xlr"],"delimiter":"!","commands":{"powerOn":"power_on!","powerToggle":"power_toggle!","volUp":"volume_up!","volDown":"volume_down!","volValue":"volume_#!","mute":"mute!","muteOn":"mute_on!","muteOff":"mute_off!"},"responses":[{"rx":"power=on","cmd":["respPowerOn"]},{"rx":"power=standby","cmd":["respPowerOff"]},{"rx":"volume=([0-9]{1,2})","cmd":["respVolume"]},{"rx":"mute=on","cmd":["respMuteOn"]},{"rx":"mute=off","cmd":["respMuteOff"]},{"rx":"source=([a-z_12]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"get_current_power!","reqSource":"get_current_source!","reqVolume":"get_volume!","reqMute":"get_mute_status!","reqModel":""}},{"vendor":"Meridian","model":"DSP420","baudRate":9600,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["CD","Radio","LP","TV","Tape 1","Tape 2","CDR","Cable","DVD","VCR 1","VCR 2","Laser Disc"],"sourceCmd":["CD","RD","LP","TV","T1","T2","CR","CB","DV","V1","V2","LD"],"sourceRespPostfix":["CD","Radio","LP","TV","Tape1","Tape2","CDR","Cable","DVD","VCR1","VCR2","LDisc"],"delimiter":"\r\n","commands":{"powerToggle":"SB","volUp":"VP","volDown":"VM","volValue":"VN##!","mute":"MU"},"responses":[{"rx":"([A-Za-z0-9]{2,5}) *([0-9]{1,2})","cmd":["respSource","respVolume","respMuteOff"]},{"rx":"Mute","cmd":["respMuteOn"]},{"rx":"Standby","cmd":["respPowerOff"]}],"statusRequests":{}},{"vendor":"Cambridge","model":"CXA61","baudRate":9600,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["A1","A2","A3","A4","D1","D2","D3","MP3","Bluetooth","USB"],"sourceCmd":["#3,04,00","#3,04,01","#3,04,02","#3,04,03","#3,04,04","#3,04,05","#3,04,06","#3,04,10","#3,04,14","#3,04,16"],"sourceRespPostfix":["00","01","02","03","04","05","06","10","14","16"],"delimiter":"\r","commands":{"powerOn":"#01,02,01","muteOn":"#1,04,01","muteOff":"#1,04,00"},"responses":[{"rx":"#02,01,01","cmd":["respPowerOn"]},{"rx":"#02,01,00","cmd":["respPowerOff"]},{"rx":"#02,03,01","cmd":["respMuteOn"]},{"rx":"#02,03,00","cmd":["respMuteOff"]},{"rx":"#04,01,([0-9]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"#01,01","reqSource":"#03,01","reqVolume":"","reqMute":"#01,03","reqModel":"#13,02"}},{"vendor":"Marantz","model":"SR5004","baudRate":9600,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["Spotify","TV"],"sourceCmd":["@SRC:2","@SRC:1"],"sourceRespPostfix":["2","1"],"delimiter":"\r","commands":{"powerOn":"@PWR:2","powerOff":"@PWR:3","powerToggle":"@PWR:1","volUp":"@VOL:1","volDown":"@VOL:2","volValue":"@VOL:0##","muteOn":"@AMT:2","muteOff":"@AMT:1"},"responses":[{"rx":"@PWR:1","cmd":["respPowerOn"]},{"rx":"@PWR:2","cmd":["respPowerOff"]},{"rx":"@VOL:0([0-9]{2})","cmd":["respVolume"]},{"rx":"@AMT:1","cmd":["respMuteOn"]},{"rx":"@AMT:2","cmd":["respMuteOff"]},{"rx":"@SRC:([0-9]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"@PWR:?","reqSource":"@SRC:?","reqVolume":"@VOL:?","reqMute":"@AMT:?","reqModel":""}}]},"callbacks":{"_":{}},"filePath":"/data/configuration/system_hardware/serialampcontroller/ampCommands.json"} May 31 12:33:36 hifiberry volumio[1094]: info: [SERIALAMPCONTROLLER] loadAmpDefinitions: loaded AmpDefinitions for 7 Amplifiers. May 31 12:33:36 hifiberry volumio[1094]: info: Volumio Calling Home May 31 12:33:37 hifiberry volumio[1094]: info: Discovery: adding 3cd3f306-394a-440c-aab3-cad8fdfa19ef May 31 12:33:37 hifiberry volumio[1094]: info: Discovery: Found device hifiberry May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::volumioGetState May 31 12:33:37 hifiberry volumio[1094]: info: CorePlayQueue::getTrack 0 May 31 12:33:37 hifiberry volumio[1094]: info: MPD Permissions set May 31 12:33:37 hifiberry volumio[1094]: info: MPD Permissions set May 31 12:33:37 hifiberry volumio[1094]: info: Discovery: this is already registered, 3cd3f306-394a-440c-aab3-cad8fdfa19ef May 31 12:33:37 hifiberry volumio[1094]: info: Discovery: Found device hifiberry May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::volumioGetState May 31 12:33:37 hifiberry volumio[1094]: info: CorePlayQueue::getTrack 0 May 31 12:33:37 hifiberry volumio[1094]: info: [jellyfin-poller] Polled http://192.168.0.74:8096: online May 31 12:33:37 hifiberry volumio[1094]: info: Volumio called home May 31 12:33:37 hifiberry volumio[1094]: info: Spotify config file written May 31 12:33:37 hifiberry sudo[1373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service May 31 12:33:37 hifiberry sudo[1373]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:37 hifiberry systemd[1]: Started go-librespot Daemon. May 31 12:33:37 hifiberry go-librespot[1375]: Librespot-go daemon starting... May 31 12:33:37 hifiberry sudo[1373]: pam_unix(sudo:session): session closed for user root May 31 12:33:37 hifiberry volumio[1094]: info: [SERIALAMPCONTROLLER] listSerialDevices: [] May 31 12:33:37 hifiberry volumio[1094]: info: [SERIALAMPCONTROLLER] listSerialDevices: found 0 devices.[] May 31 12:33:37 hifiberry volumio[1094]: info: [SERIALAMPCONTROLLER] setActiveAmp: {"vendor":"Marantz","model":"SR5004","baudRate":9600,"parity":"none","dataBits":8,"stopBits":1,"rtscts":false,"xon":false,"xoff":false,"xany":false,"sources":["Spotify","TV"],"sourceCmd":["@SRC:2","@SRC:1"],"sourceRespPostfix":["2","1"],"delimiter":"\r","commands":{"powerOn":"@PWR:2","powerOff":"@PWR:3","powerToggle":"@PWR:1","volUp":"@VOL:1","volDown":"@VOL:2","volValue":"@VOL:0##","muteOn":"@AMT:2","muteOff":"@AMT:1"},"responses":[{"rx":"@PWR:1","cmd":["respPowerOn"]},{"rx":"@PWR:2","cmd":["respPowerOff"]},{"rx":"@VOL:0([0-9]{2})","cmd":["respVolume"]},{"rx":"@AMT:1","cmd":["respMuteOn"]},{"rx":"@AMT:2","cmd":["respMuteOff"]},{"rx":"@SRC:([0-9]*)","cmd":["respSource"]}],"statusRequests":{"reqPower":"@PWR:?","reqSource":"@SRC:?","reqVolume":"@VOL:?","reqMute":"@AMT:?","reqModel":""}} May 31 12:33:37 hifiberry volumio[1094]: info: [SERIALAMPCONTROLLER] setActiveAmp: can send these responses: respPowerOn,respPowerOff,respVolume,respMuteOn,respMuteOff,respSource. May 31 12:33:37 hifiberry volumio[1094]: info: [SERIALAMPCONTROLLER] openSerialPort: starting May 31 12:33:37 hifiberry volumio[1094]: info: [SERIALAMPCONTROLLER] openSerialPort: check for usb-1a86_USB2.0-Ser_-if00-port0 in [] May 31 12:33:37 hifiberry volumio[1094]: info: [SERIALAMPCONTROLLER] openSerialPort: connect to [] configured with: {"autoOpen":false,"lock":true,"baudRate":9600,"dataBits":8,"stopBits":1,"parity":"none","rtscts":false,"xon":false,"xoff":false,"xany":false} May 31 12:33:37 hifiberry volumio[1094]: error: [SERIALAMPCONTROLLER] onStart: FAILED to start plugin: TypeError: Cannot read property 'path' of undefined May 31 12:33:37 hifiberry volumio[1094]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 31 12:33:37 hifiberry volumio[1094]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 31 12:33:37 hifiberry volumio[1094]: info: No need to fix Spotify hosts May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 31 12:33:37 hifiberry volumio[1094]: info: Starting Shairport Sync May 31 12:33:37 hifiberry volumio[1094]: info: Starting Shairport Sync May 31 12:33:37 hifiberry volumio[1094]: info: Starting Shairport Sync May 31 12:33:37 hifiberry sudo[1391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 12:33:37 hifiberry sudo[1391]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:37 hifiberry sudo[1393]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 12:33:37 hifiberry sudo[1393]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:37 hifiberry volumio[1094]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 31 12:33:37 hifiberry volumio[1094]: SPOTIFY: BQDNLHne15cbzu8rXDMKPfJAs2MOIlOCIXwegM5ImKOELmGkBpUIAa1cNn1T_iy7cg-JjbquHEYqTTDpkqLx2NDaDkVxu6YXYuQ3TjTqeAeNqE7ycBBWkjS07UtYVugrNI1855UOtmGzPTWD74BMnVsKkcQA58c9ikZ4FkQGcZXJtT-01Qy8QZGW_hf8oilDXWyVTqf2WBYIFydCKbmlsoBW9rSWmEeCJ1wFwyuQ3NVwEu6wGnrRNP6P9COYOhtV1FS92TgTmE2ga7tnM07myYu2Tcr- May 31 12:33:37 hifiberry volumio[1094]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ May 31 12:33:37 hifiberry systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 12:33:37 hifiberry volumio[1094]: info: New Spotify access token = BQDNLHne15cbzu8rXDMKPfJAs2MOIlOCIXwegM5ImKOELmGkBpUIAa1cNn1T_iy7cg-JjbquHEYqTTDpkqLx2NDaDkVxu6YXYuQ3TjTqeAeNqE7ycBBWkjS07UtYVugrNI1855UOtmGzPTWD74BMnVsKkcQA58c9ikZ4FkQGcZXJtT-01Qy8QZGW_hf8oilDXWyVTqf2WBYIFydCKbmlsoBW9rSWmEeCJ1wFwyuQ3NVwEu6wGnrRNP6P9COYOhtV1FS92TgTmE2ga7tnM07myYu2Tcr- May 31 12:33:37 hifiberry volumio[1094]: info: Spotify credentials grant success - running version from March 24, 2019 May 31 12:33:37 hifiberry sudo[1396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 31 12:33:37 hifiberry sudo[1396]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:37 hifiberry systemd[1]: shairport-sync.service: Succeeded. May 31 12:33:37 hifiberry systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 12:33:37 hifiberry go-librespot[1375]: time="2025-05-31T12:33:37+02:00" level=info msg="generated new device id: 8b42f9bde02e20baa8891b5627c8cec3123881ba" May 31 12:33:37 hifiberry go-librespot[1375]: time="2025-05-31T12:33:37+02:00" level=debug msg="stored credentials found for zwartevogel" May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::volumioGetState May 31 12:33:37 hifiberry volumio[1094]: info: CorePlayQueue::getTrack 0 May 31 12:33:37 hifiberry systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 12:33:37 hifiberry sudo[1391]: pam_unix(sudo:session): session closed for user root May 31 12:33:37 hifiberry volumio[1094]: info: CoreCommandRouter::volumioGetState May 31 12:33:37 hifiberry volumio[1094]: info: CorePlayQueue::getTrack 0 May 31 12:33:37 hifiberry systemd[1]: systemd-fsckd.service: Succeeded. May 31 12:33:37 hifiberry systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 31 12:33:37 hifiberry systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM May 31 12:33:37 hifiberry systemd[1]: shairport-sync.service: Succeeded. May 31 12:33:37 hifiberry systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 31 12:33:38 hifiberry volumio[1094]: info: [SERIALAMPCONTROLLER] on.pushState: undefined - stop May 31 12:33:38 hifiberry volumio[1094]: info: Shairport-Sync Started May 31 12:33:38 hifiberry volumio[1094]: Error adding Membership: Error: addMembership EINVAL May 31 12:33:38 hifiberry systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 31 12:33:38 hifiberry sudo[1396]: pam_unix(sudo:session): session closed for user root May 31 12:33:38 hifiberry volumio[1094]: info: Shairport-Sync Started May 31 12:33:38 hifiberry sudo[1393]: pam_unix(sudo:session): session closed for user root May 31 12:33:38 hifiberry volumio[1094]: info: Shairport-Sync Started May 31 12:33:38 hifiberry volumio[1094]: SPOTIFY: User informations: {"country":"NL","display_name":"zwartevogel","email":"kraay@hotmail.com","explicit_content":{"filter_enabled":true,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/zwartevogel"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/zwartevogel","id":"zwartevogel","images":[],"product":"premium","type":"user","uri":"spotify:user:zwartevogel"} May 31 12:33:38 hifiberry volumio[1094]: info: Spotify Successfully logged in May 31 12:33:38 hifiberry volumio[1094]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 31 12:33:38 hifiberry volumio[1094]: info: [1748687618216] CoreMusicLibrary::Adding element Spotify May 31 12:33:38 hifiberry volumio[1094]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 31 12:33:38 hifiberry volumio[1094]: Cannot find translation for source Jellyfin May 31 12:33:38 hifiberry volumio[1094]: Cannot find translation for source Spotify May 31 12:33:38 hifiberry go-librespot[1375]: time="2025-05-31T12:33:38+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" May 31 12:33:38 hifiberry go-librespot[1375]: time="2025-05-31T12:33:38+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" May 31 12:33:38 hifiberry go-librespot[1375]: time="2025-05-31T12:33:38+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" May 31 12:33:38 hifiberry go-librespot[1375]: time="2025-05-31T12:33:38+02:00" level=debug msg="zeroconf server listening on port 37039" May 31 12:33:38 hifiberry go-librespot[1375]: time="2025-05-31T12:33:38+02:00" level=debug msg="obtained new client token: AAA5IElnApfHnJk0GE+u5p2uZju1gwP6SwJQkn3U2WSAA4yNzeGYXYu4UyDjsqaCt1cA2MuQceje2JOKz3h9sAM8pr9G6NeILXGVOGlOPHl+mdZOl5fkXgMuB2kq/iyh0m+vYLrlV9FoYl2c/TIgPrEORoU3YorlJtZjCGKcAi8eiPf5L4D5EjVT3qj5JLsj5qWo5dbHa01+LUqx2E4V5khOBrFnExE0w7v9Obfz9SElzWH21PVbUCcfp+I4" May 31 12:33:38 hifiberry go-librespot[1375]: time="2025-05-31T12:33:38+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" May 31 12:33:38 hifiberry go-librespot[1375]: time="2025-05-31T12:33:38+02:00" level=debug msg="completed keyexchange" May 31 12:33:38 hifiberry mpd[1367]: May 31 12:33 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 31 12:33:38 hifiberry systemd[1]: Started Music Player Daemon. May 31 12:33:38 hifiberry sudo[1344]: pam_unix(sudo:session): session closed for user root May 31 12:33:38 hifiberry sudo[1335]: pam_unix(sudo:session): session closed for user root May 31 12:33:38 hifiberry volumio[1094]: info: Completed starting Core Plugins May 31 12:33:38 hifiberry volumio[1094]: info: ------------------------------------------- May 31 12:33:38 hifiberry volumio[1094]: info: ----- MyVolumio plugins startup ---- May 31 12:33:38 hifiberry volumio[1094]: info: ------------------------------------------- May 31 12:33:38 hifiberry volumio[1094]: info: [MyVolumio PluginManager] Fetching plans data.... May 31 12:33:38 hifiberry volumio[1094]: info: MPD running with PID1367 May 31 12:33:38 hifiberry volumio[1094]: ,establishing connection May 31 12:33:38 hifiberry volumio[1094]: error: MPD error: The expression evaluated to a falsy value: May 31 12:33:38 hifiberry volumio[1094]: assert.ok(self.idling) May 31 12:33:38 hifiberry volumio[1094]: error: The expression evaluated to a falsy value: May 31 12:33:38 hifiberry volumio[1094]: assert.ok(self.idling) May 31 12:33:38 hifiberry volumio[1094]: error: MPD error: The expression evaluated to a falsy value: May 31 12:33:38 hifiberry volumio[1094]: assert.ok(self.idling) May 31 12:33:38 hifiberry volumio[1094]: error: The expression evaluated to a falsy value: May 31 12:33:38 hifiberry volumio[1094]: assert.ok(self.idling) May 31 12:33:38 hifiberry volumio[1094]: error: updateQueue error: null May 31 12:33:38 hifiberry go-librespot[1375]: time="2025-05-31T12:33:38+02:00" level=debug msg="completed challenge" May 31 12:33:38 hifiberry go-librespot[1375]: time="2025-05-31T12:33:38+02:00" level=debug msg="authenticated as zwartevogel" May 31 12:33:39 hifiberry sudo[1424]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 31 12:33:39 hifiberry sudo[1424]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:39 hifiberry go-librespot[1375]: time="2025-05-31T12:33:39+02:00" level=debug msg="authenticated as zwartevogel" May 31 12:33:39 hifiberry sudo[1424]: pam_unix(sudo:session): session closed for user root May 31 12:33:39 hifiberry sudo[1426]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 31 12:33:39 hifiberry sudo[1426]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:39 hifiberry sudo[1426]: pam_unix(sudo:session): session closed for user root May 31 12:33:39 hifiberry sudo[1430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 31 12:33:39 hifiberry sudo[1430]: pam_unix(sudo:session): session opened for user root by (uid=0) May 31 12:33:39 hifiberry go-librespot[1375]: time="2025-05-31T12:33:39+02:00" level=debug msg="dealer connection opened" May 31 12:33:39 hifiberry go-librespot[1375]: time="2025-05-31T12:33:39+02:00" level=debug msg="initializing zeroconf session, username: zwartevogel" May 31 12:33:39 hifiberry go-librespot[1375]: time="2025-05-31T12:33:39+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" May 31 12:33:39 hifiberry go-librespot[1375]: time="2025-05-31T12:33:39+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" May 31 12:33:39 hifiberry go-librespot[1375]: time="2025-05-31T12:33:39+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" May 31 12:33:39 hifiberry go-librespot[1375]: time="2025-05-31T12:33:39+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" May 31 12:33:39 hifiberry go-librespot[1375]: time="2025-05-31T12:33:39+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" May 31 12:33:39 hifiberry go-librespot[1375]: time="2025-05-31T12:33:39+02:00" level=debug msg="autoplay enabled: false" May 31 12:33:39 hifiberry go-librespot[1375]: time="2025-05-31T12:33:39+02:00" level=debug msg="received connection id: ZjQyYTAwOWUtMDRjMy00NzBjLTlhYjMtYWQ2NzRjN2NiZmU0K2RlYWxlcit0Y3A6Ly8wYWNhNThmYS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQTIzNTZFRERCODIwQzU4Q0EzODcyMEYyMTg4NEVBRTJGRUQ4OUMzNTg0ODRGNjJENUY0M0M2ODgwN0ZFN0U4RA==" May 31 12:33:39 hifiberry systemd[1]: Started UPnP Renderer front-end to MPD. May 31 12:33:39 hifiberry sudo[1430]: pam_unix(sudo:session): session closed for user root May 31 12:33:39 hifiberry volumio[1094]: info: Upmpdcli Daemon Started May 31 12:33:39 hifiberry go-librespot[1375]: time="2025-05-31T12:33:39+02:00" level=debug msg="put connect state because NEW_DEVICE" May 31 12:33:39 hifiberry volumio[1432]: Generating RSA private key, 4096 bit long modulus (2 primes) May 31 12:33:40 hifiberry volumio[1094]: info: go-librespot daemon successfully initialized May 31 12:33:42 hifiberry systemd[1]: systemd-hostnamed.service: Succeeded. May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=debug msg="handling transfer player command from 6b4f5a604fcc71a359e095fba1edcf83c7fa8b04" May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=debug msg="resolved context of track" uri="spotify:search:europap" May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:search:europap" May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=debug msg="shuffled context with seed 8212184704404961629 (len: 1, keep: -1)" May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=debug msg="loading track (paused: false, position: 111174ms)" uri="spotify:track:0uHrMbMv3c78398pIANDqR" May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=trace msg="emitting websocket event: will_play" May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=debug msg="selected format OGG_VORBIS_320 (605bdac7ea6b9e617955a07086e10fdd00b13f93)" uri="spotify:track:0uHrMbMv3c78398pIANDqR" May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=debug msg="requested aes key for file 605bdac7ea6b9e617955a07086e10fdd00b13f93, gid: 0uHrMbMv3c78398pIANDqR" May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 345" May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1346" May 31 12:33:43 hifiberry volumio[1094]: info: Initializing connection to go-librespot Websocket May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=debug msg="new websocket client" May 31 12:33:43 hifiberry volumio[1094]: info: Connection to go-librespot Websocket established May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=debug msg="fetched first chunk of 13, total size is 6604634 bytes" uri="spotify:track:0uHrMbMv3c78398pIANDqR" May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=debug msg="fetched chunk 1/12, size: 524288" uri="spotify:track:0uHrMbMv3c78398pIANDqR" May 31 12:33:43 hifiberry go-librespot[1375]: time="2025-05-31T12:33:43+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1118" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="fetched chunk 3/12, size: 524288" uri="spotify:track:0uHrMbMv3c78398pIANDqR" May 31 12:33:44 hifiberry volumio[1094]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="fetched chunk 2/12, size: 524288" uri="spotify:track:0uHrMbMv3c78398pIANDqR" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="fetched chunk 8/12, size: 524288" uri="spotify:track:0uHrMbMv3c78398pIANDqR" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=trace msg="seek to 111174ms (diff: 84ms, samples: 4902773, bytes: 4692405)" uri="spotify:track:0uHrMbMv3c78398pIANDqR" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="fetched chunk 9/12, size: 524288" uri="spotify:track:0uHrMbMv3c78398pIANDqR" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="fetched chunk 10/12, size: 524288" uri="spotify:track:0uHrMbMv3c78398pIANDqR" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="fetched chunk 11/12, size: 524288" uri="spotify:track:0uHrMbMv3c78398pIANDqR" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 5102 us, period size = 225 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 98 frames" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="created new output device" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=info msg="loaded track \"Europapa\" (paused: false, position: 111174ms, duration: 160218ms, prefetched: false)" uri="spotify:track:0uHrMbMv3c78398pIANDqR" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=trace msg="scheduling prefetch in 19s" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=trace msg="emitting websocket event: metadata" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=trace msg="emitting websocket event: active" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="sending successful reply for dealer request" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="fetched chunk 12/12, size: 313178" uri="spotify:track:0uHrMbMv3c78398pIANDqR" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 31 12:33:44 hifiberry go-librespot[1375]: time="2025-05-31T12:33:44+02:00" level=trace msg="emitting websocket event: playing" May 31 12:33:44 hifiberry volumio[1094]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:0uHrMbMv3c78398pIANDqR","name":"Europapa","artist_names":["Joost"],"album_name":"Europapa","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02ea1b4e3aa5c1462e17b2da48","position":111174,"duration":160218,"release_date":"year:2024 month:2 day:29","track_number":1,"disc_number":1}} May 31 12:33:44 hifiberry volumio[1094]: SPOTIFY: received: {"type":"active","data":null} May 31 12:33:44 hifiberry volumio[1094]: info: Aligning Spotify Volume to Volumio Volume May 31 12:33:44 hifiberry volumio[1094]: info: CoreCommandRouter::volumioGetState May 31 12:33:44 hifiberry volumio[1094]: info: CorePlayQueue::getTrack 0 May 31 12:33:44 hifiberry volumio[1094]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:0uHrMbMv3c78398pIANDqR","play_origin":"search"}} May 31 12:33:44 hifiberry volumio[1094]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 12:33:44 hifiberry volumio[1094]: TypeError: Cannot read property 'service' of undefined May 31 12:33:44 hifiberry volumio[1094]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) May 31 12:33:44 hifiberry volumio[1094]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) May 31 12:33:44 hifiberry volumio[1094]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) May 31 12:33:44 hifiberry volumio[1094]: at WebSocket.emit (events.js:315:20) May 31 12:33:44 hifiberry volumio[1094]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) May 31 12:33:44 hifiberry volumio[1094]: at Receiver.emit (events.js:315:20) May 31 12:33:44 hifiberry volumio[1094]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) May 31 12:33:44 hifiberry volumio[1094]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) May 31 12:33:44 hifiberry volumio[1094]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) May 31 12:33:44 hifiberry volumio[1094]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) May 31 12:33:44 hifiberry volumio[1094]: at writeOrBuffer (internal/streams/writable.js:358:12) May 31 12:33:44 hifiberry volumio[1094]: at Receiver.Writable.write (internal/streams/writable.js:303:10) May 31 12:33:44 hifiberry volumio[1094]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) May 31 12:33:44 hifiberry volumio[1094]: at Socket.emit (events.js:315:20) May 31 12:33:44 hifiberry volumio[1094]: at addChunk (internal/streams/readable.js:309:12) May 31 12:33:44 hifiberry volumio[1094]: at readableAddChunk (internal/streams/readable.js:284:9) May 31 12:33:44 hifiberry volumio[1094]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 31 12:33:45 hifiberry sudo[1462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-31 12:32 May 31 12:33:45 hifiberry sudo[1462]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="570c5791513f5bac7da274aba6690c1a961705de" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 13 May 2025 05:16:30 PM CEST" VOLUMIO_VERSION="3.812" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="3934ee440d5d3f0186049a23752ad680"