-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Mon 2024-10-21 06:06:45 CEST. -- Oct 21 06:05:59 minidsp-shd systemd[1]: Starting Daily apt download activities... Oct 21 06:05:59 minidsp-shd volumio[773]: info: Loading plugin "network"... Oct 21 06:05:59 minidsp-shd volumio[773]: info: Refreshing Cached IP Addresses Oct 21 06:05:59 minidsp-shd sudo[840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 21 06:05:59 minidsp-shd sudo[840]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:05:59 minidsp-shd volumio[773]: info: Loading plugin "services"... Oct 21 06:05:59 minidsp-shd sudo[840]: pam_unix(sudo:session): session closed for user root Oct 21 06:05:59 minidsp-shd sudo[842]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 21 06:05:59 minidsp-shd sudo[842]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:05:59 minidsp-shd sudo[842]: pam_unix(sudo:session): session closed for user root Oct 21 06:05:59 minidsp-shd volumio[773]: info: Loading plugin "alsa_controller"... Oct 21 06:05:59 minidsp-shd sudo[845]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 21 06:05:59 minidsp-shd sudo[845]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:00 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 21 06:06:00 minidsp-shd volumio[773]: info: Loading plugin "wizard"... Oct 21 06:06:00 minidsp-shd volumio[773]: info: Loading plugin "networkfs"... Oct 21 06:06:00 minidsp-shd volumio[773]: info: Starting Udev Watcher for removable devices Oct 21 06:06:00 minidsp-shd sudo[867]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.10.197/USB /mnt/NAS/USB Oct 21 06:06:00 minidsp-shd sudo[867]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:00 minidsp-shd kernel: FS-Cache: Loaded Oct 21 06:06:00 minidsp-shd volumio[773]: info: Ignoring mount for partition: BOOT Oct 21 06:06:00 minidsp-shd volumio[773]: info: Ignoring mount for partition: volumio Oct 21 06:06:00 minidsp-shd volumio[773]: info: Ignoring mount for partition: volumio_data Oct 21 06:06:00 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 21 06:06:00 minidsp-shd volumio[773]: info: Loading plugin "volumio_command_line_client"... Oct 21 06:06:00 minidsp-shd volumio[773]: info: Loading plugin "upnp"... Oct 21 06:06:00 minidsp-shd volumio[773]: info: [1729483560384] Starting Upmpd Daemon Oct 21 06:06:00 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 21 06:06:00 minidsp-shd volumio[773]: info: Loading plugin "my_music"... Oct 21 06:06:00 minidsp-shd volumio[773]: info: Loading plugin "mpd"... Oct 21 06:06:00 minidsp-shd kernel: FS-Cache: Netfs 'cifs' registered for caching Oct 21 06:06:00 minidsp-shd kernel: Key type cifs.spnego registered Oct 21 06:06:00 minidsp-shd kernel: Key type cifs.idmap registered Oct 21 06:06:00 minidsp-shd kernel: CIFS: Attempting to mount //192.168.10.197/USB Oct 21 06:06:00 minidsp-shd kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Oct 21 06:06:00 minidsp-shd sudo[867]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:01 minidsp-shd systemd[1]: apt-daily.service: Succeeded. Oct 21 06:06:01 minidsp-shd systemd[1]: Started Daily apt download activities. Oct 21 06:06:01 minidsp-shd systemd[1]: Starting Daily apt upgrade and clean activities... Oct 21 06:06:01 minidsp-shd systemd[1]: systemd-fsckd.service: Succeeded. Oct 21 06:06:01 minidsp-shd volumio[773]: info: Loading plugin "upnp_browser"... Oct 21 06:06:01 minidsp-shd sh[390]: timed out Oct 21 06:06:01 minidsp-shd sh[390]: dhcpcd exited Oct 21 06:06:01 minidsp-shd dhcpcd[431]: timed out Oct 21 06:06:01 minidsp-shd sh[390]: ifup: failed to bring up eth0 Oct 21 06:06:01 minidsp-shd dhcpcd[431]: dhcpcd exited Oct 21 06:06:01 minidsp-shd systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Oct 21 06:06:01 minidsp-shd systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Oct 21 06:06:02 minidsp-shd systemd[1]: apt-daily-upgrade.service: Succeeded. Oct 21 06:06:02 minidsp-shd systemd[1]: Started Daily apt upgrade and clean activities. Oct 21 06:06:02 minidsp-shd volumio[773]: info: Loading plugin "bluetooth"... Oct 21 06:06:02 minidsp-shd volumio[773]: info: [1729483562734] Starting BluetoothController Oct 21 06:06:03 minidsp-shd volumio[773]: info: Loading plugin "alarm-clock"... Oct 21 06:06:03 minidsp-shd volumio[773]: info: Loading plugin "manifestui"... Oct 21 06:06:03 minidsp-shd volumio[773]: info: Loading plugin "metavolumio"... Oct 21 06:06:04 minidsp-shd sudo[845]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:06 minidsp-shd volumio[773]: info: Loading plugin "airplay_emulation"... Oct 21 06:06:06 minidsp-shd volumio[773]: info: Starting Shairport Sync Oct 21 06:06:06 minidsp-shd volumio[773]: info: Loading plugin "cd_controller"... Oct 21 06:06:06 minidsp-shd volumio[773]: info: Loading plugin "last_100"... Oct 21 06:06:06 minidsp-shd volumio[773]: info: Loading plugin "raat"... Oct 21 06:06:07 minidsp-shd volumio[773]: info: RAAT Plugin loaded Oct 21 06:06:07 minidsp-shd volumio[773]: info: Adding restartRAATSocket REST API Endpoint Oct 21 06:06:07 minidsp-shd volumio[773]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Oct 21 06:06:07 minidsp-shd volumio[773]: info: Loading plugin "streaming_services"... Oct 21 06:06:09 minidsp-shd volumio[773]: info: Starting Streaming Service Transparent Proxy Oct 21 06:06:09 minidsp-shd volumio[773]: info: Loading plugin "tidalconnect"... Oct 21 06:06:10 minidsp-shd volumio[773]: info: Loading plugin "webradio"... Oct 21 06:06:10 minidsp-shd volumio[773]: info: Loading plugin "i2s_dacs"... Oct 21 06:06:10 minidsp-shd volumio[773]: info: I2S DAC not set, start Auto-detection Oct 21 06:06:10 minidsp-shd volumio[773]: info: Loading plugin "volumiodiscovery"... Oct 21 06:06:10 minidsp-shd volumio[773]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 21 06:06:10 minidsp-shd volumio[773]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 21 06:06:10 minidsp-shd node[773]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Oct 21 06:06:10 minidsp-shd volumio[773]: *** WARNING *** For more information see Oct 21 06:06:10 minidsp-shd volumio[773]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 21 06:06:10 minidsp-shd volumio[773]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 21 06:06:10 minidsp-shd volumio[773]: *** WARNING *** For more information see Oct 21 06:06:10 minidsp-shd node[773]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 21 06:06:10 minidsp-shd node[773]: *** WARNING *** For more information see Oct 21 06:06:10 minidsp-shd node[773]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Oct 21 06:06:10 minidsp-shd node[773]: *** WARNING *** Please fix your application to use the native API of Avahi! Oct 21 06:06:10 minidsp-shd node[773]: *** WARNING *** For more information see Oct 21 06:06:10 minidsp-shd volumio[773]: info: Applying required configuration parameters for plugin volumiodiscovery Oct 21 06:06:10 minidsp-shd volumio[773]: info: Discovery: Started advertising with name: miniDSP SHD Oct 21 06:06:10 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 21 06:06:10 minidsp-shd volumio[773]: info: Loading plugin "spop"... Oct 21 06:06:10 minidsp-shd volumio-remote-updater[501]: [2024-10-21 06:06:10] [connect] Successful connection Oct 21 06:06:11 minidsp-shd volumio[773]: STREAMING PROXY: Starting server on port 3245 Oct 21 06:06:11 minidsp-shd volumio[773]: Node JS runtime: 14 Oct 21 06:06:13 minidsp-shd volumio[773]: info: Loading plugin "multiroom"... Oct 21 06:06:16 minidsp-shd volumio[773]: info: Applying required configuration parameters for plugin multiroom Oct 21 06:06:16 minidsp-shd sudo[982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 21 06:06:16 minidsp-shd sudo[982]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:16 minidsp-shd sudo[982]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:16 minidsp-shd volumio[773]: info: MRS: MultiRoom plugin initialized Oct 21 06:06:16 minidsp-shd volumio[773]: info: MRS: STOPPING SNAPCLIENT Oct 21 06:06:16 minidsp-shd volumio[773]: info: MRS: Snap server stop Oct 21 06:06:16 minidsp-shd sudo[998]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 21 06:06:16 minidsp-shd sudo[998]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:16 minidsp-shd volumio[773]: info: MRS: STOPPING volumioStreaming Oct 21 06:06:16 minidsp-shd sudo[1000]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 21 06:06:16 minidsp-shd sudo[1000]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:16 minidsp-shd volumio[773]: info: Loading plugin "outputs"... Oct 21 06:06:16 minidsp-shd sudo[1003]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 21 06:06:16 minidsp-shd sudo[1003]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:16 minidsp-shd sudo[998]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:16 minidsp-shd volumio[773]: info: Loading plugin "albumart"... Oct 21 06:06:16 minidsp-shd sudo[1006]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 21 06:06:16 minidsp-shd sudo[1006]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:16 minidsp-shd sudo[1006]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:16 minidsp-shd sudo[1000]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:16 minidsp-shd volumio[773]: info: Loading plugin "ms_surface_dial"... Oct 21 06:06:16 minidsp-shd sudo[1003]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:17 minidsp-shd volumio[773]: Forking 3 albumart workers Oct 21 06:06:18 minidsp-shd volumio[773]: info: Applying required configuration parameters for plugin ms_surface_dial Oct 21 06:06:18 minidsp-shd volumio[773]: info: Plugin example_plugin is not enabled Oct 21 06:06:18 minidsp-shd volumio[773]: info: Loading plugin "hi_res_audio"... Oct 21 06:06:19 minidsp-shd volumio[773]: Starting albumart workers Oct 21 06:06:19 minidsp-shd volumio[773]: Starting albumart workers Oct 21 06:06:19 minidsp-shd volumio[773]: Starting albumart workers Oct 21 06:06:21 minidsp-shd volumio[773]: info: Applying required configuration parameters for plugin hi_res_audio Oct 21 06:06:21 minidsp-shd volumio[773]: info: Loading plugin "inputs"... Oct 21 06:06:22 minidsp-shd volumio[773]: info: Loading plugin "qobuz"... Oct 21 06:06:25 minidsp-shd volumio[773]: info: Loading plugin "tidal"... Oct 21 06:06:25 minidsp-shd volumio-remote-updater[501]: [2024-10-21 06:06:25] [connect] Successful connection Oct 21 06:06:27 minidsp-shd volumio[773]: info: Loading plugin "oem_helper"... Oct 21 06:06:29 minidsp-shd volumio[773]: info: Applying required configuration parameters for plugin oem_helper Oct 21 06:06:29 minidsp-shd volumio[773]: info: Loading plugin "updater_comm"... Oct 21 06:06:29 minidsp-shd volumio[773]: info: Plugin mpdemulation is not enabled Oct 21 06:06:29 minidsp-shd volumio[773]: info: Loading plugin "rest_api"... Oct 21 06:06:29 minidsp-shd volumio[773]: info: Loading plugin "websocket"... Oct 21 06:06:29 minidsp-shd volumio[773]: info: Loading i18n strings for locale en Oct 21 06:06:29 minidsp-shd volumio[773]: Updating browse sources language Oct 21 06:06:29 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::initPlayerControls Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 21 06:06:30 minidsp-shd volumio[773]: Express server listening on port 3000 Oct 21 06:06:30 minidsp-shd volumio[773]: [Metrics] WebUI: 37s 274.90ms Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreStateMachine::resetVolumioState Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreStateMachine::getcurrentVolume Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioRetrievevolume Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreStateMachine::pushState Oct 21 06:06:30 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioPushState Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 21 06:06:30 minidsp-shd sudo[1071]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 21 06:06:30 minidsp-shd sudo[1071]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Oct 21 06:06:30 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Oct 21 06:06:30 minidsp-shd sudo[1071]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:30 minidsp-shd sudo[1073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 21 06:06:30 minidsp-shd sudo[1073]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:30 minidsp-shd sudo[1073]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:30 minidsp-shd volumio[773]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Oct 21 06:06:30 minidsp-shd volumio[773]: info: Completed loading Core Plugins Oct 21 06:06:30 minidsp-shd volumio[773]: info: Preparing to generate the ALSA configuration file Oct 21 06:06:30 minidsp-shd volumio[773]: info: Cannot read play queue from file Oct 21 06:06:30 minidsp-shd volumio[773]: info: MRS: Removed streaming files Oct 21 06:06:30 minidsp-shd volumio[773]: info: MRS: volumioStreaming STOPPED Oct 21 06:06:30 minidsp-shd volumio[773]: info: MRS: SNAPSERVER STOPPED Oct 21 06:06:30 minidsp-shd volumio[773]: info: MRS: SNAPCLIENT STOPPED Oct 21 06:06:30 minidsp-shd volumio[773]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Oct 21 06:06:30 minidsp-shd volumio[773]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 21 06:06:30 minidsp-shd volumio[773]: info: Reading ALSA contributions from plugins. Oct 21 06:06:31 minidsp-shd volumio[773]: verbose: New Socket.io Connection to 192.168.10.197 from 192.168.10.151 UA: Mozilla/5.0 (Linux; Android 14; SM-S918B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Total Clients: 1 Oct 21 06:06:31 minidsp-shd volumio[773]: verbose: New Socket.io Connection to 192.168.10.197 from 192.168.10.151 UA: Mozilla/5.0 (Linux; Android 14; SM-S918B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Total Clients: 2 Oct 21 06:06:31 minidsp-shd volumio[773]: verbose: New Socket.io Connection to 192.168.10.197 from 192.168.10.151 UA: Mozilla/5.0 (Linux; Android 14; SM-S918B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Total Clients: 3 Oct 21 06:06:31 minidsp-shd volumio[773]: verbose: New Socket.io Connection to 192.168.10.197 from 192.168.10.151 UA: Mozilla/5.0 (Linux; Android 14; SM-S918B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Total Clients: 4 Oct 21 06:06:31 minidsp-shd volumio[773]: verbose: New Socket.io Connection to 192.168.10.197 from 192.168.10.151 UA: Mozilla/5.0 (Linux; Android 14; SM-S918B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Total Clients: 5 Oct 21 06:06:31 minidsp-shd volumio[773]: info: CoreStateMachine::setRepeat null single undefined Oct 21 06:06:31 minidsp-shd volumio[773]: info: CoreStateMachine::pushState Oct 21 06:06:31 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:31 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioPushState Oct 21 06:06:31 minidsp-shd volumio[773]: info: CoreStateMachine::setRandom null Oct 21 06:06:31 minidsp-shd volumio[773]: info: CoreStateMachine::pushState Oct 21 06:06:31 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:31 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioPushState Oct 21 06:06:31 minidsp-shd volumio[773]: info: Setting Device type: NanoPi NEO 3 Oct 21 06:06:31 minidsp-shd volumio[773]: info: Asound.conf file unchanged, so no further update is needed Oct 21 06:06:31 minidsp-shd volumio[773]: info: Output device has changed, restarting MPD Oct 21 06:06:31 minidsp-shd sudo[1082]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Oct 21 06:06:31 minidsp-shd sudo[1082]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:31 minidsp-shd systemd[1]: Started UPnP Renderer front-end to MPD. Oct 21 06:06:31 minidsp-shd sudo[1085]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 21 06:06:31 minidsp-shd sudo[1085]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:31 minidsp-shd volumio[773]: info: Output device has changed, restarting Shairport Sync Oct 21 06:06:31 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:31 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 21 06:06:31 minidsp-shd sudo[1085]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:31 minidsp-shd sudo[1082]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:31 minidsp-shd sudo[1089]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 21 06:06:31 minidsp-shd sudo[1089]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:31 minidsp-shd systemd[1]: Listening on mpd.socket. Oct 21 06:06:31 minidsp-shd volumio[773]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 21 06:06:31 minidsp-shd volumio[773]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 21 06:06:31 minidsp-shd systemd[1]: Starting Music Player Daemon... Oct 21 06:06:31 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:31 minidsp-shd volumio[773]: info: ___________ START PLUGINS ___________ Oct 21 06:06:31 minidsp-shd volumio[773]: info: ControllerMpd::onStart: Initializing MPD Oct 21 06:06:31 minidsp-shd volumio[773]: info: Creating MPD Configuration file Oct 21 06:06:31 minidsp-shd sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 21 06:06:32 minidsp-shd sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 21 06:06:32 minidsp-shd volumio[773]: info: [1729483592012] CoreMusicLibrary::Adding element Media Servers Oct 21 06:06:32 minidsp-shd sudo[1098]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 21 06:06:32 minidsp-shd sudo[1100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 21 06:06:32 minidsp-shd sudo[1100]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 21 06:06:32 minidsp-shd systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 21 06:06:32 minidsp-shd systemd[1]: mpd.service: Succeeded. Oct 21 06:06:32 minidsp-shd systemd[1]: Stopped Music Player Daemon. Oct 21 06:06:32 minidsp-shd systemd[1]: Starting Music Player Daemon... Oct 21 06:06:32 minidsp-shd volumio[773]: info: Adding Manifest REST API Endpoints Oct 21 06:06:32 minidsp-shd volumio[773]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Oct 21 06:06:32 minidsp-shd volumio[773]: info: Adding METAVOLUMIO REST API Endpoints Oct 21 06:06:32 minidsp-shd volumio[773]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 21 06:06:32 minidsp-shd volumio[773]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 21 06:06:32 minidsp-shd volumio[773]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 21 06:06:32 minidsp-shd volumio[773]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 21 06:06:32 minidsp-shd sudo[1105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name miniDSP SHD Oct 21 06:06:32 minidsp-shd sudo[1105]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:32 minidsp-shd sudo[1105]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:32 minidsp-shd volumio[773]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 21 06:06:32 minidsp-shd volumio[773]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 21 06:06:32 minidsp-shd volumio[773]: info: Preparing CD Folders Oct 21 06:06:32 minidsp-shd volumio[773]: info: Adding CD REST API Endpoints Oct 21 06:06:32 minidsp-shd volumio[773]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 21 06:06:32 minidsp-shd volumio[773]: info: Starting UDEV Watcher for CD Oct 21 06:06:32 minidsp-shd volumio[773]: info: Detecting CD presence with UDEV Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 21 06:06:32 minidsp-shd volumio[773]: info: [1729483592422] CoreMusicLibrary::Adding element Last_100 Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 21 06:06:32 minidsp-shd volumio[773]: info: Starting RAAT Plugin Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Oct 21 06:06:32 minidsp-shd volumio[773]: info: Additional UI Settings Added for plugin music_service/raat Oct 21 06:06:32 minidsp-shd volumio[773]: info: Streaming services startup Oct 21 06:06:32 minidsp-shd volumio[773]: info: Starting Streaming Daemon Oct 21 06:06:32 minidsp-shd sudo[1116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 21 06:06:32 minidsp-shd sudo[1116]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:32 minidsp-shd sudo[1116]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 21 06:06:32 minidsp-shd volumio[773]: info: [1729483592673] CoreMusicLibrary::Adding element Webradio Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 21 06:06:32 minidsp-shd sudo[1123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 21 06:06:32 minidsp-shd sudo[1123]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:32 minidsp-shd volumio[773]: info: Creating Spotify config file Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:32 minidsp-shd sudo[1123]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:32 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 21 06:06:33 minidsp-shd volumio[773]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 21 06:06:33 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:33 minidsp-shd dbus-daemon[459]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.8' (uid=1000 pid=773 comm="/usr/bin/node /volumio/index.js ") Oct 21 06:06:33 minidsp-shd volumio[773]: error: Hi Res Audio Failed Login: Missing Login Data Oct 21 06:06:33 minidsp-shd systemd[1]: Starting Bluetooth service... Oct 21 06:06:33 minidsp-shd volumio[773]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 21 06:06:33 minidsp-shd volumio[773]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 21 06:06:33 minidsp-shd bluetoothd[1160]: Bluetooth daemon 5.23 Oct 21 06:06:33 minidsp-shd dbus-daemon[459]: [system] Successfully activated service 'org.bluez' Oct 21 06:06:33 minidsp-shd systemd[1]: Started Bluetooth service. Oct 21 06:06:33 minidsp-shd bluetoothd[1160]: Starting SDP server Oct 21 06:06:33 minidsp-shd bluetoothd[1160]: kernel lacks bnep-protocol support Oct 21 06:06:33 minidsp-shd bluetoothd[1160]: System does not support network plugin Oct 21 06:06:33 minidsp-shd bluetoothd[1160]: Bluetooth management interface 1.14 initialized Oct 21 06:06:33 minidsp-shd dbus-daemon[459]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.11' (uid=0 pid=1160 comm="/usr/local/libexec/bluetooth/bluetoothd ") Oct 21 06:06:33 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 21 06:06:33 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 21 06:06:33 minidsp-shd volumio[773]: info: Volumio Calling Home Oct 21 06:06:33 minidsp-shd systemd[1]: Starting Hostname Service... Oct 21 06:06:33 minidsp-shd volumio[773]: info: Stopping AccessToken refresher cron for QOBUZ Oct 21 06:06:33 minidsp-shd dbus-daemon[459]: [system] Successfully activated service 'org.freedesktop.hostname1' Oct 21 06:06:33 minidsp-shd systemd[1]: Started Hostname Service. Oct 21 06:06:33 minidsp-shd volumio[773]: info: AccessToken refresher cron started for QOBUZ Oct 21 06:06:33 minidsp-shd volumio[773]: info: Stopping AccessToken refresher cron Oct 21 06:06:33 minidsp-shd volumio[773]: info: AccessToken refresher cron started Oct 21 06:06:33 minidsp-shd volumio[773]: info: Adding TIDAL REST API Endpoints Oct 21 06:06:33 minidsp-shd volumio[773]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 21 06:06:33 minidsp-shd volumio[773]: info: Adding QOBUZ REST API Endpoints Oct 21 06:06:33 minidsp-shd volumio[773]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 21 06:06:34 minidsp-shd volumio[773]: info: Discovery: adding 7de2fe78-6056-4e2f-9f1b-de3300fc121e Oct 21 06:06:34 minidsp-shd volumio[773]: info: Discovery: Found device miniDSP SHD Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioGetState Oct 21 06:06:34 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:34 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output for this device Oct 21 06:06:34 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output Oct 21 06:06:34 minidsp-shd volumio[773]: info: Adding audio output: Oct 21 06:06:34 minidsp-shd volumio[773]: info: Adding audio output: Oct 21 06:06:34 minidsp-shd volumio[773]: info: Serial port opened successfully Oct 21 06:06:34 minidsp-shd volumio[773]: verbose: New Socket.io Connection to 192.168.10.197 from 192.168.10.151 UA: Mozilla/5.0 (Linux; Android 14; SM-S918B Build/UP1A.231005.007; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/129.0.6668.100 Mobile Safari/537.36 Total Clients: 6 Oct 21 06:06:34 minidsp-shd volumio[773]: error: Cannot start Volumio Streaming Daemon Oct 21 06:06:34 minidsp-shd volumio[773]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 21 06:06:34 minidsp-shd volumio[773]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 21 06:06:34 minidsp-shd volumio[773]: info: RAAT Albumart path created successfully Oct 21 06:06:34 minidsp-shd volumio[773]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'miniDSP SHD' Oct 21 06:06:34 minidsp-shd volumio[773]: Can't get device info: No such device Oct 21 06:06:34 minidsp-shd volumio[773]: info: MPD Permissions set Oct 21 06:06:34 minidsp-shd volumio[773]: info: MPD Permissions set Oct 21 06:06:34 minidsp-shd volumio[773]: info: Upmpdcli Daemon Started Oct 21 06:06:34 minidsp-shd volumio[773]: info: msSurfaceDial volumioupdatevolume callback: {"vol":79,"dbVolume":-27,"disableVolumeControl":false} Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreStateMachine::pushState Oct 21 06:06:34 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioPushState Oct 21 06:06:34 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output update for this device Oct 21 06:06:34 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Oct 21 06:06:34 minidsp-shd volumio[773]: info: Ignoring ROON Volume update because of undefined data Oct 21 06:06:34 minidsp-shd volumio[773]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} Oct 21 06:06:34 minidsp-shd volumio[773]: info: [msSurfaceDial init()] check /org/bluez Oct 21 06:06:34 minidsp-shd volumio[773]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Oct 21 06:06:34 minidsp-shd volumio[773]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::Reload Ui Oct 21 06:06:34 minidsp-shd volumio[773]: info: Volumio called home Oct 21 06:06:34 minidsp-shd volumio[773]: info: Spotify config file written Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Oct 21 06:06:34 minidsp-shd volumio[773]: info: updateDSP function in raat called! Oct 21 06:06:34 minidsp-shd volumio[773]: info: Updating RAAT Signal Path Oct 21 06:06:34 minidsp-shd volumio[773]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Oct 21 06:06:34 minidsp-shd volumio[773]: info: Reconfiguring and Restarting RAAT Plugin Oct 21 06:06:34 minidsp-shd sudo[1182]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Oct 21 06:06:34 minidsp-shd sudo[1182]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 21 06:06:34 minidsp-shd systemd[1]: Started go-librespot Daemon. Oct 21 06:06:34 minidsp-shd volumio[773]: info: Not Reporting Auto name since its the default one Oct 21 06:06:34 minidsp-shd volumio[773]: info: RAAT Overriding default device vendor model Oct 21 06:06:34 minidsp-shd go-librespot[1188]: Librespot-go daemon starting... Oct 21 06:06:34 minidsp-shd volumio[773]: info: Adding MINIDSP Inputs Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 21 06:06:34 minidsp-shd volumio[773]: info: [1729483594572] CoreMusicLibrary::Adding element Inputs Oct 21 06:06:34 minidsp-shd sudo[1182]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 21 06:06:34 minidsp-shd volumio[773]: info: [1729483594583] CoreMusicLibrary::Adding element Presets Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 21 06:06:34 minidsp-shd volumio[773]: Cannot find translation for source Presets Oct 21 06:06:34 minidsp-shd volumio[773]: (node:773) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Oct 21 06:06:34 minidsp-shd volumio[773]: (Use `node --trace-deprecation ...` to show where the warning was created) Oct 21 06:06:34 minidsp-shd volumio[773]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 7 Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:34+02:00" level=info msg="generated new device id: d99e1feaca6bdcdf8acf0bc60491bd376f3e855d" Oct 21 06:06:34 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:34+02:00" level=debug msg="stored credentials found for 1164747946" Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 21 06:06:34 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:34 minidsp-shd volumio[773]: info: No need to fix Spotify hosts Oct 21 06:06:35 minidsp-shd sudo[1209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 21 06:06:35 minidsp-shd sudo[1209]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:35 minidsp-shd systemd[1]: Started Volumio Bluetooth Module. Oct 21 06:06:35 minidsp-shd sudo[1209]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:35 minidsp-shd volumio[773]: info: MRS: Found cast device: Altibox-TV-435455ce13cad7e711055c9a6762ab85 Oct 21 06:06:35 minidsp-shd volumio[773]: info: Adding audio output: Oct 21 06:06:35 minidsp-shd volumio[773]: info: CoreCommandRouter::volumiosetSourceActiveno-source Oct 21 06:06:35 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 21 06:06:35 minidsp-shd volumio[773]: Cannot find translation for source Presets Oct 21 06:06:35 minidsp-shd sudo[1213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 21 06:06:35 minidsp-shd sudo[1213]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:35 minidsp-shd volumiobt[1211]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Oct 21 06:06:35 minidsp-shd sudo[1213]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:35 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:35+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Oct 21 06:06:35 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:35+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Oct 21 06:06:35 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:35+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Oct 21 06:06:35 minidsp-shd volumio[773]: info: Starting Shairport Sync Oct 21 06:06:35 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:35+02:00" level=debug msg="zeroconf server listening on port 37997" Oct 21 06:06:35 minidsp-shd volumio[773]: info: Starting Shairport Sync Oct 21 06:06:35 minidsp-shd volumio[773]: info: Starting Shairport Sync Oct 21 06:06:35 minidsp-shd sudo[1217]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 21 06:06:35 minidsp-shd sudo[1217]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:35 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 21 06:06:35 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:35+02:00" level=debug msg="obtained new client token: AACPInTVvLjh2HoieIvc/B1W9qCIReB+J43pmPxCGLfvR9j3h6X7V/IBY6mtPNVW6QduAgxnOBIFhjd8NcT+ad+CDv0Ze1EXlODkdrl6EHe4RVdeeG7sPr8pjHCuhUup5+usG8eThrIbGqlEKta/4lzZjG0U4u6b43zWpX6ilOunGgXxZcljc5Sq7G0vakvvQGo8oAoyGkOWdwm6bmnuuvLt1LTtltVL0EZn/F8kk15U672xYSpFAMSukmRbtIA=" Oct 21 06:06:35 minidsp-shd sudo[1219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 21 06:06:35 minidsp-shd sudo[1219]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:35 minidsp-shd systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 21 06:06:35 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:35+02:00" level=info msg="connected to ap-gew4.spotify.com:4070" Oct 21 06:06:35 minidsp-shd systemd[1]: shairport-sync.service: Succeeded. Oct 21 06:06:35 minidsp-shd systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 21 06:06:35 minidsp-shd sudo[1222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 21 06:06:35 minidsp-shd sudo[1222]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:35 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:35+02:00" level=debug msg="completed keyexchange" Oct 21 06:06:35 minidsp-shd volumio[773]: xcb_connection_has_error() returned true Oct 21 06:06:35 minidsp-shd systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 21 06:06:35 minidsp-shd sudo[1217]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:35 minidsp-shd sudo[1222]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:35 minidsp-shd sudo[1219]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:35 minidsp-shd volumio[773]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 21 06:06:35 minidsp-shd volumio[773]: SPOTIFY: BQCRuJ13uv8KhL1W_8hYCaGPfzvjo7-hns1-D6wV8A4-5-T7dKvqGxtCfFbm6wFu9JRMIeXanM6zhAm-Oxw5szUotnjFtoqHhd5s5L3GfbZB_PSGzZIqQ5HQf4jaM58kWB6qsrPsIsYXph31_dCN3sT3EyOdkJbbt_7T27y0pMnL4P5GubuLgrPHLc-yOO7b4QPxUh_EUFFQFVoX2BalEbZVNEOhx4XcN3LYLSMQmR2DP7CX283gjwZkc6A Oct 21 06:06:35 minidsp-shd volumio[773]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Oct 21 06:06:36 minidsp-shd volumio[773]: info: New Spotify access token = BQCRuJ13uv8KhL1W_8hYCaGPfzvjo7-hns1-D6wV8A4-5-T7dKvqGxtCfFbm6wFu9JRMIeXanM6zhAm-Oxw5szUotnjFtoqHhd5s5L3GfbZB_PSGzZIqQ5HQf4jaM58kWB6qsrPsIsYXph31_dCN3sT3EyOdkJbbt_7T27y0pMnL4P5GubuLgrPHLc-yOO7b4QPxUh_EUFFQFVoX2BalEbZVNEOhx4XcN3LYLSMQmR2DP7CX283gjwZkc6A Oct 21 06:06:36 minidsp-shd volumio[773]: info: Spotify credentials grant success - running version from March 24, 2019 Oct 21 06:06:36 minidsp-shd sudo[1229]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 21 06:06:36 minidsp-shd sudo[1229]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:36 minidsp-shd volumio[773]: info: MRS: Found cast device: Chromecast-Audio-4cae30823081f8544169b645ef26362c Oct 21 06:06:36 minidsp-shd volumio[773]: info: Adding audio output: Oct 21 06:06:36 minidsp-shd volumio[773]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 21 06:06:36 minidsp-shd systemd[1]: Started RAAT DAEMON. Oct 21 06:06:36 minidsp-shd volumio[773]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 21 06:06:36 minidsp-shd volumio[773]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 21 06:06:36 minidsp-shd volumio[773]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 21 06:06:36 minidsp-shd sudo[1229]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:36 minidsp-shd volumio[773]: info: Volumio BT Module successfully started Oct 21 06:06:36 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 21 06:06:36 minidsp-shd volumio[773]: info: Enabling external Volume Control Oct 21 06:06:36 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:36 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:36 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:36+02:00" level=debug msg="completed challenge" Oct 21 06:06:36 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:36+02:00" level=debug msg="authenticated as 1164747946" Oct 21 06:06:36 minidsp-shd volumio[773]: info: Raat Daemon started successfully Oct 21 06:06:36 minidsp-shd volumio[773]: info: Shairport-Sync Started Oct 21 06:06:36 minidsp-shd volumio[773]: Error adding Membership: Error: addMembership EINVAL Oct 21 06:06:36 minidsp-shd volumio[773]: info: Shairport-Sync Started Oct 21 06:06:36 minidsp-shd volumio[773]: info: Shairport-Sync Started Oct 21 06:06:36 minidsp-shd volumio[773]: info: TidalConnect service stoped! Oct 21 06:06:36 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:36+02:00" level=debug msg="authenticated as 1164747946" Oct 21 06:06:36 minidsp-shd sudo[1244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 21 06:06:36 minidsp-shd sudo[1244]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:36 minidsp-shd volumiobt[1211]: pulseaudio: no process found Oct 21 06:06:36 minidsp-shd sudo[1244]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:36 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:36+02:00" level=debug msg="dealer connection opened" Oct 21 06:06:36 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:36+02:00" level=debug msg="initializing zeroconf session, username: 1164747946" Oct 21 06:06:36 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:36+02:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Oct 21 06:06:36 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:36+02:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Oct 21 06:06:36 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:36+02:00" level=debug msg="received connection id: M2FiMTVhNDQtOTA4Ni00MDRhLThlZjMtNTJhMzkwYTE0YTkzK2RlYWxlcit0Y3A6Ly8wYWNhNTkzNS5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArQTc4NzIyNURDMUUwM0ZGMTBDRDFFRENGRjc5RDhCNjc1RTBDODdFNkRFQzMxM0FFRDJFODlDRTI4Q0RFRDIyRA==" Oct 21 06:06:36 minidsp-shd dbus-daemon[459]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.18' (uid=1000 pid=1252 comm="/usr/bin/pulseaudio --high-priority=true ") Oct 21 06:06:36 minidsp-shd volumio[773]: info: Executing endpoint restartRAATSocket Oct 21 06:06:36 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 21 06:06:36 minidsp-shd systemd[1]: Starting RealtimeKit Scheduling Policy Service... Oct 21 06:06:36 minidsp-shd volumio[773]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 8 Oct 21 06:06:36 minidsp-shd volumio[773]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 21 06:06:36 minidsp-shd dbus-daemon[459]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Oct 21 06:06:36 minidsp-shd rtkit-daemon[1254]: Successfully called chroot. Oct 21 06:06:36 minidsp-shd systemd[1]: Started RealtimeKit Scheduling Policy Service. Oct 21 06:06:36 minidsp-shd rtkit-daemon[1254]: Successfully dropped privileges. Oct 21 06:06:36 minidsp-shd rtkit-daemon[1254]: Successfully limited resources. Oct 21 06:06:36 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:36+02:00" level=debug msg="put connect state because NEW_DEVICE" Oct 21 06:06:36 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:36+02:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Oct 21 06:06:36 minidsp-shd rtkit-daemon[1254]: Running. Oct 21 06:06:36 minidsp-shd dbus-daemon[459]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.19' (uid=0 pid=1254 comm="/usr/lib/rtkit/rtkit-daemon ") Oct 21 06:06:36 minidsp-shd rtkit-daemon[1254]: Watchdog thread running. Oct 21 06:06:36 minidsp-shd rtkit-daemon[1254]: Canary thread running. Oct 21 06:06:36 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:36+02:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Oct 21 06:06:36 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:36+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 493" Oct 21 06:06:36 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:36+02:00" level=debug msg="autoplay enabled: false" Oct 21 06:06:37 minidsp-shd systemd[1]: Starting Authorization Manager... Oct 21 06:06:37 minidsp-shd volumio[773]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 21 06:06:37 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioGetState Oct 21 06:06:37 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:37 minidsp-shd polkitd[1257]: started daemon version 0.105 using authority implementation `local' version `0.105' Oct 21 06:06:37 minidsp-shd dbus-daemon[459]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 21 06:06:37 minidsp-shd systemd[1]: Started Authorization Manager. Oct 21 06:06:37 minidsp-shd volumio[773]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 21 06:06:37 minidsp-shd volumio[773]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 21 06:06:37 minidsp-shd sudo[1266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 21 06:06:37 minidsp-shd volumio[773]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 9 Oct 21 06:06:37 minidsp-shd sudo[1266]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:37 minidsp-shd systemd[1]: Started Volumio Tidal Connect Service. Oct 21 06:06:37 minidsp-shd sudo[1266]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:37 minidsp-shd volumio[773]: info: msSurfaceDial volumioupdatevolume callback: {"vol":79,"dbVolume":-27,"mute":false,"disableVolumeControl":false} Oct 21 06:06:37 minidsp-shd volumio[773]: info: CoreStateMachine::pushState Oct 21 06:06:37 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:37 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 06:06:37 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioPushState Oct 21 06:06:37 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output update for this device Oct 21 06:06:37 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output Oct 21 06:06:37 minidsp-shd volumio[773]: error: Help! Some callbacks for volumioPushState are crashing! Oct 21 06:06:37 minidsp-shd volumio[773]: error: Cannot read property 'sendVolumeMute' of undefined Oct 21 06:06:38 minidsp-shd volumio[773]: info: MRS: Getting audio outputs on start Oct 21 06:06:38 minidsp-shd volumio[773]: info: MRS: Requesting all other devices output Oct 21 06:06:38 minidsp-shd volumio[773]: info: go-librespot daemon successfully initialized Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioGetState Oct 21 06:06:38 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:38 minidsp-shd volumio[773]: SPOTIFY: User informations: {"country":"NO","display_name":"Bartek Gołędowski","email":"baltona84@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/1164747946"},"followers":{"href":null,"total":16},"href":"https://api.spotify.com/v1/users/1164747946","id":"1164747946","images":[{"height":300,"url":"https://scontent-bru2-1.xx.fbcdn.net/v/t31.18172-1/27797538_10156432867544369_5035997697550006766_o.jpg?stp=dst-jpg_s320x320&_nc_cat=111&ccb=1-7&_nc_sid=05c18e&_nc_ohc=ecUt4evKqCAQ7kNvgHVc99V&_nc_zt=24&_nc_ht=scontent-bru2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=AjnzkfKtWPJEDQDvKbDbdX2&oh=00_AYDd8_3HPAn9m3rbMG4FtimfxCZzXovRAQ40-3ftJrmpPw&oe=673D5023","width":300},{"height":64,"url":"https://scontent-bru2-1.xx.fbcdn.net/v/t31.18172-1/27797538_10156432867544369_5035997697550006766_o.jpg?stp=cp0_dst-jpg_s50x50&_nc_cat=111&ccb=1-7&_nc_sid=312bcd&_nc_ohc=ecUt4evKqCAQ7kNvgHVc99V&_nc_zt=24&_nc_ht=scontent-bru2-1.xx&edm=AP4hL3IEAAAA&_nc_gid=AjnzkfKtWPJEDQDvKbDbdX2&oh=00_AYBjdzgZo11uDRaxbJJ6NBIWp6PhcSSa1Asspz4I3db68A&oe=673D5023","width":64}],"product":"premium","type":"user","uri":"spotify:user:1164747946"} Oct 21 06:06:38 minidsp-shd volumio[773]: info: Spotify Successfully logged in Oct 21 06:06:38 minidsp-shd pulseaudio[1252]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 21 06:06:38 minidsp-shd pulseaudio[1252]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 21 06:06:38 minidsp-shd volumiobt[1211]: Applying permissions Oct 21 06:06:38 minidsp-shd volumiobt[1211]: Setting BT discoverable and pairable Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 21 06:06:38 minidsp-shd volumio[773]: info: [1729483598345] CoreMusicLibrary::Adding element Spotify Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 21 06:06:38 minidsp-shd volumio[773]: Cannot find translation for source Presets Oct 21 06:06:38 minidsp-shd volumio[773]: Cannot find translation for source Spotify Oct 21 06:06:38 minidsp-shd pulseaudio[1252]: 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 Oct 21 06:06:38 minidsp-shd volumiobt[1211]: [38B blob data] Oct 21 06:06:38 minidsp-shd volumiobt[1211]: Invalid command Oct 21 06:06:38 minidsp-shd volumiobt[1211]: [bluetooth]# agent on Oct 21 06:06:38 minidsp-shd volumiobt[1211]: [bluetooth]# discoverable on Oct 21 06:06:38 minidsp-shd volumiobt[1211]: No default controller available Oct 21 06:06:38 minidsp-shd volumiobt[1211]: [bluetooth]# pairable on Oct 21 06:06:38 minidsp-shd volumiobt[1211]: No default controller available Oct 21 06:06:38 minidsp-shd volumiobt[1211]: [bluetooth]# agent NoInputNoOutput Oct 21 06:06:38 minidsp-shd volumiobt[1211]: Failed to register agent object Oct 21 06:06:38 minidsp-shd volumiobt[1211]: [bluetooth]# default-agent Oct 21 06:06:38 minidsp-shd volumiobt[1211]: No agent is registered Oct 21 06:06:38 minidsp-shd volumiobt[1211]: [bluetooth]# quit Oct 21 06:06:38 minidsp-shd volumiobt[1211]: [61B blob data] Oct 21 06:06:38 minidsp-shd volumiobt[1211]: [62B blob data] Oct 21 06:06:38 minidsp-shd volumio[773]: info: msSurfaceDial volumioupdatevolume callback: {"vol":79,"dbVolume":-27,"mute":false,"disableVolumeControl":false} Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreStateMachine::pushState Oct 21 06:06:38 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioPushState Oct 21 06:06:38 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output update for this device Oct 21 06:06:38 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output Oct 21 06:06:38 minidsp-shd volumio[773]: error: Help! Some callbacks for volumioPushState are crashing! Oct 21 06:06:38 minidsp-shd volumio[773]: error: Cannot read property 'sendVolumeMute' of undefined Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: raat , updateRoonVolume Oct 21 06:06:38 minidsp-shd volumio[773]: info: Executing endpoint tc_getconfig Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 21 06:06:38 minidsp-shd vtcs[1268]: STARTING TidalConnect services, version: 1.3.0.19 Oct 21 06:06:38 minidsp-shd vtcs[1268]: STARTED TidalConnect services. Oct 21 06:06:38 minidsp-shd volumio[773]: info: Executing endpoint tc_connect Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 21 06:06:38 minidsp-shd volumio[773]: info: Connecting to TidalConnect Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreCommandRouter::servicePushState Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreStateMachine::pushState Oct 21 06:06:38 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioPushState Oct 21 06:06:38 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output update for this device Oct 21 06:06:38 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output Oct 21 06:06:38 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:38 minidsp-shd volumio[773]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 21 06:06:38 minidsp-shd volumio[773]: verbose: CURRENT POSITION 0 Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreStateMachine::syncState stateService stop Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreStateMachine::syncState currentStatus stop Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreStateMachine::pushState Oct 21 06:06:38 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioPushState Oct 21 06:06:38 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output update for this device Oct 21 06:06:38 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output Oct 21 06:06:38 minidsp-shd volumio[773]: info: No code Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreStateMachine::pushState Oct 21 06:06:38 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioPushState Oct 21 06:06:38 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output update for this device Oct 21 06:06:38 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreCommandRouter::servicePushState Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreStateMachine::pushState Oct 21 06:06:38 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:38 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioPushState Oct 21 06:06:38 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output update for this device Oct 21 06:06:38 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output Oct 21 06:06:39 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:39 minidsp-shd volumio[773]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Oct 21 06:06:39 minidsp-shd volumio[773]: verbose: CURRENT POSITION 0 Oct 21 06:06:39 minidsp-shd volumio[773]: info: CoreStateMachine::syncState stateService stop Oct 21 06:06:39 minidsp-shd volumio[773]: info: CoreStateMachine::syncState currentStatus stop Oct 21 06:06:39 minidsp-shd volumio[773]: info: CoreStateMachine::pushState Oct 21 06:06:39 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:39 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioPushState Oct 21 06:06:39 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output update for this device Oct 21 06:06:39 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output Oct 21 06:06:39 minidsp-shd volumio[773]: info: No code Oct 21 06:06:39 minidsp-shd volumio[773]: info: CoreStateMachine::pushState Oct 21 06:06:39 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:39 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioPushState Oct 21 06:06:39 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output update for this device Oct 21 06:06:39 minidsp-shd volumio[773]: info: MRS: Pushing multiroomSync output Oct 21 06:06:40 minidsp-shd volumio[773]: info: TidalConnect service started! Oct 21 06:06:40 minidsp-shd mpd[1104]: Oct 21 06:06 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 21 06:06:40 minidsp-shd systemd[1]: Started Music Player Daemon. Oct 21 06:06:40 minidsp-shd volumio[773]: Upnp client error: Error: This socket has been ended by the other party Oct 21 06:06:40 minidsp-shd volumio[773]: Upnp client error: Error: This socket has been ended by the other party Oct 21 06:06:40 minidsp-shd sudo[1100]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:40 minidsp-shd sudo[1089]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:40 minidsp-shd volumio[773]: info: Completed starting Core Plugins Oct 21 06:06:40 minidsp-shd volumio[773]: info: ------------------------------------------- Oct 21 06:06:40 minidsp-shd volumio[773]: info: ----- MyVolumio plugins startup ---- Oct 21 06:06:40 minidsp-shd volumio[773]: info: ------------------------------------------- Oct 21 06:06:40 minidsp-shd volumio[773]: info: [MyVolumio PluginManager] Fetching plans data.... Oct 21 06:06:40 minidsp-shd volumio[773]: error: MPD error: The expression evaluated to a falsy value: Oct 21 06:06:40 minidsp-shd volumio[773]: assert.ok(self.idling) Oct 21 06:06:40 minidsp-shd volumio[773]: error: The expression evaluated to a falsy value: Oct 21 06:06:40 minidsp-shd volumio[773]: assert.ok(self.idling) Oct 21 06:06:40 minidsp-shd volumio[773]: info: MPD running with PID1104 Oct 21 06:06:40 minidsp-shd volumio[773]: ,establishing connection Oct 21 06:06:40 minidsp-shd volumio[773]: error: MPD error: The expression evaluated to a falsy value: Oct 21 06:06:40 minidsp-shd volumio[773]: assert.ok(self.idling) Oct 21 06:06:40 minidsp-shd volumio[773]: error: The expression evaluated to a falsy value: Oct 21 06:06:40 minidsp-shd volumio[773]: assert.ok(self.idling) Oct 21 06:06:40 minidsp-shd volumio[773]: error: updateQueue error: null Oct 21 06:06:40 minidsp-shd volumio-remote-updater[501]: [2024-10-21 06:06:40] [connect] Successful connection Oct 21 06:06:40 minidsp-shd volumio-remote-updater[501]: [2024-10-21 06:06:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1729483600 101 Oct 21 06:06:40 minidsp-shd volumio[773]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 10 Oct 21 06:06:41 minidsp-shd volumio[773]: info: Initializing connection to go-librespot Websocket Oct 21 06:06:41 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:41+02:00" level=debug msg="new websocket client" Oct 21 06:06:41 minidsp-shd volumio[773]: info: Connection to go-librespot Websocket established Oct 21 06:06:41 minidsp-shd volumio[1087]: Generating RSA private key, 4096 bit long modulus (2 primes) Oct 21 06:06:42 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:42+02:00" level=debug msg="handling transfer player command from 1c3f961463532dd05c7fdd38b2200608ba8c3c1e" Oct 21 06:06:42 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:42+02:00" level=debug msg="resolved context of track" uri="spotify:artist:2AM4ilv6UzW0uMRuqKtDgN" Oct 21 06:06:42 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:42+02:00" level=trace msg="fetched new page 0 with 40 items (list: 40)" uri="spotify:artist:2AM4ilv6UzW0uMRuqKtDgN" Oct 21 06:06:42 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:42+02:00" level=debug msg="loading track (paused: false, position: 85100ms)" uri="spotify:track:2zNkzCAijAuCYWzxKJeYO2" Oct 21 06:06:42 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:42+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 21 06:06:42 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:42+02:00" level=trace msg="emitting websocket event: will_play" Oct 21 06:06:42 minidsp-shd volumio[773]: SPOTIFY: received: {"type":"will_play","data":{"uri":"spotify:track:2zNkzCAijAuCYWzxKJeYO2","play_origin":"free-tier-artist"}} Oct 21 06:06:42 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:42+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Oct 21 06:06:42 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:42+02:00" level=debug msg="selected format OGG_VORBIS_320 (69cd8e3873020483fe84f4e520615ce57c112cca)" uri="spotify:track:2zNkzCAijAuCYWzxKJeYO2" Oct 21 06:06:42 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:42+02:00" level=debug msg="requested aes key for file 69cd8e3873020483fe84f4e520615ce57c112cca, gid: 2zNkzCAijAuCYWzxKJeYO2" Oct 21 06:06:42 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:42+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 2822" Oct 21 06:06:42 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:42 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:42 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:42 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 21 06:06:42 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 21 06:06:42 minidsp-shd volumio[773]: info: Not Reporting Auto name since its the default one Oct 21 06:06:42 minidsp-shd volumio[773]: info: RAAT Overriding default device vendor model Oct 21 06:06:42 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 21 06:06:42 minidsp-shd volumio[773]: xcb_connection_has_error() returned true Oct 21 06:06:42 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:42+02:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1867" Oct 21 06:06:42 minidsp-shd sudo[1317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 21 06:06:42 minidsp-shd sudo[1317]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 21 06:06:42 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:42+02:00" level=debug msg="fetched first chunk of 14, total size is 7172028 bytes" uri="spotify:track:2zNkzCAijAuCYWzxKJeYO2" Oct 21 06:06:42 minidsp-shd systemd[1]: Stopping RAAT DAEMON... Oct 21 06:06:42 minidsp-shd systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 21 06:06:42 minidsp-shd systemd[1]: raat-daemon.service: Succeeded. Oct 21 06:06:42 minidsp-shd systemd[1]: Stopped RAAT DAEMON. Oct 21 06:06:42 minidsp-shd systemd[1]: Started RAAT DAEMON. Oct 21 06:06:42 minidsp-shd sudo[1317]: pam_unix(sudo:session): session closed for user root Oct 21 06:06:42 minidsp-shd volumio[773]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 21 06:06:42 minidsp-shd volumio[773]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 21 06:06:42 minidsp-shd volumio[773]: info: Raat Daemon started successfully Oct 21 06:06:43 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:43+02:00" level=debug msg="fetched chunk 1/13, size: 524288" uri="spotify:track:2zNkzCAijAuCYWzxKJeYO2" Oct 21 06:06:43 minidsp-shd volumio[773]: info: Executing endpoint restartRAATSocket Oct 21 06:06:43 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 21 06:06:43 minidsp-shd volumio[773]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Oct 21 06:06:43 minidsp-shd volumio[773]: info: updateDSP function in raat called! Oct 21 06:06:43 minidsp-shd volumio[773]: info: Updating RAAT Signal Path Oct 21 06:06:43 minidsp-shd volumio[773]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 11 Oct 21 06:06:43 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioGetState Oct 21 06:06:43 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:43 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:43+02:00" level=debug msg="fetched chunk 3/13, size: 524288" uri="spotify:track:2zNkzCAijAuCYWzxKJeYO2" Oct 21 06:06:43 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:43+02:00" level=debug msg="fetched chunk 7/13, size: 524288" uri="spotify:track:2zNkzCAijAuCYWzxKJeYO2" Oct 21 06:06:43 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:43+02:00" level=debug msg="fetched chunk 2/13, size: 524288" uri="spotify:track:2zNkzCAijAuCYWzxKJeYO2" Oct 21 06:06:43 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:43+02:00" level=debug msg="fetched chunk 8/13, size: 524288" uri="spotify:track:2zNkzCAijAuCYWzxKJeYO2" Oct 21 06:06:43 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:43+02:00" level=debug msg="fetched chunk 6/13, size: 524288" uri="spotify:track:2zNkzCAijAuCYWzxKJeYO2" Oct 21 06:06:43 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:43+02:00" level=trace msg="seek to 85100ms (diff: 129ms, samples: 3752910, bytes: 3540457)" uri="spotify:track:2zNkzCAijAuCYWzxKJeYO2" Oct 21 06:06:43 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:43+02:00" level=debug msg="fetched chunk 9/13, size: 524288" uri="spotify:track:2zNkzCAijAuCYWzxKJeYO2" Oct 21 06:06:44 minidsp-shd go-librespot[1188]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 22050, but target_pcm volumioOutput has buffer size 4096 Oct 21 06:06:44 minidsp-shd go-librespot[1188]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 22050, but target_pcm volumioOutput has buffer size 4096 Oct 21 06:06:44 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:44+02:00" level=debug msg="created new output device" Oct 21 06:06:44 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:44+02:00" level=info msg="loaded track \"Waitin' for the Bus (2006 Remaster)\" (paused: false, position: 85100ms, duration: 173266ms, prefetched: false)" uri="spotify:track:2zNkzCAijAuCYWzxKJeYO2" Oct 21 06:06:44 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:44+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 21 06:06:44 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:44+02:00" level=trace msg="scheduling prefetch in 58s" Oct 21 06:06:44 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:44+02:00" level=trace msg="emitting websocket event: metadata" Oct 21 06:06:44 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:44+02:00" level=trace msg="emitting websocket event: active" Oct 21 06:06:44 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:44+02:00" level=debug msg="sending successful reply for dealer request" Oct 21 06:06:44 minidsp-shd volumio[773]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:2zNkzCAijAuCYWzxKJeYO2","name":"Waitin' for the Bus (2006 Remaster)","artist_names":["ZZ Top"],"album_name":"Tres Hombres (Expanded 2006 Remaster)","album_cover_url":"https://i.scdn.co/image/ab67616d00001e02db9c5945ce3a3f873695d18a","position":85100,"duration":173266,"release_date":"year:1973 month:7 day:26","track_number":1,"disc_number":1}} Oct 21 06:06:44 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:44+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Oct 21 06:06:44 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:44+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Oct 21 06:06:44 minidsp-shd volumio[773]: SPOTIFY: received: {"type":"active","data":null} Oct 21 06:06:44 minidsp-shd volumio[773]: info: Aligning Spotify Volume to Volumio Volume Oct 21 06:06:44 minidsp-shd volumio[773]: info: CoreCommandRouter::volumioGetState Oct 21 06:06:44 minidsp-shd volumio[773]: info: CorePlayQueue::getTrack 0 Oct 21 06:06:44 minidsp-shd volumio[773]: info: Setting Spotify Volume from Volumio: 79 Oct 21 06:06:44 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:44+02:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Oct 21 06:06:44 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:44+02:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Oct 21 06:06:44 minidsp-shd go-librespot[1188]: time="2024-10-21T06:06:44+02:00" level=trace msg="emitting websocket event: playing" Oct 21 06:06:44 minidsp-shd volumio[773]: SPOTIFY: received: {"type":"playing","data":{"uri":"spotify:track:2zNkzCAijAuCYWzxKJeYO2","play_origin":"free-tier-artist"}} Oct 21 06:06:44 minidsp-shd volumio[773]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 06:06:44 minidsp-shd volumio[773]: TypeError: Cannot read property 'service' of undefined Oct 21 06:06:44 minidsp-shd volumio[773]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:337:50) Oct 21 06:06:44 minidsp-shd volumio[773]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:263:18) Oct 21 06:06:44 minidsp-shd volumio[773]: at WebSocket.message (/data/plugins/music_service/spop/index.js:190:14) Oct 21 06:06:44 minidsp-shd volumio[773]: at WebSocket.emit (events.js:400:28) Oct 21 06:06:44 minidsp-shd volumio[773]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1220:20) Oct 21 06:06:44 minidsp-shd volumio[773]: at Receiver.emit (events.js:400:28) Oct 21 06:06:44 minidsp-shd volumio[773]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:596:14) Oct 21 06:06:44 minidsp-shd volumio[773]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:496:10) Oct 21 06:06:44 minidsp-shd volumio[773]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:16) Oct 21 06:06:44 minidsp-shd volumio[773]: at Receiver._write (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:94:10) Oct 21 06:06:44 minidsp-shd volumio[773]: at writeOrBuffer (internal/streams/writable.js:358:12) Oct 21 06:06:44 minidsp-shd volumio[773]: at Receiver.Writable.write (internal/streams/writable.js:303:10) Oct 21 06:06:44 minidsp-shd volumio[773]: at Socket.socketOnData (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1355:35) Oct 21 06:06:44 minidsp-shd volumio[773]: at Socket.emit (events.js:400:28) Oct 21 06:06:44 minidsp-shd volumio[773]: at addChunk (internal/streams/readable.js:293:12) Oct 21 06:06:44 minidsp-shd volumio[773]: at readableAddChunk (internal/streams/readable.js:267:9) Oct 21 06:06:44 minidsp-shd volumio[773]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 21 06:06:45 minidsp-shd sudo[1340]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-21 06:05 Oct 21 06:06:45 minidsp-shd sudo[1340]: 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="17fcb754ddd6bd152b01008c46cc4ba1ca48bf35" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="281cefcd7a302aa7cfba569185cf7aae19b8c376" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="minidspshd" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 22 Mar 2024 03:13:25 PM CET" VOLUMIO_VERSION="3.608" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR="miniDSP" VOLUMIO_MODEL="SHD" VOLUMIO_VENDOR_MODEL="miniDSP SHD" VOLUMIO_HASH="18533ba33f8f92d7e7e459bb30f70beb"