-- Logs begin at Mon 2025-03-10 11:31:02 CET, end at Sat 2025-03-15 09:06:33 CET. -- Mar 15 09:05:25 rivo systemd-timedated[3356]: Changed local time to Sat Mar 15 09:05:25 2025 Mar 15 09:05:25 rivo sudo[3354]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:25 rivo volumio-time-update[2826]: volumio-time-update-util: System time updated successfully. Mar 15 09:05:25 rivo systemd[1]: Started Volumio Time Update Utility. Mar 15 09:05:25 rivo systemd[1]: Reached target Multi-User System. Mar 15 09:05:25 rivo systemd[1]: Reached target Graphical Interface. Mar 15 09:05:25 rivo systemd[1]: Starting Update UTMP about System Runlevel Changes... Mar 15 09:05:25 rivo systemd[1]: Starting Daily apt download activities... Mar 15 09:05:25 rivo systemd[1]: Starting Daily man-db regeneration... Mar 15 09:05:25 rivo systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Mar 15 09:05:25 rivo systemd[1]: Started Update UTMP about System Runlevel Changes. Mar 15 09:05:25 rivo systemd[1]: man-db.service: Succeeded. Mar 15 09:05:25 rivo systemd[1]: Started Daily man-db regeneration. Mar 15 09:05:25 rivo systemd[1]: systemd-rfkill.service: Succeeded. Mar 15 09:05:25 rivo ntpd[3322]: Soliciting pool server 185.45.112.121 Mar 15 09:05:25 rivo volumio[3116]: STREAMING PROXY: Starting server on port 3245 Mar 15 09:05:25 rivo volumio[3116]: Node JS runtime: 14 Mar 15 09:05:26 rivo volumio[3116]: info: Loading plugin "calmradio"... Mar 15 09:05:26 rivo ntpd[3322]: Soliciting pool server 178.239.19.57 Mar 15 09:05:27 rivo systemd[1]: apt-daily.service: Succeeded. Mar 15 09:05:27 rivo systemd[1]: Started Daily apt download activities. Mar 15 09:05:27 rivo systemd[1]: Starting Daily apt upgrade and clean activities... Mar 15 09:05:27 rivo volumio[3116]: info: Loading plugin "mixcloud"... Mar 15 09:05:27 rivo volumio-remote-updater[2825]: [2025-03-15 09:05:27] [connect] Successful connection Mar 15 09:05:28 rivo volumio[3116]: info: Plugin soundcloud is not enabled Mar 15 09:05:28 rivo volumio[3116]: info: Loading plugin "spop"... Mar 15 09:05:29 rivo systemd[1]: apt-daily-upgrade.service: Succeeded. Mar 15 09:05:29 rivo systemd[1]: Started Daily apt upgrade and clean activities. Mar 15 09:05:29 rivo systemd[1]: Startup finished in 12.886s (kernel) + 24.166s (userspace) = 37.053s. Mar 15 09:05:30 rivo volumio[3116]: info: Loading plugin "youtube2"... Mar 15 09:05:34 rivo volumio[3116]: info: Loading plugin "ytcr"... Mar 15 09:05:39 rivo systemd[1]: systemd-fsckd.service: Succeeded. Mar 15 09:05:39 rivo volumio[3116]: info: Loading plugin "ytmusic"... Mar 15 09:05:41 rivo volumio[3116]: info: Loading plugin "now_playing"... Mar 15 09:05:42 rivo volumio[3116]: info: Loading plugin "multiroom"... Mar 15 09:05:43 rivo volumio-remote-updater[2825]: [2025-03-15 09:05:43] [connect] Successful connection Mar 15 09:05:44 rivo volumio[3116]: info: Applying required configuration parameters for plugin multiroom Mar 15 09:05:44 rivo sudo[3500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 15 09:05:44 rivo sudo[3500]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:44 rivo sudo[3500]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:44 rivo volumio[3116]: info: MRS: MultiRoom plugin initialized Mar 15 09:05:44 rivo volumio[3116]: info: MRS: STOPPING SNAPCLIENT Mar 15 09:05:44 rivo volumio[3116]: info: MRS: Snap server stop Mar 15 09:05:44 rivo sudo[3516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 15 09:05:44 rivo sudo[3516]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:45 rivo volumio[3116]: info: MRS: STOPPING volumioStreaming Mar 15 09:05:45 rivo sudo[3516]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:45 rivo sudo[3519]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 15 09:05:45 rivo sudo[3519]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:45 rivo sudo[3522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 15 09:05:45 rivo sudo[3522]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:45 rivo sudo[3519]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:45 rivo volumio[3116]: info: Loading plugin "outputs"... Mar 15 09:05:45 rivo volumio[3116]: info: Loading plugin "albumart"... Mar 15 09:05:45 rivo sudo[3525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 15 09:05:45 rivo sudo[3525]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:45 rivo sudo[3522]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:45 rivo sudo[3525]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:45 rivo volumio[3116]: info: Plugin example_plugin is not enabled Mar 15 09:05:45 rivo volumio[3116]: info: Loading plugin "hi_res_audio"... Mar 15 09:05:45 rivo volumio[3116]: Forking 3 albumart workers Mar 15 09:05:46 rivo volumio[3116]: Starting albumart workers Mar 15 09:05:46 rivo volumio[3116]: Starting albumart workers Mar 15 09:05:46 rivo volumio[3116]: Starting albumart workers Mar 15 09:05:47 rivo volumio[3116]: info: Applying required configuration parameters for plugin hi_res_audio Mar 15 09:05:47 rivo volumio[3116]: info: Loading plugin "inputs"... Mar 15 09:05:48 rivo volumio[3116]: info: Loading plugin "qobuz"... Mar 15 09:05:49 rivo volumio[3116]: info: Loading plugin "smart_inputs"... Mar 15 09:05:50 rivo volumio[3116]: info: Loading plugin "tidal"... Mar 15 09:05:51 rivo systemd[1]: systemd-hostnamed.service: Succeeded. Mar 15 09:05:51 rivo volumio[3116]: info: Loading plugin "rivocontrol"... Mar 15 09:05:52 rivo volumio[3116]: info: Adding this device properties Mar 15 09:05:52 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Mar 15 09:05:52 rivo volumio[3116]: info: Setting Additional Device Volumio Properties: [object Object] Mar 15 09:05:52 rivo volumio[3116]: info: Loading plugin "updater_comm"... Mar 15 09:05:52 rivo volumio[3116]: info: Loading plugin "cec_controller"... Mar 15 09:05:53 rivo volumio[3116]: info: Applying required configuration parameters for plugin cec_controller Mar 15 09:05:53 rivo volumio[3116]: info: Plugin mpdemulation is not enabled Mar 15 09:05:53 rivo volumio[3116]: info: Loading plugin "rest_api"... Mar 15 09:05:53 rivo volumio[3116]: info: Loading plugin "websocket"... Mar 15 09:05:53 rivo volumio[3116]: info: Starting Socket.io Server version 2.3.0 Mar 15 09:05:53 rivo volumio[3116]: info: Loading plugin "80s80s"... Mar 15 09:05:53 rivo volumio[3116]: info: Applying required configuration parameters for plugin 80s80s Mar 15 09:05:53 rivo volumio[3116]: info: [1742025953505] [80s80s] API delay: 30 Mar 15 09:05:53 rivo volumio[3116]: info: Loading plugin "motherearthradio"... Mar 15 09:05:53 rivo volumio[3116]: info: Applying required configuration parameters for plugin motherearthradio Mar 15 09:05:53 rivo volumio[3116]: info: [1742025953840] [MotherEarth] API delay: 5 Mar 15 09:05:53 rivo volumio[3116]: info: Loading plugin "podcast"... Mar 15 09:05:54 rivo volumio[3116]: info: ControllerPodcast::constructor Mar 15 09:05:54 rivo volumio[3116]: info: Loading plugin "radio_paradise"... Mar 15 09:05:54 rivo volumio[3116]: info: Applying required configuration parameters for plugin radio_paradise Mar 15 09:05:54 rivo volumio[3116]: info: [1742025954570] [RadioParadise] API delay: 5 Mar 15 09:05:54 rivo volumio[3116]: info: Loading plugin "Systeminfo"... Mar 15 09:05:55 rivo volumio[3116]: info: Loading plugin "randomizer"... Mar 15 09:05:55 rivo kernel: fb: mem_free_work, free memory: addr:800000 Mar 15 09:05:55 rivo volumio[3116]: info: Applying required configuration parameters for plugin randomizer Mar 15 09:05:55 rivo systemd[1]: systemd-timedated.service: Succeeded. Mar 15 09:05:55 rivo volumio[3116]: info: Loading i18n strings for locale nl Mar 15 09:05:55 rivo volumio[3116]: Updating browse sources language Mar 15 09:05:55 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:05:55 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 09:05:55 rivo volumio[3116]: info: CoreCommandRouter::initPlayerControls Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 09:05:56 rivo volumio[3116]: Express server listening on port 3000 Mar 15 09:05:56 rivo volumio[3116]: [Metrics] WebUI: 41s 968.27ms Mar 15 09:05:56 rivo volumio[3116]: info: CoreStateMachine::resetVolumioState Mar 15 09:05:56 rivo volumio[3116]: info: CoreStateMachine::getcurrentVolume Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::volumioRetrievevolume Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:05:56 rivo sudo[3609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 15 09:05:56 rivo sudo[3609]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:56 rivo sudo[3609]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:56 rivo sudo[3613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 09:05:56 rivo sudo[3613]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:56 rivo sudo[3613]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:56 rivo volumio[3116]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Mar 15 09:05:56 rivo volumio[3116]: info: Completed loading Core Plugins Mar 15 09:05:56 rivo volumio[3116]: info: Preparing to generate the ALSA configuration file Mar 15 09:05:56 rivo volumio[3116]: info: Volumio Network Manager: Network status updated: 0 Mar 15 09:05:56 rivo volumio[3116]: info: MRS: Removed streaming files Mar 15 09:05:56 rivo volumio[3116]: info: MRS: volumioStreaming STOPPED Mar 15 09:05:56 rivo volumio[3116]: info: MRS: SNAPSERVER STOPPED Mar 15 09:05:56 rivo volumio[3116]: info: MRS: SNAPCLIENT STOPPED Mar 15 09:05:56 rivo volumio[3116]: info: Cannot mount NAS USB at system boot, trial number 1 ,retrying in 5 seconds Mar 15 09:05:56 rivo volumio[3116]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Mar 15 09:05:56 rivo volumio[3116]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 15 09:05:56 rivo volumio[3116]: info: Reading ALSA contributions from plugins. Mar 15 09:05:56 rivo volumio[3116]: verbose: New Socket.io Connection to 192.168.178.168:3000 from 192.168.178.73 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 15 09:05:56 rivo volumio[3116]: verbose: New Socket.io Connection to 192.168.178.168:3000 from 192.168.178.73 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Mar 15 09:05:56 rivo volumio[3116]: verbose: New Socket.io Connection to 192.168.178.168:3000 from 192.168.178.73 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Mar 15 09:05:56 rivo volumio[3116]: verbose: New Socket.io Connection to 192.168.178.168:3000 from 192.168.178.73 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 09:05:56 rivo volumio[3116]: info: Discovery: Getting this device information Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:05:56 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 09:05:56 rivo volumio[3116]: info: VolumeController:: Volume=100 Mute =false Mar 15 09:05:56 rivo volumio[3116]: info: CoreStateMachine::pushState Mar 15 09:05:56 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::volumioPushState Mar 15 09:05:56 rivo volumio[3116]: info: CoreStateMachine::updateTrackBlock Mar 15 09:05:56 rivo volumio[3116]: info: CorePlayQueue::getTrackBlock Mar 15 09:05:56 rivo volumio[3116]: info: CoreCommandRouter::volumioRetrievevolume Mar 15 09:05:56 rivo volumio[3116]: info: Reloading queue from file Mar 15 09:05:57 rivo volumio[3116]: info: CoreStateMachine::setRepeat false single undefined Mar 15 09:05:57 rivo volumio[3116]: info: CoreStateMachine::pushState Mar 15 09:05:57 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::volumioPushState Mar 15 09:05:57 rivo volumio[3116]: info: CoreStateMachine::setRandom undefined Mar 15 09:05:57 rivo volumio[3116]: info: CoreStateMachine::pushState Mar 15 09:05:57 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::volumioPushState Mar 15 09:05:57 rivo volumio[3116]: info: VolumeController:: Volume=100 Mute =false Mar 15 09:05:57 rivo volumio[3116]: info: CoreStateMachine::pushState Mar 15 09:05:57 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::volumioPushState Mar 15 09:05:57 rivo volumio[3116]: info: Discovery: adding 863507c8-b581-4711-9157-ac87f9decd21 Mar 15 09:05:57 rivo volumio[3116]: info: Discovery: Found device Volumio werkkamer Mar 15 09:05:57 rivo sudo[3626]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Mar 15 09:05:57 rivo sudo[3626]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:57 rivo systemd[1]: Started UPnP Renderer front-end to MPD. Mar 15 09:05:57 rivo sudo[3626]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:57 rivo volumio[3116]: info: Discovery: Connecting to remote: 192.168.178.73 Mar 15 09:05:57 rivo volumio[3116]: info: Discovery: A device disappeared from network Mar 15 09:05:57 rivo volumio[3116]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Mar 15 09:05:57 rivo volumio[3116]: info: Asound.conf file unchanged, so no further update is needed Mar 15 09:05:57 rivo volumio[3116]: info: Output device has changed, restarting MPD Mar 15 09:05:57 rivo sudo[3638]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 15 09:05:57 rivo sudo[3638]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:57 rivo sudo[3638]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:57 rivo volumio[3116]: info: Output device has changed, restarting Shairport Sync Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:05:57 rivo sudo[3641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 15 09:05:57 rivo sudo[3641]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:57 rivo systemd[1]: Stopping Music Player Daemon... Mar 15 09:05:57 rivo volumio[3116]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 15 09:05:57 rivo volumio[3116]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:57 rivo volumio[3116]: info: ___________ START PLUGINS ___________ Mar 15 09:05:57 rivo volumio[3116]: info: ControllerMpd::onStart: Initializing MPD Mar 15 09:05:57 rivo volumio[3116]: info: Creating MPD Configuration file Mar 15 09:05:57 rivo sudo[3649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 15 09:05:57 rivo sudo[3649]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:57 rivo sudo[3649]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 09:05:57 rivo volumio[3116]: info: [1742025957493] CoreMusicLibrary::Adding element Media Servers Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:05:57 rivo systemd[1]: mpd.service: Succeeded. Mar 15 09:05:57 rivo systemd[1]: Stopped Music Player Daemon. Mar 15 09:05:57 rivo systemd[1]: Starting Music Player Daemon... Mar 15 09:05:57 rivo sudo[3652]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 15 09:05:57 rivo sudo[3652]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Mar 15 09:05:57 rivo volumio[3116]: info: Adding METAVOLUMIO REST API Endpoints Mar 15 09:05:57 rivo systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 15 09:05:57 rivo systemd[1]: mpd.service: Succeeded. Mar 15 09:05:57 rivo volumio[3116]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 15 09:05:57 rivo volumio[3116]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 15 09:05:57 rivo volumio[3116]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 15 09:05:57 rivo volumio[3116]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 15 09:05:57 rivo systemd[1]: Stopped Music Player Daemon. Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:05:57 rivo systemd[1]: Starting Music Player Daemon... Mar 15 09:05:57 rivo sudo[3656]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Rivo Mar 15 09:05:57 rivo sudo[3656]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:57 rivo sudo[3656]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:57 rivo volumio[3116]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:05:57 rivo volumio[3116]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 15 09:05:57 rivo volumio[3116]: info: Preparing CD Folders Mar 15 09:05:57 rivo volumio[3116]: info: Adding CD REST API Endpoints Mar 15 09:05:57 rivo volumio[3116]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 15 09:05:57 rivo volumio[3116]: info: Starting UDEV Watcher for CD Mar 15 09:05:57 rivo volumio[3116]: info: Detecting CD presence with UDEV Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Mar 15 09:05:57 rivo sudo[3657]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 15 09:05:57 rivo sudo[3657]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:57 rivo sudo[3657]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 09:05:57 rivo volumio[3116]: info: [1742025957782] CoreMusicLibrary::Adding element Last_100 Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:05:57 rivo volumio[3116]: info: Streaming services startup Mar 15 09:05:57 rivo volumio[3116]: info: Starting Streaming Daemon Mar 15 09:05:57 rivo sudo[3668]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 15 09:05:57 rivo sudo[3668]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:57 rivo sudo[3668]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 09:05:57 rivo volumio[3116]: info: [1742025957928] CoreMusicLibrary::Adding element Webradio Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:05:57 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 09:05:57 rivo volumio[3116]: info: Initializing BBC Radios Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 09:05:58 rivo volumio[3116]: info: [1742025958086] CoreMusicLibrary::Adding element Bandcamp Discover Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Bandcamp Discover Mar 15 09:05:58 rivo volumio[3116]: info: Adding Calm Radio to Browse Sources Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 09:05:58 rivo volumio[3116]: info: [1742025958094] CoreMusicLibrary::Adding element Calm Radio Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Bandcamp Discover Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Calm Radio Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 09:05:58 rivo volumio[3116]: info: [1742025958108] CoreMusicLibrary::Adding element Mixcloud Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Bandcamp Discover Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Calm Radio Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Mixcloud Mar 15 09:05:58 rivo volumio[3116]: info: Creating Spotify config file Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 09:05:58 rivo volumio[3116]: info: [1742025958235] CoreMusicLibrary::Adding element YouTube2 Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Bandcamp Discover Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Calm Radio Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Mixcloud Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source YouTube2 Mar 15 09:05:58 rivo volumio-remote-updater[2825]: [2025-03-15 09:05:58] [connect] Successful connection Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 09:05:58 rivo volumio[3116]: info: [1742025958304] CoreMusicLibrary::Adding element YouTube Music Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Bandcamp Discover Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Calm Radio Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Mixcloud Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source YouTube2 Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source YouTube Music Mar 15 09:05:58 rivo volumio[3116]: info: [now-playing] ConfigUpdater: config is up to date. Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Mar 15 09:05:58 rivo volumio[3116]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:58 rivo volumio[3116]: error: Hi Res Audio Failed Login: Missing Login Data Mar 15 09:05:58 rivo volumio[3116]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 15 09:05:58 rivo volumio[3116]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 15 09:05:58 rivo volumio[3116]: info: Initializing Serial Communication on port /dev/ttyS3 Mar 15 09:05:58 rivo volumio[3116]: info: Touch Event Listener Process Starting Mar 15 09:05:58 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 0 to 9600 Mar 15 09:05:58 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 9600 Mar 15 09:05:58 rivo kernel: meson_uart ffd22000.serial: ttyS3 use xtal(24M) 24000000 change 9600 to 115200 Mar 15 09:05:58 rivo volumio[3116]: info: Adding inputs REST Endpoints Mar 15 09:05:58 rivo volumio[3116]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 15 09:05:58 rivo volumio[3116]: info: Scanning Audio Inputs Mar 15 09:05:58 rivo sudo[3689]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xinput --test-xi2 --root Mar 15 09:05:58 rivo sudo[3689]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:58 rivo sudo[3689]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:58 rivo volumio[3116]: info: Checking against Known Cards name Mar 15 09:05:58 rivo volumio[3116]: info: Checking against Known Cards name Mar 15 09:05:58 rivo volumio[3116]: info: Checking against Known Cards name Mar 15 09:05:58 rivo volumio[3116]: info: Checking against Known Cards name Mar 15 09:05:58 rivo volumio[3116]: info: Checking against Known Cards name Mar 15 09:05:58 rivo volumio[3116]: info: Adding Server instance for streaming Mar 15 09:05:58 rivo sudo[3700]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x25 0x0 Mar 15 09:05:58 rivo sudo[3700]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:58 rivo sudo[3700]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:58 rivo sudo[3703]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x28 0x0 Mar 15 09:05:58 rivo sudo[3703]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:58 rivo sudo[3703]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:58 rivo sudo[3716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x29 0x1 Mar 15 09:05:58 rivo sudo[3716]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:58 rivo sudo[3716]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:58 rivo sudo[3726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/sbin/i2cset -f -y 4 0x18 0x33 0x1 Mar 15 09:05:58 rivo sudo[3726]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:58 rivo sudo[3726]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:58 rivo volumio[3116]: info: Apply VIM3L Onboard LEDs Settings Mar 15 09:05:58 rivo volumio[3116]: info: Turning On Onboard LEDs Mar 15 09:05:58 rivo sudo[3730]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Mar 15 09:05:58 rivo sudo[3730]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:58 rivo sudo[3730]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:58 rivo volumio[3116]: info: Starting CEC Event listener Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 09:05:58 rivo volumio[3116]: info: [1742025958953] CoreMusicLibrary::Adding element 80s80s Radio Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Bandcamp Discover Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Calm Radio Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Mixcloud Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source YouTube2 Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source YouTube Music Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source 80s80s Radio Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 09:05:58 rivo volumio[3116]: info: [1742025958966] CoreMusicLibrary::Adding element Mother Earth Radio Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:05:58 rivo sudo[3734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Mar 15 09:05:58 rivo sudo[3734]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Bandcamp Discover Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Calm Radio Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Mixcloud Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source YouTube2 Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source YouTube Music Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source 80s80s Radio Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Mother Earth Radio Mar 15 09:05:58 rivo sudo[3734]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 09:05:58 rivo volumio[3116]: info: [1742025958990] CoreMusicLibrary::Adding element Podcast Mar 15 09:05:58 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Bandcamp Discover Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Calm Radio Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Mixcloud Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source YouTube2 Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source YouTube Music Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source 80s80s Radio Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Mother Earth Radio Mar 15 09:05:58 rivo volumio[3116]: Cannot find translation for source Podcast Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 09:05:59 rivo volumio[3116]: info: [1742025959001] CoreMusicLibrary::Adding element Radio Paradise Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source Bandcamp Discover Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source Calm Radio Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source Mixcloud Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source YouTube2 Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source YouTube Music Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source 80s80s Radio Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source Mother Earth Radio Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source Podcast Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source Radio Paradise Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 09:05:59 rivo volumio[3116]: info: [1742025959042] CoreMusicLibrary::Adding element Randomizer Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source Bandcamp Discover Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source Calm Radio Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source Mixcloud Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source YouTube2 Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source YouTube Music Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source 80s80s Radio Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source Mother Earth Radio Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source Podcast Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source Radio Paradise Mar 15 09:05:59 rivo volumio[3116]: Cannot find translation for source Randomizer Mar 15 09:05:59 rivo volumio[3116]: info: Volumio Calling Home Mar 15 09:05:59 rivo volumio[3116]: info: Stopping AccessToken refresher cron for QOBUZ Mar 15 09:05:59 rivo volumio[3116]: info: AccessToken refresher cron started for QOBUZ Mar 15 09:05:59 rivo volumio[3116]: info: Stopping AccessToken refresher cron Mar 15 09:05:59 rivo volumio[3116]: info: AccessToken refresher cron started Mar 15 09:05:59 rivo volumio[3116]: info: Adding TIDAL REST API Endpoints Mar 15 09:05:59 rivo volumio[3116]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 15 09:05:59 rivo volumio[3116]: info: Adding QOBUZ REST API Endpoints Mar 15 09:05:59 rivo volumio[3116]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 15 09:05:59 rivo volumio[3116]: info: [now-playing] App is listening on port 4004. Mar 15 09:05:59 rivo volumio[3116]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Mar 15 09:05:59 rivo volumio[3116]: info: Upmpdcli Daemon Started Mar 15 09:05:59 rivo volumio[3116]: info: Serial port opened successfully Mar 15 09:05:59 rivo volumio[3116]: info: Sending serial start messages Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:59 rivo volumio[3116]: info: Reporting MCU Network Status: 0 Mar 15 09:05:59 rivo volumio[3116]: info: MP1 GPIO: Signalled system ready via GPIO Mar 15 09:05:59 rivo volumio[3116]: info: MRS: Pushing audio output on request Mar 15 09:05:59 rivo volumio[3116]: info: MRS: Pushing multiroomSync output Mar 15 09:05:59 rivo volumio[3116]: info: Discovery: Connected to remote: 192.168.178.73 Mar 15 09:05:59 rivo volumio[3116]: info: Reporting MCU Network Status: 1 Mar 15 09:05:59 rivo volumio[3116]: info: Volumio Network Manager: Network status updated: 1 Mar 15 09:05:59 rivo volumio[3116]: verbose: New Socket.io Connection to 192.168.178.168:3000 from 192.168.178.73 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 15 09:05:59 rivo volumio-remote-updater[2825]: [2025-03-15 09:05:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1742025958 101 Mar 15 09:05:59 rivo volumio[3116]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 6 Mar 15 09:05:59 rivo volumio[3116]: info: Touch Event Listener Process Closed Mar 15 09:05:59 rivo volumio[3116]: error: Cannot start Volumio Streaming Daemon Mar 15 09:05:59 rivo volumio[3116]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 15 09:05:59 rivo volumio[3116]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 15 09:05:59 rivo volumio[3116]: info: Bluetooth name changed to Rivo Mar 15 09:05:59 rivo volumio[3116]: info: MPD Permissions set Mar 15 09:05:59 rivo volumio[3116]: info: MPD Permissions set Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Mar 15 09:05:59 rivo volumio[3116]: info: Setting Device Volume Override Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 15 09:05:59 rivo volumio[3116]: info: Updating Volume Controller Parameters: Device: 5 Name: SMSL USB AUDIO Mixer: SMSL USB AUDIO Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10 Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 15 09:05:59 rivo volumio[3116]: info: Disabling external Volume Control Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:05:59 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:05:59 rivo volumio[3116]: info: CoreStateMachine::pushState Mar 15 09:05:59 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::volumioPushState Mar 15 09:05:59 rivo mpd[3666]: Mar 15 09:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 15 09:05:59 rivo systemd[1]: Started Music Player Daemon. Mar 15 09:05:59 rivo sudo[3641]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:59 rivo sudo[3652]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:59 rivo volumio[3116]: info: Volumio called home Mar 15 09:05:59 rivo volumio[3116]: info: Spotify config file written Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Mar 15 09:05:59 rivo volumio[3116]: info: Setting Additional System Software info: Hardware Revision: 2.1 Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Mar 15 09:05:59 rivo volumio[3116]: info: Setting HW Firmware info: undefined Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Mar 15 09:05:59 rivo volumio[3116]: info: Setting HW Version info: 2.1 Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , setAdditionalSVInfo Mar 15 09:05:59 rivo volumio[3116]: info: Setting Additional System Software info: Hardware Revision: 2.1, Firmware Version: 0.3.4 Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , setHwFwVersion Mar 15 09:05:59 rivo volumio[3116]: info: Setting HW Firmware info: 0.3.4 Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , setHwVersion Mar 15 09:05:59 rivo volumio[3116]: info: Setting HW Version info: 2.1 Mar 15 09:05:59 rivo volumio[3116]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Mar 15 09:05:59 rivo volumio[3116]: info: MCU Signalled Headphone Mode Disabled Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: raat , reportHeadphoneState Mar 15 09:05:59 rivo volumio[3116]: info: MCU Signalled Sleep Mode Disabled Mar 15 09:05:59 rivo sudo[3751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Mar 15 09:05:59 rivo sudo[3751]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:59 rivo volumio[3116]: info: Enabling Advanced system settings configuration Mar 15 09:05:59 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , addAdditionalUISections Mar 15 09:05:59 rivo volumio[3116]: info: Additional UI Settings Added for plugin music_service/inputs Mar 15 09:05:59 rivo volumio[3116]: info: MCU Signalled Auto Boot Mode On Power Disabled Mar 15 09:05:59 rivo systemd[1]: Started go-librespot Daemon. Mar 15 09:05:59 rivo sudo[3751]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:59 rivo sudo[3754]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms force on Mar 15 09:05:59 rivo sudo[3754]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:05:59 rivo go-librespot[3755]: Librespot-go daemon starting... Mar 15 09:05:59 rivo sudo[3754]: pam_unix(sudo:session): session closed for user root Mar 15 09:05:59 rivo volumio[3116]: Upnp client error: Error: This socket has been ended by the other party Mar 15 09:05:59 rivo volumio[3116]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 15 09:05:59 rivo volumio[3116]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 15 09:06:00 rivo go-librespot[3755]: time="2025-03-15T09:06:00+01:00" level=info msg="generated new device id: f29155e4c0193396a8cbb7b6c1d61dc38cd3c12e" Mar 15 09:06:00 rivo go-librespot[3755]: time="2025-03-15T09:06:00+01:00" level=debug msg="stored credentials found for z5pn5bhagnji3rsj9aw0vvffr" Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:00 rivo volumio[3116]: info: No need to fix Spotify hosts Mar 15 09:06:00 rivo volumio[3116]: error: Serial API: Failed to decode command: MAXVOL, message: 100 Mar 15 09:06:00 rivo volumio[3116]: info: Discovery: adding d4f777a4-0cfc-4983-abd2-d6051c6944be Mar 15 09:06:00 rivo volumio[3116]: info: Discovery: Found device Rivo Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:00 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:00 rivo volumio[3116]: info: MRS: Pushing multiroomSync output for this device Mar 15 09:06:00 rivo volumio[3116]: info: MRS: Pushing multiroomSync output Mar 15 09:06:00 rivo volumio[3116]: info: Adding audio output: Mar 15 09:06:00 rivo volumio[3116]: info: Adding audio output: Mar 15 09:06:00 rivo volumio[3116]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 15 09:06:00 rivo go-librespot[3755]: time="2025-03-15T09:06:00+01:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Mar 15 09:06:00 rivo go-librespot[3755]: time="2025-03-15T09:06:00+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Mar 15 09:06:00 rivo go-librespot[3755]: time="2025-03-15T09:06:00+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Mar 15 09:06:00 rivo volumio[3116]: info: VolumeController:: Volume=100 Mute =false Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:00 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:00 rivo volumio[3116]: info: CoreStateMachine::pushState Mar 15 09:06:00 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::volumioPushState Mar 15 09:06:00 rivo volumio[3116]: info: MRS: Pushing multiroomSync output update for this device Mar 15 09:06:00 rivo go-librespot[3755]: time="2025-03-15T09:06:00+01:00" level=debug msg="zeroconf server listening on port 33067" Mar 15 09:06:00 rivo volumio[3116]: info: MRS: Pushing multiroomSync output Mar 15 09:06:00 rivo sudo[3781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/xset dpms 0 0 0 Mar 15 09:06:00 rivo sudo[3781]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:00 rivo sudo[3781]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:00 rivo sudo[3784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Mar 15 09:06:00 rivo sudo[3784]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:00 rivo volumio[3116]: info: Discovery: this is already registered, 863507c8-b581-4711-9157-ac87f9decd21 Mar 15 09:06:00 rivo volumio[3116]: info: Discovery: Found device Volumio werkkamer Mar 15 09:06:00 rivo go-librespot[3755]: time="2025-03-15T09:06:00+01:00" level=debug msg="obtained new client token: AABMsopeBWcT21d1q9mDNvadhGgf4yRBnkS1ymVOSPF9Mk7rYMzIIRjbIn8m+rQh0aX7BZTgDvcj4pne3xbjcw9tu+n4LlwoLIE8IjFJW93X8VHCAGt9wi6WMSNW8TdjFw1G2e+7QXa5VkmsILrAq71bkbc11IX5/voEZNdx7W/KYlnOxLxWSde/YcIFpRDdEGWYWwLp0oKJOfOg7ke533OJPUVhAHK1au1/8AkrQQYG0om15EaWUKjAjY7mkX8=" Mar 15 09:06:00 rivo systemd[1]: Started Volumio Bluetooth Module. Mar 15 09:06:00 rivo sudo[3784]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:00 rivo go-librespot[3755]: time="2025-03-15T09:06:00+01:00" level=info msg="connected to ap-gew4.spotify.com:4070" Mar 15 09:06:00 rivo sudo[3788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Mar 15 09:06:00 rivo sudo[3788]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:00 rivo volumiobt[3786]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Mar 15 09:06:00 rivo sudo[3788]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:00 rivo go-librespot[3755]: time="2025-03-15T09:06:00+01:00" level=debug msg="completed keyexchange" Mar 15 09:06:00 rivo volumio[3116]: error: MPD error: The expression evaluated to a falsy value: Mar 15 09:06:00 rivo volumio[3116]: assert.ok(self.idling) Mar 15 09:06:00 rivo volumio[3116]: error: The expression evaluated to a falsy value: Mar 15 09:06:00 rivo volumio[3116]: assert.ok(self.idling) Mar 15 09:06:00 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:00 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:00 rivo volumio[3116]: info: MPD running with PID3666 Mar 15 09:06:00 rivo volumio[3116]: ,establishing connection Mar 15 09:06:00 rivo volumio[3116]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 15 09:06:00 rivo volumio[3116]: SPOTIFY: BQD3DTpYuQuW9Uz4YyoG_KR32QCnr41lP2q0rAu5YSPfjBv22OmGYkl7Eo2P9ll6eAX1RUt5LjPi9RMyAcvrer2DlmlTjQAchMVXIitgmdRfRTdE7MRP5ufWdlLkEeyU77UG7nxWUis-wygf2I8urG45DkqqK2kO6tS02UAS6UOnA_DeOzqX-6HsPXlKJNSrS2cfbuQo-o_SU1GfpbPSVczpxT9AOwdM_gpwjqGCy1_Qr4OLqKI29XhEAa1PD3a4lzlsm_ztzXmA8YJrc_pS78due27og8_tVEuiOnI Mar 15 09:06:00 rivo volumio[3116]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Mar 15 09:06:00 rivo volumio[3116]: info: New Spotify access token = BQD3DTpYuQuW9Uz4YyoG_KR32QCnr41lP2q0rAu5YSPfjBv22OmGYkl7Eo2P9ll6eAX1RUt5LjPi9RMyAcvrer2DlmlTjQAchMVXIitgmdRfRTdE7MRP5ufWdlLkEeyU77UG7nxWUis-wygf2I8urG45DkqqK2kO6tS02UAS6UOnA_DeOzqX-6HsPXlKJNSrS2cfbuQo-o_SU1GfpbPSVczpxT9AOwdM_gpwjqGCy1_Qr4OLqKI29XhEAa1PD3a4lzlsm_ztzXmA8YJrc_pS78due27og8_tVEuiOnI Mar 15 09:06:00 rivo volumio[3116]: info: Spotify credentials grant success - running version from March 24, 2019 Mar 15 09:06:00 rivo volumio[3116]: error: updateQueue error: null Mar 15 09:06:01 rivo volumio[3116]: info: Volumio BT Module successfully started Mar 15 09:06:01 rivo volumio[3116]: info: Starting Shairport Sync Mar 15 09:06:01 rivo volumio[3116]: info: Starting Shairport Sync Mar 15 09:06:01 rivo sudo[3793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 15 09:06:01 rivo sudo[3793]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:01 rivo volumio[3116]: info: Starting Shairport Sync Mar 15 09:06:01 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 15 09:06:01 rivo systemd[1]: shairport-sync.service: Succeeded. Mar 15 09:06:01 rivo sudo[3797]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 15 09:06:01 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 15 09:06:01 rivo sudo[3797]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:01 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 15 09:06:01 rivo sudo[3793]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:01 rivo volumio[3116]: info: Adding Inputs via Serial API Mar 15 09:06:01 rivo volumio[3116]: info: MRS: Found cast device: Chromecast-ff883a8389c5874b25705df07111a8aa Mar 15 09:06:01 rivo volumio[3116]: info: Adding audio output: Mar 15 09:06:01 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:01 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:01 rivo sudo[3801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 15 09:06:01 rivo sudo[3801]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:01 rivo volumio[3116]: info: CoreStateMachine::pushState Mar 15 09:06:01 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:01 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 09:06:01 rivo volumio[3116]: info: CoreCommandRouter::volumioPushState Mar 15 09:06:01 rivo volumio[3116]: info: MRS: Pushing multiroomSync output update for this device Mar 15 09:06:01 rivo volumio[3116]: info: MRS: Pushing multiroomSync output Mar 15 09:06:01 rivo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 15 09:06:01 rivo systemd[1]: shairport-sync.service: Succeeded. Mar 15 09:06:01 rivo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 15 09:06:01 rivo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 15 09:06:01 rivo sudo[3797]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:01 rivo sudo[3801]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:01 rivo volumio[3116]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 15 09:06:01 rivo volumio[3116]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 15 09:06:01 rivo volumio[3116]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 15 09:06:01 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:01 rivo go-librespot[3755]: time="2025-03-15T09:06:01+01:00" level=debug msg="completed challenge" Mar 15 09:06:01 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:01 rivo volumio[3116]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 15 09:06:01 rivo volumio[3116]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Mar 15 09:06:01 rivo volumio[3116]: error: updateQueue error: null Mar 15 09:06:01 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:01 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:01 rivo go-librespot[3755]: time="2025-03-15T09:06:01+01:00" level=debug msg="authenticated as z5pn5bhagnji3rsj9aw0vvffr" Mar 15 09:06:01 rivo volumio[3116]: info: Shairport-Sync Started Mar 15 09:06:01 rivo volumio[3116]: Error adding Membership: Error: addMembership EINVAL Mar 15 09:06:01 rivo volumio[3116]: info: Shairport-Sync Started Mar 15 09:06:01 rivo volumio[3116]: info: Shairport-Sync Started Mar 15 09:06:01 rivo go-librespot[3755]: time="2025-03-15T09:06:01+01:00" level=debug msg="authenticated as z5pn5bhagnji3rsj9aw0vvffr" Mar 15 09:06:01 rivo volumio[3116]: info: CoreCommandRouter::servicePushState Mar 15 09:06:01 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:01 rivo volumio[3116]: verbose: STATE SERVICE {"status":"play","service":"inputs","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"","seek":0,"duration":0,"samplerate":"","bitdepth":"","stream":true,"disableUiControls":true,"channels":2} Mar 15 09:06:01 rivo volumio[3116]: verbose: CURRENT POSITION 0 Mar 15 09:06:01 rivo volumio[3116]: info: CoreStateMachine::syncState stateService play Mar 15 09:06:01 rivo volumio[3116]: info: CoreStateMachine::syncState currentStatus stop Mar 15 09:06:01 rivo volumio[3116]: info: CoreCommandRouter::volumiosetSourceActiveno-source Mar 15 09:06:01 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Bandcamp Discover Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Calm Radio Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Mixcloud Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source YouTube2 Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source YouTube Music Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source 80s80s Radio Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Mother Earth Radio Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Podcast Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Radio Paradise Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Randomizer Mar 15 09:06:01 rivo sudo[3809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.154/USB /mnt/NAS/USB Mar 15 09:06:01 rivo sudo[3809]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:01 rivo volumio[3116]: info: Turning Off Onboard LEDs Mar 15 09:06:01 rivo go-librespot[3755]: time="2025-03-15T09:06:01+01:00" level=debug msg="dealer connection opened" Mar 15 09:06:01 rivo go-librespot[3755]: time="2025-03-15T09:06:01+01:00" level=debug msg="initializing zeroconf session, username: z5pn5bhagnji3rsj9aw0vvffr" Mar 15 09:06:01 rivo go-librespot[3755]: time="2025-03-15T09:06:01+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Mar 15 09:06:01 rivo go-librespot[3755]: time="2025-03-15T09:06:01+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Mar 15 09:06:01 rivo go-librespot[3755]: time="2025-03-15T09:06:01+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Mar 15 09:06:01 rivo go-librespot[3755]: time="2025-03-15T09:06:01+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Mar 15 09:06:01 rivo go-librespot[3755]: time="2025-03-15T09:06:01+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Mar 15 09:06:01 rivo go-librespot[3755]: time="2025-03-15T09:06:01+01:00" level=debug msg="autoplay enabled: false" Mar 15 09:06:01 rivo go-librespot[3755]: time="2025-03-15T09:06:01+01:00" level=debug msg="received connection id: ZDkxZGNjMGMtNGU2NC00NjBjLThjMGUtNzBmZmE5YzZlMGRhK2RlYWxlcit0Y3A6Ly8wYWNhNWE0ZC5pcC5nZXc0LnNwb3RpZnkubmV0OjU3MDArMjQxOEI2NUUwNTAzQUI2NEJFMDFGRkM0RDVDRThGQTgwQzhDNTUzQUVBRkZCN0EyNDk1QTI5QzE2Qzk1RUI3NQ==" Mar 15 09:06:01 rivo sudo[3815]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/sys_led/trigger Mar 15 09:06:01 rivo sudo[3815]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:01 rivo volumio[3116]: info: MCU Signalled Playback Inactive Mar 15 09:06:01 rivo sudo[3815]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:01 rivo volumio[3116]: SPOTIFY: User informations: {"country":"NL","display_name":"Sjoerd","email":"sjoerdrapp@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/z5pn5bhagnji3rsj9aw0vvffr"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/z5pn5bhagnji3rsj9aw0vvffr","id":"z5pn5bhagnji3rsj9aw0vvffr","images":[],"product":"premium","type":"user","uri":"spotify:user:z5pn5bhagnji3rsj9aw0vvffr"} Mar 15 09:06:01 rivo volumio[3116]: info: Spotify Successfully logged in Mar 15 09:06:01 rivo sudo[3818]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/tee /sys/class/leds/red_red/trigger Mar 15 09:06:01 rivo sudo[3818]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:01 rivo volumio[3116]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Mar 15 09:06:01 rivo volumio[3116]: info: [1742025961749] CoreMusicLibrary::Adding element Spotify Mar 15 09:06:01 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Bandcamp Discover Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Calm Radio Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Mixcloud Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source YouTube2 Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source YouTube Music Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source 80s80s Radio Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Mother Earth Radio Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Podcast Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Radio Paradise Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Randomizer Mar 15 09:06:01 rivo volumio[3116]: Cannot find translation for source Spotify Mar 15 09:06:01 rivo sudo[3818]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:01 rivo sudo[3820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Mar 15 09:06:01 rivo sudo[3820]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:01 rivo volumio[3116]: info: Onboard LEDs initialized Mar 15 09:06:01 rivo volumiobt[3786]: pulseaudio: no process found Mar 15 09:06:01 rivo go-librespot[3755]: time="2025-03-15T09:06:01+01:00" level=debug msg="put connect state because NEW_DEVICE" Mar 15 09:06:01 rivo sudo[3820]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:01 rivo dbus-daemon[2841]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=3838 comm="/usr/bin/pulseaudio --high-priority=true " label="kernel") Mar 15 09:06:01 rivo volumio[3628]: Generating RSA private key, 4096 bit long modulus (2 primes) Mar 15 09:06:01 rivo systemd[1]: Starting RealtimeKit Scheduling Policy Service... Mar 15 09:06:01 rivo kernel: IPVS: Creating netns size=1928 id=3 Mar 15 09:06:01 rivo volumio[3116]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 15 09:06:01 rivo dbus-daemon[2841]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Mar 15 09:06:01 rivo systemd[1]: Started RealtimeKit Scheduling Policy Service. Mar 15 09:06:01 rivo rtkit-daemon[3839]: Successfully called chroot. Mar 15 09:06:01 rivo rtkit-daemon[3839]: Successfully dropped privileges. Mar 15 09:06:01 rivo rtkit-daemon[3839]: Successfully limited resources. Mar 15 09:06:01 rivo rtkit-daemon[3839]: Running. Mar 15 09:06:01 rivo rtkit-daemon[3839]: Canary thread running. Mar 15 09:06:01 rivo rtkit-daemon[3839]: Watchdog thread running. Mar 15 09:06:01 rivo dbus-daemon[2841]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.22' (uid=0 pid=3839 comm="/usr/lib/rtkit/rtkit-daemon " label="kernel") Mar 15 09:06:01 rivo systemd[1]: Starting Authorization Manager... Mar 15 09:06:02 rivo volumio[3116]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 15 09:06:02 rivo polkitd[3842]: started daemon version 0.105 using authority implementation `local' version `0.105' Mar 15 09:06:02 rivo dbus-daemon[2841]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Mar 15 09:06:02 rivo systemd[1]: Started Authorization Manager. Mar 15 09:06:02 rivo pulseaudio[3838]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 15 09:06:02 rivo pulseaudio[3838]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Mar 15 09:06:02 rivo volumiobt[3786]: Applying permissions Mar 15 09:06:02 rivo volumiobt[3786]: Setting BT discoverable and pairable Mar 15 09:06:02 rivo bluetoothd[3213]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSource Mar 15 09:06:02 rivo bluetoothd[3213]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSink Mar 15 09:06:02 rivo pulseaudio[3838]: 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 Mar 15 09:06:02 rivo volumiobt[3786]: Agent registered Mar 15 09:06:02 rivo volumiobt[3786]: [bluetooth]# -e power on Mar 15 09:06:02 rivo volumiobt[3786]: Invalid command in menu main: -e Mar 15 09:06:02 rivo volumiobt[3786]: Mar 15 09:06:02 rivo volumiobt[3786]: Use "help" for a list of available commands in a menu. Mar 15 09:06:02 rivo volumiobt[3786]: Use "menu " if you want to enter any submenu. Mar 15 09:06:02 rivo volumiobt[3786]: Use "back" if you want to return to menu main. Mar 15 09:06:02 rivo volumiobt[3786]: [bluetooth]# agent on Mar 15 09:06:02 rivo volumiobt[3786]: Agent is already registered Mar 15 09:06:02 rivo volumiobt[3786]: [bluetooth]# discoverable on Mar 15 09:06:02 rivo volumiobt[3786]: [bluetooth]# pairable on Mar 15 09:06:02 rivo volumiobt[3786]: [bluetooth]# agent NoInputNoOutput Mar 15 09:06:02 rivo volumiobt[3786]: Agent is already registered Mar 15 09:06:02 rivo volumiobt[3786]: [bluetooth]# default-agent Mar 15 09:06:02 rivo volumiobt[3786]: [bluetooth]# quit Mar 15 09:06:02 rivo volumiobt[3786]: [59B blob data] Mar 15 09:06:02 rivo volumio[3116]: info: [yt-cast-receiver] DIAL server listening on port 8098 Mar 15 09:06:02 rivo volumio[3116]: info: Completed starting Core Plugins Mar 15 09:06:02 rivo volumio[3116]: info: ------------------------------------------- Mar 15 09:06:02 rivo volumio[3116]: info: ----- MyVolumio plugins startup ---- Mar 15 09:06:02 rivo volumio[3116]: info: ------------------------------------------- Mar 15 09:06:02 rivo volumio[3116]: info: [MyVolumio PluginManager] Fetching plans data.... Mar 15 09:06:03 rivo volumio[3116]: info: MRS: Getting audio outputs on start Mar 15 09:06:03 rivo volumio[3116]: info: MRS: Requesting all other devices output Mar 15 09:06:03 rivo volumio[3116]: info: go-librespot daemon successfully initialized Mar 15 09:06:04 rivo volumio[3628]: .................................++++ Mar 15 09:06:05 rivo volumio[3116]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Mar 15 09:06:06 rivo volumio[3116]: info: Initializing connection to go-librespot Websocket Mar 15 09:06:06 rivo go-librespot[3755]: time="2025-03-15T09:06:06+01:00" level=debug msg="new websocket client" Mar 15 09:06:06 rivo volumio[3116]: info: Connection to go-librespot Websocket established Mar 15 09:06:07 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 09:06:07 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 09:06:07 rivo volumio[3116]: info: Discovery: Getting this device information Mar 15 09:06:07 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:07 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:07 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 09:06:07 rivo volumio[3116]: verbose: New Socket.io Connection to 192.168.178.168:3000 from 192.168.178.146 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 10 Mar 15 09:06:07 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:07 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:07 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Mar 15 09:06:07 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Mar 15 09:06:08 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 09:06:08 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 09:06:08 rivo volumio[3116]: info: Discovery: Getting this device information Mar 15 09:06:08 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:08 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:08 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 09:06:08 rivo volumio[3116]: info: Checking for updated MCU Firmware Mar 15 09:06:08 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 09:06:08 rivo volumio[3116]: info: Firware on device is on latest version, no need to update Mar 15 09:06:09 rivo volumio[3116]: info: Getting Spotify volume Mar 15 09:06:09 rivo volumio[3116]: info: Spotify volume: 100 Mar 15 09:06:09 rivo volumio[3116]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 15 09:06:09 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:09 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:09 rivo volumio[3116]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 15 09:06:11 rivo volumio[3116]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 15 09:06:11 rivo sudo[3809]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:11 rivo kernel: CIFS VFS: Error connecting to socket. Aborting operation. Mar 15 09:06:11 rivo kernel: CIFS VFS: cifs_mount failed w/return code = -115 Mar 15 09:06:12 rivo volumio[3116]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 15 09:06:12 rivo volumio[3116]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 15 09:06:12 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:06:12 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:06:12 rivo volumio[3116]: info: Starting MyVolumio Remote Streaming Endpoints Mar 15 09:06:12 rivo volumio[3116]: info: MyVolumio login type: Token Mar 15 09:06:12 rivo volumio[3116]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 15 09:06:12 rivo volumio[3116]: error: [MyVolumio PluginManager] Could not read package.json file: Error: /myvolumio/plugins/music_service/streaming_services//package.json: ENOENT: no such file or directory, open '/myvolumio/plugins/music_service/streaming_services//package.json' Mar 15 09:06:12 rivo volumio[3116]: info: Cannot mount NAS USB at system boot, trial number 2 ,retrying in 5 seconds Mar 15 09:06:12 rivo volumio[3116]: error: MyVolumio Custom Token format not valid, refreshing it Mar 15 09:06:13 rivo volumio[3628]: ...................................................................................................................++++ Mar 15 09:06:13 rivo volumio[3628]: e is 65537 (0x010001) Mar 15 09:06:13 rivo volumio[3628]: writing RSA key Mar 15 09:06:13 rivo sudo[3918]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 15 09:06:13 rivo sudo[3918]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:13 rivo sudo[3918]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:13 rivo sudo[3922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 09:06:13 rivo sudo[3922]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:13 rivo sudo[3922]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:13 rivo volumio[3116]: verbose: New Socket.io Connection to 192.168.178.168 from 192.168.178.146 UA: Mozilla/5.0 (Linux; Android 15; 23013PC75G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.137 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 15 09:06:13 rivo sudo[3927]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 15 09:06:13 rivo sudo[3927]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:13 rivo sudo[3927]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 15 09:06:13 rivo sudo[3931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 15 09:06:13 rivo sudo[3931]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:13 rivo volumio[3116]: info: MyVolumio login type: Token Mar 15 09:06:13 rivo sudo[3931]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:13 rivo volumio[3116]: verbose: New Socket.io Connection to 192.168.178.168 from 192.168.178.146 UA: Mozilla/5.0 (Linux; Android 15; 23013PC75G Build/AQ3A.240912.001; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/133.0.6943.137 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 13 Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::volumioGetVisibleSources Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:13 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 15 09:06:13 rivo volumio[3116]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Mar 15 09:06:13 rivo volumio[3116]: info: Received Get System Info Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 09:06:13 rivo volumio[3116]: info: Discovery: Getting this device information Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:13 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:13 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:13 rivo volumio[3116]: info: Listing playlists Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 15 09:06:13 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 15 09:06:14 rivo volumio[3116]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 15 09:06:14 rivo volumio[3116]: info: MyVolumio token set successfully Mar 15 09:06:14 rivo volumio[3116]: info: MYVOLUMIO: Adding device Mar 15 09:06:14 rivo volumio[3116]: info: MYVOLUMIO: Evaluating Server Mar 15 09:06:15 rivo volumio[3116]: info: MyVolumio status changed Mar 15 09:06:15 rivo volumio[3116]: info: Streaming services startup Mar 15 09:06:15 rivo volumio[3116]: info: Starting Streaming Daemon Mar 15 09:06:15 rivo volumio[3116]: info: Removing browser output: myVolumio user plan is not superstar Mar 15 09:06:15 rivo volumio[3116]: info: Removing audio output: Mar 15 09:06:15 rivo volumio[3116]: info: Stoppping Tunnel 1 Mar 15 09:06:15 rivo sudo[3955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 15 09:06:15 rivo sudo[3955]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:15 rivo sudo[3955]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:15 rivo volumio[3116]: error: Cannot start Volumio Streaming Daemon Mar 15 09:06:15 rivo volumio[3116]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 15 09:06:15 rivo volumio[3116]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 15 09:06:15 rivo sudo[3958]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 15 09:06:15 rivo sudo[3958]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:15 rivo sudo[3958]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:15 rivo volumio[3116]: info: Remote SSH Stopped Mar 15 09:06:15 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 15 09:06:15 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 09:06:15 rivo volumio[3116]: info: Received Get System Info Mar 15 09:06:15 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 09:06:15 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 09:06:15 rivo volumio[3116]: info: Discovery: Getting this device information Mar 15 09:06:15 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:15 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:15 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 09:06:15 rivo volumio[3116]: info: Setting Geolocation for MyVolumio to eu2 Mar 15 09:06:15 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:06:15 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:06:15 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:06:15 rivo volumio[3116]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 15 09:06:16 rivo volumio[3116]: info: Updating MyVolumio device info Mar 15 09:06:16 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:06:16 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 15 09:06:16 rivo volumio[3116]: info: Received Get System Info Mar 15 09:06:16 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 09:06:16 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 09:06:16 rivo volumio[3116]: info: Discovery: Getting this device information Mar 15 09:06:16 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:16 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:16 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 09:06:16 rivo volumio[3116]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 09:06:16 rivo volumio[3116]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 09:06:16 rivo volumio[3116]: info: Retrieving Cloud Streaming UI Mar 15 09:06:16 rivo volumio[3116]: info: Getting Tidal Cloud Configuration Mar 15 09:06:16 rivo volumio[3116]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 09:06:16 rivo volumio[3116]: info: Getting Qobuz Cloud Configuration Mar 15 09:06:16 rivo volumio[3116]: info: Asking plugin for UI Config Mar 15 09:06:16 rivo volumio[3116]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 09:06:16 rivo volumio[3116]: info: Getting Spotify Cloud Configuration Mar 15 09:06:16 rivo volumio[3116]: info: Asking plugin for UI Config Mar 15 09:06:16 rivo volumio[3116]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 09:06:16 rivo volumio[3116]: info: Saving Spotify Acccount Mar 15 09:06:16 rivo volumio[3116]: info: Got it Mar 15 09:06:16 rivo volumio[3116]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 15 09:06:16 rivo volumio[3116]: info: Got Tidal Cloud Configuration Mar 15 09:06:16 rivo volumio[3116]: info: Got it Mar 15 09:06:16 rivo volumio[3116]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 15 09:06:16 rivo volumio[3116]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 15 09:06:17 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 15 09:06:17 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 15 09:06:17 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 15 09:06:17 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 15 09:06:17 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 09:06:17 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 09:06:17 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 09:06:17 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 15 09:06:17 rivo volumio[3116]: info: CoreCommandRouter::volumioGetBrowseSources Mar 15 09:06:17 rivo volumio[3116]: info: CoreCommandRouter::volumioGetBrowseSources Mar 15 09:06:17 rivo volumio[3116]: info: CoreCommandRouter::volumioGetBrowseSources Mar 15 09:06:17 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 09:06:17 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 15 09:06:17 rivo sudo[3964]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.2.154/USB /mnt/NAS/USB Mar 15 09:06:17 rivo sudo[3964]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 15 09:06:19 rivo volumio[3116]: info: MYVOLUMIO: Adding device Mar 15 09:06:19 rivo volumio[3116]: info: MYVOLUMIO: Evaluating Server Mar 15 09:06:20 rivo volumio[3116]: info: Setting Geolocation for MyVolumio to eu5 Mar 15 09:06:20 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:06:21 rivo volumio[3116]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 15 09:06:21 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 09:06:21 rivo volumio[3116]: info: Updating MyVolumio device info Mar 15 09:06:21 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:06:21 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:06:22 rivo volumio[3116]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 15 09:06:22 rivo volumio[3116]: error: MyVolumio Plugin failed to start in a timely fashion Mar 15 09:06:22 rivo volumio[3116]: [Metrics] CommandRouter: 67s 646.91ms Mar 15 09:06:22 rivo volumio[3116]: info: CoreCommandRouter::volumiosetStartupVolume Mar 15 09:06:22 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:22 rivo volumio[3116]: info: VolumeController:: Setting startup Volume 100 Mar 15 09:06:22 rivo volumio[3116]: info: VolumeController::SetAlsaVolume100 Mar 15 09:06:22 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:06:22 rivo volumio[3116]: info: CoreCommandRouter::Close All Modals sent Mar 15 09:06:22 rivo volumio[3116]: info: CoreCommandRouter::Close All Modals sent Mar 15 09:06:22 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:22 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:22 rivo volumio[3116]: info: CoreStateMachine::pushState Mar 15 09:06:22 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:22 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 15 09:06:22 rivo volumio[3116]: info: CoreCommandRouter::volumioPushState Mar 15 09:06:22 rivo volumio[3116]: info: MRS: Pushing multiroomSync output update for this device Mar 15 09:06:22 rivo volumio[3116]: info: MRS: Pushing multiroomSync output Mar 15 09:06:22 rivo volumio[3116]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Mar 15 09:06:23 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 15 09:06:23 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 15 09:06:23 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 15 09:06:23 rivo volumio[3116]: xcb_connection_has_error() returned true Mar 15 09:06:23 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 15 09:06:25 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 09:06:27 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 15 09:06:27 rivo volumio[3116]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 15 09:06:27 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:27 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:28 rivo sudo[3964]: pam_unix(sudo:session): session closed for user root Mar 15 09:06:28 rivo kernel: CIFS VFS: Error connecting to socket. Aborting operation. Mar 15 09:06:28 rivo kernel: CIFS VFS: cifs_mount failed w/return code = -115 Mar 15 09:06:28 rivo volumio[3116]: info: Cannot mount NAS USB at system boot, trial number 3 ,retrying in 5 seconds Mar 15 09:06:28 rivo ntpd[3322]: Soliciting pool server 178.239.19.60 Mar 15 09:06:28 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 09:06:28 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 15 09:06:29 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 15 09:06:29 rivo volumio-remote-updater[2825]: No test mode Mar 15 09:06:29 rivo volumio-remote-updater[2825]: No alpha test mode Mar 15 09:06:29 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 15 09:06:29 rivo volumio[3116]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 15 09:06:29 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 15 09:06:29 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 15 09:06:29 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: networkfs , deleteShare Mar 15 09:06:29 rivo volumio[3116]: info: BOOT COMPLETED Mar 15 09:06:29 rivo ntpd[3322]: Soliciting pool server 158.101.216.150 Mar 15 09:06:30 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 15 09:06:32 rivo volumio[3116]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 15 09:06:32 rivo volumio[3116]: info: Received Get System Version Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 09:06:32 rivo volumio[3116]: info: Received Get System Info Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 15 09:06:32 rivo volumio[3116]: info: Discovery: Getting this device information Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::volumioGetState Mar 15 09:06:32 rivo volumio[3116]: info: CorePlayQueue::getTrack 0 Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::volumioGetBrowseSources Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Mar 15 09:06:32 rivo volumio[3116]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Mar 15 09:06:32 rivo ntpd[3322]: Soliciting pool server 45.138.55.60 Mar 15 09:06:33 rivo volumio[3116]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 15 09:06:33 rivo volumio[3116]: TypeError: Cannot read property 'replace' of undefined Mar 15 09:06:33 rivo volumio[3116]: at ControllerNetworkfs.mountShare (/volumio/app/plugins/system_controller/networkfs/index.js:202:28) Mar 15 09:06:33 rivo volumio[3116]: at Timeout._onTimeout (/volumio/app/plugins/system_controller/networkfs/index.js:255:20) Mar 15 09:06:33 rivo volumio[3116]: at listOnTimeout (internal/timers.js:557:17) Mar 15 09:06:33 rivo volumio[3116]: at processTimers (internal/timers.js:500:7) Mar 15 09:06:33 rivo volumio[3116]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 15 09:06:33 rivo sudo[4045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-15 09:05 Mar 15 09:06:33 rivo sudo[4045]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="rivo" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:49:04 PM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Rivo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Rivo" VOLUMIO_HASH="65eb03fcbe62db7e7fbed9cd39ac44df"