-- Logs begin at Wed 2024-07-03 11:52:42 +04, end at Fri 2025-07-18 09:03:01 +04. -- Jul 18 09:02:51 volumio-v2 ntpd[1743]: receive: Unexpected origin timestamp 0xea2f8230.606f36c3 does not match aorg 0000000000.00000000 from server@160.250.111.68 xmt 0xec2453fb.bf48c220 Jul 18 09:02:51 volumio-v2 ntpd[1743]: receive: Unexpected origin timestamp 0xea2f8230.60737dcf does not match aorg 0000000000.00000000 from server@192.46.215.60 xmt 0xec2453fb.c1be94d8 Jul 18 09:02:51 volumio-v2 ntpd[1743]: receive: Unexpected origin timestamp 0xea2f8230.606dc256 does not match aorg 0000000000.00000000 from server@14.139.60.106 xmt 0xec2453fb.c1f9a282 Jul 18 09:02:51 volumio-v2 systemd[1]: Starting Daily man-db regeneration... Jul 18 09:02:51 volumio-v2 systemd[1]: Starting Daily apt download activities... Jul 18 09:02:51 volumio-v2 ntpd[1743]: receive: Unexpected origin timestamp 0xea2f8230.606879ed does not match aorg 0000000000.00000000 from server@95.216.144.226 xmt 0xec2453fb.d382ad92 Jul 18 09:02:52 volumio-v2 systemd[1]: man-db.service: Succeeded. Jul 18 09:02:52 volumio-v2 systemd[1]: Started Daily man-db regeneration. Jul 18 09:02:52 volumio-v2 volumio[1952]: info: Loading plugin "ytmusic"... Jul 18 09:02:53 volumio-v2 sudo[1996]: pam_unix(sudo:session): session closed for user root Jul 18 09:02:53 volumio-v2 volumio[1952]: info: Loading plugin "autostart"... Jul 18 09:02:53 volumio-v2 volumio[1952]: info: Applying required configuration parameters for plugin autostart Jul 18 09:02:53 volumio-v2 volumio[1952]: info: AutoStart - onVolumioStart - read config.json Jul 18 09:02:53 volumio-v2 volumio[1952]: info: Loading plugin "now_playing"... Jul 18 09:02:53 volumio-v2 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 18 09:02:53 volumio-v2 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 17. Jul 18 09:02:53 volumio-v2 systemd[1]: Stopped FusionDsp Daemon. Jul 18 09:02:53 volumio-v2 systemd[1]: Started FusionDsp Daemon. Jul 18 09:02:53 volumio-v2 volumio[2034]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 18 09:02:53 volumio-v2 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 18 09:02:53 volumio-v2 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 18 09:02:54 volumio-v2 volumio[1952]: info: Loading plugin "outputs"... Jul 18 09:02:54 volumio-v2 volumio[1952]: info: Loading plugin "albumart"... Jul 18 09:02:54 volumio-v2 volumio[1952]: info: Plugin example_plugin is not enabled Jul 18 09:02:54 volumio-v2 volumio[1952]: info: Loading plugin "inputs"... Jul 18 09:02:54 volumio-v2 volumio[1952]: info: Loading plugin "updater_comm"... Jul 18 09:02:54 volumio-v2 volumio[1952]: info: Plugin mpdemulation is not enabled Jul 18 09:02:54 volumio-v2 volumio[1952]: info: Loading plugin "rest_api"... Jul 18 09:02:54 volumio-v2 volumio[1952]: info: Loading plugin "websocket"... Jul 18 09:02:54 volumio-v2 volumio[1952]: info: Starting Socket.io Server version 2.3.0 Jul 18 09:02:54 volumio-v2 volumio[1952]: info: Loading plugin "fusiondsp"... Jul 18 09:02:54 volumio-v2 volumio[1952]: Forking 3 albumart workers Jul 18 09:02:54 volumio-v2 volumio[1952]: info: Applying required configuration parameters for plugin fusiondsp Jul 18 09:02:54 volumio-v2 volumio[1952]: info: Loading plugin "mpdoutput"... Jul 18 09:02:54 volumio-v2 volumio[1952]: info: Loading plugin "80s80s"... Jul 18 09:02:54 volumio-v2 systemd[1]: apt-daily.service: Succeeded. Jul 18 09:02:54 volumio-v2 systemd[1]: Started Daily apt download activities. Jul 18 09:02:54 volumio-v2 systemd[1]: Starting Daily apt upgrade and clean activities... Jul 18 09:02:55 volumio-v2 volumio[1952]: info: Applying required configuration parameters for plugin 80s80s Jul 18 09:02:55 volumio-v2 volumio[1952]: info: [1752814975106] [80s80s] API delay: 30 Jul 18 09:02:55 volumio-v2 volumio[1952]: info: Loading plugin "RoonBridge"... Jul 18 09:02:55 volumio-v2 volumio[1952]: Starting albumart workers Jul 18 09:02:55 volumio-v2 volumio[1952]: info: Applying required configuration parameters for plugin RoonBridge Jul 18 09:02:55 volumio-v2 volumio[1952]: info: Loading plugin "dmd2_music"... Jul 18 09:02:55 volumio-v2 volumio[1952]: Starting albumart workers Jul 18 09:02:55 volumio-v2 volumio[1952]: Starting albumart workers Jul 18 09:02:55 volumio-v2 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 18 09:02:55 volumio-v2 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 18. Jul 18 09:02:55 volumio-v2 systemd[1]: Stopped FusionDsp Daemon. Jul 18 09:02:55 volumio-v2 systemd[1]: Started FusionDsp Daemon. Jul 18 09:02:55 volumio-v2 volumio[2120]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 18 09:02:55 volumio-v2 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 18 09:02:55 volumio-v2 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 18 09:02:56 volumio-v2 volumio[1952]: info: Loading plugin "backup_restore"... Jul 18 09:02:56 volumio-v2 volumio[1952]: info: Applying required configuration parameters for plugin backup_restore Jul 18 09:02:56 volumio-v2 volumio[1952]: info: Loading plugin "audiophonics_evo_sabre"... Jul 18 09:02:56 volumio-v2 systemd[1]: apt-daily-upgrade.service: Succeeded. Jul 18 09:02:56 volumio-v2 systemd[1]: Started Daily apt upgrade and clean activities. Jul 18 09:02:56 volumio-v2 volumio[1952]: info: Applying required configuration parameters for plugin audiophonics_evo_sabre Jul 18 09:02:56 volumio-v2 volumio[1952]: info: Loading plugin "ir_controller"... Jul 18 09:02:56 volumio-v2 volumio[1952]: info: Applying required configuration parameters for plugin ir_controller Jul 18 09:02:56 volumio-v2 volumio[1952]: info: Loading plugin "Systeminfo"... Jul 18 09:02:56 volumio-v2 volumio[1952]: info: Loading plugin "lastfm"... Jul 18 09:02:56 volumio-v2 volumio-remote-updater[645]: [2025-07-18 09:02:56] [connect] Successful connection Jul 18 09:02:56 volumio-v2 volumio[1952]: info: Loading plugin "peppyspectrum"... Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Loading plugin "randomizer"... Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Applying required configuration parameters for plugin randomizer Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Loading plugin "touch_display"... Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Applying required configuration parameters for plugin touch_display Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Loading i18n strings for locale en Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:57 volumio-v2 volumio[1952]: Updating browse sources language Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::initPlayerControls Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 09:02:57 volumio-v2 volumio[1952]: Express server listening on port 3000 Jul 18 09:02:57 volumio-v2 volumio[1952]: [Metrics] WebUI: 9s 633.84ms Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreStateMachine::resetVolumioState Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreStateMachine::getcurrentVolume Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 09:02:57 volumio-v2 volumio-remote-updater[645]: [2025-07-18 09:02:57] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1752814976 101 Jul 18 09:02:57 volumio-v2 volumio[1952]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Jul 18 09:02:57 volumio-v2 volumio[1952]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jul 18 09:02:57 volumio-v2 volumio[1952]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:02:57 volumio-v2 volumio[1952]: info: VolumeController:: Volume=60 Mute =false Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreStateMachine::pushState Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CorePlayQueue::getTrack 0 Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioPushState Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreStateMachine::updateTrackBlock Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CorePlayQueue::getTrackBlock Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioRetrievevolume Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreStateMachine::setRepeat true single undefined Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreStateMachine::pushState Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CorePlayQueue::getTrack 0 Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioPushState Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreStateMachine::setRandom false Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreStateMachine::pushState Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CorePlayQueue::getTrack 0 Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 18 09:02:57 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioPushState Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Setting Device type: Raspberry PI Jul 18 09:02:57 volumio-v2 volumio[1952]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 18 09:02:57 volumio-v2 volumio[1952]: info: USB Boot Capable - System SBC Revision found in cpuinfo: b03115 Jul 18 09:02:57 volumio-v2 volumio[1952]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Completed loading Core Plugins Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Preparing to generate the ALSA configuration file Jul 18 09:02:57 volumio-v2 volumio[1952]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 18 09:02:57 volumio-v2 volumio[1952]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 18 09:02:57 volumio-v2 volumio[1952]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Reading ALSA contributions from plugins. Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Discovery: A device disappeared from network Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Discovery: Browse raised the following error TypeError: Cannot read property 'toLowerCase' of undefined Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Discovery: adding 927d495d-aa12-4725-b634-7ad6f311652c Jul 18 09:02:57 volumio-v2 volumio[1952]: info: Discovery: Found device Volumio V2 Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioGetState Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CorePlayQueue::getTrack 0 Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Discovery: A device disappeared from network Jul 18 09:02:58 volumio-v2 volumio[1952]: info: VolumeController:: Volume=60 Mute =false Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreStateMachine::pushState Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CorePlayQueue::getTrack 0 Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioPushState Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Discovery: adding 927d495d-aa12-4725-b634-7ad6f311652c Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Discovery: Found device Volumio V2 Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioGetState Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CorePlayQueue::getTrack 0 Jul 18 09:02:58 volumio-v2 systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jul 18 09:02:58 volumio-v2 systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 19. Jul 18 09:02:58 volumio-v2 systemd[1]: Stopped FusionDsp Daemon. Jul 18 09:02:58 volumio-v2 systemd[1]: Started FusionDsp Daemon. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Asound.conf file unchanged, so no further update is needed Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Output device has changed, restarting MPD Jul 18 09:02:58 volumio-v2 volumio[2188]: /usr/bin/python3: can't open file '/data/plugins/audio_interface/fusiondsp/cgui/main.py': [Errno 2] No such file or directory Jul 18 09:02:58 volumio-v2 systemd[1]: fusiondsp.service: Main process exited, code=exited, status=2/INVALIDARGUMENT Jul 18 09:02:58 volumio-v2 systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jul 18 09:02:58 volumio-v2 sudo[2191]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 09:02:58 volumio-v2 sudo[2191]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:02:58 volumio-v2 sudo[2191]: pam_unix(sudo:session): session closed for user root Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Output device has changed, restarting Shairport Sync Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:02:58 volumio-v2 sudo[2194]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 09:02:58 volumio-v2 sudo[2194]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:02:58 volumio-v2 systemd[1]: Stopping Music Player Daemon... Jul 18 09:02:58 volumio-v2 systemd[1]: mpd.service: Succeeded. Jul 18 09:02:58 volumio-v2 systemd[1]: Stopped Music Player Daemon. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 09:02:58 volumio-v2 volumio[1952]: info: ___________ START PLUGINS ___________ Jul 18 09:02:58 volumio-v2 volumio[1952]: info: ControllerMpd::onStart: Initializing MPD Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Creating MPD Configuration file Jul 18 09:02:58 volumio-v2 sudo[2201]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 09:02:58 volumio-v2 sudo[2201]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [1752814978309] CoreMusicLibrary::Adding element Media Servers Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:02:58 volumio-v2 systemd[1]: Starting Music Player Daemon... Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:02:58 volumio-v2 sudo[2201]: pam_unix(sudo:session): session closed for user root Jul 18 09:02:58 volumio-v2 sudo[2204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 09:02:58 volumio-v2 sudo[2204]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:02:58 volumio-v2 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 18 09:02:58 volumio-v2 systemd[1]: mpd.service: Succeeded. Jul 18 09:02:58 volumio-v2 systemd[1]: Stopped Music Player Daemon. Jul 18 09:02:58 volumio-v2 systemd[1]: Starting Music Player Daemon... Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [1752814978435] CoreMusicLibrary::Adding element Last_100 Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [1752814978439] CoreMusicLibrary::Adding element Webradio Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Initializing BBC Radios Jul 18 09:02:58 volumio-v2 sudo[2209]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 18 09:02:58 volumio-v2 sudo[2209]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:02:58 volumio-v2 sudo[2209]: pam_unix(sudo:session): session closed for user root Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [1752814978545] CoreMusicLibrary::Adding element Bandcamp Discover Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source Bandcamp Discover Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Adding Calm Radio to Browse Sources Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [1752814978552] CoreMusicLibrary::Adding element Calm Radio Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source Bandcamp Discover Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source Calm Radio Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [1752814978564] CoreMusicLibrary::Adding element SoundCloud Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source Bandcamp Discover Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source Calm Radio Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source SoundCloud Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [1752814978625] CoreMusicLibrary::Adding element YouTube Music Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source Bandcamp Discover Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source Calm Radio Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source SoundCloud Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source YouTube Music Jul 18 09:02:58 volumio-v2 volumio[1952]: info: AutoStart - onStart Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [now-playing] ConfigUpdater: config is up to date. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Loading i18n strings for locale en Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:58 volumio-v2 volumio[1952]: info: FusionDsp - mixtype--------------------- Hardware Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [1752814978683] CoreMusicLibrary::Adding element 80s80s Radio Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source Bandcamp Discover Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source Calm Radio Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source SoundCloud Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source YouTube Music Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source 80s80s Radio Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Stopping AccessToken refresher cron for DMD2 Music Jul 18 09:02:58 volumio-v2 sudo[2226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start roonbridge.service Jul 18 09:02:58 volumio-v2 sudo[2226]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:02:58 volumio-v2 volumio[1952]: info: AccessToken refresher cron started for DMD2 Music Jul 18 09:02:58 volumio-v2 volumio[1952]: info: EVO SABRE : Starting Plugin Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Loading i18n strings for locale en Jul 18 09:02:58 volumio-v2 sudo[2226]: pam_unix(sudo:session): session closed for user root Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Loading i18n strings for locale en Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [1752814978853] CoreMusicLibrary::Adding element LastFM Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source Bandcamp Discover Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source Calm Radio Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source SoundCloud Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source YouTube Music Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source 80s80s Radio Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source LastFM Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [LastFM] scrobbler initiated! Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [LastFM] extended logging: false Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [LastFM] try scrobble stream/radio plays: true Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [LastFM] Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [LastFM] Left init routine Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [LastFM] Socket already connected: true Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 18 09:02:58 volumio-v2 volumio[1952]: info: [1752814978917] CoreMusicLibrary::Adding element Randomizer Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source Bandcamp Discover Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source Calm Radio Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source SoundCloud Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source YouTube Music Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source 80s80s Radio Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source LastFM Jul 18 09:02:58 volumio-v2 volumio[1952]: Cannot find translation for source Randomizer Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Loading i18n strings for locale en Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin mpdoutput has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Plugin audiophonics_evo_sabre has duplicated i18n key SAVE. It is ignored. Jul 18 09:02:58 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 18 09:02:58 volumio-v2 volumio[1952]: info: Volumio Calling Home Jul 18 09:02:59 volumio-v2 volumio[1952]: info: Preparing to generate the ALSA configuration file Jul 18 09:02:59 volumio-v2 volumio[1952]: info: [LastFM] finished init with error: Configuration parameters missing: "API_KEY" "API_SECRET" "username" "authToken" Jul 18 09:02:59 volumio-v2 sudo[2236]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R a+rwX /etc/lirc/irexec.lircrc /etc/lirc/lirc_options.conf /etc/lirc/lircd.conf /etc/lirc/lircd.conf.d /etc/lirc/lircmd.conf /etc/lirc/lircrc Jul 18 09:02:59 volumio-v2 sudo[2236]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:02:59 volumio-v2 sudo[2236]: pam_unix(sudo:session): session closed for user root Jul 18 09:02:59 volumio-v2 sudo[2239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jul 18 09:02:59 volumio-v2 sudo[2239]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:02:59 volumio-v2 sudo[2242]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jul 18 09:02:59 volumio-v2 sudo[2242]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:02:59 volumio-v2 sudo[2245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 18 09:02:59 volumio-v2 sudo[2245]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:02:59 volumio-v2 sudo[2239]: pam_unix(sudo:session): session closed for user root Jul 18 09:02:59 volumio-v2 volumio[1952]: verbose: [yt-cast-receiver] Setting app pid to stored value: 3dc7f0d9-bdc4-41fa-beee-99d60384148d Jul 18 09:02:59 volumio-v2 volumio[1952]: verbose: [yt-cast-receiver] Starting YouTubeApp... Jul 18 09:02:59 volumio-v2 systemd[1]: Reloading. Jul 18 09:02:59 volumio-v2 volumio[1952]: verbose: [yt-cast-receiver] (YouTube) Configuring session with stored MDX context: Jul 18 09:02:59 volumio-v2 volumio[1952]: { Jul 18 09:02:59 volumio-v2 volumio[1952]: deviceId: '5a732c2c-ced3-4452-b8a5-35b91c7fb818', Jul 18 09:02:59 volumio-v2 volumio[1952]: screenId: '8mr1b9setuc5cc2okpurjo12c4' Jul 18 09:02:59 volumio-v2 volumio[1952]: } Jul 18 09:02:59 volumio-v2 volumio[1952]: verbose: [yt-cast-receiver] (YouTube Music) Configuring session with stored MDX context: Jul 18 09:02:59 volumio-v2 volumio[1952]: { Jul 18 09:02:59 volumio-v2 volumio[1952]: deviceId: 'd17fa5f8-a538-41c1-992d-75416e2bbe94', Jul 18 09:02:59 volumio-v2 volumio[1952]: screenId: '7d1l87l3ce41kthk5brlggkje4' Jul 18 09:02:59 volumio-v2 volumio[1952]: } Jul 18 09:02:59 volumio-v2 volumio[1952]: info: [now-playing] App is listening on port 4004. Jul 18 09:02:59 volumio-v2 volumio[1952]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jul 18 09:02:59 volumio-v2 volumio[1952]: info: AutoStart - Plugin is starting Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioGetQueue Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreStateMachine::getQueue Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CorePlayQueue::getQueue Jul 18 09:02:59 volumio-v2 volumio[1952]: info: Discovery: this is already registered, 927d495d-aa12-4725-b634-7ad6f311652c Jul 18 09:02:59 volumio-v2 volumio[1952]: info: Discovery: Found device Volumio V2 Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioGetState Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CorePlayQueue::getTrack 0 Jul 18 09:02:59 volumio-v2 volumio[1952]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 18 09:02:59 volumio-v2 volumio[1952]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 18 09:02:59 volumio-v2 volumio[1952]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jul 18 09:02:59 volumio-v2 volumio[1952]: info: Reading ALSA contributions from plugins. Jul 18 09:02:59 volumio-v2 volumio[1952]: info: touch_display: No backlight interface detected. Jul 18 09:02:59 volumio-v2 volumio[1952]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jul 18 09:02:59 volumio-v2 volumio[1952]: info: ir_controller: File permissions successfully set on /etc/lirc/*. Jul 18 09:02:59 volumio-v2 volumio[1952]: info: MPD Permissions set Jul 18 09:02:59 volumio-v2 volumio[1952]: info: MPD Permissions set Jul 18 09:02:59 volumio-v2 mpd[2215]: Jul 18 09:02 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 18 09:02:59 volumio-v2 sudo[2261]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jul 18 09:02:59 volumio-v2 sudo[2261]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:02:59 volumio-v2 sudo[2265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 18 09:02:59 volumio-v2 sudo[2265]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:02:59 volumio-v2 sudo[2265]: pam_unix(sudo:session): session closed for user root Jul 18 09:02:59 volumio-v2 sudo[2269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 18 09:02:59 volumio-v2 sudo[2269]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:02:59 volumio-v2 sudo[2269]: pam_unix(sudo:session): session closed for user root Jul 18 09:02:59 volumio-v2 volumio[1952]: info: Discovery: this is already registered, 927d495d-aa12-4725-b634-7ad6f311652c Jul 18 09:02:59 volumio-v2 volumio[1952]: info: Discovery: Found device Volumio V2 Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioGetState Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CorePlayQueue::getTrack 0 Jul 18 09:02:59 volumio-v2 volumio[1952]: info: Volumio called home Jul 18 09:02:59 volumio-v2 volumio[1952]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 18 09:02:59 volumio-v2 volumio[1952]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 18 09:02:59 volumio-v2 volumio[1952]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:02:59 volumio-v2 volumio[1952]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Jul 18 09:02:59 volumio-v2 sudo[2281]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Jul 18 09:02:59 volumio-v2 sudo[2281]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:02:59 volumio-v2 sudo[2281]: pam_unix(sudo:session): session closed for user root Jul 18 09:03:00 volumio-v2 volumio[1952]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Jul 18 09:03:00 volumio-v2 volumio[1952]: info: Starting Shairport Sync Jul 18 09:03:00 volumio-v2 volumio[1952]: info: Starting Shairport Sync Jul 18 09:03:00 volumio-v2 systemd[1]: Started Music Player Daemon. Jul 18 09:03:00 volumio-v2 sudo[2204]: pam_unix(sudo:session): session closed for user root Jul 18 09:03:00 volumio-v2 sudo[2242]: pam_unix(sudo:session): session closed for user root Jul 18 09:03:00 volumio-v2 sudo[2194]: pam_unix(sudo:session): session closed for user root Jul 18 09:03:00 volumio-v2 sudo[2284]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 18 09:03:00 volumio-v2 sudo[2284]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:03:00 volumio-v2 volumio[1952]: info: Starting Shairport Sync Jul 18 09:03:00 volumio-v2 systemd[1]: Reloading. Jul 18 09:03:00 volumio-v2 sudo[2287]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 18 09:03:00 volumio-v2 sudo[2287]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:03:00 volumio-v2 sudo[2289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 18 09:03:00 volumio-v2 volumio[1952]: info: CoreCommandRouter::volumioGetState Jul 18 09:03:00 volumio-v2 sudo[2289]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:03:00 volumio-v2 volumio[1952]: info: CorePlayQueue::getTrack 0 Jul 18 09:03:00 volumio-v2 sudo[2306]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jul 18 09:03:00 volumio-v2 sudo[2306]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:03:00 volumio-v2 volumio[1952]: info: ir_controller: LIRC correctly updated. Jul 18 09:03:00 volumio-v2 volumio[1952]: info: ir_controller: HAT already loaded /proc/device-tree/ir_receiver! Jul 18 09:03:00 volumio-v2 volumio[1952]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jul 18 09:03:00 volumio-v2 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 18 09:03:00 volumio-v2 volumio[1952]: info: Asound.conf file unchanged, so no further update is needed Jul 18 09:03:00 volumio-v2 volumio[1952]: info: Output device has changed, restarting MPD Jul 18 09:03:00 volumio-v2 volumio[1952]: info: Output device has changed, restarting Shairport Sync Jul 18 09:03:00 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:03:00 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 18 09:03:00 volumio-v2 sudo[2311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 18 09:03:00 volumio-v2 sudo[2311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:03:00 volumio-v2 sudo[2311]: pam_unix(sudo:session): session closed for user root Jul 18 09:03:00 volumio-v2 sudo[2314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 18 09:03:00 volumio-v2 sudo[2314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:03:00 volumio-v2 volumio[1952]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 18 09:03:00 volumio-v2 volumio[1952]: info: Preparing to generate the ALSA configuration file Jul 18 09:03:00 volumio-v2 volumio[1952]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jul 18 09:03:00 volumio-v2 volumio[1952]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 18 09:03:00 volumio-v2 volumio[1952]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Jul 18 09:03:00 volumio-v2 volumio[1952]: info: Reading ALSA contributions from plugins. Jul 18 09:03:00 volumio-v2 volumio[1952]: error: MPD error: The expression evaluated to a falsy value: Jul 18 09:03:00 volumio-v2 volumio[1952]: assert.ok(self.idling) Jul 18 09:03:00 volumio-v2 volumio[1952]: error: The expression evaluated to a falsy value: Jul 18 09:03:00 volumio-v2 volumio[1952]: assert.ok(self.idling) Jul 18 09:03:00 volumio-v2 volumio[1952]: info: MPD Permissions set Jul 18 09:03:00 volumio-v2 volumio[1952]: info: MPD running with PID2215 Jul 18 09:03:00 volumio-v2 volumio[1952]: ,establishing connection Jul 18 09:03:00 volumio-v2 volumio[1952]: verbose: [yt-cast-receiver] (YouTube Music) Obtained lounge token: Jul 18 09:03:00 volumio-v2 volumio[1952]: { Jul 18 09:03:00 volumio-v2 volumio[1952]: screenId: '7d1l87l3ce41kthk5brlggkje4', Jul 18 09:03:00 volumio-v2 volumio[1952]: refreshIntervalInMillis: 1123200000, Jul 18 09:03:00 volumio-v2 volumio[1952]: remoteRefreshIntervalMs: 79200000, Jul 18 09:03:00 volumio-v2 volumio[1952]: refreshIntervalMs: 1123200000, Jul 18 09:03:00 volumio-v2 volumio[1952]: loungeTokenLifespanMs: 1209600000, Jul 18 09:03:00 volumio-v2 volumio[1952]: loungeToken: 'AGdO5p-_xU3Sp-STI2VL4uSHC8skOTx59cXzH4BK41WMZf8BkMlLKtWYfjIx6_zh1n1n8Gbx-yqYG7HFCi_uKGmfNGeo8qswEjRveIBWFiKvIlxwvB4NJ_s', Jul 18 09:03:00 volumio-v2 volumio[1952]: remoteRefreshIntervalInMillis: 79200000, Jul 18 09:03:00 volumio-v2 volumio[1952]: expiration: 1753938179978 Jul 18 09:03:00 volumio-v2 volumio[1952]: } Jul 18 09:03:00 volumio-v2 volumio[1952]: error: updateQueue error: null Jul 18 09:03:00 volumio-v2 volumio[1952]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 18 09:03:00 volumio-v2 volumio[1952]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jul 18 09:03:00 volumio-v2 volumio[1952]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 18 09:03:00 volumio-v2 systemd[1]: Reloading. Jul 18 09:03:00 volumio-v2 sudo[2245]: pam_unix(sudo:session): session closed for user root Jul 18 09:03:00 volumio-v2 volumio[1952]: info: camilladsp service started and running in background, instance 1 Jul 18 09:03:00 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 18 09:03:00 volumio-v2 volumio[1952]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 18 09:03:00 volumio-v2 volumio[1952]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 18 09:03:00 volumio-v2 volumio[1952]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 18 09:03:00 volumio-v2 volumio[1952]: info: FusionDsp loaded Jul 18 09:03:00 volumio-v2 volumio[1952]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 18 09:03:00 volumio-v2 volumio[1952]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 18 09:03:00 volumio-v2 volumio[1952]: info: Adding Signal Path Element [object Object] Jul 18 09:03:00 volumio-v2 volumio[1952]: info: Adding fusiondspeq DSP Signal Path Element Jul 18 09:03:00 volumio-v2 volumio[1952]: info: FusionDsp - ---- installed callbackRead Jul 18 09:03:00 volumio-v2 volumio[1952]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 09:03:00 volumio-v2 sudo[2329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 18 09:03:00 volumio-v2 sudo[2329]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 18 09:03:00 volumio-v2 volumio[1952]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 18 09:03:00 volumio-v2 volumio[1952]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 18 09:03:00 volumio-v2 volumio[1952]: at onErrorNT (internal/child_process.js:465:16) Jul 18 09:03:00 volumio-v2 volumio[1952]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 18 09:03:00 volumio-v2 volumio[1952]: at runNextTicks (internal/process/task_queues.js:62:3) Jul 18 09:03:00 volumio-v2 volumio[1952]: at listOnTimeout (internal/timers.js:523:9) Jul 18 09:03:00 volumio-v2 volumio[1952]: at processTimers (internal/timers.js:497:7) { Jul 18 09:03:00 volumio-v2 volumio[1952]: errno: -2, Jul 18 09:03:00 volumio-v2 volumio[1952]: code: 'ENOENT', Jul 18 09:03:00 volumio-v2 volumio[1952]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 18 09:03:00 volumio-v2 volumio[1952]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 18 09:03:00 volumio-v2 volumio[1952]: spawnargs: [ Jul 18 09:03:00 volumio-v2 volumio[1952]: '-p', Jul 18 09:03:00 volumio-v2 volumio[1952]: 9876, Jul 18 09:03:00 volumio-v2 volumio[1952]: '-o', Jul 18 09:03:00 volumio-v2 volumio[1952]: '/tmp/camilladsp.log', Jul 18 09:03:00 volumio-v2 volumio[1952]: '-l', Jul 18 09:03:00 volumio-v2 volumio[1952]: 'warn', Jul 18 09:03:00 volumio-v2 volumio[1952]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 18 09:03:00 volumio-v2 volumio[1952]: ] Jul 18 09:03:00 volumio-v2 volumio[1952]: } Jul 18 09:03:00 volumio-v2 volumio[1952]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 18 09:03:00 volumio-v2 systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Jul 18 09:03:01 volumio-v2 sudo[2355]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-07-18 09:02 Jul 18 09:03:01 volumio-v2 sudo[2355]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"