-- Logs begin at Fri 2024-03-29 03:46:30 GMT, end at Tue 2025-02-18 18:38:54 GMT. -- Feb 18 18:38:07 cartman-minidsp ntpd[777]: receive: Unexpected origin timestamp 0xe9b0b830.dc93f3af does not match aorg 0000000000.00000000 from server@139.162.219.252 xmt 0xeb5f520f.4fcabb2b Feb 18 18:38:07 cartman-minidsp ntpd[777]: receive: Unexpected origin timestamp 0xe9b0b830.dc8c19e9 does not match aorg 0000000000.00000000 from server@194.146.161.122 xmt 0xeb5f520f.4ffec28d Feb 18 18:38:07 cartman-minidsp ntpd[777]: receive: Unexpected origin timestamp 0xe9b0b830.dc6ef153 does not match aorg 0000000000.00000000 from server@195.171.43.12 xmt 0xeb5f520f.504b8a7a Feb 18 18:38:07 cartman-minidsp ntpd[777]: receive: Unexpected origin timestamp 0xe9b0b830.dc96b71d does not match aorg 0000000000.00000000 from server@213.5.132.231 xmt 0xeb5f520f.506ec326 Feb 18 18:38:07 cartman-minidsp ntpd[777]: receive: Unexpected origin timestamp 0xe9b0b830.dc90cd3f does not match aorg 0000000000.00000000 from server@131.111.8.63 xmt 0xeb5f520f.503b0594 Feb 18 18:38:07 cartman-minidsp ntpd[777]: receive: Unexpected origin timestamp 0xe9b0b830.dc753cc1 does not match aorg 0000000000.00000000 from server@178.215.228.24 xmt 0xeb5f520f.51118880 Feb 18 18:38:07 cartman-minidsp systemd[1]: Starting Daily apt download activities... Feb 18 18:38:07 cartman-minidsp ntpd[777]: Soliciting pool server 51.89.139.80 Feb 18 18:38:07 cartman-minidsp volumio-remote-updater[454]: [2025-02-18 18:38:07] [connect] Successful connection Feb 18 18:38:08 cartman-minidsp systemd[1]: apt-daily.service: Succeeded. Feb 18 18:38:08 cartman-minidsp systemd[1]: Started Daily apt download activities. Feb 18 18:38:08 cartman-minidsp systemd[1]: Starting Daily apt upgrade and clean activities... Feb 18 18:38:09 cartman-minidsp volumio[789]: info: Loading plugin "network"... Feb 18 18:38:09 cartman-minidsp volumio[789]: info: Refreshing Cached IP Addresses Feb 18 18:38:09 cartman-minidsp sudo[912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 18:38:09 cartman-minidsp sudo[912]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:09 cartman-minidsp volumio[789]: info: Loading plugin "services"... Feb 18 18:38:09 cartman-minidsp sudo[914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 18:38:09 cartman-minidsp sudo[914]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:09 cartman-minidsp sudo[914]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:09 cartman-minidsp volumio[789]: info: Loading plugin "alsa_controller"... Feb 18 18:38:09 cartman-minidsp sudo[912]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:09 cartman-minidsp sudo[919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 18 18:38:09 cartman-minidsp sudo[919]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:09 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 18:38:09 cartman-minidsp volumio[789]: info: Loading plugin "wizard"... Feb 18 18:38:09 cartman-minidsp volumio[789]: info: Loading plugin "networkfs"... Feb 18 18:38:09 cartman-minidsp volumio[789]: info: Starting Udev Watcher for removable devices Feb 18 18:38:09 cartman-minidsp volumio[789]: info: Ignoring mount for partition: BOOT Feb 18 18:38:09 cartman-minidsp volumio[789]: info: Ignoring mount for partition: volumio Feb 18 18:38:09 cartman-minidsp volumio[789]: info: Ignoring mount for partition: volumio_data Feb 18 18:38:09 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 18:38:09 cartman-minidsp volumio[789]: info: Loading plugin "volumio_command_line_client"... Feb 18 18:38:09 cartman-minidsp volumio[789]: info: Loading plugin "upnp"... Feb 18 18:38:09 cartman-minidsp volumio[789]: info: [1739903889977] Starting Upmpd Daemon Feb 18 18:38:09 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 18:38:09 cartman-minidsp volumio[789]: info: Loading plugin "my_music"... Feb 18 18:38:09 cartman-minidsp systemd[1]: apt-daily-upgrade.service: Succeeded. Feb 18 18:38:10 cartman-minidsp systemd[1]: Started Daily apt upgrade and clean activities. Feb 18 18:38:10 cartman-minidsp volumio[789]: info: Loading plugin "mpd"... Feb 18 18:38:10 cartman-minidsp volumio[789]: info: Loading plugin "upnp_browser"... Feb 18 18:38:11 cartman-minidsp sudo[919]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:11 cartman-minidsp volumio[789]: info: Loading plugin "bluetooth"... Feb 18 18:38:11 cartman-minidsp volumio[789]: info: [1739903891977] Starting BluetoothController Feb 18 18:38:12 cartman-minidsp volumio[789]: info: Loading plugin "alarm-clock"... Feb 18 18:38:12 cartman-minidsp volumio[789]: info: Loading plugin "manifestui"... Feb 18 18:38:12 cartman-minidsp systemd[1]: systemd-fsckd.service: Succeeded. Feb 18 18:38:13 cartman-minidsp volumio[789]: info: Loading plugin "metavolumio"... Feb 18 18:38:13 cartman-minidsp sh[375]: timed out Feb 18 18:38:13 cartman-minidsp dhcpcd[424]: timed out Feb 18 18:38:13 cartman-minidsp sh[375]: dhcpcd exited Feb 18 18:38:13 cartman-minidsp dhcpcd[424]: dhcpcd exited Feb 18 18:38:13 cartman-minidsp sh[375]: ifup: failed to bring up eth0 Feb 18 18:38:13 cartman-minidsp systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Feb 18 18:38:13 cartman-minidsp systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Feb 18 18:38:15 cartman-minidsp volumio[789]: info: Loading plugin "airplay_emulation"... Feb 18 18:38:15 cartman-minidsp volumio[789]: info: Starting Shairport Sync Feb 18 18:38:15 cartman-minidsp volumio[789]: info: Loading plugin "cd_controller"... Feb 18 18:38:16 cartman-minidsp volumio[789]: info: Loading plugin "last_100"... Feb 18 18:38:16 cartman-minidsp volumio[789]: info: Loading plugin "raat"... Feb 18 18:38:16 cartman-minidsp volumio[789]: info: RAAT Plugin loaded Feb 18 18:38:16 cartman-minidsp volumio[789]: info: Adding restartRAATSocket REST API Endpoint Feb 18 18:38:16 cartman-minidsp volumio[789]: info: Adding restartRAATSocket REST Endpoint for plugin: music_service/raat Feb 18 18:38:16 cartman-minidsp volumio[789]: info: Loading plugin "streaming_services"... Feb 18 18:38:18 cartman-minidsp volumio[789]: info: Starting Streaming Service Transparent Proxy Feb 18 18:38:18 cartman-minidsp volumio[789]: info: Loading plugin "tidalconnect"... Feb 18 18:38:19 cartman-minidsp volumio[789]: info: Loading plugin "webradio"... Feb 18 18:38:19 cartman-minidsp volumio[789]: info: Loading plugin "i2s_dacs"... Feb 18 18:38:19 cartman-minidsp volumio[789]: info: I2S DAC not set, start Auto-detection Feb 18 18:38:19 cartman-minidsp volumio[789]: info: Loading plugin "volumiodiscovery"... Feb 18 18:38:19 cartman-minidsp volumio[789]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 18:38:19 cartman-minidsp volumio[789]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 18:38:19 cartman-minidsp node[789]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 18 18:38:19 cartman-minidsp volumio[789]: *** WARNING *** For more information see Feb 18 18:38:19 cartman-minidsp volumio[789]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 18:38:19 cartman-minidsp volumio[789]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 18:38:19 cartman-minidsp volumio[789]: *** WARNING *** For more information see Feb 18 18:38:19 cartman-minidsp node[789]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 18:38:19 cartman-minidsp node[789]: *** WARNING *** For more information see Feb 18 18:38:19 cartman-minidsp node[789]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 18 18:38:19 cartman-minidsp node[789]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 18 18:38:19 cartman-minidsp node[789]: *** WARNING *** For more information see Feb 18 18:38:19 cartman-minidsp volumio[789]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 18 18:38:19 cartman-minidsp volumio[789]: info: Discovery: Started advertising with name: Cartman Minidsp Feb 18 18:38:19 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 18:38:19 cartman-minidsp volumio[789]: info: Loading plugin "soundcloud"... Feb 18 18:38:20 cartman-minidsp volumio[789]: info: Loading plugin "spop"... Feb 18 18:38:20 cartman-minidsp volumio[789]: STREAMING PROXY: Starting server on port 3245 Feb 18 18:38:20 cartman-minidsp volumio[789]: Node JS runtime: 14 Feb 18 18:38:22 cartman-minidsp volumio-remote-updater[454]: [2025-02-18 18:38:22] [connect] Successful connection Feb 18 18:38:23 cartman-minidsp volumio[789]: info: Loading plugin "multiroom"... Feb 18 18:38:26 cartman-minidsp volumio[789]: info: Applying required configuration parameters for plugin multiroom Feb 18 18:38:26 cartman-minidsp sudo[957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Feb 18 18:38:26 cartman-minidsp sudo[957]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:26 cartman-minidsp sudo[957]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:26 cartman-minidsp volumio[789]: info: MRS: MultiRoom plugin initialized Feb 18 18:38:26 cartman-minidsp volumio[789]: info: MRS: STOPPING SNAPCLIENT Feb 18 18:38:26 cartman-minidsp volumio[789]: info: MRS: Snap server stop Feb 18 18:38:26 cartman-minidsp sudo[973]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Feb 18 18:38:26 cartman-minidsp sudo[973]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:26 cartman-minidsp volumio[789]: info: MRS: STOPPING volumioStreaming Feb 18 18:38:26 cartman-minidsp sudo[975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Feb 18 18:38:26 cartman-minidsp sudo[975]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:26 cartman-minidsp sudo[973]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:26 cartman-minidsp sudo[978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Feb 18 18:38:26 cartman-minidsp volumio[789]: info: Loading plugin "outputs"... Feb 18 18:38:26 cartman-minidsp sudo[978]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:26 cartman-minidsp volumio[789]: info: Loading plugin "albumart"... Feb 18 18:38:26 cartman-minidsp sudo[975]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:26 cartman-minidsp sudo[982]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Feb 18 18:38:26 cartman-minidsp sudo[982]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:26 cartman-minidsp sudo[982]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:26 cartman-minidsp sudo[978]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:26 cartman-minidsp volumio[789]: info: Loading plugin "ms_surface_dial"... Feb 18 18:38:27 cartman-minidsp volumio[789]: Forking 3 albumart workers Feb 18 18:38:27 cartman-minidsp volumio[789]: info: Applying required configuration parameters for plugin ms_surface_dial Feb 18 18:38:27 cartman-minidsp volumio[789]: info: Plugin example_plugin is not enabled Feb 18 18:38:27 cartman-minidsp volumio[789]: info: Loading plugin "hi_res_audio"... Feb 18 18:38:29 cartman-minidsp volumio[789]: Starting albumart workers Feb 18 18:38:29 cartman-minidsp volumio[789]: Starting albumart workers Feb 18 18:38:29 cartman-minidsp volumio[789]: Starting albumart workers Feb 18 18:38:31 cartman-minidsp volumio[789]: info: Applying required configuration parameters for plugin hi_res_audio Feb 18 18:38:31 cartman-minidsp volumio[789]: info: Loading plugin "inputs"... Feb 18 18:38:32 cartman-minidsp volumio[789]: info: Loading plugin "qobuz"... Feb 18 18:38:34 cartman-minidsp volumio[789]: info: Loading plugin "tidal"... Feb 18 18:38:37 cartman-minidsp volumio[789]: info: Loading plugin "oem_helper"... Feb 18 18:38:37 cartman-minidsp volumio-remote-updater[454]: [2025-02-18 18:38:37] [connect] Successful connection Feb 18 18:38:39 cartman-minidsp volumio[789]: info: Applying required configuration parameters for plugin oem_helper Feb 18 18:38:39 cartman-minidsp volumio[789]: info: Loading plugin "updater_comm"... Feb 18 18:38:39 cartman-minidsp volumio[789]: info: Plugin mpdemulation is not enabled Feb 18 18:38:39 cartman-minidsp volumio[789]: info: Loading plugin "rest_api"... Feb 18 18:38:39 cartman-minidsp volumio[789]: info: Loading plugin "websocket"... Feb 18 18:38:39 cartman-minidsp volumio[789]: info: Loading i18n strings for locale en Feb 18 18:38:39 cartman-minidsp volumio[789]: Updating browse sources language Feb 18 18:38:39 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::initPlayerControls Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 18:38:40 cartman-minidsp volumio[789]: Express server listening on port 3000 Feb 18 18:38:40 cartman-minidsp volumio[789]: [Metrics] WebUI: 37s 470.91ms Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreStateMachine::resetVolumioState Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreStateMachine::getcurrentVolume Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioRetrievevolume Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreStateMachine::pushState Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CorePlayQueue::getTrack 0 Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioPushState Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 18:38:40 cartman-minidsp sudo[1046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 18 18:38:40 cartman-minidsp sudo[1046]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: inputs , getVolume Feb 18 18:38:40 cartman-minidsp sudo[1046]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:40 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: inputs , getDSP Feb 18 18:38:40 cartman-minidsp sudo[1048]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 18 18:38:40 cartman-minidsp sudo[1048]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:40 cartman-minidsp sudo[1048]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:40 cartman-minidsp volumio[789]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Feb 18 18:38:40 cartman-minidsp volumio[789]: info: Completed loading Core Plugins Feb 18 18:38:40 cartman-minidsp volumio[789]: info: Preparing to generate the ALSA configuration file Feb 18 18:38:40 cartman-minidsp volumio[789]: info: MRS: Removed streaming files Feb 18 18:38:40 cartman-minidsp volumio[789]: info: MRS: volumioStreaming STOPPED Feb 18 18:38:40 cartman-minidsp volumio[789]: info: MRS: SNAPSERVER STOPPED Feb 18 18:38:40 cartman-minidsp volumio[789]: info: MRS: SNAPCLIENT STOPPED Feb 18 18:38:40 cartman-minidsp volumio[789]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Feb 18 18:38:40 cartman-minidsp volumio[789]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Feb 18 18:38:40 cartman-minidsp volumio[789]: info: Reading ALSA contributions from plugins. Feb 18 18:38:40 cartman-minidsp volumio-remote-updater[454]: [2025-02-18 18:38:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1739903917 101 Feb 18 18:38:41 cartman-minidsp volumio[789]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 1 Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreStateMachine::setRepeat null single undefined Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreStateMachine::pushState Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CorePlayQueue::getTrack 0 Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioPushState Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreStateMachine::setRandom null Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreStateMachine::pushState Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CorePlayQueue::getTrack 0 Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioPushState Feb 18 18:38:41 cartman-minidsp volumio[789]: info: Setting Device type: NanoPi NEO 3 Feb 18 18:38:41 cartman-minidsp volumio[789]: info: Reloading queue from file Feb 18 18:38:41 cartman-minidsp volumio[789]: info: Asound.conf file unchanged, so no further update is needed Feb 18 18:38:41 cartman-minidsp volumio[789]: info: Output device has changed, restarting MPD Feb 18 18:38:41 cartman-minidsp sudo[1057]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 18 18:38:41 cartman-minidsp sudo[1057]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:41 cartman-minidsp systemd[1]: Started UPnP Renderer front-end to MPD. Feb 18 18:38:41 cartman-minidsp sudo[1060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 18:38:41 cartman-minidsp sudo[1060]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:41 cartman-minidsp volumio[789]: info: Output device has changed, restarting Shairport Sync Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 18:38:41 cartman-minidsp sudo[1060]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:41 cartman-minidsp sudo[1057]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:41 cartman-minidsp sudo[1064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 18:38:41 cartman-minidsp sudo[1064]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:41 cartman-minidsp systemd[1]: Listening on mpd.socket. Feb 18 18:38:41 cartman-minidsp volumio[789]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 18:38:41 cartman-minidsp systemd[1]: Starting Music Player Daemon... Feb 18 18:38:41 cartman-minidsp volumio[789]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:41 cartman-minidsp volumio[789]: info: ___________ START PLUGINS ___________ Feb 18 18:38:41 cartman-minidsp volumio[789]: info: ControllerMpd::onStart: Initializing MPD Feb 18 18:38:41 cartman-minidsp volumio[789]: info: Creating MPD Configuration file Feb 18 18:38:41 cartman-minidsp sudo[1073]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 18 18:38:41 cartman-minidsp sudo[1073]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 18:38:41 cartman-minidsp volumio[789]: info: [1739903921787] CoreMusicLibrary::Adding element Media Servers Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 18:38:41 cartman-minidsp sudo[1073]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:41 cartman-minidsp sudo[1075]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 18 18:38:41 cartman-minidsp sudo[1075]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 18 18:38:41 cartman-minidsp systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 18 18:38:41 cartman-minidsp systemd[1]: mpd.service: Succeeded. Feb 18 18:38:41 cartman-minidsp systemd[1]: Stopped Music Player Daemon. Feb 18 18:38:41 cartman-minidsp systemd[1]: Starting Music Player Daemon... Feb 18 18:38:41 cartman-minidsp volumio[789]: info: Adding Manifest REST API Endpoints Feb 18 18:38:41 cartman-minidsp volumio[789]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Feb 18 18:38:41 cartman-minidsp volumio[789]: info: Adding METAVOLUMIO REST API Endpoints Feb 18 18:38:41 cartman-minidsp volumio[789]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Feb 18 18:38:41 cartman-minidsp volumio[789]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Feb 18 18:38:41 cartman-minidsp volumio[789]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Feb 18 18:38:41 cartman-minidsp volumio[789]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:41 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 18:38:41 cartman-minidsp sudo[1080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Cartman Minidsp Feb 18 18:38:41 cartman-minidsp sudo[1080]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:41 cartman-minidsp sudo[1080]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 18:38:42 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:42 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Preparing CD Folders Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Adding CD REST API Endpoints Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Starting UDEV Watcher for CD Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Detecting CD presence with UDEV Feb 18 18:38:42 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Feb 18 18:38:42 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 18:38:42 cartman-minidsp volumio[789]: info: [1739903922199] CoreMusicLibrary::Adding element Last_100 Feb 18 18:38:42 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Starting RAAT Plugin Feb 18 18:38:42 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , addAdditionalUISections Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Additional UI Settings Added for plugin music_service/raat Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Streaming services startup Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Starting Streaming Daemon Feb 18 18:38:42 cartman-minidsp sudo[1091]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 18 18:38:42 cartman-minidsp sudo[1091]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:42 cartman-minidsp sudo[1091]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:42 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 18:38:42 cartman-minidsp volumio[789]: info: [1739903922466] CoreMusicLibrary::Adding element Webradio Feb 18 18:38:42 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 18:38:42 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 18:38:42 cartman-minidsp sudo[1098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Feb 18 18:38:42 cartman-minidsp sudo[1098]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:42 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 18:38:42 cartman-minidsp volumio[789]: info: [1739903922531] CoreMusicLibrary::Adding element SoundCloud Feb 18 18:38:42 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 18:38:42 cartman-minidsp volumio[789]: Cannot find translation for source SoundCloud Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Creating Spotify config file Feb 18 18:38:42 cartman-minidsp sudo[1098]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:42 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Feb 18 18:38:42 cartman-minidsp volumio[789]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Feb 18 18:38:42 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:42 cartman-minidsp dbus-daemon[456]: [system] Activating via systemd: service name='org.bluez' unit='dbus-org.bluez.service' requested by ':1.8' (uid=1000 pid=789 comm="/usr/bin/node /volumio/index.js ") Feb 18 18:38:42 cartman-minidsp volumio[789]: error: Hi Res Audio Failed Login: Missing Login Data Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Adding HIGHRESAUDIO REST API Endpoints Feb 18 18:38:42 cartman-minidsp systemd[1]: Starting Bluetooth service... Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Feb 18 18:38:42 cartman-minidsp volumio[789]: info: Refreshing TIDAL token Feb 18 18:38:42 cartman-minidsp bluetoothd[1104]: Bluetooth daemon 5.23 Feb 18 18:38:42 cartman-minidsp dbus-daemon[456]: [system] Successfully activated service 'org.bluez' Feb 18 18:38:42 cartman-minidsp systemd[1]: Started Bluetooth service. Feb 18 18:38:42 cartman-minidsp bluetoothd[1104]: Starting SDP server Feb 18 18:38:42 cartman-minidsp bluetoothd[1104]: kernel lacks bnep-protocol support Feb 18 18:38:42 cartman-minidsp bluetoothd[1104]: System does not support network plugin Feb 18 18:38:42 cartman-minidsp dbus-daemon[456]: [system] Activating via systemd: service name='org.freedesktop.hostname1' unit='dbus-org.freedesktop.hostname1.service' requested by ':1.11' (uid=0 pid=1104 comm="/usr/local/libexec/bluetooth/bluetoothd ") Feb 18 18:38:42 cartman-minidsp bluetoothd[1104]: Bluetooth management interface 1.14 initialized Feb 18 18:38:42 cartman-minidsp systemd[1]: Starting Hostname Service... Feb 18 18:38:43 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 18 18:38:43 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 18:38:43 cartman-minidsp volumio[789]: info: Volumio Calling Home Feb 18 18:38:43 cartman-minidsp dbus-daemon[456]: [system] Successfully activated service 'org.freedesktop.hostname1' Feb 18 18:38:43 cartman-minidsp systemd[1]: Started Hostname Service. Feb 18 18:38:43 cartman-minidsp volumio[789]: info: Stopping AccessToken refresher cron for QOBUZ Feb 18 18:38:43 cartman-minidsp volumio[789]: info: AccessToken refresher cron started for QOBUZ Feb 18 18:38:43 cartman-minidsp volumio[789]: info: Adding QOBUZ REST API Endpoints Feb 18 18:38:43 cartman-minidsp volumio[789]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Feb 18 18:38:43 cartman-minidsp volumio[789]: info: Discovery: adding 1a4e91d4-beb5-4fec-8cc8-66c52494fc78 Feb 18 18:38:43 cartman-minidsp volumio[789]: info: Discovery: Found device Cartman Minidsp Feb 18 18:38:43 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioGetState Feb 18 18:38:43 cartman-minidsp volumio[789]: info: CorePlayQueue::getTrack 0 Feb 18 18:38:43 cartman-minidsp volumio[789]: info: MRS: Pushing multiroomSync output for this device Feb 18 18:38:43 cartman-minidsp volumio[789]: info: MRS: Pushing multiroomSync output Feb 18 18:38:43 cartman-minidsp volumio[789]: info: Adding audio output: Feb 18 18:38:43 cartman-minidsp volumio[789]: info: Adding audio output: Feb 18 18:38:43 cartman-minidsp volumio[789]: info: Serial port opened successfully Feb 18 18:38:43 cartman-minidsp volumio[789]: error: Cannot start Volumio Streaming Daemon Feb 18 18:38:43 cartman-minidsp volumio[789]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Feb 18 18:38:43 cartman-minidsp volumio[789]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Feb 18 18:38:43 cartman-minidsp volumio[789]: info: RAAT Albumart path created successfully Feb 18 18:38:43 cartman-minidsp volumio[789]: error: Cannot set new Bluetooth Name : Error: Command failed: /usr/bin/sudo /bin/hciconfig hci0 name 'Cartman Minidsp' Feb 18 18:38:43 cartman-minidsp volumio[789]: Can't get device info: No such device Feb 18 18:38:43 cartman-minidsp volumio[789]: info: MPD Permissions set Feb 18 18:38:43 cartman-minidsp volumio[789]: info: MPD Permissions set Feb 18 18:38:43 cartman-minidsp volumio[789]: info: Upmpdcli Daemon Started Feb 18 18:38:43 cartman-minidsp volumio[789]: info: msSurfaceDial Managed Objs {"/org/bluez":{"org.freedesktop.DBus.Introspectable":{},"org.bluez.AgentManager1":{},"org.bluez.ProfileManager1":{}}} Feb 18 18:38:43 cartman-minidsp volumio[789]: info: [msSurfaceDial init()] check /org/bluez Feb 18 18:38:43 cartman-minidsp volumio[789]: info: [msSurfaceDial init()] Adapter: null; SurfaceDial: null Feb 18 18:38:43 cartman-minidsp volumio[789]: info: msSurfaceDial BluetoothSurfaceDial init() - ready! Feb 18 18:38:43 cartman-minidsp volumio[789]: info: CoreCommandRouter::Reload Ui Feb 18 18:38:43 cartman-minidsp volumio[789]: info: Volumio called home Feb 18 18:38:43 cartman-minidsp volumio[789]: info: Spotify config file written Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Feb 18 18:38:44 cartman-minidsp volumio[789]: info: updateDSP function in raat called! Feb 18 18:38:44 cartman-minidsp volumio[789]: info: Updating RAAT Signal Path Feb 18 18:38:44 cartman-minidsp volumio[789]: error: Cannot write to RAAT Client: TypeError: Cannot read property 'write' of undefined Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: raat , reconfigureAndRestartRaat Feb 18 18:38:44 cartman-minidsp volumio[789]: info: Reconfiguring and Restarting RAAT Plugin Feb 18 18:38:44 cartman-minidsp sudo[1121]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 18 18:38:44 cartman-minidsp sudo[1121]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:44 cartman-minidsp systemd[1]: Started go-librespot Daemon. Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: Not Reporting Auto name since its the default one Feb 18 18:38:44 cartman-minidsp go-librespot[1127]: Librespot-go daemon starting... Feb 18 18:38:44 cartman-minidsp volumio[789]: info: RAAT Overriding default device vendor model Feb 18 18:38:44 cartman-minidsp volumio[789]: info: Adding MINIDSP Inputs Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 18:38:44 cartman-minidsp volumio[789]: info: [1739903924167] CoreMusicLibrary::Adding element Inputs Feb 18 18:38:44 cartman-minidsp sudo[1121]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 18:38:44 cartman-minidsp volumio[789]: Cannot find translation for source SoundCloud Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 18:38:44 cartman-minidsp volumio[789]: info: [1739903924184] CoreMusicLibrary::Adding element Presets Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 18:38:44 cartman-minidsp volumio[789]: Cannot find translation for source SoundCloud Feb 18 18:38:44 cartman-minidsp volumio[789]: Cannot find translation for source Presets Feb 18 18:38:44 cartman-minidsp volumio[789]: (node:789) [DEP0005] DeprecationWarning: Buffer() is deprecated due to security and usability issues. Please use the Buffer.alloc(), Buffer.allocUnsafe(), or Buffer.from() methods instead. Feb 18 18:38:44 cartman-minidsp volumio[789]: (Use `node --trace-deprecation ...` to show where the warning was created) Feb 18 18:38:44 cartman-minidsp volumio[789]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 2 Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumiologrotate[464]: ls: cannot access '/var/log/samba/log.wb-CARTMAN': No such file or directory Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 18 18:38:44 cartman-minidsp volumiologrotate[464]: ls: cannot access 'MINIDSP': No such file or directory Feb 18 18:38:44 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:44Z" level=info msg="generated new device id: 7094f985566f0c5f47f7a8bbb67d36f550d4d1a5" Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Feb 18 18:38:44 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:45 cartman-minidsp sudo[1186]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Feb 18 18:38:45 cartman-minidsp sudo[1186]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:45 cartman-minidsp systemd[1]: Started Volumio Bluetooth Module. Feb 18 18:38:45 cartman-minidsp sudo[1186]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CorePlayQueue::getTrack 0 Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumiosetSourceActiveno-source Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 18:38:45 cartman-minidsp volumio[789]: Cannot find translation for source SoundCloud Feb 18 18:38:45 cartman-minidsp volumio[789]: Cannot find translation for source Presets Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioStop Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CoreStateMachine::stop Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 18:38:45 cartman-minidsp volumio[789]: info: Notifying Active Input {"trackType":"input","service":"inputs","title":"TOSLINK","disableUiControls":true,"albumart":"/albumart"} Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 18 18:38:45 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:45Z" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Feb 18 18:38:45 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:45Z" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Feb 18 18:38:45 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:45Z" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Feb 18 18:38:45 cartman-minidsp sudo[1189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Feb 18 18:38:45 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:45Z" level=debug msg="zeroconf server listening on port 38063" Feb 18 18:38:45 cartman-minidsp sudo[1189]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:45 cartman-minidsp volumiobt[1188]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Feb 18 18:38:45 cartman-minidsp sudo[1189]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:45 cartman-minidsp volumio[789]: info: Starting Shairport Sync Feb 18 18:38:45 cartman-minidsp volumio[789]: info: Starting Shairport Sync Feb 18 18:38:45 cartman-minidsp sudo[1193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 18:38:45 cartman-minidsp volumio[789]: info: Starting Shairport Sync Feb 18 18:38:45 cartman-minidsp sudo[1193]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:45 cartman-minidsp sudo[1195]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 18 18:38:45 cartman-minidsp sudo[1195]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:45 cartman-minidsp systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 18 18:38:45 cartman-minidsp systemd[1]: shairport-sync.service: Succeeded. Feb 18 18:38:45 cartman-minidsp systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 18 18:38:45 cartman-minidsp systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 18 18:38:45 cartman-minidsp sudo[1199]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 18 18:38:45 cartman-minidsp sudo[1193]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:45 cartman-minidsp sudo[1199]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:45 cartman-minidsp systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Feb 18 18:38:45 cartman-minidsp systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Feb 18 18:38:45 cartman-minidsp systemd[1]: shairport-sync.service: Succeeded. Feb 18 18:38:45 cartman-minidsp volumio[789]: xcb_connection_has_error() returned true Feb 18 18:38:45 cartman-minidsp systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Feb 18 18:38:45 cartman-minidsp systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Feb 18 18:38:45 cartman-minidsp sudo[1195]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:45 cartman-minidsp sudo[1199]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:45 cartman-minidsp volumio[789]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 18 18:38:45 cartman-minidsp sudo[1206]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 18 18:38:45 cartman-minidsp sudo[1206]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:45 cartman-minidsp volumio[789]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 18 18:38:45 cartman-minidsp volumio[789]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 18 18:38:45 cartman-minidsp volumio[789]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 18 18:38:45 cartman-minidsp volumio[789]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 18 18:38:45 cartman-minidsp systemd[1]: Started RAAT DAEMON. Feb 18 18:38:45 cartman-minidsp sudo[1206]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:45 cartman-minidsp volumio[789]: info: Volumio BT Module successfully started Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CoreCommandRouter::servicePushState Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CoreStateMachine::pushState Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioPushState Feb 18 18:38:45 cartman-minidsp volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 18 18:38:45 cartman-minidsp volumio[789]: info: MRS: Pushing multiroomSync output Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Feb 18 18:38:45 cartman-minidsp volumio[789]: info: Enabling external Volume Control Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:45 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:46 cartman-minidsp volumio[789]: info: Access Token successfully retrieved Feb 18 18:38:46 cartman-minidsp volumio[789]: info: Raat Daemon started successfully Feb 18 18:38:46 cartman-minidsp volumio[789]: info: Shairport-Sync Started Feb 18 18:38:46 cartman-minidsp volumio[789]: Error adding Membership: Error: addMembership EINVAL Feb 18 18:38:46 cartman-minidsp volumio[789]: info: Shairport-Sync Started Feb 18 18:38:46 cartman-minidsp volumio[789]: info: Shairport-Sync Started Feb 18 18:38:46 cartman-minidsp volumio[789]: info: TidalConnect service stoped! Feb 18 18:38:46 cartman-minidsp volumio[789]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 3 Feb 18 18:38:46 cartman-minidsp sudo[1224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Feb 18 18:38:46 cartman-minidsp sudo[1224]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:46 cartman-minidsp volumiobt[1188]: pulseaudio: no process found Feb 18 18:38:46 cartman-minidsp sudo[1224]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:46 cartman-minidsp dbus-daemon[456]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.16' (uid=1000 pid=1228 comm="/usr/bin/pulseaudio --high-priority=true ") Feb 18 18:38:46 cartman-minidsp volumio[789]: info: Executing endpoint restartRAATSocket Feb 18 18:38:46 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 18 18:38:46 cartman-minidsp systemd[1]: Starting RealtimeKit Scheduling Policy Service... Feb 18 18:38:46 cartman-minidsp dbus-daemon[456]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Feb 18 18:38:46 cartman-minidsp rtkit-daemon[1229]: Successfully called chroot. Feb 18 18:38:46 cartman-minidsp rtkit-daemon[1229]: Successfully dropped privileges. Feb 18 18:38:46 cartman-minidsp rtkit-daemon[1229]: Successfully limited resources. Feb 18 18:38:46 cartman-minidsp rtkit-daemon[1229]: Running. Feb 18 18:38:46 cartman-minidsp dbus-daemon[456]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.17' (uid=0 pid=1229 comm="/usr/lib/rtkit/rtkit-daemon ") Feb 18 18:38:46 cartman-minidsp rtkit-daemon[1229]: Canary thread running. Feb 18 18:38:46 cartman-minidsp rtkit-daemon[1229]: Watchdog thread running. Feb 18 18:38:46 cartman-minidsp systemd[1]: Started RealtimeKit Scheduling Policy Service. Feb 18 18:38:46 cartman-minidsp systemd[1]: Starting Authorization Manager... Feb 18 18:38:46 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioGetState Feb 18 18:38:46 cartman-minidsp volumio[789]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 18 18:38:46 cartman-minidsp polkitd[1233]: started daemon version 0.105 using authority implementation `local' version `0.105' Feb 18 18:38:46 cartman-minidsp dbus-daemon[456]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Feb 18 18:38:46 cartman-minidsp volumio[789]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 18 18:38:46 cartman-minidsp systemd[1]: Started Authorization Manager. Feb 18 18:38:46 cartman-minidsp volumio[789]: info: Successfully retrieved User Session From TIDAL Feb 18 18:38:47 cartman-minidsp volumio[789]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Feb 18 18:38:47 cartman-minidsp volumio[789]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Feb 18 18:38:47 cartman-minidsp sudo[1242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Feb 18 18:38:47 cartman-minidsp sudo[1242]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:47 cartman-minidsp volumio[789]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 4 Feb 18 18:38:47 cartman-minidsp systemd[1]: Started Volumio Tidal Connect Service. Feb 18 18:38:47 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:47Z" level=debug msg="obtained new client token: AABL4iuvFdCRaf3FzMG9KtracVdWcApv7gxq9ba+rg4XZuLGK1jUFAhxyBCqhj+p6hkjGIvTfbGrZWRkn/Q1leH95pEIRv+d6zGTFuiOrM+mVBfUgR4tMZvztBcJNHV6Ui7b6f8l93/MCyvanDlXHQuHOhevrDz7nwFzidfkm7Cc9cwDyHwifvta3IiCZaMagnOLtLJhzzH+s7z3DqUm/URAWh+wfk7ZEKnpUYP9gAVlZT0uxrM8XzqAo0wLWA==" Feb 18 18:38:47 cartman-minidsp sudo[1242]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:47 cartman-minidsp volumio[789]: info: msSurfaceDial volumioupdatevolume callback: {"mute":false,"disableVolumeControl":false} Feb 18 18:38:47 cartman-minidsp volumio[789]: info: CoreStateMachine::pushState Feb 18 18:38:47 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 18 18:38:47 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioPushState Feb 18 18:38:47 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:47Z" level=debug msg="completed keyexchange" Feb 18 18:38:47 cartman-minidsp volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 18 18:38:47 cartman-minidsp volumio[789]: info: MRS: Pushing multiroomSync output Feb 18 18:38:47 cartman-minidsp volumio[789]: error: Help! Some callbacks for volumioPushState are crashing! Feb 18 18:38:47 cartman-minidsp volumio[789]: error: Cannot read property 'sendVolumeMute' of undefined Feb 18 18:38:47 cartman-minidsp volumio[789]: info: MRS: Getting audio outputs on start Feb 18 18:38:47 cartman-minidsp volumio[789]: info: MRS: Requesting all other devices output Feb 18 18:38:47 cartman-minidsp volumio[789]: info: Successfully retrieved User Subscription From TIDAL Feb 18 18:38:47 cartman-minidsp volumio[789]: info: Adding TIDAL to Browse Sources Feb 18 18:38:47 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 18 18:38:47 cartman-minidsp volumio[789]: info: [1739903927779] CoreMusicLibrary::Adding element TIDAL Feb 18 18:38:47 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 18 18:38:47 cartman-minidsp volumio[789]: Cannot find translation for source SoundCloud Feb 18 18:38:47 cartman-minidsp volumio[789]: Cannot find translation for source Presets Feb 18 18:38:47 cartman-minidsp volumio[789]: Cannot find translation for source TIDAL Feb 18 18:38:47 cartman-minidsp volumio[789]: info: Stopping AccessToken refresher cron Feb 18 18:38:47 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:47Z" level=debug msg="completed challenge" Feb 18 18:38:47 cartman-minidsp volumio[789]: info: AccessToken refresher cron started Feb 18 18:38:47 cartman-minidsp volumio[789]: info: Adding TIDAL REST API Endpoints Feb 18 18:38:47 cartman-minidsp volumio[789]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Feb 18 18:38:47 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:47Z" level=debug msg="authenticated as 3metre" Feb 18 18:38:48 cartman-minidsp volumio[789]: info: go-librespot daemon successfully initialized Feb 18 18:38:48 cartman-minidsp pulseaudio[1228]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 18 18:38:48 cartman-minidsp pulseaudio[1228]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: Applying permissions Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: Setting BT discoverable and pairable Feb 18 18:38:48 cartman-minidsp pulseaudio[1228]: 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 Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: [38B blob data] Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: Invalid command Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: [bluetooth]# agent on Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: [bluetooth]# discoverable on Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: No default controller available Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: [bluetooth]# pairable on Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: No default controller available Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: [bluetooth]# agent NoInputNoOutput Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: Failed to register agent object Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: [bluetooth]# default-agent Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: No agent is registered Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: [bluetooth]# quit Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: [61B blob data] Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="authenticated as 3metre" Feb 18 18:38:48 cartman-minidsp volumiobt[1188]: [62B blob data] Feb 18 18:38:48 cartman-minidsp volumio[789]: info: Ignoring ROON Volume update because of undefined data Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="dealer connection opened" Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=info msg="accepted zeroconf user 3metre from iPhone" Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="received connection id: ZDQ1MWFhZTEtZGRlNy00ZTFjLTgzMzAtNzM4MmY0YjU2NGEyK2RlYWxlcit0Y3A6Ly8wYWNhNDE0NS5pcC5nZXcxLnNwb3RpZnkubmV0OjU3MDArMzE0Q0Y3QTJGRUNDNTY1ODEzMzBDMzRBMzgzMDMwMzA0MTA0Mjc1Nzc4ODY4MkI5NDU1QTRCRDkwRjZDQjZCQw==" Feb 18 18:38:48 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioGetState Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="put connect state because NEW_DEVICE" Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="handling transfer player command from 7bb2950b53b8119a097e7ea3ba554e4a829ee141" Feb 18 18:38:48 cartman-minidsp volumio[789]: info: Executing endpoint tc_getconfig Feb 18 18:38:48 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Feb 18 18:38:48 cartman-minidsp vtcs[1244]: STARTING TidalConnect services, version: 1.3.0.19 Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="loading track spotify:track:72m3Br1JRwLKD4puhA2pui (paused: false, position: 12367ms)" Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=trace msg="emitting websocket event: will_play" Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 241" Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="selected format OGG_VORBIS_320 for spotify:track:72m3Br1JRwLKD4puhA2pui" Feb 18 18:38:48 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:48Z" level=debug msg="requested aes key for file 693da395a581759ebedee64d2f1ef0e914122a83, gid: 72m3Br1JRwLKD4puhA2pui" Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1660" Feb 18 18:38:49 cartman-minidsp vtcs[1244]: STARTED TidalConnect services. Feb 18 18:38:49 cartman-minidsp volumio[789]: info: Executing endpoint tc_connect Feb 18 18:38:49 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Feb 18 18:38:49 cartman-minidsp volumio[789]: info: Connecting to TidalConnect Feb 18 18:38:49 cartman-minidsp volumio[789]: info: CoreCommandRouter::servicePushState Feb 18 18:38:49 cartman-minidsp volumio[789]: info: CoreStateMachine::pushState Feb 18 18:38:49 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioPushState Feb 18 18:38:49 cartman-minidsp volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 18 18:38:49 cartman-minidsp volumio[789]: info: MRS: Pushing multiroomSync output Feb 18 18:38:49 cartman-minidsp volumio[789]: info: CorePlayQueue::getTrack 0 Feb 18 18:38:49 cartman-minidsp volumio[789]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Feb 18 18:38:49 cartman-minidsp volumio[789]: info: CoreCommandRouter::servicePushState Feb 18 18:38:49 cartman-minidsp volumio[789]: info: CoreStateMachine::pushState Feb 18 18:38:49 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioPushState Feb 18 18:38:49 cartman-minidsp volumio[789]: info: MRS: Pushing multiroomSync output update for this device Feb 18 18:38:49 cartman-minidsp volumio[789]: info: MRS: Pushing multiroomSync output Feb 18 18:38:49 cartman-minidsp volumio[789]: info: CorePlayQueue::getTrack 0 Feb 18 18:38:49 cartman-minidsp volumio[789]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received tidalconnect Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1278" Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=debug msg="fetched first chunk of 10, total size is 4848176 bytes" Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=trace msg="seek to 12367ms (diff: 155ms, samples: 545384, bytes: 329302)" Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:257:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 65536, but target_pcm volumioOutput has buffer size 4096 Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: ALSA lib /home/pi/volumio-alsa-plugin/src/pcm_volumioswitch.c:268:(_snd_pcm_volumioswitch_hw_params) PCM volumioMultiRoomServer has buffer size 65536, but target_pcm volumioOutput has buffer size 4096 Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=info msg="loaded track \"Empire of Light\" (uri: spotify:track:72m3Br1JRwLKD4puhA2pui, paused: false, position: 12367ms, duration: 179480ms)" Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=trace msg="emitting websocket event: metadata" Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=trace msg="emitting websocket event: active" Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=debug msg="sending successful reply for delaer request" Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=debug msg="fetched chunk 1/9, size: 524288" Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=trace msg="emitting websocket event: playing" Feb 18 18:38:49 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:49Z" level=debug msg="fetched chunk 3/9, size: 524288" Feb 18 18:38:50 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:50Z" level=debug msg="fetched chunk 2/9, size: 524288" Feb 18 18:38:50 cartman-minidsp volumio[789]: info: TidalConnect service started! Feb 18 18:38:51 cartman-minidsp mpd[1079]: Feb 18 18:38 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 18 18:38:51 cartman-minidsp volumio[789]: info: Initializing connection to go-librespot Websocket Feb 18 18:38:51 cartman-minidsp systemd[1]: Started Music Player Daemon. Feb 18 18:38:51 cartman-minidsp volumio[789]: Upnp client error: Error: This socket has been ended by the other party Feb 18 18:38:51 cartman-minidsp sudo[1075]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:51 cartman-minidsp sudo[1064]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:51 cartman-minidsp volumio[789]: Upnp client error: Error: This socket has been ended by the other party Feb 18 18:38:51 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:51Z" level=debug msg="new websocket client" Feb 18 18:38:51 cartman-minidsp volumio[789]: info: Completed starting Core Plugins Feb 18 18:38:51 cartman-minidsp volumio[789]: info: ------------------------------------------- Feb 18 18:38:51 cartman-minidsp volumio[789]: info: ----- MyVolumio plugins startup ---- Feb 18 18:38:51 cartman-minidsp volumio[789]: info: ------------------------------------------- Feb 18 18:38:51 cartman-minidsp volumio[789]: info: [MyVolumio PluginManager] Fetching plans data.... Feb 18 18:38:51 cartman-minidsp volumio[789]: info: Connection to go-librespot Websocket established Feb 18 18:38:51 cartman-minidsp volumio[789]: error: MPD error: The expression evaluated to a falsy value: Feb 18 18:38:51 cartman-minidsp volumio[789]: assert.ok(self.idling) Feb 18 18:38:51 cartman-minidsp volumio[789]: error: The expression evaluated to a falsy value: Feb 18 18:38:51 cartman-minidsp volumio[789]: assert.ok(self.idling) Feb 18 18:38:51 cartman-minidsp volumio[789]: info: MPD running with PID1079 Feb 18 18:38:51 cartman-minidsp volumio[789]: ,establishing connection Feb 18 18:38:51 cartman-minidsp volumio[789]: error: updateQueue error: null Feb 18 18:38:51 cartman-minidsp volumio[789]: error: updateQueue error: null Feb 18 18:38:51 cartman-minidsp volumio[1062]: Generating RSA private key, 4096 bit long modulus (2 primes) Feb 18 18:38:52 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:52 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:52 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 18 18:38:52 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 18 18:38:52 cartman-minidsp volumio[789]: info: Not Reporting Auto name since its the default one Feb 18 18:38:52 cartman-minidsp volumio[789]: info: RAAT Overriding default device vendor model Feb 18 18:38:52 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Feb 18 18:38:52 cartman-minidsp volumio[789]: xcb_connection_has_error() returned true Feb 18 18:38:52 cartman-minidsp sudo[1295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Feb 18 18:38:52 cartman-minidsp sudo[1295]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 18 18:38:52 cartman-minidsp systemd[1]: Stopping RAAT DAEMON... Feb 18 18:38:52 cartman-minidsp systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Feb 18 18:38:52 cartman-minidsp systemd[1]: raat-daemon.service: Succeeded. Feb 18 18:38:52 cartman-minidsp systemd[1]: Stopped RAAT DAEMON. Feb 18 18:38:52 cartman-minidsp volumio[789]: ------------------------------------ BT MESSAGE: BT STATUS: running Feb 18 18:38:52 cartman-minidsp volumio[789]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Feb 18 18:38:52 cartman-minidsp systemd[1]: Started RAAT DAEMON. Feb 18 18:38:52 cartman-minidsp sudo[1295]: pam_unix(sudo:session): session closed for user root Feb 18 18:38:52 cartman-minidsp volumio[789]: info: Raat Daemon started successfully Feb 18 18:38:52 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:52Z" level=debug msg="handling pause player command from 7bb2950b53b8119a097e7ea3ba554e4a829ee141" Feb 18 18:38:52 cartman-minidsp volumio[789]: info: Executing endpoint restartRAATSocket Feb 18 18:38:52 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Feb 18 18:38:52 cartman-minidsp volumio[789]: info: CoreCommandRouter::executeOnPlugin: raat , updateDSP Feb 18 18:38:52 cartman-minidsp volumio[789]: info: updateDSP function in raat called! Feb 18 18:38:52 cartman-minidsp volumio[789]: info: Updating RAAT Signal Path Feb 18 18:38:52 cartman-minidsp volumio[789]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Feb 18 18:38:52 cartman-minidsp volumio[789]: info: CoreCommandRouter::volumioGetState Feb 18 18:38:52 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:52Z" level=debug msg="pause track at 15572ms" Feb 18 18:38:52 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:52Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 18 18:38:52 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:52Z" level=debug msg="sending successful reply for delaer request" Feb 18 18:38:53 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:53Z" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Feb 18 18:38:53 cartman-minidsp go-librespot[1127]: time="2025-02-18T18:38:53Z" level=trace msg="emitting websocket event: paused" Feb 18 18:38:53 cartman-minidsp volumio[789]: SPOTIFY: received: {"type":"paused","data":{"uri":"spotify:track:72m3Br1JRwLKD4puhA2pui","play_origin":"com.spotify.service.yourlibrarymusic.ui"}} Feb 18 18:38:53 cartman-minidsp volumio[789]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 18:38:53 cartman-minidsp volumio[789]: TypeError: Cannot read property 'service' of undefined Feb 18 18:38:53 cartman-minidsp volumio[789]: at ControllerSpotify.identifyPlaybackMode (/data/plugins/music_service/spop/index.js:322:50) Feb 18 18:38:53 cartman-minidsp volumio[789]: at ControllerSpotify.parseEventState (/data/plugins/music_service/spop/index.js:256:18) Feb 18 18:38:53 cartman-minidsp volumio[789]: at WebSocket.message (/data/plugins/music_service/spop/index.js:175:14) Feb 18 18:38:53 cartman-minidsp volumio[789]: at WebSocket.emit (events.js:400:28) Feb 18 18:38:53 cartman-minidsp volumio[789]: at Receiver.receiverOnMessage (/data/plugins/music_service/spop/node_modules/ws/lib/websocket.js:1192:20) Feb 18 18:38:53 cartman-minidsp volumio[789]: at Receiver.emit (events.js:400:28) Feb 18 18:38:53 cartman-minidsp volumio[789]: at Receiver.dataMessage (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:560:14) Feb 18 18:38:53 cartman-minidsp volumio[789]: at Receiver.getData (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:478:17) Feb 18 18:38:53 cartman-minidsp volumio[789]: at Receiver.startLoop (/data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:167:22) Feb 18 18:38:53 cartman-minidsp volumio[789]: at /data/plugins/music_service/spop/node_modules/ws/lib/receiver.js:180:18 Feb 18 18:38:53 cartman-minidsp volumio[789]: at internal/process/task_queues.js:141:7 Feb 18 18:38:53 cartman-minidsp volumio[789]: at AsyncResource.runInAsyncScope (async_hooks.js:197:9) Feb 18 18:38:53 cartman-minidsp volumio[789]: at AsyncResource.runMicrotask (internal/process/task_queues.js:138:8) Feb 18 18:38:53 cartman-minidsp volumio[789]: at processTicksAndRejections (internal/process/task_queues.js:95:5) Feb 18 18:38:53 cartman-minidsp volumio[789]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 18 18:38:53 cartman-minidsp volumio[1062]: .....................++++ Feb 18 18:38:54 cartman-minidsp sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-18 18:37 Feb 18 18:38:54 cartman-minidsp sudo[1318]: 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"