-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-11-02 12:24:53 CET. -- Nov 02 12:23:14 volumio volumio[981]: info: [ASDebug] Port: 24 Nov 02 12:23:14 volumio volumio[981]: info: [ASDebug] Inverted: false Nov 02 12:23:14 volumio volumio[981]: info: [ASDebug] Delay: 720 Nov 02 12:23:16 volumio go-librespot[1270]: time="2025-11-02T12:23:16+01:00" level=trace msg="sent dealer ping" Nov 02 12:23:16 volumio go-librespot[1270]: time="2025-11-02T12:23:16+01:00" level=trace msg="received dealer pong" Nov 02 12:23:20 volumio volumio[981]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 02 12:23:20 volumio volumio[981]: info: Uninstalling plugin ampswitch Nov 02 12:23:20 volumio volumio[981]: info: [ASDebug] Port: 24 Nov 02 12:23:20 volumio volumio[981]: info: [ASDebug] Inverted: false Nov 02 12:23:20 volumio volumio[981]: info: [ASDebug] Delay: 720 Nov 02 12:23:20 volumio volumio[981]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 12:23:20 volumio volumio[981]: Error: EBADF: bad file descriptor, close Nov 02 12:23:20 volumio volumio[981]: at Object.closeSync (fs.js:439:3) Nov 02 12:23:20 volumio volumio[981]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Nov 02 12:23:20 volumio volumio[981]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Nov 02 12:23:20 volumio volumio[981]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Nov 02 12:23:20 volumio volumio[981]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Nov 02 12:23:20 volumio volumio[981]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Nov 02 12:23:20 volumio volumio[981]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 02 12:23:20 volumio volumio[981]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Nov 02 12:23:20 volumio volumio[981]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Nov 02 12:23:20 volumio volumio[981]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Nov 02 12:23:20 volumio volumio[981]: at Socket.emit (events.js:315:20) Nov 02 12:23:20 volumio volumio[981]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 02 12:23:20 volumio volumio[981]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 02 12:23:20 volumio volumio[981]: errno: -9, Nov 02 12:23:20 volumio volumio[981]: syscall: 'close', Nov 02 12:23:20 volumio volumio[981]: code: 'EBADF' Nov 02 12:23:20 volumio volumio[981]: } Nov 02 12:23:20 volumio volumio[981]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 12:23:20 volumio sudo[2722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 12:22 Nov 02 12:23:20 volumio sudo[2722]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:20 volumio sudo[2722]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:20 volumio go-librespot[1270]: time="2025-11-02T12:23:20+01:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Nov 02 12:23:20 volumio volumio-remote-updater[644]: [2025-11-02 12:23:20] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 02 12:23:20 volumio volumio-remote-updater[644]: [2025-11-02 12:23:20] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 02 12:23:20 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 12:23:20 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Nov 02 12:23:20 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 02 12:23:20 volumio systemd[1]: Started dynamicswap service. Nov 02 12:23:20 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 02 12:23:21 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 12:23:21 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Nov 02 12:23:21 volumio systemd[1]: Started dynamicswap service. Nov 02 12:23:21 volumio systemd[1]: Stopped Volumio Backend Module. Nov 02 12:23:21 volumio systemd[1]: Started Volumio Backend Module. Nov 02 12:23:21 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 02 12:23:22 volumio volumio[2737]: info: ------------------------------------------- Nov 02 12:23:22 volumio volumio[2737]: info: ----- Volumio3 ---- Nov 02 12:23:22 volumio volumio[2737]: info: ------------------------------------------- Nov 02 12:23:22 volumio volumio[2737]: info: ----- System startup ---- Nov 02 12:23:22 volumio volumio[2737]: info: ------------------------------------------- Nov 02 12:23:22 volumio volumio[2737]: info: MYVOLUMIO Environment detected Nov 02 12:23:22 volumio volumio[2737]: info: Plugin folders cleanup Nov 02 12:23:22 volumio volumio[2737]: info: Scanning into folder /volumio/app/plugins/ Nov 02 12:23:22 volumio volumio[2737]: info: Scanning category audio_interface Nov 02 12:23:22 volumio volumio[2737]: info: Scanning category miscellanea Nov 02 12:23:22 volumio volumio[2737]: info: Scanning category music_service Nov 02 12:23:22 volumio volumio[2737]: info: Scanning category plugins.json Nov 02 12:23:22 volumio volumio[2737]: info: Scanning category system_controller Nov 02 12:23:22 volumio volumio[2737]: info: Scanning category user_interface Nov 02 12:23:22 volumio volumio[2737]: info: Scanning into folder /data/plugins/ Nov 02 12:23:22 volumio volumio[2737]: info: Scanning category audio_interface Nov 02 12:23:22 volumio volumio[2737]: info: Scanning category music_service Nov 02 12:23:22 volumio volumio[2737]: info: Scanning category system_controller Nov 02 12:23:22 volumio volumio[2737]: info: Scanning category user_interface Nov 02 12:23:22 volumio volumio[2737]: info: Plugin folders cleanup completed Nov 02 12:23:22 volumio volumio[2737]: info: ------------------------------------------- Nov 02 12:23:22 volumio volumio[2737]: info: ----- Core plugins startup ---- Nov 02 12:23:22 volumio volumio[2737]: info: ------------------------------------------- Nov 02 12:23:22 volumio volumio[2737]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 12:23:22 volumio volumio[2737]: info: Adding plugin upnp to MyMusic Plugins Nov 02 12:23:22 volumio volumio[2737]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 12:23:22 volumio volumio[2737]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 12:23:22 volumio volumio[2737]: info: Loading plugins from folder /data/plugins/ Nov 02 12:23:22 volumio volumio[2737]: info: Loading plugin "system"... Nov 02 12:23:22 volumio volumio[2737]: info: Loading plugin "ampswitch"... Nov 02 12:23:22 volumio volumio[2737]: info: Applying required configuration parameters for plugin ampswitch Nov 02 12:23:22 volumio volumio[2737]: info: Loading plugin "appearance"... Nov 02 12:23:23 volumio volumio[2737]: info: Loading plugin "network"... Nov 02 12:23:23 volumio volumio[2737]: info: Refreshing Cached IP Addresses Nov 02 12:23:23 volumio sudo[2766]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 12:23:23 volumio sudo[2766]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:23 volumio sudo[2766]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:23 volumio sudo[2768]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 12:23:23 volumio sudo[2768]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:23 volumio sudo[2768]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:23 volumio volumio[2737]: info: Loading plugin "services"... Nov 02 12:23:23 volumio volumio[2737]: info: Loading plugin "alsa_controller"... Nov 02 12:23:23 volumio sudo[2777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 12:23:23 volumio sudo[2777]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:23 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:23:23 volumio volumio[2737]: info: Loading plugin "wizard"... Nov 02 12:23:23 volumio volumio[2737]: info: Loading plugin "networkfs"... Nov 02 12:23:23 volumio volumio[2737]: info: Starting Udev Watcher for removable devices Nov 02 12:23:23 volumio sudo[2794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:23:23 volumio sudo[2794]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:23 volumio volumio[2737]: info: Ignoring mount for partition: boot Nov 02 12:23:23 volumio volumio[2737]: info: Ignoring mount for partition: volumio Nov 02 12:23:23 volumio volumio[2737]: info: Ignoring mount for partition: volumio_data Nov 02 12:23:23 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 12:23:23 volumio volumio[2737]: info: Loading plugin "volumio_command_line_client"... Nov 02 12:23:23 volumio sudo[2794]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:23 volumio volumio[2737]: info: Loading plugin "upnp"... Nov 02 12:23:23 volumio volumio[2737]: info: [1762082603628] Starting Upmpd Daemon Nov 02 12:23:23 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 12:23:23 volumio volumio[2737]: info: Loading plugin "my_music"... Nov 02 12:23:23 volumio volumio[2737]: info: Loading plugin "mpd"... Nov 02 12:23:23 volumio volumio[2737]: info: Loading plugin "upnp_browser"... Nov 02 12:23:24 volumio volumio[2737]: info: Loading plugin "alarm-clock"... Nov 02 12:23:24 volumio volumio[2737]: info: Loading plugin "airplay_emulation"... Nov 02 12:23:24 volumio volumio[2737]: info: Starting Shairport Sync Nov 02 12:23:24 volumio volumio[2737]: info: Loading plugin "last_100"... Nov 02 12:23:24 volumio volumio[2737]: info: Loading plugin "webradio"... Nov 02 12:23:24 volumio volumio[2737]: info: Loading plugin "i2s_dacs"... Nov 02 12:23:24 volumio volumio[2737]: info: Loading plugin "volumiodiscovery"... Nov 02 12:23:24 volumio volumio[2737]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 12:23:24 volumio volumio[2737]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 12:23:24 volumio volumio[2737]: *** WARNING *** For more information see Nov 02 12:23:24 volumio node[2737]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 12:23:24 volumio volumio[2737]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 12:23:24 volumio volumio[2737]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 12:23:24 volumio volumio[2737]: *** WARNING *** For more information see Nov 02 12:23:24 volumio node[2737]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 12:23:24 volumio node[2737]: *** WARNING *** For more information see Nov 02 12:23:24 volumio node[2737]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 12:23:24 volumio node[2737]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 12:23:24 volumio node[2737]: *** WARNING *** For more information see Nov 02 12:23:24 volumio volumio[2737]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 12:23:24 volumio volumio[2737]: info: Discovery: Started advertising with name: Volumio Nov 02 12:23:24 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 12:23:24 volumio volumio[2737]: info: Loading plugin "spop"... Nov 02 12:23:24 volumio volumio[2737]: info: Plugin youtube2 is not enabled Nov 02 12:23:24 volumio volumio[2737]: info: Plugin ytcr is not enabled Nov 02 12:23:24 volumio volumio[2737]: info: Loading plugin "ytmusic"... Nov 02 12:23:25 volumio volumio-remote-updater[644]: [2025-11-02 12:23:25] [connect] Successful connection Nov 02 12:23:26 volumio volumio[2737]: info: Loading plugin "outputs"... Nov 02 12:23:26 volumio volumio[2737]: info: Loading plugin "albumart"... Nov 02 12:23:26 volumio volumio[2737]: info: Plugin example_plugin is not enabled Nov 02 12:23:26 volumio volumio[2737]: info: Loading plugin "inputs"... Nov 02 12:23:26 volumio volumio[2737]: info: Loading plugin "updater_comm"... Nov 02 12:23:26 volumio volumio[2737]: info: Plugin mpdemulation is not enabled Nov 02 12:23:26 volumio volumio[2737]: info: Loading plugin "rest_api"... Nov 02 12:23:26 volumio volumio[2737]: info: Loading plugin "websocket"... Nov 02 12:23:26 volumio volumio[2737]: info: Starting Socket.io Server version 2.3.0 Nov 02 12:23:26 volumio volumio[2737]: info: Loading plugin "fusiondsp"... Nov 02 12:23:26 volumio sudo[2777]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:26 volumio volumio[2737]: Forking 3 albumart workers Nov 02 12:23:26 volumio volumio[2737]: info: Applying required configuration parameters for plugin fusiondsp Nov 02 12:23:26 volumio volumio[2737]: info: Plugin mpdoutput is not enabled Nov 02 12:23:26 volumio volumio[2737]: info: Loading plugin "80s80s"... Nov 02 12:23:27 volumio volumio[2737]: info: Applying required configuration parameters for plugin 80s80s Nov 02 12:23:27 volumio volumio[2737]: info: [1762082607176] [80s80s] API delay: 30 Nov 02 12:23:27 volumio volumio[2737]: info: Plugin minidlna is not enabled Nov 02 12:23:27 volumio volumio[2737]: info: Loading plugin "peppyspectrum"... Nov 02 12:23:27 volumio volumio[2737]: info: Loading plugin "touch_display"... Nov 02 12:23:27 volumio volumio[2737]: Starting albumart workers Nov 02 12:23:27 volumio volumio[2737]: Starting albumart workers Nov 02 12:23:27 volumio volumio[2737]: Starting albumart workers Nov 02 12:23:27 volumio volumio[2737]: info: Applying required configuration parameters for plugin touch_display Nov 02 12:23:27 volumio volumio[2737]: info: Loading i18n strings for locale pl Nov 02 12:23:27 volumio volumio[2737]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Nov 02 12:23:27 volumio volumio[2737]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Nov 02 12:23:27 volumio volumio[2737]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Nov 02 12:23:27 volumio volumio[2737]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Nov 02 12:23:27 volumio volumio[2737]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 02 12:23:27 volumio volumio[2737]: Updating browse sources language Nov 02 12:23:27 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:27 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 12:23:27 volumio volumio[2737]: info: CoreCommandRouter::initPlayerControls Nov 02 12:23:27 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:27 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:27 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:27 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:27 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:27 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:27 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:27 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:27 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 12:23:27 volumio volumio[2737]: Express server listening on port 3000 Nov 02 12:23:27 volumio volumio[2737]: [Metrics] WebUI: 6s 24.26ms Nov 02 12:23:27 volumio volumio[2737]: info: CoreStateMachine::resetVolumioState Nov 02 12:23:27 volumio volumio[2737]: info: CoreStateMachine::getcurrentVolume Nov 02 12:23:27 volumio volumio[2737]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 12:23:27 volumio volumio[2737]: info: Volumio Network Manager: Network status updated: 2 Nov 02 12:23:27 volumio volumio[2737]: info: Cannot mount NAS asustor at system boot, trial number 1 ,retrying in 5 seconds Nov 02 12:23:28 volumio volumio[2737]: verbose: New Socket.io Connection to 192.168.0.111 from 192.168.0.164 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 02 12:23:28 volumio volumio[2737]: verbose: New Socket.io Connection to 192.168.0.111 from 192.168.0.164 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 02 12:23:28 volumio volumio[2737]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 02 12:23:28 volumio volumio[2737]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Nov 02 12:23:28 volumio volumio[2737]: 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: 5 Nov 02 12:23:28 volumio volumio-remote-updater[644]: [2025-11-02 12:23:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1762082605 101 Nov 02 12:23:28 volumio volumio[2737]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 02 12:23:28 volumio volumio[2737]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:28 volumio volumio[2737]: info: Reloading queue from file Nov 02 12:23:28 volumio volumio[2737]: info: VolumeController:: Volume=79 Mute =false Nov 02 12:23:28 volumio volumio[2737]: info: CoreStateMachine::pushState Nov 02 12:23:28 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioPushState Nov 02 12:23:28 volumio volumio[2737]: info: CoreStateMachine::updateTrackBlock Nov 02 12:23:28 volumio volumio[2737]: info: CorePlayQueue::getTrackBlock Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 12:23:28 volumio volumio[2737]: info: CoreStateMachine::setRepeat false single undefined Nov 02 12:23:28 volumio volumio[2737]: info: CoreStateMachine::pushState Nov 02 12:23:28 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioPushState Nov 02 12:23:28 volumio volumio[2737]: info: CoreStateMachine::setRandom false Nov 02 12:23:28 volumio volumio[2737]: info: CoreStateMachine::pushState Nov 02 12:23:28 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioPushState Nov 02 12:23:28 volumio volumio[2737]: info: Setting Device type: Raspberry PI Nov 02 12:23:28 volumio volumio[2737]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 02 12:23:28 volumio volumio[2737]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Nov 02 12:23:28 volumio volumio[2737]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 02 12:23:28 volumio volumio[2737]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 12:23:28 volumio volumio[2737]: info: VolumeController:: Volume=79 Mute =false Nov 02 12:23:28 volumio volumio[2737]: info: CoreStateMachine::pushState Nov 02 12:23:28 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioPushState Nov 02 12:23:28 volumio volumio[2737]: info: Discovery: adding b4632d2c-ae2b-4b74-93e2-04ef0f1277cd Nov 02 12:23:28 volumio volumio[2737]: info: Discovery: Found device Volumio Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:28 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:28 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 12:23:28 volumio volumio[2737]: info: Received Get System Info Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 12:23:28 volumio volumio[2737]: info: Discovery: Getting this device information Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:28 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:28 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:28 volumio volumio[2737]: info: Listing playlists Nov 02 12:23:28 volumio volumio[2737]: info: Completed loading Core Plugins Nov 02 12:23:28 volumio volumio[2737]: info: Preparing to generate the ALSA configuration file Nov 02 12:23:28 volumio volumio[2737]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 02 12:23:28 volumio volumio[2737]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 12:23:28 volumio volumio[2737]: info: Reading ALSA contributions from plugins. Nov 02 12:23:28 volumio volumio[2737]: info: Asound.conf file unchanged, so no further update is needed Nov 02 12:23:28 volumio volumio[2737]: info: Output device has changed, restarting MPD Nov 02 12:23:28 volumio sudo[2866]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:23:28 volumio sudo[2866]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:28 volumio sudo[2866]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:28 volumio volumio[2737]: info: Output device has changed, restarting Shairport Sync Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:28 volumio sudo[2869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:23:28 volumio sudo[2869]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:28 volumio systemd[1]: Stopping Music Player Daemon... Nov 02 12:23:28 volumio volumio[2737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:23:28 volumio systemd[1]: mpd.service: Succeeded. Nov 02 12:23:28 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 12:23:28 volumio volumio[2737]: info: ___________ START PLUGINS ___________ Nov 02 12:23:28 volumio systemd[1]: Starting Music Player Daemon... Nov 02 12:23:28 volumio volumio[2737]: info: ControllerMpd::onStart: Initializing MPD Nov 02 12:23:28 volumio volumio[2737]: info: Creating MPD Configuration file Nov 02 12:23:28 volumio sudo[2889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:23:28 volumio sudo[2889]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:23:28 volumio volumio[2737]: info: [1762082608658] CoreMusicLibrary::Adding element Serwery Mediów Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:28 volumio sudo[2889]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:28 volumio sudo[2892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:23:28 volumio sudo[2892]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:28 volumio sudo[2875]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:23:28 volumio sudo[2875]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:28 volumio sudo[2875]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:28 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 02 12:23:28 volumio systemd[1]: mpd.service: Succeeded. Nov 02 12:23:28 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 12:23:28 volumio systemd[1]: Starting Music Player Daemon... Nov 02 12:23:28 volumio volumio[2737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:28 volumio volumio[2737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:23:28 volumio volumio[2737]: info: [1762082608787] CoreMusicLibrary::Adding element Last_100 Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:28 volumio sudo[2899]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:23:28 volumio sudo[2899]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:23:28 volumio volumio[2737]: info: [1762082608791] CoreMusicLibrary::Adding element Webradio Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:23:28 volumio sudo[2899]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:28 volumio volumio[2737]: info: Initializing BBC Radios Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:28 volumio volumio[2737]: info: Creating Spotify config file Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:23:28 volumio volumio[2737]: info: [1762082608936] CoreMusicLibrary::Adding element YouTube Music Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:28 volumio volumio[2737]: Cannot find translation for source YouTube Music Nov 02 12:23:28 volumio volumio[2737]: info: Loading i18n strings for locale pl Nov 02 12:23:28 volumio volumio[2737]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Nov 02 12:23:28 volumio volumio[2737]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Nov 02 12:23:28 volumio volumio[2737]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Nov 02 12:23:28 volumio volumio[2737]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Nov 02 12:23:28 volumio volumio[2737]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:28 volumio volumio[2737]: info: FusionDsp - mixtype--------------------- Hardware Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:23:28 volumio volumio[2737]: info: [1762082608958] CoreMusicLibrary::Adding element 80s80s Radio Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:28 volumio volumio[2737]: Cannot find translation for source YouTube Music Nov 02 12:23:28 volumio volumio[2737]: Cannot find translation for source 80s80s Radio Nov 02 12:23:28 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 02 12:23:28 volumio volumio[2737]: info: Loading i18n strings for locale pl Nov 02 12:23:29 volumio volumio[2737]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Nov 02 12:23:29 volumio volumio[2737]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Nov 02 12:23:29 volumio volumio[2737]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Nov 02 12:23:29 volumio volumio[2737]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Nov 02 12:23:29 volumio volumio[2737]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:23:29 volumio volumio[2737]: info: Volumio Calling Home Nov 02 12:23:29 volumio volumio[2737]: info: Preparing to generate the ALSA configuration file Nov 02 12:23:29 volumio sudo[2919]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Nov 02 12:23:29 volumio sudo[2919]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:29 volumio sudo[2922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Nov 02 12:23:29 volumio sudo[2922]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:29 volumio sudo[2925]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 02 12:23:29 volumio sudo[2925]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:29 volumio sudo[2919]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:29 volumio volumio[2737]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 02 12:23:29 volumio volumio[2737]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 12:23:29 volumio volumio[2737]: info: Reading ALSA contributions from plugins. Nov 02 12:23:29 volumio volumio[2737]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:23:29 volumio systemd[1]: Reloading. Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:29 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:29 volumio volumio[2737]: info: [ASDebug] CurState: stop PrevState: na Nov 02 12:23:29 volumio volumio[2737]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 02 12:23:29 volumio volumio[2737]: info: [ASDebug] CurState: stop PrevState: na Nov 02 12:23:29 volumio volumio[2737]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 02 12:23:29 volumio volumio[2737]: verbose: New Socket.io Connection to 192.168.0.111 from 192.168.0.164 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Nov 02 12:23:29 volumio volumio[2737]: info: MPD Permissions set Nov 02 12:23:29 volumio volumio[2737]: info: touch_display: systemctl stop getty@tty1.service succeeded. Nov 02 12:23:29 volumio volumio[2737]: info: Spotify config file written Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 12:23:29 volumio sudo[2929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 02 12:23:29 volumio sudo[2929]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:29 volumio volumio[2737]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Nov 02 12:23:29 volumio volumio[2737]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:29 volumio volumio[2737]: info: No need to fix Spotify hosts Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:29 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:29 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::volumioGetQueue Nov 02 12:23:29 volumio volumio[2737]: info: CoreStateMachine::getQueue Nov 02 12:23:29 volumio volumio[2737]: info: CorePlayQueue::getQueue Nov 02 12:23:29 volumio volumio[2737]: info: Listing playlists Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 12:23:29 volumio volumio[2737]: info: Received Get System Info Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 12:23:29 volumio volumio[2737]: info: Discovery: Getting this device information Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:29 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 12:23:29 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:29 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:29 volumio 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. Nov 02 12:23:29 volumio volumio[2737]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Nov 02 12:23:29 volumio sudo[2952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Nov 02 12:23:29 volumio volumio[2737]: info: FusionDsp - no preset used Nov 02 12:23:29 volumio sudo[2952]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:29 volumio mpd[2904]: Nov 02 12:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 12:23:29 volumio sudo[2952]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:29 volumio volumio[2737]: info: FusionDsp - no preset used Nov 02 12:23:29 volumio systemd[1]: Started Music Player Daemon. Nov 02 12:23:29 volumio sudo[2925]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:29 volumio sudo[2892]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:29 volumio sudo[2869]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:29 volumio systemd[1]: Reloading. Nov 02 12:23:29 volumio volumio[2737]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:23:30 volumio volumio[2737]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:23:30 volumio volumio[2737]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Nov 02 12:23:30 volumio volumio[2737]: info: touch_display: systemctl daemon-reload succeeded. Nov 02 12:23:30 volumio 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. Nov 02 12:23:30 volumio volumio[2737]: info: [ASDebug] Togle GPIO: OFF Nov 02 12:23:30 volumio volumio[2737]: info: Starting Shairport Sync Nov 02 12:23:30 volumio sudo[2972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 02 12:23:30 volumio sudo[2972]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:30 volumio volumio[2737]: info: Starting Shairport Sync Nov 02 12:23:30 volumio sudo[2975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:23:30 volumio sudo[2975]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:30 volumio volumio[2737]: info: Starting Shairport Sync Nov 02 12:23:30 volumio sudo[2978]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:23:30 volumio sudo[2978]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:30 volumio volumio[2737]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 02 12:23:30 volumio volumio[2737]: SPOTIFY: BQBALCsxLoEKilUSW2lOJBhFC2YsvkZT4Ug1NDQcnfM7P2KMWhllpGNBt3jDm9e_8v2LRExlfODa6LZq-kaZcRqVGIZF1PxKRkl2KjsaeVJfx31UUpo4NER26qFVUPoQWKlygdmE0jYEoUkA83dzwZalZq9vJHEYOmjQqcX1Mbirszw1XVbyPFAvuQK6c0CjRhjaCNClFy-SqqJHTUmlHoYxC_t5T-qoAYBKwtSlEYsmIdX08puQEPKZ1NKFgKQRJDhUEm3mt4e9Z62aTRuyZaY Nov 02 12:23:30 volumio volumio[2737]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 02 12:23:30 volumio volumio[2737]: info: New Spotify access token = BQBALCsxLoEKilUSW2lOJBhFC2YsvkZT4Ug1NDQcnfM7P2KMWhllpGNBt3jDm9e_8v2LRExlfODa6LZq-kaZcRqVGIZF1PxKRkl2KjsaeVJfx31UUpo4NER26qFVUPoQWKlygdmE0jYEoUkA83dzwZalZq9vJHEYOmjQqcX1Mbirszw1XVbyPFAvuQK6c0CjRhjaCNClFy-SqqJHTUmlHoYxC_t5T-qoAYBKwtSlEYsmIdX08puQEPKZ1NKFgKQRJDhUEm3mt4e9Z62aTRuyZaY Nov 02 12:23:30 volumio volumio[2737]: info: Spotify credentials grant success - running version from March 24, 2019 Nov 02 12:23:30 volumio sudo[2981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:23:30 volumio sudo[2981]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:30 volumio volumio[2737]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:23:30 volumio volumio[2737]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:23:30 volumio volumio[2737]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:23:30 volumio volumio[2737]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:23:30 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:30 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:30 volumio volumio[2737]: info: MPD running with PID2904 Nov 02 12:23:30 volumio volumio[2737]: ,establishing connection Nov 02 12:23:30 volumio volumio[2737]: info: Volumio called home Nov 02 12:23:30 volumio sudo[2922]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 12:23:30 volumio systemd[1]: Stopping go-librespot Daemon... Nov 02 12:23:30 volumio sudo[2972]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:30 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 02 12:23:30 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Nov 02 12:23:30 volumio systemd[1]: Stopped go-librespot Daemon. Nov 02 12:23:30 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 12:23:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 12:23:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 12:23:30 volumio sudo[2975]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:30 volumio systemd[1]: Started go-librespot Daemon. Nov 02 12:23:30 volumio sudo[2981]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:30 volumio sudo[2929]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:30 volumio go-librespot[2984]: go-librespot daemon starting... Nov 02 12:23:30 volumio sudo[2978]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:30 volumio go-librespot[2984]: time="2025-11-02T12:23:30+01:00" level=info msg="running go-librespot 0.4.0" Nov 02 12:23:30 volumio go-librespot[2984]: time="2025-11-02T12:23:30+01:00" level=debug msg="app state loaded" Nov 02 12:23:30 volumio go-librespot[2984]: time="2025-11-02T12:23:30+01:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 12:23:30 volumio volumio[2737]: error: MPD error: The expression evaluated to a falsy value: Nov 02 12:23:30 volumio volumio[2737]: assert.ok(self.idling) Nov 02 12:23:30 volumio volumio[2737]: error: The expression evaluated to a falsy value: Nov 02 12:23:30 volumio volumio[2737]: assert.ok(self.idling) Nov 02 12:23:30 volumio volumio[2737]: error: MPD error: The expression evaluated to a falsy value: Nov 02 12:23:30 volumio volumio[2737]: assert.ok(self.idling) Nov 02 12:23:30 volumio volumio[2737]: error: The expression evaluated to a falsy value: Nov 02 12:23:30 volumio volumio[2737]: assert.ok(self.idling) Nov 02 12:23:30 volumio volumio[2737]: info: Asound.conf file unchanged, so no further update is needed Nov 02 12:23:30 volumio volumio[2737]: info: Output device has changed, restarting MPD Nov 02 12:23:30 volumio sudo[2994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:23:30 volumio sudo[2994]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:30 volumio sudo[2994]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:30 volumio volumio[2737]: info: Output device has changed, restarting Shairport Sync Nov 02 12:23:30 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:30 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:30 volumio sudo[2997]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:23:30 volumio sudo[2997]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:30 volumio systemd[1]: Stopping Music Player Daemon... Nov 02 12:23:30 volumio systemd[1]: mpd.service: Succeeded. Nov 02 12:23:30 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 12:23:30 volumio volumio[2737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:23:30 volumio go-librespot[2984]: time="2025-11-02T12:23:30+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-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Nov 02 12:23:30 volumio go-librespot[2984]: time="2025-11-02T12:23:30+01:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Nov 02 12:23:30 volumio go-librespot[2984]: time="2025-11-02T12:23:30+01:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Nov 02 12:23:30 volumio go-librespot[2984]: time="2025-11-02T12:23:30+01:00" level=info msg="zeroconf server listening on port 38219" Nov 02 12:23:30 volumio systemd[1]: Starting Music Player Daemon... Nov 02 12:23:30 volumio volumio[2737]: info: Preparing to generate the ALSA configuration file Nov 02 12:23:30 volumio volumio[2737]: error: MPD error: Error: write EPIPE Nov 02 12:23:30 volumio volumio[2737]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Nov 02 12:23:30 volumio volumio[2737]: info: Shairport-Sync Started Nov 02 12:23:30 volumio volumio[2737]: Error adding Membership: Error: addMembership EINVAL Nov 02 12:23:30 volumio volumio[2737]: info: Shairport-Sync Started Nov 02 12:23:30 volumio volumio[2737]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 02 12:23:30 volumio volumio[2737]: info: touch_display: Volumio Kiosk started. Nov 02 12:23:30 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:30 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:30 volumio volumio[2737]: info: Completed starting Core Plugins Nov 02 12:23:30 volumio volumio[2737]: info: ------------------------------------------- Nov 02 12:23:30 volumio volumio[2737]: info: ----- MyVolumio plugins startup ---- Nov 02 12:23:30 volumio volumio[2737]: info: ------------------------------------------- Nov 02 12:23:30 volumio volumio[2737]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 12:23:30 volumio volumio[2737]: info: touch_display: systemctl disable getty@tty1.service succeeded. Nov 02 12:23:30 volumio volumio[2737]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 02 12:23:30 volumio volumio[2737]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 12:23:30 volumio volumio[2737]: info: Reading ALSA contributions from plugins. Nov 02 12:23:30 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:30 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:30 volumio volumio[2737]: info: MPD Permissions set Nov 02 12:23:30 volumio volumio[2737]: info: FusionDsp - no preset used Nov 02 12:23:30 volumio sudo[3004]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:23:30 volumio sudo[3004]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:30 volumio sudo[3004]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:30 volumio go-librespot[2984]: time="2025-11-02T12:23:30+01:00" level=debug msg="obtained new client token: AAC1zcZXVrV8oXJNVCllG8N8Iy4XJfzxdO5pd+yQF6Q3zwnhky800rHn23Y6KaTsKDJG/BC8rboPdpIiAWz4rfwjMSEtQXfPj9LIbSnyoxH0fzDfPh4qNcm9pxEruR64aVza3bhbABEM9h84NyAoo+ppKtGoQ5wMBNCQGuZC+ZhRBuQ0gBSRiS9StwuHr8Li/cRZjmQpuYH+vCJ0TEOx2DSlp5ZL3P01gr4db6YwTM60dVTqWChSLhNW/ZE=" Nov 02 12:23:30 volumio volumio[2737]: info: FusionDsp - no preset used Nov 02 12:23:30 volumio go-librespot[2984]: time="2025-11-02T12:23:30+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=debug msg="completed keyexchange" Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=debug msg="completed challenge" Nov 02 12:23:31 volumio volumio[2737]: info: FusionDsp - no preset used Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=info msg="authenticated AP" username="an***75" Nov 02 12:23:31 volumio volumio[2737]: info: FusionDsp - no preset used Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=info msg="authenticated Login5" username="an***75" Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=debug msg="initializing zeroconf session" username="an***75" Nov 02 12:23:31 volumio volumio[2737]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 02 12:23:31 volumio volumio[2737]: 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 Nov 02 12:23:31 volumio volumio[2737]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 02 12:23:31 volumio volumio[2737]: info: camilladsp service started and running in background, instance 1 Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Nov 02 12:23:31 volumio volumio[2737]: info: FusionDsp loaded Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=debug msg="dealer connection opened" Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=trace msg="starting accesspoint recv loop" Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=trace msg="starting dealer recv loop" Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=trace msg="received accesspoint ping" Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Nov 02 12:23:31 volumio sudo[3028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=debug msg="received connection id: NTA5ZjBmZDctM2E2...MDNCNUZEQjExNA==" Nov 02 12:23:31 volumio volumio[2737]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 02 12:23:31 volumio volumio[2737]: info: Adding Signal Path Element [object Object] Nov 02 12:23:31 volumio volumio[2737]: info: Adding fusiondspeq DSP Signal Path Element Nov 02 12:23:31 volumio sudo[3028]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:31 volumio volumio[2737]: info: FusionDsp - ---- installed callbackRead Nov 02 12:23:31 volumio volumio[2737]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 12:23:31 volumio volumio[2737]: info: FusionDsp - Loudness is ON true Nov 02 12:23:31 volumio sudo[3028]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:31 volumio volumio[2737]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=trace msg="received accesspoint pong ack" Nov 02 12:23:31 volumio volumio[2737]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:23:31 volumio volumio[2737]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:23:31 volumio volumio[2737]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:23:31 volumio go-librespot[2984]: time="2025-11-02T12:23:31+01:00" level=debug msg="put connect state because NEW_DEVICE" Nov 02 12:23:31 volumio volumio[2737]: info: touch_display: X display number found: 0 Nov 02 12:23:31 volumio volumio[2737]: info: touch_display: X display number found: 0 Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Nov 02 12:23:31 volumio volumio[2737]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 02 12:23:31 volumio volumio[2737]: info: Starting Shairport Sync Nov 02 12:23:31 volumio mpd[3008]: Nov 02 12:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 12:23:31 volumio systemd[1]: Started Music Player Daemon. Nov 02 12:23:31 volumio sudo[2997]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:31 volumio sudo[3038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:23:31 volumio sudo[3038]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:31 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 12:23:31 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 12:23:31 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 12:23:31 volumio volumio[2737]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 02 12:23:31 volumio volumio[2737]: info: Asound.conf file unchanged, so no further update is needed Nov 02 12:23:31 volumio volumio[2737]: info: Output device has changed, restarting MPD Nov 02 12:23:31 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 12:23:31 volumio sudo[3038]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:31 volumio volumio[2737]: info: Output device has changed, restarting Shairport Sync Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:31 volumio sudo[3044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:23:31 volumio sudo[3044]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:31 volumio sudo[3044]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:31 volumio sudo[3046]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:23:31 volumio sudo[3046]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:31 volumio systemd[1]: Stopping Music Player Daemon... Nov 02 12:23:31 volumio volumio[2737]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:23:31 volumio systemd[1]: mpd.service: Succeeded. Nov 02 12:23:31 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 12:23:31 volumio systemd[1]: Starting Music Player Daemon... Nov 02 12:23:31 volumio volumio[2737]: error: MPD error: Error: write EPIPE Nov 02 12:23:31 volumio volumio[2737]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Nov 02 12:23:31 volumio sudo[3055]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:23:31 volumio sudo[3055]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:31 volumio sudo[3055]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:31 volumio volumio[2737]: info: MPD Permissions set Nov 02 12:23:31 volumio volumio[2737]: info: touch_display: X display number found: 0 Nov 02 12:23:31 volumio volumio[2737]: info: Shairport-Sync Started Nov 02 12:23:31 volumio volumio[2737]: SPOTIFY: User informations: {"country":"PL","display_name":"anzio75","email":"anzio75@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/anzio75"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/anzio75","id":"anzio75","images":[],"product":"free","type":"user","uri":"spotify:user:anzio75"} Nov 02 12:23:31 volumio volumio[2737]: info: Spotify Successfully logged in Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:23:31 volumio volumio[2737]: info: [1762082611939] CoreMusicLibrary::Adding element Spotify Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:31 volumio volumio[2737]: Cannot find translation for source YouTube Music Nov 02 12:23:31 volumio volumio[2737]: Cannot find translation for source 80s80s Radio Nov 02 12:23:31 volumio volumio[2737]: Cannot find translation for source Spotify Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:31 volumio volumio[2737]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 02 12:23:32 volumio volumio[2737]: info: Starting Shairport Sync Nov 02 12:23:32 volumio sudo[3069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:23:32 volumio sudo[3069]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:32 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 12:23:32 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 12:23:32 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 12:23:32 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 12:23:32 volumio sudo[3069]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:32 volumio volumio[2737]: info: Shairport-Sync Started Nov 02 12:23:32 volumio mpd[3061]: Nov 02 12:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 12:23:32 volumio systemd[1]: Started Music Player Daemon. Nov 02 12:23:32 volumio sudo[3046]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:32 volumio volumio[2737]: error: updateQueue error: null Nov 02 12:23:32 volumio sudo[3078]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:23:32 volumio sudo[3078]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:32 volumio sudo[3078]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:32 volumio volumio[2737]: info: Cannot mount NAS asustor at system boot, trial number 2 ,retrying in 5 seconds Nov 02 12:23:33 volumio sudo[3097]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 12:23:33 volumio sudo[3097]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:33 volumio sudo[3097]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:33 volumio sudo[3100]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 12:23:33 volumio sudo[3100]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:33 volumio sudo[3100]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:33 volumio sudo[3103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 12:23:33 volumio sudo[3103]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:33 volumio sudo[3103]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:33 volumio volumio[2737]: info: Upmpdcli Daemon Started Nov 02 12:23:33 volumio volumio[2737]: info: go-librespot daemon successfully initialized Nov 02 12:23:35 volumio volumio[2737]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 02 12:23:36 volumio volumio[2737]: info: Initializing connection to go-librespot Websocket Nov 02 12:23:36 volumio go-librespot[2984]: time="2025-11-02T12:23:36+01:00" level=debug msg="new websocket client" Nov 02 12:23:36 volumio volumio[2737]: info: Connection to go-librespot Websocket established Nov 02 12:23:37 volumio volumio[2737]: info: CoreCommandRouter::volumioPlay Nov 02 12:23:37 volumio volumio[2737]: info: CoreStateMachine::play index undefined Nov 02 12:23:37 volumio volumio[2737]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 02 12:23:37 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:37 volumio volumio[2737]: info: CoreStateMachine::startPlaybackTimer Nov 02 12:23:37 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:37 volumio volumio[2737]: info: [ytmusic-play] clearAddPlayTrack: ytmusic/song@explodeTrackData:o=%7B%22type%22%3A%22song%22%2C%22title%22%3A%22Voil%C3%A0%22%2C%22artist%22%3A%22Andr%C3%A9%20Rieu%2C%20Johann%20Strauss%20Orchestra%20%26%20Emma%20Kok%22%2C%22album%22%3A%22%22%2C%22albumart%22%3A%22https%3A%2F%2Flh3.googleusercontent.com%2FsfSUY-eV0JffFwPIrHiDgCOAPrVK_17MDO6QpH1yqlEJ41DHoGM5rMS4F6PR5ko2_OVIU40DaVNzFsYu%3Dw701-h701-l90-rj%22%2C%22endpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%2C%22autoplayContext%22%3A%7B%22fetchEndpoint%22%3A%7B%22type%22%3A%22watch%22%2C%22payload%22%3A%7B%22videoId%22%3A%2260r6ysvP0hU%22%7D%2C%22musicVideoType%22%3A%22MUSIC_VIDEO_TYPE_ATV%22%7D%7D%7D Nov 02 12:23:37 volumio volumio[2737]: info: [ytmusic] InnertubeLoader: creating Innertube instance... Nov 02 12:23:38 volumio sudo[3109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:23:38 volumio sudo[3109]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:38 volumio sudo[3109]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:38 volumio volumio[2737]: info: Cannot mount NAS asustor at system boot, trial number 3 ,retrying in 5 seconds Nov 02 12:23:42 volumio volumio[2737]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Nov 02 12:23:42 volumio volumio[2737]: info: [ytmusic] InnertubeLoader: obtaining po_token by visitorData... Nov 02 12:23:42 volumio volumio[2737]: info: Getting Spotify volume Nov 02 12:23:42 volumio volumio[2737]: info: Spotify volume: 100 Nov 02 12:23:42 volumio volumio[2737]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Nov 02 12:23:42 volumio volumio[2737]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:42 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:42 volumio volumio[2737]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Nov 02 12:23:42 volumio volumio[2737]: SPOTIFY: SPOTIFY VOLUME 100 Nov 02 12:23:42 volumio volumio[2737]: SPOTIFY: VOLUMIO VOLUME 79 Nov 02 12:23:42 volumio volumio[2737]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 02 12:23:42 volumio volumio[2737]: info: Setting Spotify Volume from Volumio: 79 Nov 02 12:23:43 volumio sudo[3114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:23:43 volumio sudo[3114]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:43 volumio sudo[3114]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:43 volumio volumio[2737]: info: Cannot mount NAS asustor at system boot, trial number 4 ,retrying in 5 seconds Nov 02 12:23:43 volumio volumio[2737]: info: [ytmusic] InnertubeLoader: obtained po_token (expires in 43200 seconds) Nov 02 12:23:43 volumio volumio[2737]: info: [ytmusic] InnertubeLoader: re-create Innertube instance with po_token Nov 02 12:23:43 volumio volumio[2737]: info: [ytmusic] InnertubeLoader: creating Innertube instance with po_token... Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 02 12:23:43 volumio volumio[2737]: info: Adding plugin bluetooth to MyMusic Plugins Nov 02 12:23:43 volumio volumio[2737]: info: Adding plugin multiroom to MyMusic Plugins Nov 02 12:23:43 volumio volumio[2737]: info: Adding plugin metavolumio to MyMusic Plugins Nov 02 12:23:43 volumio volumio[2737]: info: Adding plugin cd_controller to MyMusic Plugins Nov 02 12:23:43 volumio volumio[2737]: info: Adding plugin qobuzconnect to MyMusic Plugins Nov 02 12:23:43 volumio volumio[2737]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 02 12:23:43 volumio volumio[2737]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 12:23:43 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:43 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:43 volumio volumio[2737]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 12:23:43 volumio volumio[2737]: info: MyVolumio login type: Token Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 12:23:43 volumio volumio[2737]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 02 12:23:44 volumio volumio[2737]: info: Starting Streaming Service Transparent Proxy Nov 02 12:23:44 volumio volumio[2737]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 02 12:23:44 volumio volumio[2737]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 02 12:23:44 volumio volumio[2737]: info: Streaming services startup Nov 02 12:23:44 volumio volumio[2737]: info: Starting Streaming Daemon Nov 02 12:23:44 volumio sudo[3139]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 12:23:44 volumio sudo[3139]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:44 volumio sudo[3139]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:44 volumio volumio[2737]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 02 12:23:44 volumio volumio[2737]: SPOTIFY: SETTING SPOTIFY VOLUME 79 Nov 02 12:23:44 volumio volumio[2737]: info: Sending Spotify command with payload to local API: /player/volume Nov 02 12:23:44 volumio go-librespot[2984]: time="2025-11-02T12:23:44+01:00" level=debug msg="update volume requested to 51772/65535" Nov 02 12:23:44 volumio volumio[2737]: error: Cannot start Volumio Streaming Daemon Nov 02 12:23:44 volumio volumio[2737]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 12:23:44 volumio volumio[2737]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 12:23:44 volumio go-librespot[2984]: time="2025-11-02T12:23:44+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Nov 02 12:23:44 volumio go-librespot[2984]: time="2025-11-02T12:23:44+01:00" level=trace msg="emitting websocket event: volume" Nov 02 12:23:44 volumio volumio[2737]: SPOTIFY: received: {"type":"volume","data":{"value":79,"max":100}} Nov 02 12:23:44 volumio volumio[2737]: SPOTIFY: RECEIVED SPOTIFY VOLUME 79 Nov 02 12:23:45 volumio volumio[2737]: STREAMING PROXY: Starting server on port 3245 Nov 02 12:23:45 volumio volumio[2737]: Node JS runtime: 14 Nov 02 12:23:47 volumio volumio[2737]: [YOUTUBEJS][Player]: Failed to extract signature decipher algorithm. Nov 02 12:23:47 volumio volumio[2737]: info: [ytmusic] InnertubeLoader: going to refresh po_token in 43100 seconds Nov 02 12:23:47 volumio volumio[2737]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 12:23:47 volumio volumio[2737]: info: [ASDebug] Port: 24 Nov 02 12:23:47 volumio volumio[2737]: info: [ASDebug] Inverted: false Nov 02 12:23:47 volumio volumio[2737]: info: [ASDebug] Delay: 720 Nov 02 12:23:48 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:48 volumio volumio[2737]: verbose: ControllerMpd::sendMpdCommand stop Nov 02 12:23:48 volumio volumio[2737]: info: sendMpdCommand stop took 34 milliseconds Nov 02 12:23:48 volumio volumio[2737]: verbose: ControllerMpd::sendMpdCommand clear Nov 02 12:23:48 volumio volumio[2737]: info: Nov 02 12:23:48 volumio volumio[2737]: ---------------------------- MPD announces system playlist update Nov 02 12:23:48 volumio volumio[2737]: info: Ignoring MPD Status Update Nov 02 12:23:48 volumio volumio[2737]: info: sendMpdCommand clear took 2 milliseconds Nov 02 12:23:48 volumio volumio[2737]: verbose: ControllerMpd::sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104227&ei=Qz8HacrgLre5i9oPmeC-mQs&ip=89.231.107.132&id=o-AOiRwjwtiukZ2uA5m08hv16QE8mQYQCcepXwP-YYGvoI&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=24&met=1762082627%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7kn7z&ms=au%2Crdu&mv=m&mvi=3&pcm2cms=yes&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5TkM7n1cn7QGpNDnk8w4r7EnpFsvq-61246Ei7GsMutbh7rASyFQEOpexXZkE3XjnaaBMuxFcUu&spc=6b0G_IU2W7KZRX50IRWql2C88X8Jv3lajBdU4Mqnc9zgbZ_0XD_WhYVaLudVC08HiCI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=euQbyo85rXQuLEdCNqEud0oQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALZbpeZwNXT9j5ueUyMp2XO7EatIgJEehmptEqeh3IXLAiEA61GjYmrnNAVJmfiXE9mQKNtwOhCUpnS1Xf5Hj8BaIAI%3D&pot=MpoBNF0_jtBK-SI0Y-R7OGMVxubsbJU0ClTHOxnXEfQspLFP8tT4dGGuyouFB25gVqJ4Bo7vBRfY-1BbY--8Yu1Ucykci2e4fAaRfO4g82LQMfQVV_wNcrs1SZ0kIo91NPRjLplDfKY8hr2sRHvo0iIatUB60In4uafYu8EvzzybWHuHXaC218FegCZfG6LVxeiAIfp6G33QMt_a7A%3D%3D&cver=1.20250219.01.00&t.YouTube" Nov 02 12:23:48 volumio volumio[2737]: error: updateQueue error: null Nov 02 12:23:48 volumio volumio[2737]: info: Nov 02 12:23:48 volumio volumio[2737]: ---------------------------- MPD announces system playlist update Nov 02 12:23:48 volumio volumio[2737]: info: Ignoring MPD Status Update Nov 02 12:23:48 volumio volumio[2737]: info: ------------------------------ 5ms Nov 02 12:23:48 volumio volumio[2737]: info: sendMpdCommand addid "https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104227&ei=Qz8HacrgLre5i9oPmeC-mQs&ip=89.231.107.132&id=o-AOiRwjwtiukZ2uA5m08hv16QE8mQYQCcepXwP-YYGvoI&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=24&met=1762082627%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7kn7z&ms=au%2Crdu&mv=m&mvi=3&pcm2cms=yes&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5TkM7n1cn7QGpNDnk8w4r7EnpFsvq-61246Ei7GsMutbh7rASyFQEOpexXZkE3XjnaaBMuxFcUu&spc=6b0G_IU2W7KZRX50IRWql2C88X8Jv3lajBdU4Mqnc9zgbZ_0XD_WhYVaLudVC08HiCI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=euQbyo85rXQuLEdCNqEud0oQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALZbpeZwNXT9j5ueUyMp2XO7EatIgJEehmptEqeh3IXLAiEA61GjYmrnNAVJmfiXE9mQKNtwOhCUpnS1Xf5Hj8BaIAI%3D&pot=MpoBNF0_jtBK-SI0Y-R7OGMVxubsbJU0ClTHOxnXEfQspLFP8tT4dGGuyouFB25gVqJ4Bo7vBRfY-1BbY--8Yu1Ucykci2e4fAaRfO4g82LQMfQVV_wNcrs1SZ0kIo91NPRjLplDfKY8hr2sRHvo0iIatUB60In4uafYu8EvzzybWHuHXaC218FegCZfG6LVxeiAIfp6G33QMt_a7A%3D%3D&cver=1.20250219.01.00&t.YouTube" took 3 milliseconds Nov 02 12:23:48 volumio volumio[2737]: verbose: MPD COMMAND [object Object] Nov 02 12:23:48 volumio volumio[2737]: verbose: MPD COMMAND [object Object] Nov 02 12:23:48 volumio volumio[2737]: verbose: MPD COMMAND [object Object] Nov 02 12:23:48 volumio sudo[3149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:23:48 volumio sudo[3149]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:48 volumio volumio[2737]: info: Nov 02 12:23:48 volumio volumio[2737]: ---------------------------- MPD announces system playlist update Nov 02 12:23:48 volumio volumio[2737]: info: Ignoring MPD Status Update Nov 02 12:23:48 volumio volumio[2737]: info: ------------------------------ 6ms Nov 02 12:23:48 volumio volumio[2737]: info: CoreStateMachine::setConsumeUpdateService mpd Nov 02 12:23:48 volumio volumio[2737]: verbose: ControllerMpd::sendMpdCommand play Nov 02 12:23:48 volumio volumio[2737]: info: ------------------------------ 4ms Nov 02 12:23:48 volumio volumio[2737]: info: sendMpdCommand play took 2 milliseconds Nov 02 12:23:48 volumio sudo[3149]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:48 volumio volumio[2737]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 02 12:23:48 volumio volumio[2737]: info: ControllerMpd::getState Nov 02 12:23:48 volumio volumio[2737]: verbose: ControllerMpd::sendMpdCommand status Nov 02 12:23:48 volumio volumio[2737]: info: Nov 02 12:23:48 volumio volumio[2737]: ---------------------------- MPD announces state update: player Nov 02 12:23:48 volumio volumio[2737]: info: ControllerMpd::getState Nov 02 12:23:48 volumio volumio[2737]: verbose: ControllerMpd::sendMpdCommand status Nov 02 12:23:48 volumio volumio[2737]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 02 12:23:48 volumio volumio[2737]: info: sendMpdCommand status took 10 milliseconds Nov 02 12:23:48 volumio volumio[2737]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 02 12:23:48 volumio volumio[2737]: info: sendMpdCommand status took 11 milliseconds Nov 02 12:23:48 volumio volumio[2737]: verbose: ControllerMpd::parseState Nov 02 12:23:48 volumio volumio[2737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 12:23:48 volumio volumio[2737]: verbose: ControllerMpd::parseState Nov 02 12:23:48 volumio volumio[2737]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 02 12:23:48 volumio volumio[2737]: info: sendMpdCommand clearerror took 10 milliseconds Nov 02 12:23:48 volumio volumio[2737]: info: sendMpdCommand clearerror took 9 milliseconds Nov 02 12:23:48 volumio volumio[2737]: info: sendMpdCommand playlistinfo took 3 milliseconds Nov 02 12:23:48 volumio volumio[2737]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 02 12:23:48 volumio volumio[2737]: verbose: ControllerMpd::parseTrackInfo Nov 02 12:23:48 volumio volumio[2737]: verbose: ControllerMpd::parseTrackInfo Nov 02 12:23:48 volumio volumio[2737]: info: CoreStateMachine::getQueue Nov 02 12:23:48 volumio volumio[2737]: info: CorePlayQueue::getQueue Nov 02 12:23:48 volumio volumio[2737]: info: CoreStateMachine::getQueue Nov 02 12:23:48 volumio volumio[2737]: info: CorePlayQueue::getQueue Nov 02 12:23:48 volumio volumio[2737]: info: ControllerMpd::pushState Nov 02 12:23:48 volumio volumio[2737]: info: CoreCommandRouter::servicePushState Nov 02 12:23:48 volumio volumio[2737]: info: CoreStateMachine::pushState Nov 02 12:23:48 volumio volumio[2737]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 12:23:48 volumio volumio[2737]: info: CoreCommandRouter::volumioPushState Nov 02 12:23:48 volumio volumio[2737]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:48 volumio volumio[2737]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Voilà","artist":"André Rieu, Johann Strauss Orchestra oraz Emma Kok","album":"Voilà","uri":"https://rr3---sn-8xjug5oxu-f5fs.googlevideo.com/videoplayback?expire=1762104227&ei=Qz8HacrgLre5i9oPmeC-mQs&ip=89.231.107.132&id=o-AOiRwjwtiukZ2uA5m08hv16QE8mQYQCcepXwP-YYGvoI&itag=251&source=youtube&requiressl=yes&xpc=EgVo2aDSNQ%3D%3D&cps=24&met=1762082627%2C&mh=ks&mm=31%2C29&mn=sn-8xjug5oxu-f5fs%2Csn-f5f7kn7z&ms=au%2Crdu&mv=m&mvi=3&pcm2cms=yes&pl=19&rms=au%2Cau&gcr=pl&initcwndbps=3485000&bui=AdEuB5TkM7n1cn7QGpNDnk8w4r7EnpFsvq-61246Ei7GsMutbh7rASyFQEOpexXZkE3XjnaaBMuxFcUu&spc=6b0G_IU2W7KZRX50IRWql2C88X8Jv3lajBdU4Mqnc9zgbZ_0XD_WhYVaLudVC08HiCI&vprv=1&svpuc=1&mime=audio%2Fwebm&ns=euQbyo85rXQuLEdCNqEud0oQ&rqh=1&gir=yes&clen=5191365&dur=310.541&lmt=1760014346220842&mt=1762082338&fvip=5&keepalive=yes&fexp=51552689%2C51565116%2C51565681%2C51580968&c=WEB_REMIX&sefc=1&txp=1432534&n=v&sparams=expire%2Cei%2Cip%2Cid%2Citag%2Csource%2Crequiressl%2Cxpc%2Cgcr%2Cbui%2Cspc%2Cvprv%2Csvpuc%2Cmime%2Cns%2Crqh%2Cgir%2Cclen%2Cdur%2Clmt&lsparams=cps%2Cmet%2Cmh%2Cmm%2Cmn%2Cms%2Cmv%2Cmvi%2Cpcm2cms%2Cpl%2Crms%2Cinitcwndbps&lsig=APaTxxMwRgIhALZbpeZwNXT9j5ueUyMp2XO7EatIgJEehmptEqeh3IXLAiEA61GjYmrnNAVJmfiXE9mQKNtwOhCUpnS1Xf5Hj8BaIAI%3D&pot=MpoBNF0_jtBK-SI0Y-R7OGMVxubsbJU0ClTHOxnXEfQspLFP8tT4dGGuyouFB25gVqJ4Bo7vBRfY-1BbY--8Yu1Ucykci2e4fAaRfO4g82LQMfQVV_wNcrs1SZ0kIo91NPRjLplDfKY8hr2sRHvo0iIatUB60In4uafYu8EvzzybWHuHXaC218FegCZfG6LVxeiAIfp6G33QMt_a7A%3D%3D&cver=1.20250219.01.00&t.YouTube","trackType":"YouTube"} Nov 02 12:23:48 volumio volumio[2737]: verbose: CURRENT POSITION 0 Nov 02 12:23:48 volumio volumio[2737]: info: CoreStateMachine::syncState stateService stop Nov 02 12:23:48 volumio volumio[2737]: info: CoreStateMachine::syncState currentStatus stop Nov 02 12:23:48 volumio volumio[2737]: info: CoreStateMachine::pushState Nov 02 12:23:48 volumio volumio[2737]: info: CoreCommandRouter::volumioPushState Nov 02 12:23:48 volumio volumio[2737]: info: No code Nov 02 12:23:48 volumio volumio[2737]: info: CoreStateMachine::pushState Nov 02 12:23:48 volumio volumio[2737]: info: CoreCommandRouter::volumioPushState Nov 02 12:23:48 volumio volumio[2737]: info: ------------------------------ 65ms Nov 02 12:23:48 volumio volumio[2737]: info: [ASDebug] CurState: PrevState: stop Nov 02 12:23:48 volumio volumio[2737]: info: [ASDebug] Togle GPIO: ON Nov 02 12:23:48 volumio volumio[2737]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 12:23:48 volumio volumio[2737]: Error: ESPIPE: invalid seek, write Nov 02 12:23:48 volumio volumio[2737]: at Object.writeSync (fs.js:711:3) Nov 02 12:23:48 volumio volumio[2737]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Nov 02 12:23:48 volumio volumio[2737]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) Nov 02 12:23:48 volumio volumio[2737]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) Nov 02 12:23:48 volumio volumio[2737]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 02 12:23:48 volumio volumio[2737]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) Nov 02 12:23:48 volumio volumio[2737]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) Nov 02 12:23:48 volumio volumio[2737]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 02 12:23:48 volumio volumio[2737]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 02 12:23:48 volumio volumio[2737]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) Nov 02 12:23:48 volumio volumio[2737]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 02 12:23:48 volumio volumio[2737]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) Nov 02 12:23:48 volumio volumio[2737]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) Nov 02 12:23:48 volumio volumio[2737]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) Nov 02 12:23:48 volumio volumio[2737]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) Nov 02 12:23:48 volumio volumio[2737]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { Nov 02 12:23:48 volumio volumio[2737]: errno: -29, Nov 02 12:23:48 volumio volumio[2737]: syscall: 'write', Nov 02 12:23:48 volumio volumio[2737]: code: 'ESPIPE' Nov 02 12:23:48 volumio volumio[2737]: } Nov 02 12:23:48 volumio volumio[2737]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 12:23:48 volumio sudo[3165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 12:22 Nov 02 12:23:48 volumio sudo[3165]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:49 volumio sudo[3165]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:49 volumio volumio-remote-updater[644]: [2025-11-02 12:23:49] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Nov 02 12:23:49 volumio volumio-remote-updater[644]: [2025-11-02 12:23:49] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Nov 02 12:23:49 volumio go-librespot[2984]: time="2025-11-02T12:23:49+01:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Nov 02 12:23:49 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Nov 02 12:23:49 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Nov 02 12:23:49 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Nov 02 12:23:49 volumio systemd[1]: Started dynamicswap service. Nov 02 12:23:49 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 02 12:23:49 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Nov 02 12:23:49 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 2. Nov 02 12:23:49 volumio systemd[1]: Started dynamicswap service. Nov 02 12:23:49 volumio systemd[1]: Stopped Volumio Backend Module. Nov 02 12:23:49 volumio systemd[1]: Started Volumio Backend Module. Nov 02 12:23:49 volumio systemd[1]: dynamicswap.service: Succeeded. Nov 02 12:23:50 volumio volumio[3179]: info: ------------------------------------------- Nov 02 12:23:50 volumio volumio[3179]: info: ----- Volumio3 ---- Nov 02 12:23:50 volumio volumio[3179]: info: ------------------------------------------- Nov 02 12:23:50 volumio volumio[3179]: info: ----- System startup ---- Nov 02 12:23:50 volumio volumio[3179]: info: ------------------------------------------- Nov 02 12:23:51 volumio volumio[3179]: info: MYVOLUMIO Environment detected Nov 02 12:23:51 volumio volumio[3179]: info: Plugin folders cleanup Nov 02 12:23:51 volumio volumio[3179]: info: Scanning into folder /volumio/app/plugins/ Nov 02 12:23:51 volumio volumio[3179]: info: Scanning category audio_interface Nov 02 12:23:51 volumio volumio[3179]: info: Scanning category miscellanea Nov 02 12:23:51 volumio volumio[3179]: info: Scanning category music_service Nov 02 12:23:51 volumio volumio[3179]: info: Scanning category plugins.json Nov 02 12:23:51 volumio volumio[3179]: info: Scanning category system_controller Nov 02 12:23:51 volumio volumio[3179]: info: Scanning category user_interface Nov 02 12:23:51 volumio volumio[3179]: info: Scanning into folder /data/plugins/ Nov 02 12:23:51 volumio volumio[3179]: info: Scanning category audio_interface Nov 02 12:23:51 volumio volumio[3179]: info: Scanning category music_service Nov 02 12:23:51 volumio volumio[3179]: info: Scanning category system_controller Nov 02 12:23:51 volumio volumio[3179]: info: Scanning category user_interface Nov 02 12:23:51 volumio volumio[3179]: info: Plugin folders cleanup completed Nov 02 12:23:51 volumio volumio[3179]: info: ------------------------------------------- Nov 02 12:23:51 volumio volumio[3179]: info: ----- Core plugins startup ---- Nov 02 12:23:51 volumio volumio[3179]: info: ------------------------------------------- Nov 02 12:23:51 volumio volumio[3179]: info: Loading plugins from folder /volumio/app/plugins/ Nov 02 12:23:51 volumio volumio[3179]: info: Adding plugin upnp to MyMusic Plugins Nov 02 12:23:51 volumio volumio[3179]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 02 12:23:51 volumio volumio[3179]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 02 12:23:51 volumio volumio[3179]: info: Loading plugins from folder /data/plugins/ Nov 02 12:23:51 volumio volumio[3179]: info: Loading plugin "system"... Nov 02 12:23:51 volumio volumio[3179]: info: Loading plugin "ampswitch"... Nov 02 12:23:51 volumio volumio[3179]: info: Applying required configuration parameters for plugin ampswitch Nov 02 12:23:51 volumio volumio[3179]: info: Loading plugin "appearance"... Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "network"... Nov 02 12:23:52 volumio volumio[3179]: info: Refreshing Cached IP Addresses Nov 02 12:23:52 volumio sudo[3207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 12:23:52 volumio sudo[3207]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:52 volumio sudo[3207]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:52 volumio sudo[3209]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 12:23:52 volumio sudo[3209]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:52 volumio sudo[3209]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "services"... Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "alsa_controller"... Nov 02 12:23:52 volumio sudo[3218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 02 12:23:52 volumio sudo[3218]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:52 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "wizard"... Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "networkfs"... Nov 02 12:23:52 volumio volumio[3179]: info: Starting Udev Watcher for removable devices Nov 02 12:23:52 volumio sudo[3235]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:23:52 volumio sudo[3235]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:52 volumio volumio[3179]: info: Ignoring mount for partition: boot Nov 02 12:23:52 volumio volumio[3179]: info: Ignoring mount for partition: volumio Nov 02 12:23:52 volumio volumio[3179]: info: Ignoring mount for partition: volumio_data Nov 02 12:23:52 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "volumio_command_line_client"... Nov 02 12:23:52 volumio sudo[3235]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "upnp"... Nov 02 12:23:52 volumio volumio[3179]: info: [1762082632313] Starting Upmpd Daemon Nov 02 12:23:52 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "my_music"... Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "mpd"... Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "upnp_browser"... Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "alarm-clock"... Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "airplay_emulation"... Nov 02 12:23:52 volumio volumio[3179]: info: Starting Shairport Sync Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "last_100"... Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "webradio"... Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "i2s_dacs"... Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "volumiodiscovery"... Nov 02 12:23:52 volumio volumio[3179]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 12:23:52 volumio volumio[3179]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 12:23:52 volumio volumio[3179]: *** WARNING *** For more information see Nov 02 12:23:52 volumio volumio[3179]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 12:23:52 volumio volumio[3179]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 12:23:52 volumio volumio[3179]: *** WARNING *** For more information see Nov 02 12:23:52 volumio node[3179]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 02 12:23:52 volumio node[3179]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 12:23:52 volumio node[3179]: *** WARNING *** For more information see Nov 02 12:23:52 volumio node[3179]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 02 12:23:52 volumio node[3179]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 02 12:23:52 volumio node[3179]: *** WARNING *** For more information see Nov 02 12:23:52 volumio volumio[3179]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 02 12:23:52 volumio volumio[3179]: info: Discovery: Started advertising with name: Volumio Nov 02 12:23:52 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 02 12:23:52 volumio volumio[3179]: info: Loading plugin "spop"... Nov 02 12:23:53 volumio volumio[3179]: info: Plugin youtube2 is not enabled Nov 02 12:23:53 volumio volumio[3179]: info: Plugin ytcr is not enabled Nov 02 12:23:53 volumio volumio[3179]: info: Loading plugin "ytmusic"... Nov 02 12:23:54 volumio volumio-remote-updater[644]: [2025-11-02 12:23:54] [connect] Successful connection Nov 02 12:23:55 volumio volumio[3179]: info: Loading plugin "outputs"... Nov 02 12:23:55 volumio volumio[3179]: info: Loading plugin "albumart"... Nov 02 12:23:55 volumio volumio[3179]: info: Plugin example_plugin is not enabled Nov 02 12:23:55 volumio volumio[3179]: info: Loading plugin "inputs"... Nov 02 12:23:55 volumio volumio[3179]: info: Loading plugin "updater_comm"... Nov 02 12:23:55 volumio volumio[3179]: info: Plugin mpdemulation is not enabled Nov 02 12:23:55 volumio volumio[3179]: info: Loading plugin "rest_api"... Nov 02 12:23:55 volumio volumio[3179]: info: Loading plugin "websocket"... Nov 02 12:23:55 volumio volumio[3179]: info: Starting Socket.io Server version 2.3.0 Nov 02 12:23:55 volumio volumio[3179]: info: Loading plugin "fusiondsp"... Nov 02 12:23:55 volumio volumio[3179]: Forking 3 albumart workers Nov 02 12:23:55 volumio sudo[3218]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:55 volumio volumio[3179]: info: Applying required configuration parameters for plugin fusiondsp Nov 02 12:23:55 volumio volumio[3179]: info: Plugin mpdoutput is not enabled Nov 02 12:23:55 volumio volumio[3179]: info: Loading plugin "80s80s"... Nov 02 12:23:55 volumio volumio[3179]: info: Applying required configuration parameters for plugin 80s80s Nov 02 12:23:55 volumio volumio[3179]: info: [1762082635820] [80s80s] API delay: 30 Nov 02 12:23:55 volumio volumio[3179]: info: Plugin minidlna is not enabled Nov 02 12:23:55 volumio volumio[3179]: info: Loading plugin "peppyspectrum"... Nov 02 12:23:56 volumio volumio[3179]: info: Loading plugin "touch_display"... Nov 02 12:23:56 volumio volumio[3179]: Starting albumart workers Nov 02 12:23:56 volumio volumio[3179]: Starting albumart workers Nov 02 12:23:56 volumio volumio[3179]: Starting albumart workers Nov 02 12:23:56 volumio volumio[3179]: info: Applying required configuration parameters for plugin touch_display Nov 02 12:23:56 volumio volumio[3179]: info: Loading i18n strings for locale pl Nov 02 12:23:56 volumio volumio[3179]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Nov 02 12:23:56 volumio volumio[3179]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Nov 02 12:23:56 volumio volumio[3179]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Nov 02 12:23:56 volumio volumio[3179]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Nov 02 12:23:56 volumio volumio[3179]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 02 12:23:56 volumio volumio[3179]: Updating browse sources language Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::initPlayerControls Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 12:23:56 volumio volumio[3179]: Express server listening on port 3000 Nov 02 12:23:56 volumio volumio[3179]: [Metrics] WebUI: 6s 128.12ms Nov 02 12:23:56 volumio volumio[3179]: info: CoreStateMachine::resetVolumioState Nov 02 12:23:56 volumio volumio[3179]: info: CoreStateMachine::getcurrentVolume Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 12:23:56 volumio volumio[3179]: info: Volumio Network Manager: Network status updated: 2 Nov 02 12:23:56 volumio volumio[3179]: info: Cannot mount NAS asustor at system boot, trial number 1 ,retrying in 5 seconds Nov 02 12:23:56 volumio volumio[3179]: verbose: New Socket.io Connection to 192.168.0.111 from 192.168.0.164 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Nov 02 12:23:56 volumio volumio[3179]: verbose: New Socket.io Connection to 192.168.0.111 from 192.168.0.164 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Nov 02 12:23:56 volumio volumio[3179]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Nov 02 12:23:56 volumio volumio-remote-updater[644]: [2025-11-02 12:23:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1762082634 101 Nov 02 12:23:56 volumio volumio[3179]: 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: 4 Nov 02 12:23:56 volumio volumio[3179]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Nov 02 12:23:56 volumio volumio[3179]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Nov 02 12:23:56 volumio volumio[3179]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:56 volumio volumio[3179]: info: Reloading queue from file Nov 02 12:23:56 volumio volumio[3179]: info: CoreStateMachine::setRepeat false single undefined Nov 02 12:23:56 volumio volumio[3179]: info: CoreStateMachine::pushState Nov 02 12:23:56 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::volumioPushState Nov 02 12:23:56 volumio volumio[3179]: info: CoreStateMachine::setRandom false Nov 02 12:23:56 volumio volumio[3179]: info: CoreStateMachine::pushState Nov 02 12:23:56 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::volumioPushState Nov 02 12:23:56 volumio volumio[3179]: info: Setting Device type: Raspberry PI Nov 02 12:23:56 volumio volumio[3179]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Nov 02 12:23:56 volumio volumio[3179]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c03112 Nov 02 12:23:56 volumio volumio[3179]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Nov 02 12:23:56 volumio volumio[3179]: verbose: New Socket.io Connection to 192.168.0.111 from 192.168.0.164 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/141.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Nov 02 12:23:56 volumio volumio[3179]: info: VolumeController:: Volume=79 Mute =false Nov 02 12:23:56 volumio volumio[3179]: info: CoreStateMachine::pushState Nov 02 12:23:56 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::volumioPushState Nov 02 12:23:56 volumio volumio[3179]: info: CoreStateMachine::updateTrackBlock Nov 02 12:23:56 volumio volumio[3179]: info: CorePlayQueue::getTrackBlock Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::volumioRetrievevolume Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:56 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:56 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::volumioGetQueue Nov 02 12:23:56 volumio volumio[3179]: info: CoreStateMachine::getQueue Nov 02 12:23:56 volumio volumio[3179]: info: CorePlayQueue::getQueue Nov 02 12:23:56 volumio volumio[3179]: info: Listing playlists Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 12:23:56 volumio volumio[3179]: info: Received Get System Info Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 12:23:56 volumio volumio[3179]: info: Discovery: Getting this device information Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:56 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 12:23:56 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:56 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:57 volumio volumio[3179]: info: VolumeController:: Volume=79 Mute =false Nov 02 12:23:57 volumio volumio[3179]: info: CoreStateMachine::pushState Nov 02 12:23:57 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::volumioPushState Nov 02 12:23:57 volumio volumio[3179]: info: Discovery: adding b4632d2c-ae2b-4b74-93e2-04ef0f1277cd Nov 02 12:23:57 volumio volumio[3179]: info: Discovery: Found device Volumio Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:57 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:57 volumio volumio[3179]: info: Completed loading Core Plugins Nov 02 12:23:57 volumio volumio[3179]: info: Preparing to generate the ALSA configuration file Nov 02 12:23:57 volumio volumio[3179]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 02 12:23:57 volumio volumio[3179]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 12:23:57 volumio volumio[3179]: info: Reading ALSA contributions from plugins. Nov 02 12:23:57 volumio volumio[3179]: info: Asound.conf file unchanged, so no further update is needed Nov 02 12:23:57 volumio volumio[3179]: info: Output device has changed, restarting MPD Nov 02 12:23:57 volumio sudo[3309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:23:57 volumio sudo[3309]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:57 volumio sudo[3309]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:57 volumio volumio[3179]: info: Output device has changed, restarting Shairport Sync Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:57 volumio sudo[3312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:23:57 volumio sudo[3312]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:57 volumio systemd[1]: Stopping Music Player Daemon... Nov 02 12:23:57 volumio systemd[1]: mpd.service: Succeeded. Nov 02 12:23:57 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 12:23:57 volumio volumio[3179]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:23:57 volumio volumio[3179]: info: ___________ START PLUGINS ___________ Nov 02 12:23:57 volumio systemd[1]: Starting Music Player Daemon... Nov 02 12:23:57 volumio volumio[3179]: info: ControllerMpd::onStart: Initializing MPD Nov 02 12:23:57 volumio volumio[3179]: info: Creating MPD Configuration file Nov 02 12:23:57 volumio sudo[3331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:23:57 volumio sudo[3331]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:23:57 volumio volumio[3179]: info: [1762082637557] CoreMusicLibrary::Adding element Serwery Mediów Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:57 volumio sudo[3331]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:57 volumio sudo[3334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:23:57 volumio sudo[3334]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:57 volumio sudo[3317]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:23:57 volumio sudo[3317]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:57 volumio sudo[3317]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:57 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 02 12:23:57 volumio systemd[1]: mpd.service: Succeeded. Nov 02 12:23:57 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 12:23:57 volumio systemd[1]: Starting Music Player Daemon... Nov 02 12:23:57 volumio volumio[3179]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:57 volumio sudo[3339]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:23:57 volumio volumio[3179]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:23:57 volumio sudo[3339]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:23:57 volumio volumio[3179]: info: [1762082637671] CoreMusicLibrary::Adding element Last_100 Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:23:57 volumio volumio[3179]: info: [1762082637674] CoreMusicLibrary::Adding element Webradio Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:57 volumio sudo[3339]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:23:57 volumio volumio[3179]: info: Initializing BBC Radios Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:57 volumio volumio[3179]: info: Creating Spotify config file Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:23:57 volumio volumio[3179]: info: [1762082637812] CoreMusicLibrary::Adding element YouTube Music Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:57 volumio volumio[3179]: Cannot find translation for source YouTube Music Nov 02 12:23:57 volumio volumio[3179]: info: Loading i18n strings for locale pl Nov 02 12:23:57 volumio volumio[3179]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Nov 02 12:23:57 volumio volumio[3179]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Nov 02 12:23:57 volumio volumio[3179]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Nov 02 12:23:57 volumio volumio[3179]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Nov 02 12:23:57 volumio volumio[3179]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:57 volumio volumio[3179]: info: FusionDsp - mixtype--------------------- Hardware Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:23:57 volumio volumio[3179]: info: [1762082637834] CoreMusicLibrary::Adding element 80s80s Radio Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:23:57 volumio volumio[3179]: Cannot find translation for source YouTube Music Nov 02 12:23:57 volumio volumio[3179]: Cannot find translation for source 80s80s Radio Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 02 12:23:57 volumio volumio[3179]: info: Loading i18n strings for locale pl Nov 02 12:23:57 volumio volumio[3179]: info: Plugin peppyspectrum has duplicated i18n key APPLY. It is ignored. Nov 02 12:23:57 volumio volumio[3179]: info: Plugin peppyspectrum has duplicated i18n key SAVE. It is ignored. Nov 02 12:23:57 volumio volumio[3179]: info: Plugin peppyspectrum has duplicated i18n key CLICK_TO_UPDATE_LIST. It is ignored. Nov 02 12:23:57 volumio volumio[3179]: info: Plugin peppyspectrum has duplicated i18n key LIST_SUCCESS_UPDATED. It is ignored. Nov 02 12:23:57 volumio volumio[3179]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 02 12:23:57 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:23:57 volumio volumio[3179]: info: Volumio Calling Home Nov 02 12:23:57 volumio volumio[3179]: info: Preparing to generate the ALSA configuration file Nov 02 12:23:58 volumio sudo[3361]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Nov 02 12:23:58 volumio sudo[3361]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:58 volumio sudo[3364]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Nov 02 12:23:58 volumio sudo[3364]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:58 volumio sudo[3361]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:58 volumio sudo[3367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Nov 02 12:23:58 volumio sudo[3367]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:58 volumio systemd[1]: Reloading. Nov 02 12:23:58 volumio volumio[3179]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 02 12:23:58 volumio volumio[3179]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 12:23:58 volumio volumio[3179]: info: Reading ALSA contributions from plugins. Nov 02 12:23:58 volumio volumio[3179]: error: touch_display: Error detecting backlight interface: Error: ENOENT: no such file or directory, scandir '/sys/class/backlight/' Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:58 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:58 volumio volumio[3179]: info: [ASDebug] CurState: stop PrevState: na Nov 02 12:23:58 volumio volumio[3179]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 02 12:23:58 volumio volumio[3179]: info: [ASDebug] CurState: stop PrevState: na Nov 02 12:23:58 volumio volumio[3179]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Nov 02 12:23:58 volumio volumio[3179]: info: touch_display: systemctl stop getty@tty1.service succeeded. Nov 02 12:23:58 volumio volumio[3179]: info: MPD Permissions set Nov 02 12:23:58 volumio volumio[3179]: info: MPD Permissions set Nov 02 12:23:58 volumio volumio[3179]: info: Volumio called home Nov 02 12:23:58 volumio volumio[3179]: info: Spotify config file written Nov 02 12:23:58 volumio volumio[3179]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Nov 02 12:23:58 volumio volumio[3179]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Nov 02 12:23:58 volumio sudo[3383]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 02 12:23:58 volumio sudo[3383]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:23:58 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:58 volumio 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. Nov 02 12:23:58 volumio volumio[3179]: info: No need to fix Spotify hosts Nov 02 12:23:58 volumio volumio[3179]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Nov 02 12:23:58 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:23:58 volumio sudo[3394]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Nov 02 12:23:58 volumio sudo[3394]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:58 volumio sudo[3394]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:58 volumio mpd[3346]: Nov 02 12:23 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 12:23:58 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:23:58 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:23:58 volumio systemd[1]: Started Music Player Daemon. Nov 02 12:23:58 volumio sudo[3312]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:58 volumio sudo[3334]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:58 volumio sudo[3364]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:58 volumio systemd[1]: Reloading. Nov 02 12:23:58 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:23:59 volumio 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. Nov 02 12:23:59 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:23:59 volumio volumio[3179]: info: [ASDebug] Togle GPIO: OFF Nov 02 12:23:59 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:23:59 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:23:59 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:23:59 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:23:59 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:23:59 volumio volumio[3179]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Nov 02 12:23:59 volumio volumio[3179]: info: touch_display: systemctl disable getty@tty1.service succeeded. Nov 02 12:23:59 volumio systemd[1]: Stopping go-librespot Daemon... Nov 02 12:23:59 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Nov 02 12:23:59 volumio sudo[3367]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:59 volumio systemd[1]: go-librespot-daemon.service: Succeeded. Nov 02 12:23:59 volumio systemd[1]: Stopped go-librespot Daemon. Nov 02 12:23:59 volumio systemd[1]: Started go-librespot Daemon. Nov 02 12:23:59 volumio go-librespot[3412]: go-librespot daemon starting... Nov 02 12:23:59 volumio sudo[3383]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:59 volumio go-librespot[3412]: time="2025-11-02T12:23:59+01:00" level=info msg="running go-librespot 0.4.0" Nov 02 12:23:59 volumio go-librespot[3412]: time="2025-11-02T12:23:59+01:00" level=debug msg="app state loaded" Nov 02 12:23:59 volumio go-librespot[3412]: time="2025-11-02T12:23:59+01:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 02 12:23:59 volumio volumio[3179]: info: Starting Shairport Sync Nov 02 12:23:59 volumio volumio[3179]: info: Starting Shairport Sync Nov 02 12:23:59 volumio sudo[3422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:23:59 volumio sudo[3422]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:59 volumio volumio[3179]: info: Starting Shairport Sync Nov 02 12:23:59 volumio sudo[3425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:23:59 volumio sudo[3425]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 12:23:59 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 12:23:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 12:23:59 volumio sudo[3428]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:23:59 volumio sudo[3428]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:59 volumio volumio[3179]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 02 12:23:59 volumio volumio[3179]: SPOTIFY: BQCFNLssagKf4J5ufY47bC02XbN3uFyccLmrXNp7TpQD_R5w9EouG7vTX2WGgBHn6fl6V9DjYl0jMV-u4RFez0ExpNY7_XDE8nYvVyRn-oj04_KmK-ncUkKpRv41pBZBAMurZ6mj-lfeycIQhOVLTM91Vahzz5IE64mVyz-l-xeJICTAul2KQd8hjyoZYurzT3_ENdXCoRc5Uk7axzaHrcEydWOUV4Bo9ht69wl4Laj5NpfeMIJOHrC8UPFBMvML-qR949wLQA8CRpt0EmjBejQ Nov 02 12:23:59 volumio volumio[3179]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Nov 02 12:23:59 volumio volumio[3179]: info: New Spotify access token = BQCFNLssagKf4J5ufY47bC02XbN3uFyccLmrXNp7TpQD_R5w9EouG7vTX2WGgBHn6fl6V9DjYl0jMV-u4RFez0ExpNY7_XDE8nYvVyRn-oj04_KmK-ncUkKpRv41pBZBAMurZ6mj-lfeycIQhOVLTM91Vahzz5IE64mVyz-l-xeJICTAul2KQd8hjyoZYurzT3_ENdXCoRc5Uk7axzaHrcEydWOUV4Bo9ht69wl4Laj5NpfeMIJOHrC8UPFBMvML-qR949wLQA8CRpt0EmjBejQ Nov 02 12:23:59 volumio volumio[3179]: info: Spotify credentials grant success - running version from March 24, 2019 Nov 02 12:23:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 12:23:59 volumio sudo[3422]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:59 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 12:23:59 volumio systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Nov 02 12:23:59 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 12:23:59 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 12:23:59 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 12:23:59 volumio sudo[3425]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:59 volumio sudo[3428]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:59 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:23:59 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:23:59 volumio volumio[3179]: info: touch_display: systemctl daemon-reload succeeded. Nov 02 12:23:59 volumio go-librespot[3412]: time="2025-11-02T12:23:59+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]" Nov 02 12:23:59 volumio go-librespot[3412]: time="2025-11-02T12:23:59+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]" Nov 02 12:23:59 volumio go-librespot[3412]: time="2025-11-02T12:23:59+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]" Nov 02 12:23:59 volumio go-librespot[3412]: time="2025-11-02T12:23:59+01:00" level=info msg="zeroconf server listening on port 33499" Nov 02 12:23:59 volumio sudo[3437]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Nov 02 12:23:59 volumio sudo[3437]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:23:59 volumio sudo[3437]: pam_unix(sudo:session): session closed for user root Nov 02 12:23:59 volumio volumio[3179]: error: MPD error: The expression evaluated to a falsy value: Nov 02 12:23:59 volumio volumio[3179]: assert.ok(self.idling) Nov 02 12:23:59 volumio volumio[3179]: error: The expression evaluated to a falsy value: Nov 02 12:23:59 volumio volumio[3179]: assert.ok(self.idling) Nov 02 12:23:59 volumio go-librespot[3412]: time="2025-11-02T12:23:59+01:00" level=debug msg="obtained new client token: AABpTNKIWyX2YNYngMs2OPAFF/AjYZMuEJ81kil9hD+5PMnbNWgzCZOesaau/VX19vVj7h4IqJ8E/vXy5j+Mnw6s4evzNV3jIrpCQCbN4CFnZD39/4dxu8iQmBbb6yIBu3MIWaNkoaP9/ArawgchUT+txZmiauL3eTPhgSOAvkcm0DLdKjTtNjEgs2E3I5PwfP5uIOzE4yN2hJ2zK8wD1Cfdg1y6Bdv4G7EfJ130zN6+xO5DhZ5NbFLJ/hU=" Nov 02 12:23:59 volumio volumio[3179]: info: Shairport-Sync Started Nov 02 12:23:59 volumio volumio[3179]: Error adding Membership: Error: addMembership EINVAL Nov 02 12:23:59 volumio volumio[3179]: info: Shairport-Sync Started Nov 02 12:23:59 volumio volumio[3179]: info: Shairport-Sync Started Nov 02 12:23:59 volumio volumio[3179]: info: MPD running with PID3346 Nov 02 12:23:59 volumio volumio[3179]: ,establishing connection Nov 02 12:23:59 volumio go-librespot[3412]: time="2025-11-02T12:23:59+01:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Nov 02 12:23:59 volumio volumio[3179]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 02 12:23:59 volumio volumio[3179]: 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 Nov 02 12:23:59 volumio volumio[3179]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Nov 02 12:23:59 volumio volumio[3179]: info: camilladsp service started and running in background, instance 1 Nov 02 12:23:59 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:23:59 volumio go-librespot[3412]: time="2025-11-02T12:23:59+01:00" level=debug msg="completed keyexchange" Nov 02 12:23:59 volumio go-librespot[3412]: time="2025-11-02T12:23:59+01:00" level=debug msg="completed challenge" Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 <-AAAAAAAAAAAAA Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp loaded Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=info msg="authenticated AP" username="an***75" Nov 02 12:24:00 volumio sudo[3456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 02 12:24:00 volumio sudo[3456]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 02 12:24:00 volumio volumio[3179]: info: Adding Signal Path Element [object Object] Nov 02 12:24:00 volumio volumio[3179]: info: Adding fusiondspeq DSP Signal Path Element Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp - ---- installed callbackRead Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp - Loudness is ON true Nov 02 12:24:00 volumio sudo[3456]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:00 volumio volumio[3179]: info: Asound.conf file unchanged, so no further update is needed Nov 02 12:24:00 volumio volumio[3179]: info: Output device has changed, restarting MPD Nov 02 12:24:00 volumio sudo[3459]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:24:00 volumio sudo[3459]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio sudo[3459]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:00 volumio volumio[3179]: info: Output device has changed, restarting Shairport Sync Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:00 volumio sudo[3462]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:24:00 volumio sudo[3462]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio systemd[1]: Stopping Music Player Daemon... Nov 02 12:24:00 volumio systemd[1]: mpd.service: Succeeded. Nov 02 12:24:00 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 12:24:00 volumio volumio[3179]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:24:00 volumio volumio[3179]: info: Preparing to generate the ALSA configuration file Nov 02 12:24:00 volumio volumio[3179]: error: updateQueue error: null Nov 02 12:24:00 volumio volumio[3179]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Nov 02 12:24:00 volumio volumio[3179]: info: touch_display: Volumio Kiosk started. Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:24:00 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:24:00 volumio volumio[3179]: info: Completed starting Core Plugins Nov 02 12:24:00 volumio volumio[3179]: info: ------------------------------------------- Nov 02 12:24:00 volumio volumio[3179]: info: ----- MyVolumio plugins startup ---- Nov 02 12:24:00 volumio volumio[3179]: info: ------------------------------------------- Nov 02 12:24:00 volumio volumio[3179]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 02 12:24:00 volumio volumio[3179]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 02 12:24:00 volumio volumio[3179]: info: The plugin peppyspectrum has an ALSA contribution file peppy_in.peppy_out.6.conf Nov 02 12:24:00 volumio volumio[3179]: info: Reading ALSA contributions from plugins. Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=info msg="authenticated Login5" username="an***75" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="initializing zeroconf session" username="an***75" Nov 02 12:24:00 volumio volumio[3179]: error: MPD error: Error: write EPIPE Nov 02 12:24:00 volumio volumio[3179]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Nov 02 12:24:00 volumio systemd[1]: Starting Music Player Daemon... Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:24:00 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:24:00 volumio volumio[3179]: info: MPD Permissions set Nov 02 12:24:00 volumio volumio[3179]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp - {"Reload":{"result":"Ok"}} Nov 02 12:24:00 volumio volumio[3179]: info: touch_display: X display number found: 0 Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio sudo[3467]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:24:00 volumio sudo[3467]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio sudo[3467]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:00 volumio volumio[3179]: info: Starting Shairport Sync Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="dealer connection opened" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=trace msg="starting accesspoint recv loop" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=trace msg="starting dealer recv loop" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=trace msg="received accesspoint ping" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="received connection id: YTJmNjQwNmMtYjA4...MDgyM0FENTI3RQ==" Nov 02 12:24:00 volumio sudo[3478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:24:00 volumio sudo[3478]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 12:24:00 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 12:24:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=trace msg="received accesspoint pong ack" Nov 02 12:24:00 volumio volumio[3179]: SPOTIFY: User informations: {"country":"PL","display_name":"anzio75","email":"anzio75@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/anzio75"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/anzio75","id":"anzio75","images":[],"product":"free","type":"user","uri":"spotify:user:anzio75"} Nov 02 12:24:00 volumio volumio[3179]: info: Spotify Successfully logged in Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 02 12:24:00 volumio volumio[3179]: info: [1762082640460] CoreMusicLibrary::Adding element Spotify Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:24:00 volumio volumio[3179]: Cannot find translation for source YouTube Music Nov 02 12:24:00 volumio volumio[3179]: Cannot find translation for source 80s80s Radio Nov 02 12:24:00 volumio volumio[3179]: Cannot find translation for source Spotify Nov 02 12:24:00 volumio volumio[3179]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Nov 02 12:24:00 volumio volumio[3179]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Nov 02 12:24:00 volumio volumio[3179]: info: Asound.conf file unchanged, so no further update is needed Nov 02 12:24:00 volumio volumio[3179]: info: Output device has changed, restarting MPD Nov 02 12:24:00 volumio sudo[3478]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 12:24:00 volumio sudo[3485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 02 12:24:00 volumio sudo[3485]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio volumio[3179]: info: Output device has changed, restarting Shairport Sync Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:00 volumio go-librespot[3412]: time="2025-11-02T12:24:00+01:00" level=debug msg="put connect state because NEW_DEVICE" Nov 02 12:24:00 volumio sudo[3485]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:00 volumio sudo[3489]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 02 12:24:00 volumio sudo[3489]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 02 12:24:00 volumio systemd[1]: mpd.service: Succeeded. Nov 02 12:24:00 volumio systemd[1]: Stopped Music Player Daemon. Nov 02 12:24:00 volumio volumio[3179]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 02 12:24:00 volumio systemd[1]: Starting Music Player Daemon... Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 12:24:00 volumio volumio[3179]: info: MPD Permissions set Nov 02 12:24:00 volumio volumio[3179]: info: touch_display: X display number found: 0 Nov 02 12:24:00 volumio volumio[3179]: info: Shairport-Sync Started Nov 02 12:24:00 volumio sudo[3495]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::volumioGetVisibleSources Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:24:00 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:24:00 volumio sudo[3495]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Nov 02 12:24:00 volumio volumio[3179]: info: Received Get System Info Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Nov 02 12:24:00 volumio volumio[3179]: info: Discovery: Getting this device information Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:24:00 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:24:00 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:24:00 volumio sudo[3495]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:00 volumio volumio[3179]: info: Listing playlists Nov 02 12:24:00 volumio volumio[3179]: info: touch_display: Setting screensaver timeout to 120 seconds. Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:00 volumio volumio[3179]: info: Starting Shairport Sync Nov 02 12:24:00 volumio sudo[3510]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 02 12:24:00 volumio sudo[3510]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:00 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:00 volumio systemd[1]: shairport-sync.service: Succeeded. Nov 02 12:24:00 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:00 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 02 12:24:00 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:24:00 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 02 12:24:00 volumio sudo[3510]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:01 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:24:01 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:24:01 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:24:01 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:24:01 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:24:01 volumio volumio[3179]: info: Shairport-Sync Started Nov 02 12:24:01 volumio mpd[3504]: Nov 02 12:24 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 02 12:24:01 volumio systemd[1]: Started Music Player Daemon. Nov 02 12:24:01 volumio sudo[3462]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:01 volumio sudo[3489]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:01 volumio volumio[3179]: error: MPD error: The expression evaluated to a falsy value: Nov 02 12:24:01 volumio volumio[3179]: assert.ok(self.idling) Nov 02 12:24:01 volumio volumio[3179]: error: The expression evaluated to a falsy value: Nov 02 12:24:01 volumio volumio[3179]: assert.ok(self.idling) Nov 02 12:24:01 volumio volumio[3179]: error: updateQueue error: null Nov 02 12:24:01 volumio sudo[3518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:24:01 volumio sudo[3518]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:01 volumio sudo[3518]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:01 volumio volumio[3179]: info: Cannot mount NAS asustor at system boot, trial number 2 ,retrying in 5 seconds Nov 02 12:24:02 volumio sudo[3537]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 02 12:24:02 volumio sudo[3537]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:02 volumio sudo[3537]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:02 volumio sudo[3540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 02 12:24:02 volumio sudo[3540]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:02 volumio sudo[3540]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:02 volumio sudo[3543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 02 12:24:02 volumio sudo[3543]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:02 volumio sudo[3543]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:02 volumio volumio[3179]: info: Upmpdcli Daemon Started Nov 02 12:24:02 volumio volumio[3179]: info: go-librespot daemon successfully initialized Nov 02 12:24:04 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:04 volumio volumio[3179]: info: [ASDebug] Setting UI defaults Nov 02 12:24:04 volumio volumio[3179]: info: [ASDebug] Port: 24 Nov 02 12:24:04 volumio volumio[3179]: info: [ASDebug] Inverted: false Nov 02 12:24:04 volumio volumio[3179]: info: [ASDebug] Latched: false Nov 02 12:24:04 volumio volumio[3179]: info: [ASDebug] On pulse width: 500 Nov 02 12:24:04 volumio volumio[3179]: info: [ASDebug] Off pulse width: 500 Nov 02 12:24:04 volumio volumio[3179]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Nov 02 12:24:05 volumio volumio[3179]: info: Initializing connection to go-librespot Websocket Nov 02 12:24:05 volumio go-librespot[3412]: time="2025-11-02T12:24:05+01:00" level=debug msg="new websocket client" Nov 02 12:24:05 volumio volumio[3179]: info: Connection to go-librespot Websocket established Nov 02 12:24:06 volumio sudo[3549]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:24:06 volumio sudo[3549]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:06 volumio sudo[3549]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:06 volumio volumio[3179]: info: Cannot mount NAS asustor at system boot, trial number 3 ,retrying in 5 seconds Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Nov 02 12:24:08 volumio volumio[3179]: info: Adding plugin bluetooth to MyMusic Plugins Nov 02 12:24:08 volumio volumio[3179]: info: Adding plugin multiroom to MyMusic Plugins Nov 02 12:24:08 volumio volumio[3179]: info: Adding plugin metavolumio to MyMusic Plugins Nov 02 12:24:08 volumio volumio[3179]: info: Adding plugin cd_controller to MyMusic Plugins Nov 02 12:24:08 volumio volumio[3179]: info: Adding plugin qobuzconnect to MyMusic Plugins Nov 02 12:24:08 volumio volumio[3179]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 02 12:24:08 volumio volumio[3179]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 02 12:24:08 volumio volumio[3179]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Nov 02 12:24:09 volumio volumio[3179]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Nov 02 12:24:09 volumio volumio[3179]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Nov 02 12:24:09 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:09 volumio volumio[3179]: info: Starting MyVolumio Remote Streaming Endpoints Nov 02 12:24:09 volumio volumio[3179]: info: MyVolumio login type: Token Nov 02 12:24:09 volumio volumio[3179]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Nov 02 12:24:09 volumio volumio[3179]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Nov 02 12:24:09 volumio volumio[3179]: info: Starting Streaming Service Transparent Proxy Nov 02 12:24:09 volumio volumio[3179]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Nov 02 12:24:09 volumio volumio[3179]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Nov 02 12:24:09 volumio volumio[3179]: info: Streaming services startup Nov 02 12:24:09 volumio volumio[3179]: info: Starting Streaming Daemon Nov 02 12:24:09 volumio sudo[3560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 12:24:09 volumio sudo[3560]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:09 volumio sudo[3560]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:09 volumio volumio[3179]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Nov 02 12:24:09 volumio volumio[3179]: info: Getting Spotify volume Nov 02 12:24:09 volumio volumio[3179]: error: Cannot start Volumio Streaming Daemon Nov 02 12:24:09 volumio volumio[3179]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 12:24:09 volumio volumio[3179]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 12:24:09 volumio volumio[3179]: info: Spotify volume: 100 Nov 02 12:24:09 volumio volumio[3179]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Nov 02 12:24:09 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:24:09 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:24:09 volumio volumio[3179]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Nov 02 12:24:09 volumio volumio[3179]: SPOTIFY: SPOTIFY VOLUME 100 Nov 02 12:24:09 volumio volumio[3179]: SPOTIFY: VOLUMIO VOLUME 79 Nov 02 12:24:09 volumio volumio[3179]: SPOTIFY: DELTA VOLUME ENOUGH: true Nov 02 12:24:09 volumio volumio[3179]: info: Setting Spotify Volume from Volumio: 79 Nov 02 12:24:10 volumio volumio[3179]: STREAMING PROXY: Starting server on port 3245 Nov 02 12:24:10 volumio volumio[3179]: Node JS runtime: 14 Nov 02 12:24:10 volumio volumio[3179]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Nov 02 12:24:10 volumio volumio[3179]: info: MyVolumio token set successfully Nov 02 12:24:10 volumio volumio[3179]: info: MYVOLUMIO: Adding device Nov 02 12:24:10 volumio volumio[3179]: info: MYVOLUMIO: Evaluating Server Nov 02 12:24:11 volumio volumio[3179]: info: MyVolumio status changed Nov 02 12:24:11 volumio volumio[3179]: info: Streaming services startup Nov 02 12:24:11 volumio volumio[3179]: info: Starting Streaming Daemon Nov 02 12:24:11 volumio volumio[3179]: info: Removing browser output: myVolumio user plan is not superstar Nov 02 12:24:11 volumio volumio[3179]: info: Removing audio output: Nov 02 12:24:11 volumio volumio[3179]: info: Stoppping Tunnel 1 Nov 02 12:24:11 volumio sudo[3585]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 02 12:24:11 volumio sudo[3585]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:11 volumio sudo[3585]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:11 volumio volumio[3179]: SPOTIFY: SETTING SPOTIFY VOLUME 79 Nov 02 12:24:11 volumio sudo[3588]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Nov 02 12:24:11 volumio volumio[3179]: info: Sending Spotify command with payload to local API: /player/volume Nov 02 12:24:11 volumio sudo[3588]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:11 volumio go-librespot[3412]: time="2025-11-02T12:24:11+01:00" level=debug msg="update volume requested to 51772/65535" Nov 02 12:24:11 volumio volumio[3179]: error: Cannot start Volumio Streaming Daemon Nov 02 12:24:11 volumio volumio[3179]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 02 12:24:11 volumio volumio[3179]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 02 12:24:11 volumio sudo[3588]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:11 volumio volumio[3179]: info: Remote SSH Stopped Nov 02 12:24:11 volumio go-librespot[3412]: time="2025-11-02T12:24:11+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Nov 02 12:24:11 volumio go-librespot[3412]: time="2025-11-02T12:24:11+01:00" level=trace msg="emitting websocket event: volume" Nov 02 12:24:11 volumio volumio[3179]: SPOTIFY: received: {"type":"volume","data":{"value":79,"max":100}} Nov 02 12:24:11 volumio volumio[3179]: SPOTIFY: RECEIVED SPOTIFY VOLUME 79 Nov 02 12:24:11 volumio volumio[3179]: info: Setting Geolocation for MyVolumio to eu9 Nov 02 12:24:11 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:11 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:11 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:11 volumio sudo[3591]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:24:11 volumio sudo[3591]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:11 volumio sudo[3591]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:11 volumio volumio[3179]: info: Cannot mount NAS asustor at system boot, trial number 4 ,retrying in 5 seconds Nov 02 12:24:12 volumio volumio[3179]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 12:24:12 volumio volumio[3179]: info: Updating MyVolumio device info Nov 02 12:24:12 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:12 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:12 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:14 volumio volumio[3179]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 12:24:16 volumio volumio[3179]: info: MYVOLUMIO: Adding device Nov 02 12:24:16 volumio volumio[3179]: info: MYVOLUMIO: Evaluating Server Nov 02 12:24:16 volumio sudo[3669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //http://192.168.0.16//music /mnt/NAS/asustor Nov 02 12:24:16 volumio sudo[3669]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 02 12:24:16 volumio sudo[3669]: pam_unix(sudo:session): session closed for user root Nov 02 12:24:16 volumio volumio[3179]: info: Cannot mount NAS at system boot, trial number 4 ,stopping Nov 02 12:24:16 volumio volumio[3179]: info: Setting Geolocation for MyVolumio to eu8 Nov 02 12:24:16 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:16 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:16 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:17 volumio volumio[3179]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Nov 02 12:24:17 volumio volumio[3179]: info: Updating MyVolumio device info Nov 02 12:24:17 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:17 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:17 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:18 volumio volumio[3179]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Nov 02 12:24:23 volumio volumio[3179]: info: CALLMETHOD: system_controller ampswitch saveOptions [object Object] Nov 02 12:24:23 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: ampswitch , saveOptions Nov 02 12:24:23 volumio volumio[3179]: info: [ASDebug] Saving Settings: Port: 2 Nov 02 12:24:23 volumio volumio[3179]: info: [ASDebug] Saving Settings: Inverted: false Nov 02 12:24:23 volumio volumio[3179]: info: [ASDebug] Saving Settings: Delay: 720 Nov 02 12:24:23 volumio volumio[3179]: info: [ASDebug] Saving Settings: Latched: false Nov 02 12:24:23 volumio volumio[3179]: info: [ASDebug] Saving Settings: On Pulse width: 500 Nov 02 12:24:23 volumio volumio[3179]: info: [ASDebug] Saving Settings: Off Pulse width: 500 Nov 02 12:24:24 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Nov 02 12:24:24 volumio volumio[3179]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Nov 02 12:24:24 volumio volumio[3179]: info: CoreCommandRouter::volumioGetState Nov 02 12:24:24 volumio volumio[3179]: info: CorePlayQueue::getTrack 0 Nov 02 12:24:27 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:24:27 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:27 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Nov 02 12:24:27 volumio volumio-remote-updater[644]: No test mode Nov 02 12:24:27 volumio volumio-remote-updater[644]: No alpha test mode Nov 02 12:24:27 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Nov 02 12:24:28 volumio volumio[3179]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Nov 02 12:24:28 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::volumioGetBrowseSources Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 02 12:24:29 volumio volumio[3179]: error: MyVolumio Plugin failed to authenticate in a timely fashion Nov 02 12:24:29 volumio volumio[3179]: info: Completed starting MyVolumio Plugin Nov 02 12:24:29 volumio volumio[3179]: [Metrics] CommandRouter: 38s 954.76ms Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::volumiosetStartupVolume Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::Close All Modals sent Nov 02 12:24:29 volumio volumio[3179]: info: CoreCommandRouter::Close All Modals sent Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 02 12:24:29 volumio volumio[3179]: info: camilladsp stopping service pid 3445... Nov 02 12:24:29 volumio kernel: bcm2835_audio bcm2835_audio: failed to close VCHI service connection (status=-11) Nov 02 12:24:29 volumio volumio[3179]: info: camilladsp service terminated, instance 1 Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - Loudness is ON true Nov 02 12:24:29 volumio volumio[3179]: info: camilladsp service started and running in background, instance 1 Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - ---- read samplerate from file: 44100 Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - If filter freq >samplerate/2 then disable it Nov 02 12:24:29 volumio volumio[3179]: info: FusionDsp - Loudness is ON true Nov 02 12:24:29 volumio volumio[3179]: error: FusionDsp - WebSocket error: [object Object] Nov 02 12:24:30 volumio go-librespot[3412]: time="2025-11-02T12:24:30+01:00" level=trace msg="sent dealer ping" Nov 02 12:24:30 volumio go-librespot[3412]: time="2025-11-02T12:24:30+01:00" level=trace msg="received dealer pong" Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:30 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:24:30 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Nov 02 12:24:30 volumio volumio[3179]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Nov 02 12:24:30 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:24:31 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:24:31 volumio volumio[3179]: info: FusionDsp - no preset used Nov 02 12:24:31 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:24:31 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:24:31 volumio volumio[3179]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Nov 02 12:24:36 volumio volumio[3179]: info: BOOT COMPLETED Nov 02 12:24:44 volumio volumio[3179]: info: Starting Uninstall of plugin system_controller - ampswitch Nov 02 12:24:44 volumio volumio[3179]: info: Uninstalling plugin ampswitch Nov 02 12:24:44 volumio volumio[3179]: info: [ASDebug] Port: 2 Nov 02 12:24:44 volumio volumio[3179]: info: [ASDebug] Inverted: false Nov 02 12:24:44 volumio volumio[3179]: info: [ASDebug] Delay: 720 Nov 02 12:24:53 volumio volumio[3179]: info: [ASDebug] Port: 2 Nov 02 12:24:53 volumio volumio[3179]: info: [ASDebug] Inverted: false Nov 02 12:24:53 volumio volumio[3179]: info: [ASDebug] Delay: 720 Nov 02 12:24:53 volumio volumio[3179]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 12:24:53 volumio volumio[3179]: Error: EBADF: bad file descriptor, close Nov 02 12:24:53 volumio volumio[3179]: at Object.closeSync (fs.js:439:3) Nov 02 12:24:53 volumio volumio[3179]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Nov 02 12:24:53 volumio volumio[3179]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Nov 02 12:24:53 volumio volumio[3179]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Nov 02 12:24:53 volumio volumio[3179]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Nov 02 12:24:53 volumio volumio[3179]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Nov 02 12:24:53 volumio volumio[3179]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Nov 02 12:24:53 volumio volumio[3179]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Nov 02 12:24:53 volumio volumio[3179]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1556:29) Nov 02 12:24:53 volumio volumio[3179]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1401:47) Nov 02 12:24:53 volumio volumio[3179]: at Socket.emit (events.js:315:20) Nov 02 12:24:53 volumio volumio[3179]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Nov 02 12:24:53 volumio volumio[3179]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Nov 02 12:24:53 volumio volumio[3179]: errno: -9, Nov 02 12:24:53 volumio volumio[3179]: syscall: 'close', Nov 02 12:24:53 volumio volumio[3179]: code: 'EBADF' Nov 02 12:24:53 volumio volumio[3179]: } Nov 02 12:24:53 volumio volumio[3179]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 02 12:24:53 volumio sudo[3780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-02 12:23 Nov 02 12:24:53 volumio sudo[3780]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"