-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Fri 2025-12-26 18:14:21 CET. -- Dec 26 18:13:36 dsp systemd-timedated[929]: Changed local time to Fri Dec 26 18:13:36 2025 Dec 26 18:13:36 dsp sudo[927]: pam_unix(sudo:session): session closed for user root Dec 26 18:13:36 dsp volumio-time-update[556]: volumio-time-update-util: System time updated successfully. Dec 26 18:13:36 dsp systemd[1]: Starting Daily apt download activities... Dec 26 18:13:36 dsp systemd[1]: Started Volumio Time Update Utility. Dec 26 18:13:36 dsp systemd[1]: Reached target Multi-User System. Dec 26 18:13:36 dsp systemd[1]: Reached target Graphical Interface. Dec 26 18:13:36 dsp systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 26 18:13:36 dsp systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 26 18:13:36 dsp systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 26 18:13:37 dsp volumio[894]: info: ------------------------------------------- Dec 26 18:13:37 dsp volumio[894]: info: ----- Volumio3 ---- Dec 26 18:13:37 dsp volumio[894]: info: ------------------------------------------- Dec 26 18:13:37 dsp volumio[894]: info: ----- System startup ---- Dec 26 18:13:37 dsp volumio[894]: info: ------------------------------------------- Dec 26 18:13:37 dsp systemd[1]: apt-daily.service: Succeeded. Dec 26 18:13:37 dsp systemd[1]: Started Daily apt download activities. Dec 26 18:13:37 dsp systemd[1]: Starting Daily apt upgrade and clean activities... Dec 26 18:13:38 dsp systemd[1]: apt-daily-upgrade.service: Succeeded. Dec 26 18:13:38 dsp systemd[1]: Started Daily apt upgrade and clean activities. Dec 26 18:13:38 dsp systemd[1]: Startup finished in 6.150s (kernel) + 23.777s (userspace) = 29.928s. Dec 26 18:13:39 dsp volumio[894]: info: MYVOLUMIO Environment detected Dec 26 18:13:39 dsp volumio[894]: info: Plugin folders cleanup Dec 26 18:13:39 dsp volumio[894]: info: Scanning into folder /volumio/app/plugins/ Dec 26 18:13:39 dsp volumio[894]: info: Scanning category audio_interface Dec 26 18:13:39 dsp volumio[894]: info: Scanning category miscellanea Dec 26 18:13:39 dsp volumio[894]: info: Scanning category music_service Dec 26 18:13:39 dsp volumio[894]: info: Scanning category plugins.json Dec 26 18:13:39 dsp volumio[894]: info: Scanning category system_controller Dec 26 18:13:39 dsp volumio[894]: info: Scanning category user_interface Dec 26 18:13:39 dsp volumio[894]: info: Scanning into folder /data/plugins/ Dec 26 18:13:39 dsp volumio[894]: info: Plugin folders cleanup completed Dec 26 18:13:39 dsp volumio[894]: info: ------------------------------------------- Dec 26 18:13:39 dsp volumio[894]: info: ----- Core plugins startup ---- Dec 26 18:13:39 dsp volumio[894]: info: ------------------------------------------- Dec 26 18:13:39 dsp volumio[894]: info: Loading plugins from folder /volumio/app/plugins/ Dec 26 18:13:39 dsp volumio[894]: info: Adding plugin bluetooth to MyMusic Plugins Dec 26 18:13:39 dsp volumio[894]: info: Adding plugin multiroom to MyMusic Plugins Dec 26 18:13:39 dsp volumio[894]: info: Adding plugin upnp to MyMusic Plugins Dec 26 18:13:39 dsp volumio[894]: info: Adding plugin metavolumio to MyMusic Plugins Dec 26 18:13:39 dsp volumio[894]: info: Adding plugin ms_surface_dial to MyMusic Plugins Dec 26 18:13:39 dsp volumio[894]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 26 18:13:39 dsp volumio[894]: info: Adding plugin cd_controller to MyMusic Plugins Dec 26 18:13:39 dsp volumio[894]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 26 18:13:39 dsp volumio[894]: info: Adding plugin raat to MyMusic Plugins Dec 26 18:13:39 dsp volumio[894]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 26 18:13:39 dsp volumio[894]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 26 18:13:39 dsp volumio[894]: info: Loading plugins from folder /data/plugins/ Dec 26 18:13:39 dsp volumio[894]: info: Loading plugin "system"... Dec 26 18:13:39 dsp volumio[894]: info: Loading plugin "appearance"... Dec 26 18:13:42 dsp volumio[894]: info: Loading plugin "network"... Dec 26 18:13:42 dsp volumio[894]: info: Refreshing Cached IP Addresses Dec 26 18:13:42 dsp sudo[1044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 18:13:42 dsp sudo[1044]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:13:42 dsp sudo[1044]: pam_unix(sudo:session): session closed for user root Dec 26 18:13:42 dsp sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 18:13:42 dsp sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:13:42 dsp sudo[1046]: pam_unix(sudo:session): session closed for user root Dec 26 18:13:42 dsp volumio[894]: info: Loading plugin "services"... Dec 26 18:13:42 dsp volumio[894]: info: Loading plugin "alsa_controller"... Dec 26 18:13:42 dsp sudo[1055]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 26 18:13:42 dsp sudo[1055]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:13:42 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 18:13:42 dsp volumio[894]: info: Loading plugin "wizard"... Dec 26 18:13:42 dsp volumio[894]: info: Loading plugin "networkfs"... Dec 26 18:13:42 dsp volumio[894]: info: Starting Udev Watcher for removable devices Dec 26 18:13:42 dsp volumio[894]: info: Ignoring mount for partition: BOOT Dec 26 18:13:42 dsp volumio[894]: info: Ignoring mount for partition: volumio Dec 26 18:13:42 dsp volumio[894]: info: Ignoring mount for partition: volumio_data Dec 26 18:13:42 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 18:13:42 dsp volumio[894]: info: Loading plugin "volumio_command_line_client"... Dec 26 18:13:42 dsp volumio[894]: info: Loading plugin "upnp"... Dec 26 18:13:42 dsp volumio[894]: info: [1766769222528] Starting Upmpd Daemon Dec 26 18:13:42 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 18:13:42 dsp volumio[894]: info: Loading plugin "my_music"... Dec 26 18:13:42 dsp volumio[894]: info: Loading plugin "mpd"... Dec 26 18:13:42 dsp volumio-remote-updater[531]: [2025-12-26 18:13:42] [connect] Successful connection Dec 26 18:13:43 dsp volumio[894]: info: Loading plugin "upnp_browser"... Dec 26 18:13:44 dsp volumio[894]: info: Loading plugin "bluetooth"... Dec 26 18:13:44 dsp volumio[894]: info: [1766769224450] Starting BluetoothController Dec 26 18:13:44 dsp volumio[894]: info: Loading plugin "alarm-clock"... Dec 26 18:13:45 dsp volumio[894]: info: Loading plugin "manifestui"... Dec 26 18:13:45 dsp volumio[894]: info: Loading plugin "metavolumio"... Dec 26 18:13:46 dsp sudo[1055]: pam_unix(sudo:session): session closed for user root Dec 26 18:13:48 dsp volumio[894]: info: Loading plugin "airplay_emulation"... Dec 26 18:13:48 dsp volumio[894]: info: Starting Shairport Sync Dec 26 18:13:48 dsp volumio[894]: info: Loading plugin "cd_controller"... Dec 26 18:13:48 dsp systemd[1]: systemd-fsckd.service: Succeeded. Dec 26 18:13:48 dsp volumio[894]: info: Loading plugin "last_100"... Dec 26 18:13:48 dsp volumio[894]: info: Loading plugin "qobuzconnect"... Dec 26 18:13:48 dsp volumio[894]: info: Loading plugin "raat"... Dec 26 18:13:49 dsp volumio[894]: info: RAAT Plugin loaded Dec 26 18:13:49 dsp sh[459]: timed out Dec 26 18:13:49 dsp dhcpcd[510]: timed out Dec 26 18:13:49 dsp volumio[894]: info: Adding restartRAATSocket REST API Endpoint Dec 26 18:13:49 dsp volumio[894]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Dec 26 18:13:49 dsp volumio[894]: info: Loading plugin "streaming_services"... Dec 26 18:13:49 dsp sh[459]: dhcpcd exited Dec 26 18:13:49 dsp dhcpcd[510]: dhcpcd exited Dec 26 18:13:49 dsp sh[459]: ifup: failed to bring up eth0 Dec 26 18:13:49 dsp systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 26 18:13:49 dsp systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 26 18:13:51 dsp volumio[894]: info: Starting Streaming Service Transparent Proxy Dec 26 18:13:51 dsp volumio[894]: info: Loading plugin "tidalconnect"... Dec 26 18:13:52 dsp volumio[894]: info: Loading plugin "webradio"... Dec 26 18:13:52 dsp volumio[894]: info: Loading plugin "i2s_dacs"... Dec 26 18:13:52 dsp volumio[894]: info: I2S DAC not set, start Auto-detection Dec 26 18:13:52 dsp volumio[894]: info: Loading plugin "volumiodiscovery"... Dec 26 18:13:52 dsp volumio[894]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 26 18:13:52 dsp node[894]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 26 18:13:52 dsp node[894]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 18:13:52 dsp volumio[894]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 18:13:52 dsp volumio[894]: *** WARNING *** For more information see Dec 26 18:13:52 dsp volumio[894]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 26 18:13:52 dsp volumio[894]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 18:13:52 dsp volumio[894]: *** WARNING *** For more information see Dec 26 18:13:52 dsp node[894]: *** WARNING *** For more information see Dec 26 18:13:52 dsp node[894]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 26 18:13:52 dsp node[894]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 26 18:13:52 dsp node[894]: *** WARNING *** For more information see Dec 26 18:13:52 dsp volumio[894]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 26 18:13:52 dsp volumio[894]: info: Discovery: Started advertising with name: DSP Dec 26 18:13:52 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 18:13:52 dsp volumio[894]: info: Loading plugin "multiroom"... Dec 26 18:13:53 dsp volumio[894]: STREAMING PROXY: Starting server on port 3245 Dec 26 18:13:53 dsp volumio[894]: Node JS runtime: 14 Dec 26 18:13:55 dsp volumio[894]: info: Applying required configuration parameters for plugin multiroom Dec 26 18:13:55 dsp sudo[1097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 26 18:13:55 dsp sudo[1097]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:13:55 dsp sudo[1097]: pam_unix(sudo:session): session closed for user root Dec 26 18:13:55 dsp volumio[894]: info: MRS: MultiRoom plugin initialized Dec 26 18:13:55 dsp volumio[894]: info: MRS: STOPPING SNAPCLIENT Dec 26 18:13:55 dsp volumio[894]: info: MRS: Snap server stop Dec 26 18:13:55 dsp volumio[894]: info: MRS: STOPPING volumioStreaming Dec 26 18:13:55 dsp sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 26 18:13:55 dsp sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:13:55 dsp sudo[1115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 26 18:13:55 dsp sudo[1115]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:13:56 dsp sudo[1118]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 26 18:13:56 dsp volumio[894]: info: Loading plugin "outputs"... Dec 26 18:13:56 dsp sudo[1118]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:13:56 dsp sudo[1113]: pam_unix(sudo:session): session closed for user root Dec 26 18:13:56 dsp volumio[894]: info: Loading plugin "albumart"... Dec 26 18:13:56 dsp sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 26 18:13:56 dsp sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:13:56 dsp sudo[1121]: pam_unix(sudo:session): session closed for user root Dec 26 18:13:56 dsp volumio[894]: info: Loading plugin "ms_surface_dial"... Dec 26 18:13:56 dsp sudo[1118]: pam_unix(sudo:session): session closed for user root Dec 26 18:13:56 dsp sudo[1115]: pam_unix(sudo:session): session closed for user root Dec 26 18:13:56 dsp volumio[894]: Forking 3 albumart workers Dec 26 18:13:57 dsp volumio[894]: info: Applying required configuration parameters for plugin ms_surface_dial Dec 26 18:13:57 dsp volumio[894]: info: Plugin example_plugin is not enabled Dec 26 18:13:57 dsp volumio[894]: info: Loading plugin "hi_res_audio"... Dec 26 18:13:57 dsp volumio-remote-updater[531]: [2025-12-26 18:13:57] [connect] Successful connection Dec 26 18:13:58 dsp volumio[894]: Starting albumart workers Dec 26 18:13:58 dsp volumio[894]: Starting albumart workers Dec 26 18:13:58 dsp volumio[894]: Starting albumart workers Dec 26 18:14:01 dsp shairport-sync[723]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 8192 Dec 26 18:14:01 dsp shairport-sync[723]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 131072, but target_pcm volumioOutput has buffer size 8192 Dec 26 18:14:01 dsp volumio[894]: info: Applying required configuration parameters for plugin hi_res_audio Dec 26 18:14:01 dsp volumio[894]: info: Loading plugin "inputs"... Dec 26 18:14:02 dsp volumio[894]: info: Loading plugin "qobuz"... Dec 26 18:14:05 dsp volumio[894]: info: Loading plugin "tidal"... Dec 26 18:14:06 dsp systemd[1]: systemd-timedated.service: Succeeded. Dec 26 18:14:07 dsp volumio[894]: info: Loading plugin "oem_helper"... Dec 26 18:14:09 dsp volumio[894]: info: Applying required configuration parameters for plugin oem_helper Dec 26 18:14:09 dsp volumio[894]: info: Loading plugin "updater_comm"... Dec 26 18:14:10 dsp volumio[894]: info: Plugin mpdemulation is not enabled Dec 26 18:14:10 dsp volumio[894]: info: Loading plugin "rest_api"... Dec 26 18:14:10 dsp volumio[894]: info: Loading plugin "websocket"... Dec 26 18:14:10 dsp volumio[894]: info: Starting Socket.io Server version 2.3.0 Dec 26 18:14:10 dsp volumio[894]: info: Loading i18n strings for locale en Dec 26 18:14:10 dsp volumio[894]: Updating browse sources language Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::initPlayerControls Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 18:14:10 dsp volumio[894]: Express server listening on port 3000 Dec 26 18:14:10 dsp volumio[894]: [Metrics] WebUI: 35s 565.24ms Dec 26 18:14:10 dsp volumio[894]: info: CoreStateMachine::resetVolumioState Dec 26 18:14:10 dsp volumio[894]: info: CoreStateMachine::getcurrentVolume Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::volumioRetrievevolume Dec 26 18:14:10 dsp volumio[894]: info: CoreStateMachine::pushState Dec 26 18:14:10 dsp volumio[894]: info: CorePlayQueue::getTrack 0 Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:14:10 dsp volumio[894]: info: CoreCommandRouter::volumioPushState Dec 26 18:14:11 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 18:14:11 dsp sudo[1208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 26 18:14:11 dsp sudo[1208]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:11 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Dec 26 18:14:11 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Dec 26 18:14:11 dsp sudo[1208]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:11 dsp sudo[1210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 26 18:14:11 dsp sudo[1210]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:11 dsp sudo[1210]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:11 dsp volumio[894]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Dec 26 18:14:11 dsp volumio[894]: info: Completed loading Core Plugins Dec 26 18:14:11 dsp volumio[894]: info: Preparing to generate the ALSA configuration file Dec 26 18:14:11 dsp volumio[894]: info: Volumio Network Manager: Network status updated: 2 Dec 26 18:14:11 dsp volumio[894]: info: MRS: Removed streaming files Dec 26 18:14:11 dsp volumio[894]: info: MRS: volumioStreaming STOPPED Dec 26 18:14:11 dsp volumio[894]: info: MRS: SNAPSERVER STOPPED Dec 26 18:14:11 dsp volumio[894]: info: MRS: SNAPCLIENT STOPPED Dec 26 18:14:11 dsp volumio[894]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Dec 26 18:14:11 dsp volumio[894]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 26 18:14:11 dsp volumio[894]: info: Reading ALSA contributions from plugins. Dec 26 18:14:11 dsp volumio[894]: info: Reloading queue from file Dec 26 18:14:11 dsp volumio[894]: info: CoreStateMachine::setRepeat null single undefined Dec 26 18:14:11 dsp volumio[894]: info: CoreStateMachine::pushState Dec 26 18:14:11 dsp volumio[894]: info: CorePlayQueue::getTrack 0 Dec 26 18:14:11 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:14:11 dsp volumio[894]: info: CoreCommandRouter::volumioPushState Dec 26 18:14:11 dsp volumio[894]: info: CoreStateMachine::setRandom null Dec 26 18:14:11 dsp volumio[894]: info: CoreStateMachine::pushState Dec 26 18:14:11 dsp volumio[894]: info: CorePlayQueue::getTrack 0 Dec 26 18:14:11 dsp volumio[894]: info: CoreCommandRouter::volumioPushState Dec 26 18:14:11 dsp volumio[894]: info: Setting Device type: NanoPi NEO 3 Dec 26 18:14:11 dsp volumio[894]: info: Asound.conf file unchanged, so no further update is needed Dec 26 18:14:11 dsp volumio[894]: info: Output device has changed, restarting MPD Dec 26 18:14:11 dsp sudo[1219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 26 18:14:11 dsp sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:11 dsp systemd[1]: Started UPnP Renderer front-end to MPD. Dec 26 18:14:11 dsp sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 18:14:11 dsp sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:11 dsp volumio[894]: info: Output device has changed, restarting Shairport Sync Dec 26 18:14:11 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:11 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 18:14:11 dsp sudo[1219]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:11 dsp sudo[1222]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:12 dsp sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 18:14:12 dsp sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:12 dsp systemd[1]: Listening on mpd.socket. Dec 26 18:14:12 dsp systemd[1]: Starting Music Player Daemon... Dec 26 18:14:12 dsp volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 18:14:12 dsp volumio[894]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 26 18:14:12 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:12 dsp volumio[894]: info: ___________ START PLUGINS ___________ Dec 26 18:14:12 dsp volumio[894]: info: ControllerMpd::onStart: Initializing MPD Dec 26 18:14:12 dsp volumio[894]: info: Creating MPD Configuration file Dec 26 18:14:12 dsp sudo[1235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 26 18:14:12 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 18:14:12 dsp volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 18:14:12 dsp sudo[1235]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:12 dsp volumio[894]: info: [1766769252265] CoreMusicLibrary::Adding element Media Servers Dec 26 18:14:12 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 18:14:12 dsp sudo[1235]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:12 dsp sudo[1237]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 26 18:14:12 dsp sudo[1237]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:12 dsp sudo[1231]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 18:14:12 dsp sudo[1231]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:12 dsp sudo[1231]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 26 18:14:12 dsp sudo[1231]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:12 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 26 18:14:12 dsp systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Dec 26 18:14:12 dsp systemd[1]: mpd.service: Succeeded. Dec 26 18:14:12 dsp systemd[1]: Stopped Music Player Daemon. Dec 26 18:14:12 dsp systemd[1]: Starting Music Player Daemon... Dec 26 18:14:12 dsp volumio[894]: info: Adding METAVOLUMIO REST API Endpoints Dec 26 18:14:12 dsp volumio[894]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 26 18:14:12 dsp volumio[894]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 26 18:14:12 dsp volumio[894]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 26 18:14:12 dsp volumio[894]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 26 18:14:12 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:12 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 18:14:12 dsp sudo[1244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name DSP Dec 26 18:14:12 dsp sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:12 dsp sudo[1244]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:12 dsp volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 18:14:12 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:12 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 18:14:12 dsp sudo[1243]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 26 18:14:12 dsp sudo[1243]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:12 dsp sudo[1243]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 26 18:14:12 dsp sudo[1243]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:12 dsp volumio[894]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 26 18:14:12 dsp volumio[894]: info: Preparing CD Folders Dec 26 18:14:12 dsp volumio[894]: info: Adding CD REST API Endpoints Dec 26 18:14:12 dsp volumio[894]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 26 18:14:12 dsp volumio[894]: info: Starting UDEV Watcher for CD Dec 26 18:14:12 dsp volumio[894]: info: Detecting CD presence with UDEV Dec 26 18:14:12 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 26 18:14:12 dsp volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 18:14:12 dsp volumio[894]: info: [1766769252685] CoreMusicLibrary::Adding element Last_100 Dec 26 18:14:12 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 18:14:12 dsp volumio[894]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 26 18:14:12 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 18:14:12 dsp volumio[894]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 26 18:14:12 dsp volumio[894]: info: Starting RAAT Plugin Dec 26 18:14:12 dsp sudo[1272]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 26 18:14:12 dsp sudo[1272]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:12 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Dec 26 18:14:12 dsp volumio[894]: info: Additional UI Settings Added for plugin music_service/raat Dec 26 18:14:12 dsp volumio-remote-updater[531]: [2025-12-26 18:14:12] [connect] Successful connection Dec 26 18:14:12 dsp volumio[894]: info: Streaming services startup Dec 26 18:14:12 dsp volumio[894]: info: Starting Streaming Daemon Dec 26 18:14:12 dsp sudo[1272]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:12 dsp sudo[1277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 26 18:14:12 dsp sudo[1277]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:12 dsp sudo[1278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 26 18:14:12 dsp sudo[1278]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:13 dsp sudo[1278]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:13 dsp systemd[1]: Started Volumio Qobuz Connect Service. Dec 26 18:14:13 dsp sudo[1277]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:13 dsp volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 18:14:13 dsp volumio[894]: info: [1766769253089] CoreMusicLibrary::Adding element Webradio Dec 26 18:14:13 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 18:14:13 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 18:14:13 dsp sudo[1287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 26 18:14:13 dsp sudo[1287]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:13 dsp volumio[894]: info: Initializing BBC Radios Dec 26 18:14:13 dsp sudo[1287]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:13 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 18:14:13 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 18:14:13 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 26 18:14:13 dsp volumio[894]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 26 18:14:13 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:13 dsp dbus-daemon[543]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.11' (uid=1000 pid=894 comm="/usr/bin/node /volumio/index.js ") Dec 26 18:14:13 dsp volumio[894]: error: Hi Res Audio Failed Login: Missing Login Data Dec 26 18:14:13 dsp volumio[894]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 26 18:14:13 dsp volumio[894]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 26 18:14:13 dsp systemd[1]: Starting Bluetooth service... Dec 26 18:14:13 dsp volumio[894]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 26 18:14:13 dsp volumio[894]: info: Refreshing TIDAL token Dec 26 18:14:13 dsp bluetoothd[1304]: Bluetooth daemon 5.23 Dec 26 18:14:13 dsp dbus-daemon[543]: [system] Successfully activated service 'org.bluez' Dec 26 18:14:13 dsp systemd[1]: Started Bluetooth service. Dec 26 18:14:13 dsp bluetoothd[1304]: Starting SDP server Dec 26 18:14:13 dsp bluetoothd[1304]: kernel lacks bnep-protocol support Dec 26 18:14:13 dsp bluetoothd[1304]: System does not support network plugin Dec 26 18:14:13 dsp dbus-daemon[543]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.14' (uid=0 pid=1304 comm="/usr/local/libexec/bluetooth/bluetoothd ") Dec 26 18:14:13 dsp bluetoothd[1304]: Bluetooth management interface 1.14 initialized Dec 26 18:14:13 dsp systemd[1]: Starting Hostname Service... Dec 26 18:14:13 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 26 18:14:13 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 18:14:13 dsp volumio[894]: info: Volumio Calling Home Dec 26 18:14:13 dsp volumio[894]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 26 18:14:14 dsp dbus-daemon[543]: [system] Successfully activated service 'org.freedesktop.hostname1' Dec 26 18:14:14 dsp systemd[1]: Started Hostname Service. Dec 26 18:14:14 dsp volumio[894]: info: Stopping AccessToken refresher cron for QOBUZ Dec 26 18:14:14 dsp volumio[894]: info: AccessToken refresher cron started for QOBUZ Dec 26 18:14:14 dsp volumio[894]: info: Adding QOBUZ REST API Endpoints Dec 26 18:14:14 dsp volumio[894]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Dec 26 18:14:14 dsp volumio[894]: info: Discovery: adding 6b43bda4-04db-4c01-9eac-0e2014c97757 Dec 26 18:14:14 dsp volumio[894]: info: Discovery: Found device DSP Dec 26 18:14:14 dsp volumio[894]: info: CoreCommandRouter::volumioGetState Dec 26 18:14:14 dsp volumio[894]: info: CorePlayQueue::getTrack 0 Dec 26 18:14:14 dsp volumio[894]: info: MRS: Pushing multiroomSync output for this device Dec 26 18:14:14 dsp volumio[894]: info: MRS: Pushing multiroomSync output Dec 26 18:14:14 dsp volumio[894]: info: Adding audio output: Dec 26 18:14:14 dsp volumio[894]: info: Adding audio output: Dec 26 18:14:14 dsp volumio[894]: info: Serial port opened successfully Dec 26 18:14:14 dsp volumio[894]: error: Cannot start Volumio Streaming Daemon Dec 26 18:14:14 dsp volumio[894]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 26 18:14:14 dsp volumio[894]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 26 18:14:14 dsp volumio[894]: info: RAAT Albumart path created successfully Dec 26 18:14:14 dsp volumio[894]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'DSP' Dec 26 18:14:14 dsp volumio[894]: Can't get device info: No such device Dec 26 18:14:14 dsp volumio[894]: info: MPD Permissions set Dec 26 18:14:14 dsp volumio[894]: info: MPD Permissions set Dec 26 18:14:14 dsp volumio[894]: info: Upmpdcli Daemon Started Dec 26 18:14:14 dsp volumio[894]: info: msSurfaceDial volumioupdatevolume callback: {"vol":100,"dbVolume":0,"disableVolumeControl":false} Dec 26 18:14:14 dsp volumio[894]: info: CoreStateMachine::pushState Dec 26 18:14:14 dsp volumio[894]: info: CorePlayQueue::getTrack 0 Dec 26 18:14:14 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:14:14 dsp volumio[894]: info: CoreCommandRouter::volumioPushState Dec 26 18:14:14 dsp volumio[894]: info: CoreCommandRouter::volumioGetState Dec 26 18:14:14 dsp volumio[894]: info: CorePlayQueue::getTrack 0 Dec 26 18:14:14 dsp volumio[894]: info: MRS: Pushing multiroomSync output update for this device Dec 26 18:14:14 dsp volumio[894]: info: MRS: Pushing multiroomSync output Dec 26 18:14:14 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Dec 26 18:14:14 dsp volumio[894]: info: Ignoring ROON Volume update because of undefined data Dec 26 18:14:14 dsp volumio[894]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} Dec 26 18:14:14 dsp volumio[894]: info: [msSurfaceDial init()] check /org/bluez Dec 26 18:14:14 dsp volumio[894]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Dec 26 18:14:14 dsp volumio[894]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Dec 26 18:14:14 dsp volumio[894]: info: CoreCommandRouter::Reload Ui Dec 26 18:14:14 dsp volumio-remote-updater[531]: [2025-12-26 18:14:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1766769252 101 Dec 26 18:14:14 dsp volumio[894]: 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 Dec 26 18:14:14 dsp volumio[894]: info: Executing endpoint qc_getconfig Dec 26 18:14:14 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 26 18:14:15 dsp qobuz-connect[1286]: 20251226 18:14:15.054 [1286.1286] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 26 18:14:15 dsp volumio[894]: info: Volumio called home Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Dec 26 18:14:15 dsp volumio[894]: info: updateDSP function in raat called! Dec 26 18:14:15 dsp volumio[894]: info: Updating RAAT Signal Path Dec 26 18:14:15 dsp volumio[894]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Dec 26 18:14:15 dsp volumio[894]: info: Reconfiguring and Restarting RAAT Plugin Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 26 18:14:15 dsp qobuz-connect[1286]: 20251226 18:14:15.220 [1286.1286] INFO VolumeManager: [0x1593830]: Setting new playback volume: 75 Dec 26 18:14:15 dsp qobuz-connect[1286]: 20251226 18:14:15.220 [1286.1286] INFO VolumeManager: [0x1593830]: Setting new mute state: 0 Dec 26 18:14:15 dsp qobuz-connect[1286]: 20251226 18:14:15.220 [1286.1286] INFO QobuzConnect: [0x1594200]: Client initialized! Dec 26 18:14:15 dsp qobuz-connect[1286]: 20251226 18:14:15.220 [1286.1286] INFO SampleApp: Starting Avahi advertising, name: DSP, service name: _qobuz-connect._tcp Dec 26 18:14:15 dsp volumio[894]: info: Not Reporting Auto name since its the default one Dec 26 18:14:15 dsp volumio[894]: info: RAAT Overriding default device vendor model Dec 26 18:14:15 dsp volumio[894]: info: Adding MINIDSP Inputs Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 18:14:15 dsp volumio[894]: info: [1766769255233] CoreMusicLibrary::Adding element Inputs Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 18:14:15 dsp volumio[894]: info: [1766769255243] CoreMusicLibrary::Adding element Presets Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 18:14:15 dsp volumio[894]: Cannot find translation for source Presets Dec 26 18:14:15 dsp qobuz-connect[1286]: 20251226 18:14:15.251 [1286.1286] INFO LocalConfigManager: [0x1593110]: Starting Local Configuration server Dec 26 18:14:15 dsp qobuz-connect[1286]: 20251226 18:14:15.251 [1286.1286] INFO SampleApp: Starting Local configuration server Dec 26 18:14:15 dsp qobuz-connect[1286]: 20251226 18:14:15.253 [1286.1286] INFO SampleApp: Playback volume changed: 75 Dec 26 18:14:15 dsp qobuz-connect[1286]: 20251226 18:14:15.254 [1286.1286] INFO SampleApp: Connected to UNIX socket client 0x1588ed8 Dec 26 18:14:15 dsp volumio[894]: (node:894) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Dec 26 18:14:15 dsp volumio[894]: (Use `node --trace-deprecation ...` to show where the warning was created) Dec 26 18:14:15 dsp volumio[894]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 26 18:14:15 dsp volumio[894]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 26 18:14:15 dsp volumio[894]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp sudo[1328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 26 18:14:15 dsp sudo[1328]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 26 18:14:15 dsp systemd[1]: Started Volumio Bluetooth Module. Dec 26 18:14:15 dsp sudo[1328]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp sudo[1337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Dec 26 18:14:15 dsp sudo[1337]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:15 dsp volumiobt[1334]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Dec 26 18:14:15 dsp sudo[1337]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Dec 26 18:14:15 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:16 dsp volumio[894]: info: CoreCommandRouter::volumioGetState Dec 26 18:14:16 dsp volumio[894]: info: CorePlayQueue::getTrack 0 Dec 26 18:14:16 dsp volumio[894]: info: Access Token successfully retrieved Dec 26 18:14:16 dsp volumio[894]: info: Volumio BT Module successfully started Dec 26 18:14:16 dsp volumio[894]: info: CorePlayQueue::getTrack 0 Dec 26 18:14:16 dsp volumio[894]: info: CoreCommandRouter::volumiosetSourceActiveno-source Dec 26 18:14:16 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 18:14:16 dsp volumio[894]: Cannot find translation for source Presets Dec 26 18:14:16 dsp volumio[894]: info: CoreCommandRouter::volumioStop Dec 26 18:14:16 dsp volumio[894]: info: CoreStateMachine::stop Dec 26 18:14:16 dsp volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:14:16 dsp volumio[894]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"USB","disableUiControls":true,"albumart":"/albumart"} Dec 26 18:14:16 dsp volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:14:16 dsp volumio[894]: info: TidalConnect service stoped! Dec 26 18:14:16 dsp volumio[894]: info: Starting Shairport Sync Dec 26 18:14:16 dsp volumio[894]: info: Starting Shairport Sync Dec 26 18:14:16 dsp sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 18:14:16 dsp sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:16 dsp volumio[894]: info: Starting Shairport Sync Dec 26 18:14:16 dsp sudo[1353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 18:14:16 dsp sudo[1353]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:16 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Dec 26 18:14:16 dsp systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 26 18:14:16 dsp systemd[1]: shairport-sync.service: Succeeded. Dec 26 18:14:16 dsp systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 26 18:14:16 dsp sudo[1357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 26 18:14:16 dsp sudo[1357]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:16 dsp systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 26 18:14:16 dsp sudo[1351]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:16 dsp sudo[1353]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:16 dsp volumio[894]: xcb_connection_has_error() returned true Dec 26 18:14:16 dsp systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 26 18:14:16 dsp systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Dec 26 18:14:16 dsp systemd[1]: shairport-sync.service: Succeeded. Dec 26 18:14:16 dsp systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 26 18:14:16 dsp sudo[1364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Dec 26 18:14:16 dsp sudo[1364]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:16 dsp systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 26 18:14:16 dsp sudo[1363]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Dec 26 18:14:16 dsp sudo[1363]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:16 dsp sudo[1357]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:16 dsp volumiobt[1334]: pulseaudio: no process found Dec 26 18:14:16 dsp systemd[1]: Started RAAT DAEMON. Dec 26 18:14:16 dsp sudo[1363]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:16 dsp sudo[1364]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:16 dsp volumio[894]: info: CoreCommandRouter::volumioGetState Dec 26 18:14:17 dsp volumio[894]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 26 18:14:17 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Dec 26 18:14:17 dsp volumio[894]: info: Enabling external Volume Control Dec 26 18:14:17 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 26 18:14:17 dsp volumio[894]: info: CoreCommandRouter::servicePushState Dec 26 18:14:17 dsp volumio[894]: info: CoreStateMachine::pushState Dec 26 18:14:17 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:14:17 dsp volumio[894]: info: CoreCommandRouter::volumioPushState Dec 26 18:14:17 dsp volumio[894]: info: CoreCommandRouter::volumioGetState Dec 26 18:14:17 dsp volumio[894]: info: MRS: Pushing multiroomSync output update for this device Dec 26 18:14:17 dsp volumio[894]: info: MRS: Pushing multiroomSync output Dec 26 18:14:17 dsp volumio[894]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 26 18:14:17 dsp volumio[894]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 26 18:14:17 dsp volumio[894]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 26 18:14:17 dsp dbus-daemon[543]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.20' (uid=1000 pid=1382 comm="/usr/bin/pulseaudio --high-priority=true ") Dec 26 18:14:17 dsp volumio[894]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 26 18:14:17 dsp volumio[894]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Dec 26 18:14:17 dsp systemd[1]: Starting RealtimeKit Scheduling Policy Service... Dec 26 18:14:17 dsp dbus-daemon[543]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Dec 26 18:14:17 dsp systemd[1]: Started RealtimeKit Scheduling Policy Service. Dec 26 18:14:17 dsp rtkit-daemon[1384]: Successfully called chroot. Dec 26 18:14:17 dsp rtkit-daemon[1384]: Successfully dropped privileges. Dec 26 18:14:17 dsp rtkit-daemon[1384]: Successfully limited resources. Dec 26 18:14:17 dsp rtkit-daemon[1384]: Running. Dec 26 18:14:17 dsp rtkit-daemon[1384]: Canary thread running. Dec 26 18:14:17 dsp rtkit-daemon[1384]: Watchdog thread running. Dec 26 18:14:17 dsp dbus-daemon[543]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.21' (uid=0 pid=1384 comm="/usr/lib/rtkit/rtkit-daemon ") Dec 26 18:14:17 dsp systemd[1]: Starting Authorization Manager... Dec 26 18:14:17 dsp volumio[894]: info: Raat Daemon started successfully Dec 26 18:14:17 dsp volumio[894]: info: Shairport-Sync Started Dec 26 18:14:17 dsp volumio[894]: Error adding Membership: Error: addMembership EINVAL Dec 26 18:14:17 dsp volumio[894]: info: Shairport-Sync Started Dec 26 18:14:17 dsp volumio[894]: info: Shairport-Sync Started Dec 26 18:14:17 dsp polkitd[1387]: started daemon version 0.105 using authority implementation `local' version `0.105' Dec 26 18:14:17 dsp dbus-daemon[543]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Dec 26 18:14:17 dsp systemd[1]: Started Authorization Manager. Dec 26 18:14:17 dsp volumio[894]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 26 18:14:17 dsp volumio[894]: ------------------------------------ BT MESSAGE: BT STATUS: running Dec 26 18:14:17 dsp volumio[894]: info: Executing endpoint restartRAATSocket Dec 26 18:14:17 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Dec 26 18:14:17 dsp volumio[894]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 26 18:14:17 dsp volumio[894]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 26 18:14:17 dsp volumio[894]: info: Successfully retrieved User Session From TIDAL Dec 26 18:14:17 dsp sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 26 18:14:17 dsp sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 26 18:14:17 dsp systemd[1]: Started Volumio Tidal Connect Service. Dec 26 18:14:18 dsp sudo[1397]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:18 dsp volumio[894]: info: MRS: Getting audio outputs on start Dec 26 18:14:18 dsp volumio[894]: info: MRS: Requesting all other devices output Dec 26 18:14:18 dsp volumio[894]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 26 18:14:18 dsp volumio[894]: info: msSurfaceDial volumioupdatevolume callback: {"vol":100,"dbVolume":0,"mute":false,"disableVolumeControl":false} Dec 26 18:14:18 dsp volumio[894]: info: CoreStateMachine::pushState Dec 26 18:14:18 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:14:18 dsp volumio[894]: info: CoreCommandRouter::volumioPushState Dec 26 18:14:18 dsp volumio[894]: info: CoreCommandRouter::volumioGetState Dec 26 18:14:18 dsp volumio[894]: info: MRS: Pushing multiroomSync output update for this device Dec 26 18:14:18 dsp volumio[894]: info: MRS: Pushing multiroomSync output Dec 26 18:14:18 dsp volumio[894]: error: Help! Some callbacks for volumioPushState are crashing! Dec 26 18:14:18 dsp volumio[894]: error: Cannot read property 'sendVolumeMute' of undefined Dec 26 18:14:18 dsp pulseaudio[1382]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 26 18:14:18 dsp pulseaudio[1382]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Dec 26 18:14:18 dsp volumiobt[1334]: Applying permissions Dec 26 18:14:18 dsp volumiobt[1334]: Setting BT discoverable and pairable Dec 26 18:14:18 dsp pulseaudio[1382]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Dec 26 18:14:18 dsp volumiobt[1334]: [38B blob data] Dec 26 18:14:18 dsp volumiobt[1334]: Invalid command Dec 26 18:14:18 dsp volumiobt[1334]: [bluetooth]# agent on Dec 26 18:14:18 dsp volumio[894]: info: Successfully retrieved User Subscription From TIDAL Dec 26 18:14:18 dsp volumiobt[1334]: [bluetooth]# discoverable on Dec 26 18:14:18 dsp volumio[894]: info: Adding TIDAL to Browse Sources Dec 26 18:14:18 dsp volumio[894]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 26 18:14:18 dsp volumiobt[1334]: No default controller available Dec 26 18:14:18 dsp volumio[894]: info: [1766769258705] CoreMusicLibrary::Adding element TIDAL Dec 26 18:14:18 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 26 18:14:18 dsp volumio[894]: Cannot find translation for source Presets Dec 26 18:14:18 dsp volumio[894]: Cannot find translation for source TIDAL Dec 26 18:14:18 dsp volumiobt[1334]: [bluetooth]# pairable on Dec 26 18:14:18 dsp volumiobt[1334]: No default controller available Dec 26 18:14:18 dsp volumiobt[1334]: [bluetooth]# agent NoInputNoOutput Dec 26 18:14:18 dsp volumiobt[1334]: Failed to register agent object Dec 26 18:14:18 dsp volumiobt[1334]: [bluetooth]# default-agent Dec 26 18:14:18 dsp volumiobt[1334]: No agent is registered Dec 26 18:14:18 dsp volumiobt[1334]: [bluetooth]# quit Dec 26 18:14:18 dsp volumiobt[1334]: [61B blob data] Dec 26 18:14:18 dsp volumio[894]: info: Adding TIDAL REST API Endpoints Dec 26 18:14:18 dsp volumio[894]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Dec 26 18:14:18 dsp volumiobt[1334]: [62B blob data] Dec 26 18:14:18 dsp volumio[894]: info: CoreCommandRouter::volumioGetState Dec 26 18:14:19 dsp volumio[894]: info: msSurfaceDial volumioupdatevolume callback: {"vol":100,"dbVolume":0,"mute":false,"disableVolumeControl":false} Dec 26 18:14:19 dsp volumio[894]: info: CoreStateMachine::pushState Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::volumioPushState Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::volumioGetState Dec 26 18:14:19 dsp volumio[894]: info: MRS: Pushing multiroomSync output update for this device Dec 26 18:14:19 dsp volumio[894]: info: MRS: Pushing multiroomSync output Dec 26 18:14:19 dsp volumio[894]: error: Help! Some callbacks for volumioPushState are crashing! Dec 26 18:14:19 dsp volumio[894]: error: Cannot read property 'sendVolumeMute' of undefined Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Dec 26 18:14:19 dsp volumio[894]: info: Executing endpoint tc_getconfig Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 26 18:14:19 dsp vtcs[1399]: STARTING TidalConnect services, version: 1.5.1-beta1 Dec 26 18:14:19 dsp vtcs[1399]: STARTED TidalConnect services. Dec 26 18:14:19 dsp volumio[894]: info: Executing endpoint tc_connect Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 26 18:14:19 dsp volumio[894]: info: Connecting to TidalConnect Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::servicePushState Dec 26 18:14:19 dsp volumio[894]: info: CoreStateMachine::pushState Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::volumioPushState Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::volumioGetState Dec 26 18:14:19 dsp volumio[894]: info: MRS: Pushing multiroomSync output update for this device Dec 26 18:14:19 dsp volumio[894]: info: MRS: Pushing multiroomSync output Dec 26 18:14:19 dsp volumio[894]: info: CorePlayQueue::getTrack 0 Dec 26 18:14:19 dsp volumio[894]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::servicePushState Dec 26 18:14:19 dsp volumio[894]: info: CoreStateMachine::pushState Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::volumioPushState Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::volumioGetState Dec 26 18:14:19 dsp volumio[894]: info: MRS: Pushing multiroomSync output update for this device Dec 26 18:14:19 dsp volumio[894]: info: MRS: Pushing multiroomSync output Dec 26 18:14:19 dsp volumio[894]: info: CorePlayQueue::getTrack 0 Dec 26 18:14:19 dsp volumio[894]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Dec 26 18:14:19 dsp volumio[894]: info: Airplay started streaming, receiving metadatas Dec 26 18:14:19 dsp volumio[894]: verbose: UNSET VOLATILE: Service: undefined Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::servicePushState Dec 26 18:14:19 dsp volumio[894]: info: CoreStateMachine::pushState Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::volumioPushState Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::volumioGetState Dec 26 18:14:19 dsp volumio[894]: info: MRS: Pushing multiroomSync output update for this device Dec 26 18:14:19 dsp volumio[894]: info: MRS: Pushing multiroomSync output Dec 26 18:14:19 dsp volumio[894]: info: Airplay started streaming, receiving metadatas Dec 26 18:14:19 dsp volumio[894]: info: CorePlayQueue::getTrack 0 Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::volumioStop Dec 26 18:14:19 dsp volumio[894]: info: CoreStateMachine::stop Dec 26 18:14:19 dsp volumio[894]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 26 18:14:19 dsp volumio[894]: info: CoreStateMachine::stPlaybackTimer Dec 26 18:14:19 dsp volumio[894]: info: CoreStateMachine::updateTrackBlock Dec 26 18:14:19 dsp volumio[894]: info: CorePlayQueue::getTrackBlock Dec 26 18:14:19 dsp volumio[894]: info: CoreStateMachine::pushState Dec 26 18:14:19 dsp volumio[894]: info: CorePlayQueue::getTrack 0 Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 26 18:14:19 dsp volumio[894]: info: CoreCommandRouter::volumioPushState Dec 26 18:14:20 dsp shairport-sync[1365]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 26 18:14:20 dsp shairport-sync[1365]: Dload Upload Total Spent Left Speed Dec 26 18:14:20 dsp volumio[894]: info: CoreCommandRouter::volumioGetState Dec 26 18:14:20 dsp volumio[894]: info: CorePlayQueue::getTrack 0 Dec 26 18:14:20 dsp volumio[894]: info: MRS: Pushing multiroomSync output update for this device Dec 26 18:14:20 dsp volumio[894]: info: MRS: Pushing multiroomSync output Dec 26 18:14:20 dsp volumio[894]: info: CoreStateMachine::serviceStop Dec 26 18:14:20 dsp volumio[894]: info: CorePlayQueue::getTrack 0 Dec 26 18:14:20 dsp volumio[894]: info: CoreCommandRouter::serviceStop Dec 26 18:14:20 dsp volumio[894]: info: [1766769260043] ControllerTidal::stop Dec 26 18:14:20 dsp volumio[894]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 26 18:14:20 dsp volumio[894]: info: ControllerMpd::stop Dec 26 18:14:20 dsp volumio[894]: verbose: ControllerMpd::sendMpdCommand stop Dec 26 18:14:20 dsp volumio[894]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 18:14:20 dsp volumio[894]: TypeError: Cannot read property 'then' of undefined Dec 26 18:14:20 dsp volumio[894]: at ControllerMpd.sendMpdCommand (/volumio/app/plugins/music_service/mpd/index.js:332:6) Dec 26 18:14:20 dsp volumio[894]: at ControllerMpd.stop (/volumio/app/plugins/music_service/mpd/index.js:2797:15) Dec 26 18:14:20 dsp volumio[894]: at ControllerTidal.stop (/volumio/app/plugins/music_service/tidal/tidal_real:1:6606) Dec 26 18:14:20 dsp volumio[894]: at CoreCommandRouter.serviceStop (/volumio/app/index.js:371:25) Dec 26 18:14:20 dsp volumio[894]: at CoreStateMachine.serviceStop (/volumio/app/statemachine.js:1273:33) Dec 26 18:14:20 dsp volumio[894]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1248:19) Dec 26 18:14:20 dsp volumio[894]: at CoreCommandRouter.volumioStop (/volumio/app/index.js:91:28) Dec 26 18:14:20 dsp volumio[894]: at AirPlayInterface.prepareAirplayPlayback (/volumio/app/plugins/music_service/airplay_emulation/index.js:564:32) Dec 26 18:14:20 dsp volumio[894]: at ShairportSyncReaderUDP. (/volumio/app/plugins/music_service/airplay_emulation/index.js:266:12) Dec 26 18:14:20 dsp volumio[894]: at ShairportSyncReaderUDP.emit (events.js:412:35) Dec 26 18:14:20 dsp volumio[894]: at ShairportSyncReaderUDP.useData (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-base.js:86:16) Dec 26 18:14:20 dsp volumio[894]: at Socket. (/volumio/app/plugins/music_service/airplay_emulation/shairport-sync-reader/shairport-sync-reader-udp.js:21:12) Dec 26 18:14:20 dsp volumio[894]: at Socket.emit (events.js:400:28) Dec 26 18:14:20 dsp volumio[894]: at UDP.onMessage [as onmessage] (dgram.js:931:8) Dec 26 18:14:20 dsp volumio[894]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 26 18:14:20 dsp mpd[1267]: Dec 26 18:14 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 26 18:14:20 dsp systemd[1]: Started Music Player Daemon. Dec 26 18:14:20 dsp sudo[1226]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:20 dsp sudo[1237]: pam_unix(sudo:session): session closed for user root Dec 26 18:14:21 dsp sudo[1477]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-26 18:13 Dec 26 18:14:21 dsp sudo[1477]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="cc50ad4a2058d01de272214eb33827883bd2b7d8" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="8bcc10c3dbcbcb349e9887dc0527d54876b32688" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 18 Nov 2025 04:27:40 PM CET" VOLUMIO_VERSION="3.877" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="861d146b0ca5e28d2e157bf87e6b15ea"