-- Logs begin at Wed 2024-12-25 13:55:21 UTC, end at Mon 2025-01-20 19:53:25 UTC. -- Jan 20 19:52:08 office volumio[743]: error: error Jan 20 19:52:08 office volumio[743]: info: Discovery: Remote connection error: 192.168.1.248 Jan 20 19:52:11 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 20 19:52:11 office volumio[743]: info: Received Get System Info Jan 20 19:52:11 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 19:52:11 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 19:52:11 office volumio[743]: info: Discovery: Getting this device information Jan 20 19:52:11 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:11 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:11 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 19:52:13 office volumio[743]: info: MyVolumio Plan changed: premium Jan 20 19:52:13 office volumio[743]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 20 19:52:13 office volumio[743]: info: Removing browser output: myVolumio user plan is not superstar Jan 20 19:52:13 office volumio[743]: info: Removing audio output: Jan 20 19:52:13 office volumio[743]: info: MYVOLUMIO: Adding device Jan 20 19:52:13 office volumio[743]: info: MYVOLUMIO: Evaluating Server Jan 20 19:52:13 office volumio[743]: info: Remote config written successfully Jan 20 19:52:13 office volumio[743]: info: Starting Tunnel 1 Jan 20 19:52:13 office volumio[743]: info: Starting Tunnel Connection Checker Jan 20 19:52:13 office volumio[743]: info: MYVolumio Device disabled Jan 20 19:52:13 office volumio[743]: info: Setting Geolocation for MyVolumio to us1 Jan 20 19:52:13 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:13 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:13 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:14 office volumio[743]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Jan 20 19:52:14 office volumio[743]: info: Updating MyVolumio device info Jan 20 19:52:14 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:14 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:14 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:15 office volumio[743]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Jan 20 19:52:16 office volumio[743]: info: Discovery: Remote connection error: 192.168.1.248 Jan 20 19:52:16 office sudo[11129]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 20 19:52:16 office sudo[11129]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:16 office sudo[11129]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:16 office sudo[11132]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 20 19:52:16 office sudo[11132]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:16 office sudo[11132]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:16 office volumio[743]: verbose: New Socket.io Connection to 192.168.1.199 from 192.168.1.139 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Total Clients: 5 Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:16 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:16 office volumio[743]: info: Received Get System Info Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 19:52:16 office volumio[743]: info: Discovery: Getting this device information Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:16 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:16 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::volumioGetVisibleSources Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:16 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:16 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 20 19:52:16 office volumio[743]: info: Received Get System Info Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 19:52:16 office volumio[743]: info: Discovery: Getting this device information Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:16 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:16 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:16 office volumio[743]: info: Listing playlists Jan 20 19:52:16 office volumio[743]: info: Received Get System Info Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 19:52:16 office volumio[743]: info: Discovery: Getting this device information Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:16 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:16 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:16 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 20 19:52:17 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:17 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:18 office volumio[743]: info: MYVOLUMIO: Adding device Jan 20 19:52:18 office volumio[743]: info: MYVOLUMIO: Evaluating Server Jan 20 19:52:18 office sudo[11156]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 20 19:52:18 office sudo[11156]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:18 office systemd[1]: Started MyVolumio SSH Tunnel. Jan 20 19:52:18 office sudo[11156]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:18 office volumio[743]: info: Remote SSH Started Jan 20 19:52:18 office autossh[11159]: port set to 0, monitoring disabled Jan 20 19:52:18 office autossh[11159]: starting ssh (count 1) Jan 20 19:52:18 office autossh[11159]: ssh child pid is 11162 Jan 20 19:52:18 office volumio[743]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 Jan 20 19:52:18 office volumiossh-tunnel[11158]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Jan 20 19:52:18 office volumio[743]: info: Setting Geolocation for MyVolumio to us1 Jan 20 19:52:18 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:18 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:18 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:18 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:18 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:19 office volumio[743]: info: MYVolumio Device enabled Jan 20 19:52:19 office volumio[743]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 20 19:52:19 office volumio[743]: info: MyVolumio status changed Jan 20 19:52:19 office volumio[743]: info: Streaming services startup Jan 20 19:52:19 office volumio[743]: info: Starting Streaming Daemon Jan 20 19:52:19 office volumio[743]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Jan 20 19:52:19 office sudo[11164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 20 19:52:19 office sudo[11164]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:19 office volumio[743]: info: Successfully Added MyVolumio device Jan 20 19:52:19 office sudo[11164]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:19 office volumio[743]: error: Cannot start Volumio Streaming Daemon Jan 20 19:52:19 office volumio[743]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 20 19:52:19 office volumio[743]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 20 19:52:19 office volumio[743]: info: Updating MyVolumio device info Jan 20 19:52:19 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:19 office volumio[743]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 20 19:52:20 office volumio[743]: info: [1737402740109] Starting BluetoothController Jan 20 19:52:20 office volumio[743]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 20 19:52:21 office sudo[11167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast Jan 20 19:52:21 office sudo[11167]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:21 office sudo[11170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 20 19:52:21 office sudo[11170]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:21 office sudo[11167]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:21 office sudo[11170]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:21 office volumio[743]: info: MRS: MultiRoom plugin initialized Jan 20 19:52:21 office volumio[743]: info: MRS: STOPPING SNAPCLIENT Jan 20 19:52:21 office volumio[743]: info: MRS: Snap server stop Jan 20 19:52:21 office sudo[11187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 20 19:52:21 office sudo[11187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:21 office volumio[743]: info: MRS: STOPPING volumioStreaming Jan 20 19:52:21 office sudo[11190]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 20 19:52:21 office sudo[11190]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:21 office sudo[11187]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:21 office sudo[11193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 20 19:52:21 office sudo[11193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:21 office volumio[743]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 20 19:52:21 office sudo[11196]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 20 19:52:21 office sudo[11196]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:21 office sudo[11190]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:21 office sudo[11196]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:21 office volumio[743]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 20 19:52:21 office sudo[11193]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:21 office volumio[743]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 20 19:52:21 office volumio[743]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 20 19:52:21 office volumio[743]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 20 19:52:21 office volumio[743]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 20 19:52:21 office volumio[743]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 20 19:52:21 office volumio[743]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 20 19:52:21 office volumio[743]: info: Preparing to generate the ALSA configuration file Jan 20 19:52:21 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 20 19:52:21 office volumio[743]: info: Successfully Updated MyVolumio device Jan 20 19:52:21 office volumio[743]: info: MRS: Removed streaming files Jan 20 19:52:21 office volumio[743]: info: MRS: volumioStreaming STOPPED Jan 20 19:52:21 office volumio[743]: info: MRS: SNAPSERVER STOPPED Jan 20 19:52:21 office volumio[743]: info: MRS: SNAPCLIENT STOPPED Jan 20 19:52:21 office volumio[743]: info: Snap server and client installed Jan 20 19:52:21 office volumio[743]: info: Asound.conf file unchanged, so no further update is needed Jan 20 19:52:21 office volumio[743]: info: Output device has changed, restarting MPD Jan 20 19:52:21 office volumio[743]: info: Output device has changed, restarting Shairport Sync Jan 20 19:52:21 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:21 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:21 office sudo[11200]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 20 19:52:21 office sudo[11200]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:21 office sudo[11200]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:21 office sudo[11201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 20 19:52:21 office sudo[11201]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:21 office systemd[1]: Stopping Music Player Daemon... Jan 20 19:52:21 office systemd[1]: mpd.service: Succeeded. Jan 20 19:52:21 office systemd[1]: Stopped Music Player Daemon. Jan 20 19:52:21 office volumio[743]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 19:52:21 office systemd[1]: Starting Music Player Daemon... Jan 20 19:52:21 office volumio[743]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 20 19:52:21 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:21 office volumio[743]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 20 19:52:21 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 19:52:21 office volumio[743]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 20 19:52:21 office kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 20 19:52:21 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 20 19:52:21 office volumio[743]: info: MRS: Pushing multiroomSync output for this device Jan 20 19:52:21 office volumio[743]: info: MRS: Pushing multiroomSync output Jan 20 19:52:21 office volumio[743]: info: Adding audio output: Jan 20 19:52:21 office sudo[11211]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name office Jan 20 19:52:21 office sudo[11211]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:21 office volumio[743]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 20 19:52:21 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:21 office sudo[11211]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:21 office volumio[743]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 20 19:52:21 office volumio[743]: info: Adding METAVOLUMIO REST API Endpoints Jan 20 19:52:21 office volumio[743]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 20 19:52:21 office volumio[743]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 20 19:52:21 office volumio[743]: info: Adding Manifest REST API Endpoints Jan 20 19:52:21 office volumio[743]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui Jan 20 19:52:21 office kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 20 19:52:21 office volumio[743]: info: CoreCommandRouter::Reload Ui Jan 20 19:52:21 office volumio[743]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 20 19:52:21 office volumio[743]: info: Preparing CD Folders Jan 20 19:52:21 office volumio[743]: info: Adding CD REST API Endpoints Jan 20 19:52:21 office volumio[743]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Jan 20 19:52:21 office volumio[743]: info: Starting UDEV Watcher for CD Jan 20 19:52:21 office volumio[743]: info: Detecting CD presence with UDEV Jan 20 19:52:21 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Jan 20 19:52:22 office volumio[743]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 20 19:52:22 office volumio[743]: info: Adding inputs REST Endpoints Jan 20 19:52:22 office volumio[743]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Jan 20 19:52:22 office volumio[743]: info: Scanning Audio Inputs Jan 20 19:52:22 office volumio[743]: info: Checking against Known Cards name Jan 20 19:52:22 office volumio[743]: info: Adding Server instance for streaming Jan 20 19:52:22 office volumio[743]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 20 19:52:22 office volumio[743]: error: Hi Res Audio Failed Login: Missing Login Data Jan 20 19:52:22 office volumio[743]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 20 19:52:22 office volumio[743]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 20 19:52:22 office volumio[743]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 20 19:52:22 office volumio[743]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 20 19:52:22 office volumio[743]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 20 19:52:22 office volumio[743]: info: Stopping AccessToken refresher cron Jan 20 19:52:22 office sudo[11224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Jan 20 19:52:22 office sudo[11224]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:22 office volumio[743]: info: AccessToken refresher cron started Jan 20 19:52:22 office volumio[743]: info: Stopping AccessToken refresher cron for QOBUZ Jan 20 19:52:22 office sudo[11224]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:22 office volumio[743]: info: AccessToken refresher cron started for QOBUZ Jan 20 19:52:22 office volumio[743]: info: Adding TIDAL REST API Endpoints Jan 20 19:52:22 office volumio[743]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Jan 20 19:52:22 office volumio[743]: info: Adding QOBUZ REST API Endpoints Jan 20 19:52:22 office volumio[743]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 19:52:22 office volumio[743]: info: Retrieving Cloud Streaming UI Jan 20 19:52:22 office volumio[743]: info: Bluetooth name changed to office Jan 20 19:52:22 office volumio[743]: info: MPD Permissions set Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: Getting Tidal Cloud Configuration Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 19:52:22 office volumio[743]: info: Getting Qobuz Cloud Configuration Jan 20 19:52:22 office volumio[743]: info: Asking plugin for UI Config Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 19:52:22 office volumio[743]: info: Getting Spotify Cloud Configuration Jan 20 19:52:22 office volumio[743]: info: Asking plugin for UI Config Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 19:52:22 office volumio[743]: info: Saving Spotify Acccount Jan 20 19:52:22 office volumio[743]: info: Got it Jan 20 19:52:22 office volumio[743]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 20 19:52:22 office volumio[743]: info: Starting Shairport Sync Jan 20 19:52:22 office mpd[11220]: Jan 20 19:52 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 20 19:52:22 office mpd[11220]: Jan 20 19:52 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 20 19:52:22 office mpd[11220]: Jan 20 19:52 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 20 19:52:22 office sudo[11233]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 19:52:22 office volumio[743]: info: Preparing to generate the ALSA configuration file Jan 20 19:52:22 office sudo[11233]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:22 office systemd[1]: Started Music Player Daemon. Jan 20 19:52:22 office volumio[743]: info: Got Tidal Cloud Configuration Jan 20 19:52:22 office volumio[743]: info: Got it Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 19:52:22 office sudo[11201]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:22 office systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 20 19:52:22 office systemd[1]: shairport-sync.service: Succeeded. Jan 20 19:52:22 office systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 20 19:52:22 office systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 20 19:52:22 office volumio[743]: info: Asound.conf file unchanged, so no further update is needed Jan 20 19:52:22 office volumio[743]: info: Output device has changed, restarting MPD Jan 20 19:52:22 office sudo[11233]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:22 office sudo[11239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 20 19:52:22 office sudo[11239]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:22 office sudo[11239]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:22 office volumio[743]: info: Output device has changed, restarting Shairport Sync Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:22 office sudo[11244]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 20 19:52:22 office sudo[11244]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:22 office systemd[1]: Stopping Music Player Daemon... Jan 20 19:52:22 office systemd[1]: mpd.service: Succeeded. Jan 20 19:52:22 office volumio[743]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 19:52:22 office systemd[1]: Stopped Music Player Daemon. Jan 20 19:52:22 office volumio[743]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office systemd[1]: Starting Music Player Daemon... Jan 20 19:52:22 office volumio[743]: info: Preparing to generate the ALSA configuration file Jan 20 19:52:22 office volumio[743]: error: updateQueue error: null Jan 20 19:52:22 office kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 20 19:52:22 office volumio[743]: info: MPD Permissions set Jan 20 19:52:22 office volumio[743]: info: Shairport-Sync Started Jan 20 19:52:22 office sudo[11254]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 20 19:52:22 office sudo[11254]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office sudo[11257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 20 19:52:22 office sudo[11257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:22 office sudo[11254]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:22 office sudo[11257]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: Asound.conf file unchanged, so no further update is needed Jan 20 19:52:22 office volumio[743]: info: Output device has changed, restarting MPD Jan 20 19:52:22 office sudo[11265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 20 19:52:22 office sudo[11265]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:22 office volumio[743]: info: Output device has changed, restarting Shairport Sync Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:22 office sudo[11265]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:22 office sudo[11268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 20 19:52:22 office sudo[11268]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:22 office systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 20 19:52:22 office systemd[1]: mpd.service: Succeeded. Jan 20 19:52:22 office systemd[1]: Stopped Music Player Daemon. Jan 20 19:52:22 office systemd[1]: Starting Music Player Daemon... Jan 20 19:52:22 office volumio[743]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 20 19:52:22 office volumio[743]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: MPD Permissions set Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::volumioGetBrowseSources Jan 20 19:52:22 office volumio[743]: info: Starting Shairport Sync Jan 20 19:52:22 office kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office sudo[11278]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 19:52:22 office sudo[11278]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 20 19:52:22 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 20 19:52:22 office systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 20 19:52:22 office systemd[1]: shairport-sync.service: Succeeded. Jan 20 19:52:22 office systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 20 19:52:22 office systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 20 19:52:22 office sudo[11278]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:22 office volumio[743]: info: Starting Shairport Sync Jan 20 19:52:22 office volumio[743]: info: Shairport-Sync Started Jan 20 19:52:23 office sudo[11290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 20 19:52:23 office sudo[11290]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:23 office systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 20 19:52:23 office systemd[1]: shairport-sync.service: Succeeded. Jan 20 19:52:23 office systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 20 19:52:23 office systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 20 19:52:23 office sudo[11290]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:23 office volumio[743]: info: Shairport-Sync Started Jan 20 19:52:23 office volumio[743]: verbose: New Socket.io Connection to 192.168.1.199 from 192.168.1.139 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Safari/537.36 Total Clients: 5 Jan 20 19:52:23 office volumio[743]: info: Received Get System Info Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 19:52:23 office volumio[743]: info: Discovery: Getting this device information Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:23 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:23 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::volumioGetVisibleSources Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:23 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 20 19:52:23 office volumio[743]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Jan 20 19:52:23 office volumio[743]: info: Received Get System Info Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 19:52:23 office volumio[743]: info: Discovery: Getting this device information Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:23 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:23 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:23 office volumio[743]: info: Listing playlists Jan 20 19:52:23 office mpd[11286]: Jan 20 19:52 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 20 19:52:23 office mpd[11286]: Jan 20 19:52 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured Jan 20 19:52:23 office mpd[11286]: Jan 20 19:52 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured Jan 20 19:52:23 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 20 19:52:23 office systemd[1]: Started Music Player Daemon. Jan 20 19:52:23 office sudo[11244]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:23 office sudo[11268]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:23 office volumio[743]: error: MPD error: The expression evaluated to a falsy value: Jan 20 19:52:23 office volumio[743]: assert.ok(self.idling) Jan 20 19:52:23 office volumio[743]: error: The expression evaluated to a falsy value: Jan 20 19:52:23 office volumio[743]: assert.ok(self.idling) Jan 20 19:52:23 office volumio[743]: error: updateQueue error: null Jan 20 19:52:23 office volumio[743]: info: MRS: Found cast device: Chromecast-71612aa31b38d954a10e0ba7b8af39b3 Jan 20 19:52:23 office volumio[743]: info: Adding audio output: Jan 20 19:52:24 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 20 19:52:24 office volumio[743]: info: Received Get System Info Jan 20 19:52:24 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 19:52:24 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 19:52:24 office volumio[743]: info: Discovery: Getting this device information Jan 20 19:52:24 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:24 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:24 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 19:52:24 office volumio[743]: info: Discovery: Remote connection error: 192.168.1.248 Jan 20 19:52:24 office sudo[11298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Jan 20 19:52:24 office sudo[11298]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:24 office systemd[1]: Started Volumio Bluetooth Module. Jan 20 19:52:24 office sudo[11298]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:24 office volumio[743]: info: Volumio BT Module successfully started Jan 20 19:52:24 office sudo[11301]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Jan 20 19:52:24 office sudo[11301]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:24 office volumiobt[11300]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Jan 20 19:52:24 office sudo[11301]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 20 19:52:25 office volumio[743]: info: Received Get System Info Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 19:52:25 office volumio[743]: info: Discovery: Getting this device information Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:25 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 19:52:25 office volumio[743]: info: TidalConnect service stoped! Jan 20 19:52:25 office volumio[743]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Jan 20 19:52:25 office volumio[743]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Jan 20 19:52:25 office sudo[11313]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Jan 20 19:52:25 office sudo[11313]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:25 office systemd[1]: Started Volumio Tidal Connect Service. Jan 20 19:52:25 office sudo[11313]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:25 office volumio[743]: info: Executing endpoint tc_getconfig Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Jan 20 19:52:25 office vtcs[11315]: STARTING TidalConnect services, version: 1.1.7.5 Jan 20 19:52:25 office vtcs[11315]: STARTED TidalConnect services. Jan 20 19:52:25 office volumio[743]: info: Executing endpoint tc_connect Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Jan 20 19:52:25 office volumio[743]: info: Connecting to TidalConnect Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::servicePushState Jan 20 19:52:25 office volumio[743]: info: CoreStateMachine::pushState Jan 20 19:52:25 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::volumioPushState Jan 20 19:52:25 office volumio[743]: info: MRS: Pushing multiroomSync output update for this device Jan 20 19:52:25 office volumio[743]: info: MRS: Pushing multiroomSync output Jan 20 19:52:25 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:25 office volumio[743]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jan 20 19:52:25 office volumio[743]: verbose: CURRENT POSITION 0 Jan 20 19:52:25 office volumio[743]: info: CoreStateMachine::syncState stateService stop Jan 20 19:52:25 office volumio[743]: info: CoreStateMachine::syncState currentStatus stop Jan 20 19:52:25 office volumio[743]: info: CoreStateMachine::pushState Jan 20 19:52:25 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::volumioPushState Jan 20 19:52:25 office volumio[743]: info: MRS: Pushing multiroomSync output update for this device Jan 20 19:52:25 office volumio[743]: info: MRS: Pushing multiroomSync output Jan 20 19:52:25 office volumio[743]: info: No code Jan 20 19:52:25 office volumio[743]: info: CoreStateMachine::pushState Jan 20 19:52:25 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::volumioPushState Jan 20 19:52:25 office volumio[743]: info: MRS: Pushing multiroomSync output update for this device Jan 20 19:52:25 office volumio[743]: info: MRS: Pushing multiroomSync output Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::servicePushState Jan 20 19:52:25 office volumio[743]: info: CoreStateMachine::pushState Jan 20 19:52:25 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::volumioPushState Jan 20 19:52:25 office volumio[743]: info: MRS: Pushing multiroomSync output update for this device Jan 20 19:52:25 office volumio[743]: info: MRS: Pushing multiroomSync output Jan 20 19:52:25 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:25 office volumio[743]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Jan 20 19:52:25 office volumio[743]: verbose: CURRENT POSITION 0 Jan 20 19:52:25 office volumio[743]: info: CoreStateMachine::syncState stateService stop Jan 20 19:52:25 office volumio[743]: info: CoreStateMachine::syncState currentStatus stop Jan 20 19:52:25 office volumio[743]: info: CoreStateMachine::pushState Jan 20 19:52:25 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::volumioPushState Jan 20 19:52:25 office volumio[743]: info: MRS: Pushing multiroomSync output update for this device Jan 20 19:52:25 office volumio[743]: info: MRS: Pushing multiroomSync output Jan 20 19:52:25 office volumio[743]: info: No code Jan 20 19:52:25 office volumio[743]: info: CoreStateMachine::pushState Jan 20 19:52:25 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:25 office volumio[743]: info: CoreCommandRouter::volumioPushState Jan 20 19:52:25 office volumio[743]: info: MRS: Pushing multiroomSync output update for this device Jan 20 19:52:25 office volumio[743]: info: MRS: Pushing multiroomSync output Jan 20 19:52:25 office sudo[11333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Jan 20 19:52:25 office sudo[11333]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:52:25 office volumiobt[11300]: pulseaudio: no process found Jan 20 19:52:25 office sudo[11333]: pam_unix(sudo:session): session closed for user root Jan 20 19:52:26 office dbus-daemon[503]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.33' (uid=1000 pid=11337 comm="/usr/bin/pulseaudio --high-priority=true ") Jan 20 19:52:26 office systemd[1]: Starting RealtimeKit Scheduling Policy Service... Jan 20 19:52:26 office volumio[743]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:52:26 office dbus-daemon[503]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Jan 20 19:52:26 office systemd[1]: Started RealtimeKit Scheduling Policy Service. Jan 20 19:52:26 office rtkit-daemon[11338]: Successfully called chroot. Jan 20 19:52:26 office rtkit-daemon[11338]: Successfully dropped privileges. Jan 20 19:52:26 office rtkit-daemon[11338]: Successfully limited resources. Jan 20 19:52:26 office rtkit-daemon[11338]: Running. Jan 20 19:52:26 office rtkit-daemon[11338]: Canary thread running. Jan 20 19:52:26 office rtkit-daemon[11338]: Watchdog thread running. Jan 20 19:52:26 office dbus-daemon[503]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.34' (uid=0 pid=11338 comm="/usr/lib/rtkit/rtkit-daemon ") Jan 20 19:52:26 office systemd[1]: Starting Authorization Manager... Jan 20 19:52:26 office volumio[743]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:52:26 office polkitd[11341]: started daemon version 0.105 using authority implementation `local' version `0.105' Jan 20 19:52:26 office dbus-daemon[503]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Jan 20 19:52:26 office systemd[1]: Started Authorization Manager. Jan 20 19:52:26 office pulseaudio[11337]: Failed to open cookie file '/home/volumio/.config/pulse/cookie': No such file or directory Jan 20 19:52:26 office pulseaudio[11337]: Failed to load authentication key '/home/volumio/.config/pulse/cookie': No such file or directory Jan 20 19:52:26 office pulseaudio[11337]: Failed to open cookie file '/home/volumio/.pulse-cookie': No such file or directory Jan 20 19:52:26 office pulseaudio[11337]: Failed to load authentication key '/home/volumio/.pulse-cookie': No such file or directory Jan 20 19:52:26 office pulseaudio[11337]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 20 19:52:26 office pulseaudio[11337]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Jan 20 19:52:26 office volumiobt[11300]: Applying permissions Jan 20 19:52:26 office volumiobt[11300]: Setting BT discoverable and pairable Jan 20 19:52:26 office bluetoothd[712]: Endpoint registered: sender=:1.54 path=/MediaEndpoint/A2DPSource Jan 20 19:52:26 office bluetoothd[712]: Endpoint registered: sender=:1.54 path=/MediaEndpoint/A2DPSink Jan 20 19:52:26 office pulseaudio[11337]: 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 Jan 20 19:52:26 office kernel: Bluetooth: RFCOMM TTY layer initialized Jan 20 19:52:26 office kernel: Bluetooth: RFCOMM socket layer initialized Jan 20 19:52:26 office kernel: Bluetooth: RFCOMM ver 1.11 Jan 20 19:52:26 office volumiobt[11300]: Agent registered Jan 20 19:52:26 office volumiobt[11300]: [bluetooth]# -e power on Jan 20 19:52:26 office volumiobt[11300]: Invalid command in menu main: -e Jan 20 19:52:26 office volumiobt[11300]: Jan 20 19:52:26 office volumiobt[11300]: Use "help" for a list of available commands in a menu. Jan 20 19:52:26 office volumiobt[11300]: Use "menu " if you want to enter any submenu. Jan 20 19:52:26 office volumiobt[11300]: Use "back" if you want to return to menu main. Jan 20 19:52:26 office volumiobt[11300]: [bluetooth]# agent on Jan 20 19:52:26 office volumiobt[11300]: Agent is already registered Jan 20 19:52:26 office volumiobt[11300]: [bluetooth]# discoverable on Jan 20 19:52:26 office volumiobt[11300]: [bluetooth]# pairable on Jan 20 19:52:26 office volumiobt[11300]: [bluetooth]# agent NoInputNoOutput Jan 20 19:52:26 office volumiobt[11300]: Agent is already registered Jan 20 19:52:26 office volumiobt[11300]: [bluetooth]# default-agent Jan 20 19:52:26 office volumiobt[11300]: [bluetooth]# quit Jan 20 19:52:26 office volumiobt[11300]: [59B blob data] Jan 20 19:52:26 office volumio[743]: info: MRS: Getting audio outputs on start Jan 20 19:52:26 office volumio[743]: info: MRS: Requesting all other devices output Jan 20 19:52:28 office volumio[743]: info: TidalConnect service started! Jan 20 19:52:32 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:32 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:32 office volumio[743]: info: Discovery: Remote connection error: 192.168.1.248 Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 19:52:33 office volumio[743]: info: Retrieving Cloud Streaming UI Jan 20 19:52:33 office volumio[743]: info: Getting Tidal Cloud Configuration Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 19:52:33 office volumio[743]: info: Getting Qobuz Cloud Configuration Jan 20 19:52:33 office volumio[743]: info: Asking plugin for UI Config Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 19:52:33 office volumio[743]: info: Getting Spotify Cloud Configuration Jan 20 19:52:33 office volumio[743]: info: Asking plugin for UI Config Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 19:52:33 office volumio[743]: info: Saving Spotify Acccount Jan 20 19:52:33 office volumio[743]: info: Got it Jan 20 19:52:33 office volumio[743]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Jan 20 19:52:33 office volumio[743]: info: Got Tidal Cloud Configuration Jan 20 19:52:33 office volumio[743]: info: Got it Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::volumioGetBrowseSources Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 19:52:33 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Jan 20 19:52:37 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 19:52:41 office volumio[743]: info: Discovery: Remote connection error: 192.168.1.248 Jan 20 19:52:41 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 20 19:52:41 office volumio[743]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 20 19:52:41 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 20 19:52:41 office volumio[743]: info: Received Get System Version Jan 20 19:52:41 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 20 19:52:41 office volumio[743]: info: Received Get System Info Jan 20 19:52:41 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 20 19:52:41 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 20 19:52:41 office volumio[743]: info: Discovery: Getting this device information Jan 20 19:52:41 office volumio[743]: info: CoreCommandRouter::volumioGetState Jan 20 19:52:41 office volumio[743]: info: CorePlayQueue::getTrack 0 Jan 20 19:52:41 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 20 19:52:41 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 19:52:45 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 19:52:49 office volumio[743]: info: Discovery: Remote connection error: 192.168.1.248 Jan 20 19:52:49 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 19:52:53 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 19:52:57 office volumio[743]: info: Discovery: Remote connection error: 192.168.1.248 Jan 20 19:52:57 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 19:52:58 office volumio[743]: error: error Jan 20 19:53:01 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 19:53:05 office volumio[743]: info: Discovery: Remote connection error: 192.168.1.248 Jan 20 19:53:05 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 19:53:09 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 19:53:13 office volumio[743]: info: Discovery: Remote connection error: 192.168.1.248 Jan 20 19:53:13 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 19:53:17 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 19:53:21 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Jan 20 19:53:21 office volumio[743]: info: Discovery: Remote connection error: 192.168.1.248 Jan 20 19:53:24 office volumio[743]: info: Disabling MyMusic plugin upnp Jan 20 19:53:24 office sudo[11357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Jan 20 19:53:24 office sudo[11357]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 20 19:53:24 office systemd[1]: Stopping UPnP Renderer front-end to MPD... Jan 20 19:53:24 office volumio[743]: error: Upnp client error: Error: This socket has been ended by the other party Jan 20 19:53:24 office volumio[743]: ------------------------------------ BT MESSAGE: BT STATUS: running Jan 20 19:53:24 office volumio[743]: info: Enabling MyMusic plugin upnp Jan 20 19:53:24 office volumio[743]: info: Enabling plugin upnp Jan 20 19:53:24 office volumio[743]: info: Loading plugin "upnp"... Jan 20 19:53:24 office volumio[743]: info: [1737402804743] Starting Upmpd Daemon Jan 20 19:53:24 office volumio[743]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 20 19:53:24 office volumio[743]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 19:53:24 office volumio[743]: Error: listen EADDRINUSE: address already in use :::6599 Jan 20 19:53:24 office volumio[743]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Jan 20 19:53:24 office volumio[743]: at listenInCluster (net.js:1366:12) Jan 20 19:53:24 office volumio[743]: at Server.listen (net.js:1452:7) Jan 20 19:53:24 office volumio[743]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Jan 20 19:53:24 office volumio[743]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Jan 20 19:53:24 office volumio[743]: at Promise._successFn (/volumio/app/pluginmanager.js:1841:19) Jan 20 19:53:24 office volumio[743]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Jan 20 19:53:24 office volumio[743]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Jan 20 19:53:24 office volumio[743]: code: 'EADDRINUSE', Jan 20 19:53:24 office volumio[743]: errno: -98, Jan 20 19:53:24 office volumio[743]: syscall: 'listen', Jan 20 19:53:24 office volumio[743]: address: '::', Jan 20 19:53:24 office volumio[743]: port: 6599 Jan 20 19:53:24 office volumio[743]: } Jan 20 19:53:24 office volumio[743]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 20 19:53:25 office sudo[11368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-20 19:52 Jan 20 19:53:25 office sudo[11368]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="c87c6e2b1ae33dc5801986cbcbe970898b92e7b4" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="b949d6001d7e656b4668f31c119d2247cdf45158" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 30 Mar 2023 05:45:03 PM CEST" VOLUMIO_VERSION="3.449" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6428d8e2c06e98483f5c5d77e8f01989"