-- Logs begin at Mon 2025-04-28 02:22:02 CEST, end at Mon 2025-04-28 02:47:31 CEST. -- Apr 28 02:46:00 volumio volumio[18924]: info: Loading plugin "ytmusic"... Apr 28 02:46:01 volumio volumio[18924]: info: Loading plugin "outputs"... Apr 28 02:46:01 volumio volumio[18924]: info: Loading plugin "albumart"... Apr 28 02:46:01 volumio volumio[18924]: info: Plugin example_plugin is not enabled Apr 28 02:46:01 volumio volumio[18924]: info: Loading plugin "inputs"... Apr 28 02:46:01 volumio volumio[18924]: info: Loading plugin "updater_comm"... Apr 28 02:46:01 volumio volumio[18924]: info: Plugin mpdemulation is not enabled Apr 28 02:46:01 volumio volumio[18924]: info: Loading plugin "rest_api"... Apr 28 02:46:01 volumio volumio[18924]: info: Loading plugin "websocket"... Apr 28 02:46:01 volumio volumio[18924]: info: Starting Socket.io Server version 2.3.0 Apr 28 02:46:01 volumio volumio[18924]: info: Plugin fusiondsp is not enabled Apr 28 02:46:01 volumio volumio[18924]: info: Plugin minidlna is not enabled Apr 28 02:46:01 volumio volumio[18924]: info: Loading i18n strings for locale de Apr 28 02:46:01 volumio volumio[18924]: Updating browse sources language Apr 28 02:46:01 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:01 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:01 volumio volumio[18924]: info: CoreCommandRouter::initPlayerControls Apr 28 02:46:01 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:01 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:01 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:01 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:01 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:01 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:01 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:01 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:01 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:01 volumio volumio[18924]: Express server listening on port 3000 Apr 28 02:46:01 volumio volumio[18924]: [Metrics] WebUI: 7s 353.33ms Apr 28 02:46:02 volumio volumio[18924]: Forking 3 albumart workers Apr 28 02:46:02 volumio volumio[18924]: info: CoreStateMachine::resetVolumioState Apr 28 02:46:02 volumio volumio[18924]: info: CoreStateMachine::getcurrentVolume Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:02 volumio volumio[18924]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 28 02:46:02 volumio volumio[18924]: info: Completed loading Core Plugins Apr 28 02:46:02 volumio volumio[18924]: info: Preparing to generate the ALSA configuration file Apr 28 02:46:02 volumio volumio[18924]: info: Volumio Network Manager: Network status updated: 1 Apr 28 02:46:02 volumio volumio-remote-updater[541]: [2025-04-28 02:46:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745801158 101 Apr 28 02:46:02 volumio volumio[18924]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 28 02:46:02 volumio volumio[18924]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 28 02:46:02 volumio volumio[18924]: info: CoreStateMachine::setRepeat false single undefined Apr 28 02:46:02 volumio volumio[18924]: info: CoreStateMachine::pushState Apr 28 02:46:02 volumio volumio[18924]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:02 volumio volumio[18924]: info: CoreStateMachine::setRandom true Apr 28 02:46:02 volumio volumio[18924]: info: CoreStateMachine::pushState Apr 28 02:46:02 volumio volumio[18924]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:02 volumio volumio[18924]: info: Setting Device type: Tinkerboard Apr 28 02:46:02 volumio volumio[18924]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:46:02 volumio volumio[18924]: info: CoreStateMachine::pushState Apr 28 02:46:02 volumio volumio[18924]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:02 volumio volumio[18924]: info: CoreStateMachine::updateTrackBlock Apr 28 02:46:02 volumio volumio[18924]: info: CorePlayQueue::getTrackBlock Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:46:02 volumio volumio[18924]: info: Asound.conf file unchanged, so no further update is needed Apr 28 02:46:02 volumio volumio[18924]: info: Output device has changed, restarting MPD Apr 28 02:46:02 volumio volumio[18924]: info: ___________ START PLUGINS ___________ Apr 28 02:46:02 volumio sudo[19041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:46:02 volumio sudo[19043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:46:02 volumio sudo[19041]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:02 volumio sudo[19043]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:02 volumio systemd[1]: Stopping Music Player Daemon... Apr 28 02:46:02 volumio kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Apr 28 02:46:02 volumio volumio[18924]: info: ControllerMpd::onStart: Initializing MPD Apr 28 02:46:02 volumio volumio[18924]: info: Creating MPD Configuration file Apr 28 02:46:02 volumio sudo[19041]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:02 volumio volumio[18924]: info: [1745801162656] CoreMusicLibrary::Adding element Medienserver Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:02 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:46:02 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:46:02 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:02 volumio volumio[18924]: info: [1745801162688] CoreMusicLibrary::Adding element Last_100 Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:02 volumio volumio[18924]: info: [1745801162691] CoreMusicLibrary::Adding element Webradio Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:02 volumio sudo[19063]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:02 volumio volumio[18924]: info: Initializing BBC Radios Apr 28 02:46:02 volumio sudo[19063]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:02 volumio sudo[19067]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:46:02 volumio sudo[19063]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:02 volumio sudo[19067]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:02 volumio sudo[19068]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:02 volumio volumio[18924]: info: [1745801162885] CoreMusicLibrary::Adding element Mixcloud Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:02 volumio volumio[18924]: Cannot find translation for source Mixcloud Apr 28 02:46:02 volumio sudo[19068]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:02 volumio sudo[19068]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:02 volumio volumio[18924]: info: [1745801162923] CoreMusicLibrary::Adding element SoundCloud Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:02 volumio volumio[18924]: Cannot find translation for source Mixcloud Apr 28 02:46:02 volumio volumio[18924]: Cannot find translation for source SoundCloud Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:02 volumio volumio[18924]: info: [1745801162932] CoreMusicLibrary::Adding element YouTube2 Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:02 volumio volumio[18924]: Cannot find translation for source Mixcloud Apr 28 02:46:02 volumio volumio[18924]: Cannot find translation for source SoundCloud Apr 28 02:46:02 volumio volumio[18924]: Cannot find translation for source YouTube2 Apr 28 02:46:02 volumio volumio[18924]: Starting albumart workers Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:02 volumio volumio[18924]: info: [1745801162995] CoreMusicLibrary::Adding element YouTube Music Apr 28 02:46:02 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:02 volumio volumio[18924]: Cannot find translation for source Mixcloud Apr 28 02:46:02 volumio volumio[18924]: Cannot find translation for source SoundCloud Apr 28 02:46:02 volumio volumio[18924]: Cannot find translation for source YouTube2 Apr 28 02:46:02 volumio volumio[18924]: Cannot find translation for source YouTube Music Apr 28 02:46:03 volumio volumio[18924]: info: Volumio Calling Home Apr 28 02:46:03 volumio volumio[18924]: Starting albumart workers Apr 28 02:46:03 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 28 02:46:03 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:46:03 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:46:03 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:46:03 volumio volumio[18924]: Starting albumart workers Apr 28 02:46:03 volumio sudo[19100]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 28 02:46:03 volumio sudo[19100]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:03 volumio sudo[19100]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:03 volumio volumio[18924]: info: Volumio called home Apr 28 02:46:03 volumio volumio[18924]: info: MPD Permissions set Apr 28 02:46:03 volumio volumio[18924]: info: MPD Permissions set Apr 28 02:46:03 volumio volumio[18924]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:03 volumio volumio[18924]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:03 volumio volumio[18924]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:03 volumio volumio[18924]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:03 volumio volumio[18924]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:03 volumio volumio[18924]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:03 volumio volumio[18924]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:03 volumio volumio[18924]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:03 volumio volumio[18924]: info: Discovery: adding 009929a0-f413-4a13-a312-90def98326b0 Apr 28 02:46:03 volumio volumio[18924]: info: Discovery: Found device Volumio Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:03 volumio volumio[18924]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:03 volumio volumio[18924]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:03 volumio volumio[18924]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 28 02:46:03 volumio volumio[18924]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:03 volumio volumio[18924]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:03 volumio volumio[18924]: info: CoreStateMachine::pushState Apr 28 02:46:03 volumio volumio[18924]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:03 volumio volumio[18924]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:03 volumio volumio[18924]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:03 volumio volumio[18924]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:03 volumio volumio[18924]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:03 volumio volumio[18924]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:03 volumio volumio[18924]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:04 volumio volumio[18924]: info: [yt-cast-receiver] DIAL server listening on port 8098 Apr 28 02:46:04 volumio mpd[19111]: Apr 28 02:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 28 02:46:04 volumio volumio[18924]: info: [ASDebug] Togle GPIO: OFF Apr 28 02:46:04 volumio systemd[1]: Started Music Player Daemon. Apr 28 02:46:04 volumio volumio[18924]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:46:04 volumio sudo[19043]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:04 volumio sudo[19067]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:04 volumio volumio[18924]: Error: EPERM: operation not permitted, write Apr 28 02:46:04 volumio volumio[18924]: at Object.writeSync (fs.js:737:3) Apr 28 02:46:04 volumio volumio[18924]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 28 02:46:04 volumio volumio[18924]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Apr 28 02:46:04 volumio volumio[18924]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Apr 28 02:46:04 volumio volumio[18924]: at listOnTimeout (internal/timers.js:557:17) Apr 28 02:46:04 volumio volumio[18924]: at processTimers (internal/timers.js:500:7) { Apr 28 02:46:04 volumio volumio[18924]: errno: -1, Apr 28 02:46:04 volumio volumio[18924]: syscall: 'write', Apr 28 02:46:04 volumio volumio[18924]: code: 'EPERM' Apr 28 02:46:04 volumio volumio[18924]: } Apr 28 02:46:04 volumio volumio[18924]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:46:05 volumio sudo[19165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 02:45 Apr 28 02:46:05 volumio sudo[19165]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:05 volumio sudo[19165]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:05 volumio volumio-remote-updater[541]: [2025-04-28 02:46:05] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 28 02:46:05 volumio volumio-remote-updater[541]: [2025-04-28 02:46:05] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 28 02:46:05 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 28 02:46:05 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 28 02:46:05 volumio systemd[1]: Started dynamicswap service. Apr 28 02:46:05 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:46:05 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 28 02:46:05 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1017. Apr 28 02:46:05 volumio systemd[1]: Started dynamicswap service. Apr 28 02:46:05 volumio systemd[1]: Stopped Volumio Backend Module. Apr 28 02:46:05 volumio systemd[1]: Started Volumio Backend Module. Apr 28 02:46:05 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:46:06 volumio volumio[19183]: info: ------------------------------------------- Apr 28 02:46:06 volumio volumio[19183]: info: ----- Volumio3 ---- Apr 28 02:46:06 volumio volumio[19183]: info: ------------------------------------------- Apr 28 02:46:06 volumio volumio[19183]: info: ----- System startup ---- Apr 28 02:46:06 volumio volumio[19183]: info: ------------------------------------------- Apr 28 02:46:06 volumio volumio[19183]: info: MYVOLUMIO Environment detected Apr 28 02:46:06 volumio volumio[19183]: info: Plugin folders cleanup Apr 28 02:46:06 volumio volumio[19183]: info: Scanning into folder /volumio/app/plugins/ Apr 28 02:46:06 volumio volumio[19183]: info: Scanning category audio_interface Apr 28 02:46:06 volumio volumio[19183]: info: Scanning category miscellanea Apr 28 02:46:06 volumio volumio[19183]: info: Scanning category music_service Apr 28 02:46:06 volumio volumio[19183]: info: Scanning category plugins.json Apr 28 02:46:06 volumio volumio[19183]: info: Scanning category system_controller Apr 28 02:46:06 volumio volumio[19183]: info: Scanning category user_interface Apr 28 02:46:06 volumio volumio[19183]: info: Scanning into folder /data/plugins/ Apr 28 02:46:06 volumio volumio[19183]: info: Scanning category audio_interface Apr 28 02:46:06 volumio volumio[19183]: info: Scanning category music_service Apr 28 02:46:06 volumio volumio[19183]: info: Scanning category system_controller Apr 28 02:46:06 volumio volumio[19183]: info: Plugin folders cleanup completed Apr 28 02:46:06 volumio volumio[19183]: info: ------------------------------------------- Apr 28 02:46:06 volumio volumio[19183]: info: ----- Core plugins startup ---- Apr 28 02:46:06 volumio volumio[19183]: info: ------------------------------------------- Apr 28 02:46:06 volumio volumio[19183]: info: Loading plugins from folder /volumio/app/plugins/ Apr 28 02:46:06 volumio volumio[19183]: info: Adding plugin upnp to MyMusic Plugins Apr 28 02:46:06 volumio volumio[19183]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 28 02:46:06 volumio volumio[19183]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 28 02:46:07 volumio volumio[19183]: info: Loading plugins from folder /data/plugins/ Apr 28 02:46:07 volumio volumio[19183]: info: Loading plugin "system"... Apr 28 02:46:07 volumio volumio[19183]: info: Loading plugin "ampswitch"... Apr 28 02:46:07 volumio volumio[19183]: info: Applying required configuration parameters for plugin ampswitch Apr 28 02:46:07 volumio volumio[19183]: info: Loading plugin "appearance"... Apr 28 02:46:07 volumio volumio[19183]: info: Loading plugin "network"... Apr 28 02:46:07 volumio volumio[19183]: info: Refreshing Cached IP Addresses Apr 28 02:46:07 volumio sudo[19213]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 02:46:07 volumio volumio[19183]: info: Loading plugin "services"... Apr 28 02:46:07 volumio volumio[19183]: info: Loading plugin "alsa_controller"... Apr 28 02:46:07 volumio sudo[19215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 02:46:07 volumio sudo[19213]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:07 volumio sudo[19215]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:07 volumio sudo[19213]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:07 volumio sudo[19215]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:07 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:07 volumio volumio[19183]: info: Loading plugin "wizard"... Apr 28 02:46:07 volumio volumio[19183]: info: Loading plugin "networkfs"... Apr 28 02:46:07 volumio volumio[19183]: info: Starting Udev Watcher for removable devices Apr 28 02:46:07 volumio volumio[19183]: info: Ignoring mount for partition: BOOT Apr 28 02:46:07 volumio volumio[19183]: info: Ignoring mount for partition: volumio Apr 28 02:46:07 volumio volumio[19183]: info: Ignoring mount for partition: volumio_data Apr 28 02:46:07 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:07 volumio volumio[19183]: info: Loading plugin "volumio_command_line_client"... Apr 28 02:46:07 volumio volumio[19183]: info: Loading plugin "upnp"... Apr 28 02:46:07 volumio volumio[19183]: info: [1745801167964] Starting Upmpd Daemon Apr 28 02:46:07 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:07 volumio volumio[19183]: info: Loading plugin "my_music"... Apr 28 02:46:07 volumio volumio[19183]: info: Loading plugin "mpd"... Apr 28 02:46:08 volumio volumio[19183]: info: Loading plugin "upnp_browser"... Apr 28 02:46:08 volumio volumio[19183]: info: Loading plugin "alarm-clock"... Apr 28 02:46:08 volumio volumio[19183]: info: Plugin airplay_emulation is not enabled Apr 28 02:46:08 volumio volumio[19183]: info: Loading plugin "last_100"... Apr 28 02:46:08 volumio volumio[19183]: info: Loading plugin "webradio"... Apr 28 02:46:08 volumio volumio[19183]: info: Loading plugin "i2s_dacs"... Apr 28 02:46:08 volumio volumio[19183]: info: I2S DAC not set, start Auto-detection Apr 28 02:46:08 volumio volumio[19183]: info: Loading plugin "volumiodiscovery"... Apr 28 02:46:08 volumio volumio[19183]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:08 volumio volumio[19183]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:08 volumio volumio[19183]: *** WARNING *** For more information see Apr 28 02:46:08 volumio volumio[19183]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:08 volumio node[19183]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:08 volumio volumio[19183]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:08 volumio volumio[19183]: *** WARNING *** For more information see Apr 28 02:46:08 volumio node[19183]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:08 volumio node[19183]: *** WARNING *** For more information see Apr 28 02:46:08 volumio node[19183]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:08 volumio node[19183]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:08 volumio node[19183]: *** WARNING *** For more information see Apr 28 02:46:08 volumio volumio[19183]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 28 02:46:08 volumio volumio[19183]: info: Discovery: Started advertising with name: Volumio Apr 28 02:46:08 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:08 volumio volumio[19183]: info: Loading plugin "mixcloud"... Apr 28 02:46:08 volumio volumio[19183]: info: Loading plugin "soundcloud"... Apr 28 02:46:09 volumio volumio[19183]: info: Loading plugin "youtube2"... Apr 28 02:46:10 volumio volumio-remote-updater[541]: [2025-04-28 02:46:10] [connect] Successful connection Apr 28 02:46:10 volumio volumio[19183]: info: Loading plugin "ytcr"... Apr 28 02:46:12 volumio volumio[19183]: info: Loading plugin "ytmusic"... Apr 28 02:46:12 volumio volumio[19183]: info: Loading plugin "outputs"... Apr 28 02:46:12 volumio volumio[19183]: info: Loading plugin "albumart"... Apr 28 02:46:12 volumio volumio[19183]: info: Plugin example_plugin is not enabled Apr 28 02:46:12 volumio volumio[19183]: info: Loading plugin "inputs"... Apr 28 02:46:12 volumio volumio[19183]: info: Loading plugin "updater_comm"... Apr 28 02:46:12 volumio volumio[19183]: info: Plugin mpdemulation is not enabled Apr 28 02:46:12 volumio volumio[19183]: info: Loading plugin "rest_api"... Apr 28 02:46:12 volumio volumio[19183]: info: Loading plugin "websocket"... Apr 28 02:46:12 volumio volumio[19183]: info: Starting Socket.io Server version 2.3.0 Apr 28 02:46:12 volumio volumio[19183]: info: Plugin fusiondsp is not enabled Apr 28 02:46:12 volumio volumio[19183]: info: Plugin minidlna is not enabled Apr 28 02:46:12 volumio volumio[19183]: info: Loading i18n strings for locale de Apr 28 02:46:12 volumio volumio[19183]: Updating browse sources language Apr 28 02:46:12 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:12 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:12 volumio volumio[19183]: info: CoreCommandRouter::initPlayerControls Apr 28 02:46:12 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:12 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:12 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:12 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:12 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:12 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:12 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:12 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:12 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:12 volumio volumio[19183]: Express server listening on port 3000 Apr 28 02:46:12 volumio volumio[19183]: [Metrics] WebUI: 6s 861.10ms Apr 28 02:46:13 volumio volumio[19183]: Forking 3 albumart workers Apr 28 02:46:13 volumio volumio[19183]: info: CoreStateMachine::resetVolumioState Apr 28 02:46:13 volumio volumio[19183]: info: CoreStateMachine::getcurrentVolume Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:13 volumio volumio[19183]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 28 02:46:13 volumio volumio[19183]: info: Completed loading Core Plugins Apr 28 02:46:13 volumio volumio[19183]: info: Preparing to generate the ALSA configuration file Apr 28 02:46:13 volumio volumio[19183]: info: Volumio Network Manager: Network status updated: 1 Apr 28 02:46:13 volumio volumio-remote-updater[541]: [2025-04-28 02:46:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745801170 101 Apr 28 02:46:13 volumio volumio[19183]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 28 02:46:13 volumio volumio[19183]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 28 02:46:13 volumio volumio[19183]: info: CoreStateMachine::setRepeat false single undefined Apr 28 02:46:13 volumio volumio[19183]: info: CoreStateMachine::pushState Apr 28 02:46:13 volumio volumio[19183]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:13 volumio volumio[19183]: info: CoreStateMachine::setRandom true Apr 28 02:46:13 volumio volumio[19183]: info: CoreStateMachine::pushState Apr 28 02:46:13 volumio volumio[19183]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:13 volumio volumio[19183]: info: Setting Device type: Tinkerboard Apr 28 02:46:13 volumio volumio[19183]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:46:13 volumio volumio[19183]: info: CoreStateMachine::pushState Apr 28 02:46:13 volumio volumio[19183]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:13 volumio volumio[19183]: info: CoreStateMachine::updateTrackBlock Apr 28 02:46:13 volumio volumio[19183]: info: CorePlayQueue::getTrackBlock Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:46:13 volumio volumio[19183]: info: Asound.conf file unchanged, so no further update is needed Apr 28 02:46:13 volumio volumio[19183]: info: Output device has changed, restarting MPD Apr 28 02:46:13 volumio volumio[19183]: info: ___________ START PLUGINS ___________ Apr 28 02:46:13 volumio sudo[19303]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:46:13 volumio sudo[19304]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:46:13 volumio kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Apr 28 02:46:13 volumio sudo[19303]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:13 volumio volumio[19183]: info: ControllerMpd::onStart: Initializing MPD Apr 28 02:46:13 volumio volumio[19183]: info: Creating MPD Configuration file Apr 28 02:46:13 volumio sudo[19304]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:13 volumio sudo[19303]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:13 volumio systemd[1]: Stopping Music Player Daemon... Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:13 volumio volumio[19183]: info: [1745801173577] CoreMusicLibrary::Adding element Medienserver Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:13 volumio sudo[19326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:46:13 volumio sudo[19322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:13 volumio sudo[19326]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:13 volumio volumio[19183]: info: [1745801173607] CoreMusicLibrary::Adding element Last_100 Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:13 volumio volumio[19183]: info: [1745801173610] CoreMusicLibrary::Adding element Webradio Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:13 volumio volumio[19183]: info: Initializing BBC Radios Apr 28 02:46:13 volumio sudo[19322]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:13 volumio sudo[19322]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:13 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:46:13 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:46:13 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:46:13 volumio volumio[19183]: Starting albumart workers Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:13 volumio sudo[19339]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 28 02:46:13 volumio volumio[19183]: Starting albumart workers Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:13 volumio volumio[19183]: info: [1745801173791] CoreMusicLibrary::Adding element Mixcloud Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:13 volumio volumio[19183]: Cannot find translation for source Mixcloud Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:13 volumio volumio[19183]: info: [1745801173805] CoreMusicLibrary::Adding element SoundCloud Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:13 volumio volumio[19183]: Cannot find translation for source Mixcloud Apr 28 02:46:13 volumio volumio[19183]: Cannot find translation for source SoundCloud Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:13 volumio volumio[19183]: info: [1745801173813] CoreMusicLibrary::Adding element YouTube2 Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:13 volumio volumio[19183]: Cannot find translation for source Mixcloud Apr 28 02:46:13 volumio volumio[19183]: Cannot find translation for source SoundCloud Apr 28 02:46:13 volumio volumio[19183]: Cannot find translation for source YouTube2 Apr 28 02:46:13 volumio sudo[19339]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:13 volumio sudo[19339]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:13 volumio volumio[19183]: Starting albumart workers Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:13 volumio volumio[19183]: info: [1745801173875] CoreMusicLibrary::Adding element YouTube Music Apr 28 02:46:13 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:13 volumio volumio[19183]: Cannot find translation for source Mixcloud Apr 28 02:46:13 volumio volumio[19183]: Cannot find translation for source SoundCloud Apr 28 02:46:13 volumio volumio[19183]: Cannot find translation for source YouTube2 Apr 28 02:46:13 volumio volumio[19183]: Cannot find translation for source YouTube Music Apr 28 02:46:13 volumio volumio[19183]: info: Volumio Calling Home Apr 28 02:46:14 volumio volumio[19183]: info: Volumio called home Apr 28 02:46:14 volumio volumio[19183]: info: MPD Permissions set Apr 28 02:46:14 volumio volumio[19183]: info: MPD Permissions set Apr 28 02:46:14 volumio volumio[19183]: info: Discovery: adding 009929a0-f413-4a13-a312-90def98326b0 Apr 28 02:46:14 volumio volumio[19183]: info: Discovery: Found device Volumio Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:14 volumio volumio[19183]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:14 volumio volumio[19183]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:14 volumio volumio[19183]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:14 volumio volumio[19183]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:14 volumio volumio[19183]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:14 volumio volumio[19183]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:14 volumio volumio[19183]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:14 volumio volumio[19183]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:14 volumio volumio[19183]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:14 volumio volumio[19183]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:14 volumio volumio[19183]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 28 02:46:14 volumio volumio[19183]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:14 volumio volumio[19183]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:14 volumio volumio[19183]: info: CoreStateMachine::pushState Apr 28 02:46:14 volumio volumio[19183]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:14 volumio volumio[19183]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:14 volumio volumio[19183]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:14 volumio volumio[19183]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:14 volumio volumio[19183]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:14 volumio volumio[19183]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:14 volumio volumio[19183]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:15 volumio volumio[19183]: info: [yt-cast-receiver] DIAL server listening on port 8098 Apr 28 02:46:15 volumio mpd[19362]: Apr 28 02:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 28 02:46:15 volumio systemd[1]: Started Music Player Daemon. Apr 28 02:46:15 volumio sudo[19326]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:15 volumio sudo[19304]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:15 volumio volumio[19183]: info: Completed starting Core Plugins Apr 28 02:46:15 volumio volumio[19183]: info: ------------------------------------------- Apr 28 02:46:15 volumio volumio[19183]: info: ----- MyVolumio plugins startup ---- Apr 28 02:46:15 volumio volumio[19183]: info: ------------------------------------------- Apr 28 02:46:15 volumio volumio[19183]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 28 02:46:15 volumio volumio[19183]: error: MPD error: The expression evaluated to a falsy value: Apr 28 02:46:15 volumio volumio[19183]: assert.ok(self.idling) Apr 28 02:46:15 volumio volumio[19183]: error: The expression evaluated to a falsy value: Apr 28 02:46:15 volumio volumio[19183]: assert.ok(self.idling) Apr 28 02:46:15 volumio volumio[19183]: info: MPD running with PID19362 Apr 28 02:46:15 volumio volumio[19183]: ,establishing connection Apr 28 02:46:15 volumio volumio[19183]: error: updateQueue error: null Apr 28 02:46:15 volumio volumio[19183]: error: updateQueue error: null Apr 28 02:46:15 volumio volumio[19183]: info: [ASDebug] Togle GPIO: OFF Apr 28 02:46:15 volumio volumio[19183]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:46:15 volumio volumio[19183]: Error: EPERM: operation not permitted, write Apr 28 02:46:15 volumio volumio[19183]: at Object.writeSync (fs.js:737:3) Apr 28 02:46:15 volumio volumio[19183]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 28 02:46:15 volumio volumio[19183]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Apr 28 02:46:15 volumio volumio[19183]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Apr 28 02:46:15 volumio volumio[19183]: at listOnTimeout (internal/timers.js:557:17) Apr 28 02:46:15 volumio volumio[19183]: at processTimers (internal/timers.js:500:7) { Apr 28 02:46:15 volumio volumio[19183]: errno: -1, Apr 28 02:46:15 volumio volumio[19183]: syscall: 'write', Apr 28 02:46:15 volumio volumio[19183]: code: 'EPERM' Apr 28 02:46:15 volumio volumio[19183]: } Apr 28 02:46:15 volumio volumio[19183]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:46:15 volumio sudo[19421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 02:45 Apr 28 02:46:15 volumio sudo[19421]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:15 volumio sudo[19421]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:15 volumio volumio-remote-updater[541]: [2025-04-28 02:46:15] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 28 02:46:15 volumio volumio-remote-updater[541]: [2025-04-28 02:46:15] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 28 02:46:15 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 28 02:46:15 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 28 02:46:15 volumio systemd[1]: Started dynamicswap service. Apr 28 02:46:15 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:46:16 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 28 02:46:16 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1018. Apr 28 02:46:16 volumio systemd[1]: Started dynamicswap service. Apr 28 02:46:16 volumio systemd[1]: Stopped Volumio Backend Module. Apr 28 02:46:16 volumio systemd[1]: Started Volumio Backend Module. Apr 28 02:46:16 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:46:17 volumio volumio[19439]: info: ------------------------------------------- Apr 28 02:46:17 volumio volumio[19439]: info: ----- Volumio3 ---- Apr 28 02:46:17 volumio volumio[19439]: info: ------------------------------------------- Apr 28 02:46:17 volumio volumio[19439]: info: ----- System startup ---- Apr 28 02:46:17 volumio volumio[19439]: info: ------------------------------------------- Apr 28 02:46:17 volumio volumio[19439]: info: MYVOLUMIO Environment detected Apr 28 02:46:17 volumio volumio[19439]: info: Plugin folders cleanup Apr 28 02:46:17 volumio volumio[19439]: info: Scanning into folder /volumio/app/plugins/ Apr 28 02:46:17 volumio volumio[19439]: info: Scanning category audio_interface Apr 28 02:46:17 volumio volumio[19439]: info: Scanning category miscellanea Apr 28 02:46:17 volumio volumio[19439]: info: Scanning category music_service Apr 28 02:46:17 volumio volumio[19439]: info: Scanning category plugins.json Apr 28 02:46:17 volumio volumio[19439]: info: Scanning category system_controller Apr 28 02:46:17 volumio volumio[19439]: info: Scanning category user_interface Apr 28 02:46:17 volumio volumio[19439]: info: Scanning into folder /data/plugins/ Apr 28 02:46:17 volumio volumio[19439]: info: Scanning category audio_interface Apr 28 02:46:17 volumio volumio[19439]: info: Scanning category music_service Apr 28 02:46:17 volumio volumio[19439]: info: Scanning category system_controller Apr 28 02:46:17 volumio volumio[19439]: info: Plugin folders cleanup completed Apr 28 02:46:17 volumio volumio[19439]: info: ------------------------------------------- Apr 28 02:46:17 volumio volumio[19439]: info: ----- Core plugins startup ---- Apr 28 02:46:17 volumio volumio[19439]: info: ------------------------------------------- Apr 28 02:46:17 volumio volumio[19439]: info: Loading plugins from folder /volumio/app/plugins/ Apr 28 02:46:17 volumio volumio[19439]: info: Adding plugin upnp to MyMusic Plugins Apr 28 02:46:17 volumio volumio[19439]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 28 02:46:17 volumio volumio[19439]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 28 02:46:17 volumio volumio[19439]: info: Loading plugins from folder /data/plugins/ Apr 28 02:46:17 volumio volumio[19439]: info: Loading plugin "system"... Apr 28 02:46:17 volumio volumio[19439]: info: Loading plugin "ampswitch"... Apr 28 02:46:17 volumio volumio[19439]: info: Applying required configuration parameters for plugin ampswitch Apr 28 02:46:17 volumio volumio[19439]: info: Loading plugin "appearance"... Apr 28 02:46:18 volumio volumio[19439]: info: Loading plugin "network"... Apr 28 02:46:18 volumio volumio[19439]: info: Refreshing Cached IP Addresses Apr 28 02:46:18 volumio volumio[19439]: info: Loading plugin "services"... Apr 28 02:46:18 volumio sudo[19467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 02:46:18 volumio volumio[19439]: info: Loading plugin "alsa_controller"... Apr 28 02:46:18 volumio sudo[19469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 02:46:18 volumio sudo[19469]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:18 volumio sudo[19467]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:18 volumio sudo[19469]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:18 volumio sudo[19467]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:18 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:18 volumio volumio[19439]: info: Loading plugin "wizard"... Apr 28 02:46:18 volumio volumio[19439]: info: Loading plugin "networkfs"... Apr 28 02:46:18 volumio volumio[19439]: info: Starting Udev Watcher for removable devices Apr 28 02:46:18 volumio volumio[19439]: info: Ignoring mount for partition: BOOT Apr 28 02:46:18 volumio volumio[19439]: info: Ignoring mount for partition: volumio Apr 28 02:46:18 volumio volumio[19439]: info: Ignoring mount for partition: volumio_data Apr 28 02:46:18 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:18 volumio volumio[19439]: info: Loading plugin "volumio_command_line_client"... Apr 28 02:46:18 volumio volumio[19439]: info: Loading plugin "upnp"... Apr 28 02:46:18 volumio volumio[19439]: info: [1745801178692] Starting Upmpd Daemon Apr 28 02:46:18 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:18 volumio volumio[19439]: info: Loading plugin "my_music"... Apr 28 02:46:18 volumio volumio[19439]: info: Loading plugin "mpd"... Apr 28 02:46:18 volumio volumio[19439]: info: Loading plugin "upnp_browser"... Apr 28 02:46:19 volumio volumio[19439]: info: Loading plugin "alarm-clock"... Apr 28 02:46:19 volumio volumio[19439]: info: Plugin airplay_emulation is not enabled Apr 28 02:46:19 volumio volumio[19439]: info: Loading plugin "last_100"... Apr 28 02:46:19 volumio volumio[19439]: info: Loading plugin "webradio"... Apr 28 02:46:19 volumio volumio[19439]: info: Loading plugin "i2s_dacs"... Apr 28 02:46:19 volumio volumio[19439]: info: I2S DAC not set, start Auto-detection Apr 28 02:46:19 volumio volumio[19439]: info: Loading plugin "volumiodiscovery"... Apr 28 02:46:19 volumio volumio[19439]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:19 volumio volumio[19439]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:19 volumio volumio[19439]: *** WARNING *** For more information see Apr 28 02:46:19 volumio volumio[19439]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:19 volumio volumio[19439]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:19 volumio node[19439]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:19 volumio volumio[19439]: *** WARNING *** For more information see Apr 28 02:46:19 volumio node[19439]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:19 volumio node[19439]: *** WARNING *** For more information see Apr 28 02:46:19 volumio node[19439]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:19 volumio node[19439]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:19 volumio node[19439]: *** WARNING *** For more information see Apr 28 02:46:19 volumio volumio[19439]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 28 02:46:19 volumio volumio[19439]: info: Discovery: Started advertising with name: Volumio Apr 28 02:46:19 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:19 volumio volumio[19439]: info: Loading plugin "mixcloud"... Apr 28 02:46:19 volumio volumio[19439]: info: Loading plugin "soundcloud"... Apr 28 02:46:19 volumio volumio[19439]: info: Loading plugin "youtube2"... Apr 28 02:46:20 volumio volumio-remote-updater[541]: [2025-04-28 02:46:20] [connect] Successful connection Apr 28 02:46:21 volumio volumio[19439]: info: Loading plugin "ytcr"... Apr 28 02:46:22 volumio volumio[19439]: info: Loading plugin "ytmusic"... Apr 28 02:46:23 volumio volumio[19439]: info: Loading plugin "outputs"... Apr 28 02:46:23 volumio volumio[19439]: info: Loading plugin "albumart"... Apr 28 02:46:23 volumio volumio[19439]: info: Plugin example_plugin is not enabled Apr 28 02:46:23 volumio volumio[19439]: info: Loading plugin "inputs"... Apr 28 02:46:23 volumio volumio[19439]: info: Loading plugin "updater_comm"... Apr 28 02:46:23 volumio volumio[19439]: info: Plugin mpdemulation is not enabled Apr 28 02:46:23 volumio volumio[19439]: info: Loading plugin "rest_api"... Apr 28 02:46:23 volumio volumio[19439]: info: Loading plugin "websocket"... Apr 28 02:46:23 volumio volumio[19439]: info: Starting Socket.io Server version 2.3.0 Apr 28 02:46:23 volumio volumio[19439]: info: Plugin fusiondsp is not enabled Apr 28 02:46:23 volumio volumio[19439]: info: Plugin minidlna is not enabled Apr 28 02:46:23 volumio volumio[19439]: info: Loading i18n strings for locale de Apr 28 02:46:23 volumio volumio[19439]: Updating browse sources language Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::initPlayerControls Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:23 volumio volumio[19439]: Express server listening on port 3000 Apr 28 02:46:23 volumio volumio[19439]: [Metrics] WebUI: 6s 552.39ms Apr 28 02:46:23 volumio volumio[19439]: Forking 3 albumart workers Apr 28 02:46:23 volumio volumio[19439]: info: CoreStateMachine::resetVolumioState Apr 28 02:46:23 volumio volumio[19439]: info: CoreStateMachine::getcurrentVolume Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:23 volumio volumio[19439]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 28 02:46:23 volumio volumio[19439]: info: Completed loading Core Plugins Apr 28 02:46:23 volumio volumio[19439]: info: Preparing to generate the ALSA configuration file Apr 28 02:46:23 volumio volumio[19439]: info: Volumio Network Manager: Network status updated: 1 Apr 28 02:46:23 volumio volumio-remote-updater[541]: [2025-04-28 02:46:23] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745801180 101 Apr 28 02:46:23 volumio volumio[19439]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 28 02:46:23 volumio volumio[19439]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 28 02:46:23 volumio volumio[19439]: info: CoreStateMachine::setRepeat false single undefined Apr 28 02:46:23 volumio volumio[19439]: info: CoreStateMachine::pushState Apr 28 02:46:23 volumio volumio[19439]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:23 volumio volumio[19439]: info: CoreStateMachine::setRandom true Apr 28 02:46:23 volumio volumio[19439]: info: CoreStateMachine::pushState Apr 28 02:46:23 volumio volumio[19439]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:23 volumio volumio[19439]: info: Setting Device type: Tinkerboard Apr 28 02:46:23 volumio volumio[19439]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:46:23 volumio volumio[19439]: info: CoreStateMachine::pushState Apr 28 02:46:23 volumio volumio[19439]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:23 volumio volumio[19439]: info: CoreStateMachine::updateTrackBlock Apr 28 02:46:23 volumio volumio[19439]: info: CorePlayQueue::getTrackBlock Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:46:23 volumio volumio[19439]: info: Asound.conf file unchanged, so no further update is needed Apr 28 02:46:23 volumio volumio[19439]: info: Output device has changed, restarting MPD Apr 28 02:46:23 volumio sudo[19556]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:46:23 volumio volumio[19439]: info: ___________ START PLUGINS ___________ Apr 28 02:46:23 volumio sudo[19556]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:23 volumio sudo[19556]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:23 volumio sudo[19561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:46:23 volumio sudo[19561]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:23 volumio kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Apr 28 02:46:23 volumio volumio[19439]: info: ControllerMpd::onStart: Initializing MPD Apr 28 02:46:23 volumio volumio[19439]: info: Creating MPD Configuration file Apr 28 02:46:23 volumio systemd[1]: Stopping Music Player Daemon... Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:23 volumio volumio[19439]: info: [1745801183984] CoreMusicLibrary::Adding element Medienserver Apr 28 02:46:23 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:24 volumio sudo[19580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:24 volumio volumio[19439]: info: [1745801184010] CoreMusicLibrary::Adding element Last_100 Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:24 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:24 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:46:24 volumio volumio[19439]: info: [1745801184014] CoreMusicLibrary::Adding element Webradio Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:24 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:24 volumio sudo[19579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:46:24 volumio volumio[19439]: info: Initializing BBC Radios Apr 28 02:46:24 volumio sudo[19580]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:24 volumio sudo[19579]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:24 volumio sudo[19579]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:24 volumio volumio[19439]: info: [1745801184144] CoreMusicLibrary::Adding element Mixcloud Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:24 volumio volumio[19439]: Cannot find translation for source Mixcloud Apr 28 02:46:24 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 28 02:46:24 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:46:24 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:46:24 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:24 volumio volumio[19439]: info: [1745801184159] CoreMusicLibrary::Adding element SoundCloud Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:24 volumio volumio[19439]: Cannot find translation for source Mixcloud Apr 28 02:46:24 volumio volumio[19439]: Cannot find translation for source SoundCloud Apr 28 02:46:24 volumio volumio[19439]: Starting albumart workers Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:24 volumio volumio[19439]: info: [1745801184167] CoreMusicLibrary::Adding element YouTube2 Apr 28 02:46:24 volumio volumio[19439]: Starting albumart workers Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:24 volumio volumio[19439]: Cannot find translation for source Mixcloud Apr 28 02:46:24 volumio volumio[19439]: Cannot find translation for source SoundCloud Apr 28 02:46:24 volumio volumio[19439]: Cannot find translation for source YouTube2 Apr 28 02:46:24 volumio volumio[19439]: Starting albumart workers Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:24 volumio volumio[19439]: info: [1745801184222] CoreMusicLibrary::Adding element YouTube Music Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:24 volumio volumio[19439]: Cannot find translation for source Mixcloud Apr 28 02:46:24 volumio volumio[19439]: Cannot find translation for source SoundCloud Apr 28 02:46:24 volumio volumio[19439]: Cannot find translation for source YouTube2 Apr 28 02:46:24 volumio volumio[19439]: Cannot find translation for source YouTube Music Apr 28 02:46:24 volumio volumio[19439]: info: Volumio Calling Home Apr 28 02:46:24 volumio sudo[19605]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 28 02:46:24 volumio sudo[19605]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:24 volumio sudo[19605]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:24 volumio volumio[19439]: info: Volumio called home Apr 28 02:46:24 volumio volumio[19439]: info: MPD Permissions set Apr 28 02:46:24 volumio volumio[19439]: info: MPD Permissions set Apr 28 02:46:24 volumio volumio[19439]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:24 volumio volumio[19439]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:24 volumio volumio[19439]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:24 volumio volumio[19439]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:24 volumio volumio[19439]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:24 volumio volumio[19439]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:24 volumio volumio[19439]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:24 volumio volumio[19439]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:24 volumio volumio[19439]: info: Discovery: adding 009929a0-f413-4a13-a312-90def98326b0 Apr 28 02:46:24 volumio volumio[19439]: info: Discovery: Found device Volumio Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:24 volumio volumio[19439]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:24 volumio volumio[19439]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:24 volumio volumio[19439]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 28 02:46:24 volumio volumio[19439]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:24 volumio volumio[19439]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:24 volumio volumio[19439]: info: CoreStateMachine::pushState Apr 28 02:46:24 volumio volumio[19439]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:24 volumio volumio[19439]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:24 volumio volumio[19439]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:24 volumio volumio[19439]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:24 volumio volumio[19439]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:24 volumio volumio[19439]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:24 volumio volumio[19439]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:25 volumio volumio[19439]: info: [yt-cast-receiver] DIAL server listening on port 8098 Apr 28 02:46:25 volumio mpd[19617]: Apr 28 02:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 28 02:46:25 volumio systemd[1]: Started Music Player Daemon. Apr 28 02:46:25 volumio sudo[19561]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:25 volumio sudo[19580]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:25 volumio volumio[19439]: info: Completed starting Core Plugins Apr 28 02:46:25 volumio volumio[19439]: info: ------------------------------------------- Apr 28 02:46:25 volumio volumio[19439]: info: ----- MyVolumio plugins startup ---- Apr 28 02:46:25 volumio volumio[19439]: info: ------------------------------------------- Apr 28 02:46:25 volumio volumio[19439]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 28 02:46:25 volumio volumio[19439]: error: MPD error: The expression evaluated to a falsy value: Apr 28 02:46:25 volumio volumio[19439]: assert.ok(self.idling) Apr 28 02:46:25 volumio volumio[19439]: error: The expression evaluated to a falsy value: Apr 28 02:46:25 volumio volumio[19439]: assert.ok(self.idling) Apr 28 02:46:25 volumio volumio[19439]: info: MPD running with PID19617 Apr 28 02:46:25 volumio volumio[19439]: ,establishing connection Apr 28 02:46:25 volumio volumio[19439]: error: updateQueue error: null Apr 28 02:46:25 volumio volumio[19439]: error: updateQueue error: null Apr 28 02:46:25 volumio volumio[19439]: info: [ASDebug] Togle GPIO: OFF Apr 28 02:46:25 volumio volumio[19439]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:46:25 volumio volumio[19439]: Error: EPERM: operation not permitted, write Apr 28 02:46:25 volumio volumio[19439]: at Object.writeSync (fs.js:737:3) Apr 28 02:46:25 volumio volumio[19439]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 28 02:46:25 volumio volumio[19439]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Apr 28 02:46:25 volumio volumio[19439]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Apr 28 02:46:25 volumio volumio[19439]: at listOnTimeout (internal/timers.js:557:17) Apr 28 02:46:25 volumio volumio[19439]: at processTimers (internal/timers.js:500:7) { Apr 28 02:46:25 volumio volumio[19439]: errno: -1, Apr 28 02:46:25 volumio volumio[19439]: syscall: 'write', Apr 28 02:46:25 volumio volumio[19439]: code: 'EPERM' Apr 28 02:46:25 volumio volumio[19439]: } Apr 28 02:46:25 volumio volumio[19439]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:46:26 volumio sudo[19677]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 02:45 Apr 28 02:46:26 volumio sudo[19677]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:26 volumio sudo[19677]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:26 volumio volumio-remote-updater[541]: [2025-04-28 02:46:26] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 28 02:46:26 volumio volumio-remote-updater[541]: [2025-04-28 02:46:26] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 28 02:46:26 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 28 02:46:26 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 28 02:46:26 volumio systemd[1]: Started dynamicswap service. Apr 28 02:46:26 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:46:26 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 28 02:46:26 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1019. Apr 28 02:46:26 volumio systemd[1]: Started dynamicswap service. Apr 28 02:46:26 volumio systemd[1]: Stopped Volumio Backend Module. Apr 28 02:46:26 volumio systemd[1]: Started Volumio Backend Module. Apr 28 02:46:26 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:46:27 volumio volumio[19695]: info: ------------------------------------------- Apr 28 02:46:27 volumio volumio[19695]: info: ----- Volumio3 ---- Apr 28 02:46:27 volumio volumio[19695]: info: ------------------------------------------- Apr 28 02:46:27 volumio volumio[19695]: info: ----- System startup ---- Apr 28 02:46:27 volumio volumio[19695]: info: ------------------------------------------- Apr 28 02:46:27 volumio volumio[19695]: info: MYVOLUMIO Environment detected Apr 28 02:46:27 volumio volumio[19695]: info: Plugin folders cleanup Apr 28 02:46:27 volumio volumio[19695]: info: Scanning into folder /volumio/app/plugins/ Apr 28 02:46:27 volumio volumio[19695]: info: Scanning category audio_interface Apr 28 02:46:27 volumio volumio[19695]: info: Scanning category miscellanea Apr 28 02:46:27 volumio volumio[19695]: info: Scanning category music_service Apr 28 02:46:27 volumio volumio[19695]: info: Scanning category plugins.json Apr 28 02:46:27 volumio volumio[19695]: info: Scanning category system_controller Apr 28 02:46:27 volumio volumio[19695]: info: Scanning category user_interface Apr 28 02:46:27 volumio volumio[19695]: info: Scanning into folder /data/plugins/ Apr 28 02:46:27 volumio volumio[19695]: info: Scanning category audio_interface Apr 28 02:46:27 volumio volumio[19695]: info: Scanning category music_service Apr 28 02:46:27 volumio volumio[19695]: info: Scanning category system_controller Apr 28 02:46:27 volumio volumio[19695]: info: Plugin folders cleanup completed Apr 28 02:46:27 volumio volumio[19695]: info: ------------------------------------------- Apr 28 02:46:27 volumio volumio[19695]: info: ----- Core plugins startup ---- Apr 28 02:46:27 volumio volumio[19695]: info: ------------------------------------------- Apr 28 02:46:27 volumio volumio[19695]: info: Loading plugins from folder /volumio/app/plugins/ Apr 28 02:46:27 volumio volumio[19695]: info: Adding plugin upnp to MyMusic Plugins Apr 28 02:46:27 volumio volumio[19695]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 28 02:46:27 volumio volumio[19695]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 28 02:46:27 volumio volumio[19695]: info: Loading plugins from folder /data/plugins/ Apr 28 02:46:27 volumio volumio[19695]: info: Loading plugin "system"... Apr 28 02:46:28 volumio volumio[19695]: info: Loading plugin "ampswitch"... Apr 28 02:46:28 volumio volumio[19695]: info: Applying required configuration parameters for plugin ampswitch Apr 28 02:46:28 volumio volumio[19695]: info: Loading plugin "appearance"... Apr 28 02:46:28 volumio volumio[19695]: info: Loading plugin "network"... Apr 28 02:46:28 volumio volumio[19695]: info: Refreshing Cached IP Addresses Apr 28 02:46:28 volumio sudo[19722]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 02:46:28 volumio volumio[19695]: info: Loading plugin "services"... Apr 28 02:46:28 volumio volumio[19695]: info: Loading plugin "alsa_controller"... Apr 28 02:46:28 volumio sudo[19724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 02:46:28 volumio sudo[19722]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:28 volumio sudo[19722]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:28 volumio sudo[19724]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:28 volumio sudo[19724]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:28 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:28 volumio volumio[19695]: info: Loading plugin "wizard"... Apr 28 02:46:28 volumio volumio[19695]: info: Loading plugin "networkfs"... Apr 28 02:46:28 volumio volumio[19695]: info: Starting Udev Watcher for removable devices Apr 28 02:46:28 volumio volumio[19695]: info: Ignoring mount for partition: BOOT Apr 28 02:46:28 volumio volumio[19695]: info: Ignoring mount for partition: volumio Apr 28 02:46:28 volumio volumio[19695]: info: Ignoring mount for partition: volumio_data Apr 28 02:46:28 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:28 volumio volumio[19695]: info: Loading plugin "volumio_command_line_client"... Apr 28 02:46:28 volumio volumio[19695]: info: Loading plugin "upnp"... Apr 28 02:46:28 volumio volumio[19695]: info: [1745801188958] Starting Upmpd Daemon Apr 28 02:46:28 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:28 volumio volumio[19695]: info: Loading plugin "my_music"... Apr 28 02:46:28 volumio volumio[19695]: info: Loading plugin "mpd"... Apr 28 02:46:29 volumio volumio[19695]: info: Loading plugin "upnp_browser"... Apr 28 02:46:29 volumio volumio[19695]: info: Loading plugin "alarm-clock"... Apr 28 02:46:29 volumio volumio[19695]: info: Plugin airplay_emulation is not enabled Apr 28 02:46:29 volumio volumio[19695]: info: Loading plugin "last_100"... Apr 28 02:46:29 volumio volumio[19695]: info: Loading plugin "webradio"... Apr 28 02:46:29 volumio volumio[19695]: info: Loading plugin "i2s_dacs"... Apr 28 02:46:29 volumio volumio[19695]: info: I2S DAC not set, start Auto-detection Apr 28 02:46:29 volumio volumio[19695]: info: Loading plugin "volumiodiscovery"... Apr 28 02:46:29 volumio volumio[19695]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:29 volumio volumio[19695]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:29 volumio volumio[19695]: *** WARNING *** For more information see Apr 28 02:46:29 volumio volumio[19695]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:29 volumio volumio[19695]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:29 volumio node[19695]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:29 volumio volumio[19695]: *** WARNING *** For more information see Apr 28 02:46:29 volumio node[19695]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:29 volumio node[19695]: *** WARNING *** For more information see Apr 28 02:46:29 volumio node[19695]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:29 volumio node[19695]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:29 volumio node[19695]: *** WARNING *** For more information see Apr 28 02:46:29 volumio volumio[19695]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 28 02:46:29 volumio volumio[19695]: info: Discovery: Started advertising with name: Volumio Apr 28 02:46:29 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:29 volumio volumio[19695]: info: Loading plugin "mixcloud"... Apr 28 02:46:29 volumio volumio[19695]: info: Loading plugin "soundcloud"... Apr 28 02:46:30 volumio volumio[19695]: info: Loading plugin "youtube2"... Apr 28 02:46:31 volumio volumio-remote-updater[541]: [2025-04-28 02:46:31] [connect] Successful connection Apr 28 02:46:31 volumio volumio[19695]: info: Loading plugin "ytcr"... Apr 28 02:46:32 volumio volumio[19695]: info: Loading plugin "ytmusic"... Apr 28 02:46:33 volumio volumio[19695]: info: Loading plugin "outputs"... Apr 28 02:46:33 volumio volumio[19695]: info: Loading plugin "albumart"... Apr 28 02:46:33 volumio volumio[19695]: info: Plugin example_plugin is not enabled Apr 28 02:46:33 volumio volumio[19695]: info: Loading plugin "inputs"... Apr 28 02:46:33 volumio volumio[19695]: info: Loading plugin "updater_comm"... Apr 28 02:46:33 volumio volumio[19695]: info: Plugin mpdemulation is not enabled Apr 28 02:46:33 volumio volumio[19695]: info: Loading plugin "rest_api"... Apr 28 02:46:33 volumio volumio[19695]: info: Loading plugin "websocket"... Apr 28 02:46:33 volumio volumio[19695]: info: Starting Socket.io Server version 2.3.0 Apr 28 02:46:33 volumio volumio[19695]: info: Plugin fusiondsp is not enabled Apr 28 02:46:33 volumio volumio[19695]: info: Plugin minidlna is not enabled Apr 28 02:46:33 volumio volumio[19695]: info: Loading i18n strings for locale de Apr 28 02:46:33 volumio volumio[19695]: Updating browse sources language Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::initPlayerControls Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:33 volumio volumio[19695]: Express server listening on port 3000 Apr 28 02:46:33 volumio volumio[19695]: [Metrics] WebUI: 6s 555.44ms Apr 28 02:46:33 volumio volumio[19695]: Forking 3 albumart workers Apr 28 02:46:33 volumio volumio[19695]: info: CoreStateMachine::resetVolumioState Apr 28 02:46:33 volumio volumio[19695]: info: CoreStateMachine::getcurrentVolume Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:33 volumio volumio[19695]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 28 02:46:33 volumio volumio[19695]: info: Completed loading Core Plugins Apr 28 02:46:33 volumio volumio[19695]: info: Preparing to generate the ALSA configuration file Apr 28 02:46:33 volumio volumio[19695]: info: Volumio Network Manager: Network status updated: 1 Apr 28 02:46:33 volumio volumio-remote-updater[541]: [2025-04-28 02:46:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745801191 101 Apr 28 02:46:33 volumio volumio[19695]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 28 02:46:33 volumio volumio[19695]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 28 02:46:33 volumio volumio[19695]: info: CoreStateMachine::setRepeat false single undefined Apr 28 02:46:33 volumio volumio[19695]: info: CoreStateMachine::pushState Apr 28 02:46:33 volumio volumio[19695]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:33 volumio volumio[19695]: info: CoreStateMachine::setRandom true Apr 28 02:46:33 volumio volumio[19695]: info: CoreStateMachine::pushState Apr 28 02:46:33 volumio volumio[19695]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:33 volumio volumio[19695]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:34 volumio volumio[19695]: info: Setting Device type: Tinkerboard Apr 28 02:46:34 volumio volumio[19695]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:46:34 volumio volumio[19695]: info: CoreStateMachine::pushState Apr 28 02:46:34 volumio volumio[19695]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:34 volumio volumio[19695]: info: CoreStateMachine::updateTrackBlock Apr 28 02:46:34 volumio volumio[19695]: info: CorePlayQueue::getTrackBlock Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:46:34 volumio volumio[19695]: info: Asound.conf file unchanged, so no further update is needed Apr 28 02:46:34 volumio volumio[19695]: info: Output device has changed, restarting MPD Apr 28 02:46:34 volumio volumio[19695]: info: ___________ START PLUGINS ___________ Apr 28 02:46:34 volumio sudo[19810]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:46:34 volumio sudo[19813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:46:34 volumio sudo[19810]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:34 volumio sudo[19810]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:34 volumio sudo[19813]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:34 volumio kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Apr 28 02:46:34 volumio volumio[19695]: info: ControllerMpd::onStart: Initializing MPD Apr 28 02:46:34 volumio volumio[19695]: info: Creating MPD Configuration file Apr 28 02:46:34 volumio systemd[1]: Stopping Music Player Daemon... Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:34 volumio volumio[19695]: info: [1745801194236] CoreMusicLibrary::Adding element Medienserver Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:34 volumio volumio[19695]: info: [1745801194254] CoreMusicLibrary::Adding element Last_100 Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:34 volumio sudo[19833]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:34 volumio volumio[19695]: info: [1745801194263] CoreMusicLibrary::Adding element Webradio Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:34 volumio volumio[19695]: info: Initializing BBC Radios Apr 28 02:46:34 volumio sudo[19833]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:34 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:46:34 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:46:34 volumio sudo[19835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:46:34 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:46:34 volumio sudo[19833]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:34 volumio sudo[19835]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:34 volumio volumio[19695]: info: [1745801194377] CoreMusicLibrary::Adding element Mixcloud Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:34 volumio volumio[19695]: Cannot find translation for source Mixcloud Apr 28 02:46:34 volumio volumio[19695]: Starting albumart workers Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:34 volumio volumio[19695]: info: [1745801194425] CoreMusicLibrary::Adding element SoundCloud Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:34 volumio volumio[19695]: Cannot find translation for source Mixcloud Apr 28 02:46:34 volumio volumio[19695]: Cannot find translation for source SoundCloud Apr 28 02:46:34 volumio volumio[19695]: Starting albumart workers Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:34 volumio volumio[19695]: info: [1745801194432] CoreMusicLibrary::Adding element YouTube2 Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:34 volumio volumio[19695]: Cannot find translation for source Mixcloud Apr 28 02:46:34 volumio volumio[19695]: Cannot find translation for source SoundCloud Apr 28 02:46:34 volumio volumio[19695]: Cannot find translation for source YouTube2 Apr 28 02:46:34 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 28 02:46:34 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:46:34 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:46:34 volumio volumio[19695]: Starting albumart workers Apr 28 02:46:34 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:34 volumio volumio[19695]: info: [1745801194484] CoreMusicLibrary::Adding element YouTube Music Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:34 volumio volumio[19695]: Cannot find translation for source Mixcloud Apr 28 02:46:34 volumio volumio[19695]: Cannot find translation for source SoundCloud Apr 28 02:46:34 volumio volumio[19695]: Cannot find translation for source YouTube2 Apr 28 02:46:34 volumio volumio[19695]: Cannot find translation for source YouTube Music Apr 28 02:46:34 volumio volumio[19695]: info: Volumio Calling Home Apr 28 02:46:34 volumio sudo[19860]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 28 02:46:34 volumio sudo[19860]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:34 volumio sudo[19860]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:34 volumio volumio[19695]: info: Volumio called home Apr 28 02:46:34 volumio volumio[19695]: info: MPD Permissions set Apr 28 02:46:34 volumio volumio[19695]: info: MPD Permissions set Apr 28 02:46:34 volumio volumio[19695]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:34 volumio volumio[19695]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:34 volumio volumio[19695]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:34 volumio volumio[19695]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:34 volumio volumio[19695]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:34 volumio volumio[19695]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:34 volumio volumio[19695]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:34 volumio volumio[19695]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:34 volumio volumio[19695]: info: Discovery: adding 009929a0-f413-4a13-a312-90def98326b0 Apr 28 02:46:34 volumio volumio[19695]: info: Discovery: Found device Volumio Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:34 volumio volumio[19695]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:34 volumio volumio[19695]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:34 volumio volumio[19695]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:34 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:35 volumio volumio[19695]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:35 volumio volumio[19695]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:35 volumio volumio[19695]: info: CoreStateMachine::pushState Apr 28 02:46:35 volumio volumio[19695]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:35 volumio volumio[19695]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:35 volumio volumio[19695]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:35 volumio volumio[19695]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:35 volumio volumio[19695]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:35 volumio volumio[19695]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:35 volumio volumio[19695]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:35 volumio volumio[19695]: info: [yt-cast-receiver] DIAL server listening on port 8098 Apr 28 02:46:35 volumio mpd[19873]: Apr 28 02:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 28 02:46:35 volumio systemd[1]: Started Music Player Daemon. Apr 28 02:46:35 volumio sudo[19813]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:35 volumio sudo[19835]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:35 volumio volumio[19695]: info: Completed starting Core Plugins Apr 28 02:46:35 volumio volumio[19695]: info: ------------------------------------------- Apr 28 02:46:35 volumio volumio[19695]: info: ----- MyVolumio plugins startup ---- Apr 28 02:46:35 volumio volumio[19695]: info: ------------------------------------------- Apr 28 02:46:35 volumio volumio[19695]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 28 02:46:35 volumio volumio[19695]: error: MPD error: The expression evaluated to a falsy value: Apr 28 02:46:35 volumio volumio[19695]: assert.ok(self.idling) Apr 28 02:46:35 volumio volumio[19695]: error: The expression evaluated to a falsy value: Apr 28 02:46:35 volumio volumio[19695]: assert.ok(self.idling) Apr 28 02:46:35 volumio volumio[19695]: info: [ASDebug] Togle GPIO: OFF Apr 28 02:46:36 volumio volumio[19695]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:46:36 volumio volumio[19695]: Error: EPERM: operation not permitted, write Apr 28 02:46:36 volumio volumio[19695]: at Object.writeSync (fs.js:737:3) Apr 28 02:46:36 volumio volumio[19695]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 28 02:46:36 volumio volumio[19695]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Apr 28 02:46:36 volumio volumio[19695]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Apr 28 02:46:36 volumio volumio[19695]: at listOnTimeout (internal/timers.js:557:17) Apr 28 02:46:36 volumio volumio[19695]: at processTimers (internal/timers.js:500:7) { Apr 28 02:46:36 volumio volumio[19695]: errno: -1, Apr 28 02:46:36 volumio volumio[19695]: syscall: 'write', Apr 28 02:46:36 volumio volumio[19695]: code: 'EPERM' Apr 28 02:46:36 volumio volumio[19695]: } Apr 28 02:46:36 volumio volumio[19695]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:46:36 volumio sudo[19929]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 02:45 Apr 28 02:46:36 volumio sudo[19929]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:36 volumio sudo[19929]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:36 volumio volumio-remote-updater[541]: [2025-04-28 02:46:36] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 28 02:46:36 volumio volumio-remote-updater[541]: [2025-04-28 02:46:36] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 28 02:46:36 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 28 02:46:36 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 28 02:46:36 volumio systemd[1]: Started dynamicswap service. Apr 28 02:46:36 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:46:36 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 28 02:46:36 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1020. Apr 28 02:46:36 volumio systemd[1]: Started dynamicswap service. Apr 28 02:46:36 volumio systemd[1]: Stopped Volumio Backend Module. Apr 28 02:46:36 volumio systemd[1]: Started Volumio Backend Module. Apr 28 02:46:36 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:46:37 volumio volumio[19948]: info: ------------------------------------------- Apr 28 02:46:37 volumio volumio[19948]: info: ----- Volumio3 ---- Apr 28 02:46:37 volumio volumio[19948]: info: ------------------------------------------- Apr 28 02:46:37 volumio volumio[19948]: info: ----- System startup ---- Apr 28 02:46:37 volumio volumio[19948]: info: ------------------------------------------- Apr 28 02:46:38 volumio volumio[19948]: info: MYVOLUMIO Environment detected Apr 28 02:46:38 volumio volumio[19948]: info: Plugin folders cleanup Apr 28 02:46:38 volumio volumio[19948]: info: Scanning into folder /volumio/app/plugins/ Apr 28 02:46:38 volumio volumio[19948]: info: Scanning category audio_interface Apr 28 02:46:38 volumio volumio[19948]: info: Scanning category miscellanea Apr 28 02:46:38 volumio volumio[19948]: info: Scanning category music_service Apr 28 02:46:38 volumio volumio[19948]: info: Scanning category plugins.json Apr 28 02:46:38 volumio volumio[19948]: info: Scanning category system_controller Apr 28 02:46:38 volumio volumio[19948]: info: Scanning category user_interface Apr 28 02:46:38 volumio volumio[19948]: info: Scanning into folder /data/plugins/ Apr 28 02:46:38 volumio volumio[19948]: info: Scanning category audio_interface Apr 28 02:46:38 volumio volumio[19948]: info: Scanning category music_service Apr 28 02:46:38 volumio volumio[19948]: info: Scanning category system_controller Apr 28 02:46:38 volumio volumio[19948]: info: Plugin folders cleanup completed Apr 28 02:46:38 volumio volumio[19948]: info: ------------------------------------------- Apr 28 02:46:38 volumio volumio[19948]: info: ----- Core plugins startup ---- Apr 28 02:46:38 volumio volumio[19948]: info: ------------------------------------------- Apr 28 02:46:38 volumio volumio[19948]: info: Loading plugins from folder /volumio/app/plugins/ Apr 28 02:46:38 volumio volumio[19948]: info: Adding plugin upnp to MyMusic Plugins Apr 28 02:46:38 volumio volumio[19948]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 28 02:46:38 volumio volumio[19948]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 28 02:46:38 volumio volumio[19948]: info: Loading plugins from folder /data/plugins/ Apr 28 02:46:38 volumio volumio[19948]: info: Loading plugin "system"... Apr 28 02:46:38 volumio volumio[19948]: info: Loading plugin "ampswitch"... Apr 28 02:46:38 volumio volumio[19948]: info: Applying required configuration parameters for plugin ampswitch Apr 28 02:46:38 volumio volumio[19948]: info: Loading plugin "appearance"... Apr 28 02:46:39 volumio volumio[19948]: info: Loading plugin "network"... Apr 28 02:46:39 volumio volumio[19948]: info: Refreshing Cached IP Addresses Apr 28 02:46:39 volumio sudo[19975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 02:46:39 volumio volumio[19948]: info: Loading plugin "services"... Apr 28 02:46:39 volumio volumio[19948]: info: Loading plugin "alsa_controller"... Apr 28 02:46:39 volumio sudo[19977]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 02:46:39 volumio sudo[19975]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:39 volumio sudo[19977]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:39 volumio sudo[19975]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:39 volumio sudo[19977]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:39 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:39 volumio volumio[19948]: info: Loading plugin "wizard"... Apr 28 02:46:39 volumio volumio[19948]: info: Loading plugin "networkfs"... Apr 28 02:46:39 volumio volumio[19948]: info: Starting Udev Watcher for removable devices Apr 28 02:46:39 volumio volumio[19948]: info: Ignoring mount for partition: BOOT Apr 28 02:46:39 volumio volumio[19948]: info: Ignoring mount for partition: volumio Apr 28 02:46:39 volumio volumio[19948]: info: Ignoring mount for partition: volumio_data Apr 28 02:46:39 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:39 volumio volumio[19948]: info: Loading plugin "volumio_command_line_client"... Apr 28 02:46:39 volumio volumio[19948]: info: Loading plugin "upnp"... Apr 28 02:46:39 volumio volumio[19948]: info: [1745801199493] Starting Upmpd Daemon Apr 28 02:46:39 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:39 volumio volumio[19948]: info: Loading plugin "my_music"... Apr 28 02:46:39 volumio volumio[19948]: info: Loading plugin "mpd"... Apr 28 02:46:39 volumio volumio[19948]: info: Loading plugin "upnp_browser"... Apr 28 02:46:39 volumio volumio[19948]: info: Loading plugin "alarm-clock"... Apr 28 02:46:40 volumio volumio[19948]: info: Plugin airplay_emulation is not enabled Apr 28 02:46:40 volumio volumio[19948]: info: Loading plugin "last_100"... Apr 28 02:46:40 volumio volumio[19948]: info: Loading plugin "webradio"... Apr 28 02:46:40 volumio volumio[19948]: info: Loading plugin "i2s_dacs"... Apr 28 02:46:40 volumio volumio[19948]: info: I2S DAC not set, start Auto-detection Apr 28 02:46:40 volumio volumio[19948]: info: Loading plugin "volumiodiscovery"... Apr 28 02:46:40 volumio volumio[19948]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:40 volumio volumio[19948]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:40 volumio volumio[19948]: *** WARNING *** For more information see Apr 28 02:46:40 volumio volumio[19948]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:40 volumio volumio[19948]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:40 volumio node[19948]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:40 volumio volumio[19948]: *** WARNING *** For more information see Apr 28 02:46:40 volumio node[19948]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:40 volumio node[19948]: *** WARNING *** For more information see Apr 28 02:46:40 volumio node[19948]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:40 volumio node[19948]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:40 volumio node[19948]: *** WARNING *** For more information see Apr 28 02:46:40 volumio volumio[19948]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 28 02:46:40 volumio volumio[19948]: info: Discovery: Started advertising with name: Volumio Apr 28 02:46:40 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:40 volumio volumio[19948]: info: Loading plugin "mixcloud"... Apr 28 02:46:40 volumio volumio[19948]: info: Loading plugin "soundcloud"... Apr 28 02:46:40 volumio volumio[19948]: info: Loading plugin "youtube2"... Apr 28 02:46:41 volumio volumio-remote-updater[541]: [2025-04-28 02:46:41] [connect] Successful connection Apr 28 02:46:42 volumio volumio[19948]: info: Loading plugin "ytcr"... Apr 28 02:46:43 volumio volumio[19948]: info: Loading plugin "ytmusic"... Apr 28 02:46:44 volumio volumio[19948]: info: Loading plugin "outputs"... Apr 28 02:46:44 volumio volumio[19948]: info: Loading plugin "albumart"... Apr 28 02:46:44 volumio volumio[19948]: info: Plugin example_plugin is not enabled Apr 28 02:46:44 volumio volumio[19948]: info: Loading plugin "inputs"... Apr 28 02:46:44 volumio volumio[19948]: info: Loading plugin "updater_comm"... Apr 28 02:46:44 volumio volumio[19948]: info: Plugin mpdemulation is not enabled Apr 28 02:46:44 volumio volumio[19948]: info: Loading plugin "rest_api"... Apr 28 02:46:44 volumio volumio[19948]: info: Loading plugin "websocket"... Apr 28 02:46:44 volumio volumio[19948]: info: Starting Socket.io Server version 2.3.0 Apr 28 02:46:44 volumio volumio[19948]: info: Plugin fusiondsp is not enabled Apr 28 02:46:44 volumio volumio[19948]: info: Plugin minidlna is not enabled Apr 28 02:46:44 volumio volumio[19948]: info: Loading i18n strings for locale de Apr 28 02:46:44 volumio volumio[19948]: Updating browse sources language Apr 28 02:46:44 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:44 volumio volumio[19948]: Forking 3 albumart workers Apr 28 02:46:44 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:44 volumio volumio[19948]: info: CoreCommandRouter::initPlayerControls Apr 28 02:46:44 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:44 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:44 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:44 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:44 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:44 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:44 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:44 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:44 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:44 volumio volumio[19948]: Express server listening on port 3000 Apr 28 02:46:44 volumio volumio[19948]: [Metrics] WebUI: 7s 124.66ms Apr 28 02:46:44 volumio volumio[19948]: info: CoreStateMachine::resetVolumioState Apr 28 02:46:44 volumio volumio[19948]: info: CoreStateMachine::getcurrentVolume Apr 28 02:46:44 volumio volumio[19948]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:46:44 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:44 volumio volumio[19948]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 28 02:46:44 volumio volumio[19948]: info: Completed loading Core Plugins Apr 28 02:46:44 volumio volumio[19948]: info: Preparing to generate the ALSA configuration file Apr 28 02:46:44 volumio volumio[19948]: info: Volumio Network Manager: Network status updated: 1 Apr 28 02:46:44 volumio volumio-remote-updater[541]: [2025-04-28 02:46:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745801201 101 Apr 28 02:46:44 volumio volumio[19948]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 28 02:46:45 volumio volumio[19948]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 28 02:46:45 volumio volumio[19948]: info: CoreStateMachine::setRepeat false single undefined Apr 28 02:46:45 volumio volumio[19948]: info: CoreStateMachine::pushState Apr 28 02:46:45 volumio volumio[19948]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:45 volumio volumio[19948]: info: CoreStateMachine::setRandom true Apr 28 02:46:45 volumio volumio[19948]: info: CoreStateMachine::pushState Apr 28 02:46:45 volumio volumio[19948]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:45 volumio volumio[19948]: info: Setting Device type: Tinkerboard Apr 28 02:46:45 volumio volumio[19948]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:46:45 volumio volumio[19948]: info: CoreStateMachine::pushState Apr 28 02:46:45 volumio volumio[19948]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:45 volumio volumio[19948]: info: CoreStateMachine::updateTrackBlock Apr 28 02:46:45 volumio volumio[19948]: info: CorePlayQueue::getTrackBlock Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:46:45 volumio volumio[19948]: info: Asound.conf file unchanged, so no further update is needed Apr 28 02:46:45 volumio volumio[19948]: info: Output device has changed, restarting MPD Apr 28 02:46:45 volumio volumio[19948]: info: ___________ START PLUGINS ___________ Apr 28 02:46:45 volumio sudo[20064]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:46:45 volumio sudo[20066]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:46:45 volumio sudo[20064]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:45 volumio kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Apr 28 02:46:45 volumio sudo[20066]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:45 volumio sudo[20064]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:45 volumio volumio[19948]: info: ControllerMpd::onStart: Initializing MPD Apr 28 02:46:45 volumio volumio[19948]: info: Creating MPD Configuration file Apr 28 02:46:45 volumio systemd[1]: Stopping Music Player Daemon... Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:45 volumio volumio[19948]: info: [1745801205385] CoreMusicLibrary::Adding element Medienserver Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:45 volumio sudo[20086]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:46:45 volumio sudo[20086]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:45 volumio volumio[19948]: Starting albumart workers Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:45 volumio sudo[20086]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:45 volumio volumio[19948]: info: [1745801205430] CoreMusicLibrary::Adding element Last_100 Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:45 volumio sudo[20088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:45 volumio volumio[19948]: Starting albumart workers Apr 28 02:46:45 volumio volumio[19948]: info: [1745801205442] CoreMusicLibrary::Adding element Webradio Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:45 volumio sudo[20088]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:45 volumio volumio[19948]: info: Initializing BBC Radios Apr 28 02:46:45 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:46:45 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:46:45 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:46:45 volumio volumio[19948]: Starting albumart workers Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:45 volumio volumio[19948]: info: [1745801205555] CoreMusicLibrary::Adding element Mixcloud Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:45 volumio volumio[19948]: Cannot find translation for source Mixcloud Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:45 volumio volumio[19948]: info: [1745801205582] CoreMusicLibrary::Adding element SoundCloud Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:45 volumio volumio[19948]: Cannot find translation for source Mixcloud Apr 28 02:46:45 volumio volumio[19948]: Cannot find translation for source SoundCloud Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:45 volumio sudo[20108]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 28 02:46:45 volumio volumio[19948]: info: [1745801205604] CoreMusicLibrary::Adding element YouTube2 Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:45 volumio volumio[19948]: Cannot find translation for source Mixcloud Apr 28 02:46:45 volumio volumio[19948]: Cannot find translation for source SoundCloud Apr 28 02:46:45 volumio volumio[19948]: Cannot find translation for source YouTube2 Apr 28 02:46:45 volumio sudo[20108]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:45 volumio sudo[20108]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:45 volumio volumio[19948]: info: [1745801205684] CoreMusicLibrary::Adding element YouTube Music Apr 28 02:46:45 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:45 volumio volumio[19948]: Cannot find translation for source Mixcloud Apr 28 02:46:45 volumio volumio[19948]: Cannot find translation for source SoundCloud Apr 28 02:46:45 volumio volumio[19948]: Cannot find translation for source YouTube2 Apr 28 02:46:45 volumio volumio[19948]: Cannot find translation for source YouTube Music Apr 28 02:46:45 volumio volumio[19948]: info: Volumio Calling Home Apr 28 02:46:46 volumio volumio[19948]: info: Volumio called home Apr 28 02:46:46 volumio volumio[19948]: info: MPD Permissions set Apr 28 02:46:46 volumio volumio[19948]: info: MPD Permissions set Apr 28 02:46:46 volumio volumio[19948]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:46 volumio volumio[19948]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:46 volumio volumio[19948]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:46 volumio volumio[19948]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:46 volumio volumio[19948]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:46 volumio volumio[19948]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:46 volumio volumio[19948]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:46 volumio volumio[19948]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:46 volumio volumio[19948]: info: Discovery: adding 009929a0-f413-4a13-a312-90def98326b0 Apr 28 02:46:46 volumio volumio[19948]: info: Discovery: Found device Volumio Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:46 volumio volumio[19948]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:46 volumio volumio[19948]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:46 volumio volumio[19948]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 28 02:46:46 volumio volumio[19948]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:46 volumio volumio[19948]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:46 volumio volumio[19948]: info: CoreStateMachine::pushState Apr 28 02:46:46 volumio volumio[19948]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:46 volumio volumio[19948]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:46 volumio volumio[19948]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:46 volumio volumio[19948]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:46 volumio volumio[19948]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:46 volumio volumio[19948]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:46 volumio volumio[19948]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:46 volumio volumio[19948]: info: [yt-cast-receiver] DIAL server listening on port 8098 Apr 28 02:46:46 volumio mpd[20123]: Apr 28 02:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 28 02:46:46 volumio systemd[1]: Started Music Player Daemon. Apr 28 02:46:46 volumio sudo[20088]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:46 volumio sudo[20066]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:47 volumio volumio[19948]: info: Completed starting Core Plugins Apr 28 02:46:47 volumio volumio[19948]: info: ------------------------------------------- Apr 28 02:46:47 volumio volumio[19948]: info: ----- MyVolumio plugins startup ---- Apr 28 02:46:47 volumio volumio[19948]: info: ------------------------------------------- Apr 28 02:46:47 volumio volumio[19948]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 28 02:46:47 volumio volumio[19948]: error: MPD error: The expression evaluated to a falsy value: Apr 28 02:46:47 volumio volumio[19948]: assert.ok(self.idling) Apr 28 02:46:47 volumio volumio[19948]: error: The expression evaluated to a falsy value: Apr 28 02:46:47 volumio volumio[19948]: assert.ok(self.idling) Apr 28 02:46:47 volumio volumio[19948]: info: MPD running with PID20123 Apr 28 02:46:47 volumio volumio[19948]: ,establishing connection Apr 28 02:46:47 volumio volumio[19948]: error: updateQueue error: null Apr 28 02:46:47 volumio volumio[19948]: error: updateQueue error: null Apr 28 02:46:47 volumio volumio[19948]: info: [ASDebug] Togle GPIO: OFF Apr 28 02:46:47 volumio volumio[19948]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:46:47 volumio volumio[19948]: Error: EPERM: operation not permitted, write Apr 28 02:46:47 volumio volumio[19948]: at Object.writeSync (fs.js:737:3) Apr 28 02:46:47 volumio volumio[19948]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 28 02:46:47 volumio volumio[19948]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Apr 28 02:46:47 volumio volumio[19948]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Apr 28 02:46:47 volumio volumio[19948]: at listOnTimeout (internal/timers.js:557:17) Apr 28 02:46:47 volumio volumio[19948]: at processTimers (internal/timers.js:500:7) { Apr 28 02:46:47 volumio volumio[19948]: errno: -1, Apr 28 02:46:47 volumio volumio[19948]: syscall: 'write', Apr 28 02:46:47 volumio volumio[19948]: code: 'EPERM' Apr 28 02:46:47 volumio volumio[19948]: } Apr 28 02:46:47 volumio volumio[19948]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:46:47 volumio sudo[20184]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 02:45 Apr 28 02:46:47 volumio sudo[20184]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:47 volumio sudo[20184]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:47 volumio volumio-remote-updater[541]: [2025-04-28 02:46:47] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 28 02:46:47 volumio volumio-remote-updater[541]: [2025-04-28 02:46:47] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 28 02:46:47 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 28 02:46:47 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 28 02:46:47 volumio systemd[1]: Started dynamicswap service. Apr 28 02:46:47 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:46:47 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 28 02:46:47 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1021. Apr 28 02:46:47 volumio systemd[1]: Stopped Volumio Backend Module. Apr 28 02:46:47 volumio systemd[1]: Started Volumio Backend Module. Apr 28 02:46:47 volumio systemd[1]: Started dynamicswap service. Apr 28 02:46:47 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:46:48 volumio volumio[20201]: info: ------------------------------------------- Apr 28 02:46:48 volumio volumio[20201]: info: ----- Volumio3 ---- Apr 28 02:46:48 volumio volumio[20201]: info: ------------------------------------------- Apr 28 02:46:48 volumio volumio[20201]: info: ----- System startup ---- Apr 28 02:46:48 volumio volumio[20201]: info: ------------------------------------------- Apr 28 02:46:49 volumio volumio[20201]: info: MYVOLUMIO Environment detected Apr 28 02:46:49 volumio volumio[20201]: info: Plugin folders cleanup Apr 28 02:46:49 volumio volumio[20201]: info: Scanning into folder /volumio/app/plugins/ Apr 28 02:46:49 volumio volumio[20201]: info: Scanning category audio_interface Apr 28 02:46:49 volumio volumio[20201]: info: Scanning category miscellanea Apr 28 02:46:49 volumio volumio[20201]: info: Scanning category music_service Apr 28 02:46:49 volumio volumio[20201]: info: Scanning category plugins.json Apr 28 02:46:49 volumio volumio[20201]: info: Scanning category system_controller Apr 28 02:46:49 volumio volumio[20201]: info: Scanning category user_interface Apr 28 02:46:49 volumio volumio[20201]: info: Scanning into folder /data/plugins/ Apr 28 02:46:49 volumio volumio[20201]: info: Scanning category audio_interface Apr 28 02:46:49 volumio volumio[20201]: info: Scanning category music_service Apr 28 02:46:49 volumio volumio[20201]: info: Scanning category system_controller Apr 28 02:46:49 volumio volumio[20201]: info: Plugin folders cleanup completed Apr 28 02:46:49 volumio volumio[20201]: info: ------------------------------------------- Apr 28 02:46:49 volumio volumio[20201]: info: ----- Core plugins startup ---- Apr 28 02:46:49 volumio volumio[20201]: info: ------------------------------------------- Apr 28 02:46:49 volumio volumio[20201]: info: Loading plugins from folder /volumio/app/plugins/ Apr 28 02:46:49 volumio volumio[20201]: info: Adding plugin upnp to MyMusic Plugins Apr 28 02:46:49 volumio volumio[20201]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 28 02:46:49 volumio volumio[20201]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 28 02:46:49 volumio volumio[20201]: info: Loading plugins from folder /data/plugins/ Apr 28 02:46:49 volumio volumio[20201]: info: Loading plugin "system"... Apr 28 02:46:49 volumio volumio[20201]: info: Loading plugin "ampswitch"... Apr 28 02:46:49 volumio volumio[20201]: info: Applying required configuration parameters for plugin ampswitch Apr 28 02:46:49 volumio volumio[20201]: info: Loading plugin "appearance"... Apr 28 02:46:50 volumio volumio[20201]: info: Loading plugin "network"... Apr 28 02:46:50 volumio volumio[20201]: info: Refreshing Cached IP Addresses Apr 28 02:46:50 volumio sudo[20267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 02:46:50 volumio volumio[20201]: info: Loading plugin "services"... Apr 28 02:46:50 volumio volumio[20201]: info: Loading plugin "alsa_controller"... Apr 28 02:46:50 volumio sudo[20269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 02:46:50 volumio sudo[20267]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:50 volumio sudo[20269]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:50 volumio sudo[20267]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:50 volumio sudo[20269]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:50 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:50 volumio volumio[20201]: info: Loading plugin "wizard"... Apr 28 02:46:50 volumio volumio[20201]: info: Loading plugin "networkfs"... Apr 28 02:46:50 volumio volumio[20201]: info: Starting Udev Watcher for removable devices Apr 28 02:46:50 volumio volumio[20201]: info: Ignoring mount for partition: BOOT Apr 28 02:46:50 volumio volumio[20201]: info: Ignoring mount for partition: volumio Apr 28 02:46:50 volumio volumio[20201]: info: Ignoring mount for partition: volumio_data Apr 28 02:46:50 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:50 volumio volumio[20201]: info: Loading plugin "volumio_command_line_client"... Apr 28 02:46:50 volumio volumio[20201]: info: Loading plugin "upnp"... Apr 28 02:46:50 volumio volumio[20201]: info: [1745801210637] Starting Upmpd Daemon Apr 28 02:46:50 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:50 volumio volumio[20201]: info: Loading plugin "my_music"... Apr 28 02:46:50 volumio volumio[20201]: info: Loading plugin "mpd"... Apr 28 02:46:50 volumio volumio[20201]: info: Loading plugin "upnp_browser"... Apr 28 02:46:51 volumio volumio[20201]: info: Loading plugin "alarm-clock"... Apr 28 02:46:51 volumio volumio[20201]: info: Plugin airplay_emulation is not enabled Apr 28 02:46:51 volumio volumio[20201]: info: Loading plugin "last_100"... Apr 28 02:46:51 volumio volumio[20201]: info: Loading plugin "webradio"... Apr 28 02:46:51 volumio volumio[20201]: info: Loading plugin "i2s_dacs"... Apr 28 02:46:51 volumio volumio[20201]: info: I2S DAC not set, start Auto-detection Apr 28 02:46:51 volumio volumio[20201]: info: Loading plugin "volumiodiscovery"... Apr 28 02:46:51 volumio volumio[20201]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:51 volumio volumio[20201]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:51 volumio volumio[20201]: *** WARNING *** For more information see Apr 28 02:46:51 volumio volumio[20201]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:51 volumio node[20201]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:51 volumio volumio[20201]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:51 volumio volumio[20201]: *** WARNING *** For more information see Apr 28 02:46:51 volumio node[20201]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:51 volumio node[20201]: *** WARNING *** For more information see Apr 28 02:46:51 volumio node[20201]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:46:51 volumio node[20201]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:46:51 volumio node[20201]: *** WARNING *** For more information see Apr 28 02:46:51 volumio volumio[20201]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 28 02:46:51 volumio volumio[20201]: info: Discovery: Started advertising with name: Volumio Apr 28 02:46:51 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:46:51 volumio volumio[20201]: info: Loading plugin "mixcloud"... Apr 28 02:46:51 volumio volumio[20201]: info: Loading plugin "soundcloud"... Apr 28 02:46:51 volumio volumio[20201]: info: Loading plugin "youtube2"... Apr 28 02:46:52 volumio volumio-remote-updater[541]: [2025-04-28 02:46:52] [connect] Successful connection Apr 28 02:46:53 volumio volumio[20201]: info: Loading plugin "ytcr"... Apr 28 02:46:55 volumio volumio[20201]: info: Loading plugin "ytmusic"... Apr 28 02:46:56 volumio volumio[20201]: info: Loading plugin "outputs"... Apr 28 02:46:56 volumio volumio[20201]: info: Loading plugin "albumart"... Apr 28 02:46:56 volumio volumio[20201]: info: Plugin example_plugin is not enabled Apr 28 02:46:56 volumio volumio[20201]: info: Loading plugin "inputs"... Apr 28 02:46:56 volumio volumio[20201]: info: Loading plugin "updater_comm"... Apr 28 02:46:56 volumio volumio[20201]: info: Plugin mpdemulation is not enabled Apr 28 02:46:56 volumio volumio[20201]: info: Loading plugin "rest_api"... Apr 28 02:46:56 volumio volumio[20201]: info: Loading plugin "websocket"... Apr 28 02:46:56 volumio volumio[20201]: info: Starting Socket.io Server version 2.3.0 Apr 28 02:46:56 volumio volumio[20201]: info: Plugin fusiondsp is not enabled Apr 28 02:46:56 volumio volumio[20201]: info: Plugin minidlna is not enabled Apr 28 02:46:56 volumio volumio[20201]: info: Loading i18n strings for locale de Apr 28 02:46:56 volumio volumio[20201]: Updating browse sources language Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::initPlayerControls Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:56 volumio volumio[20201]: Express server listening on port 3000 Apr 28 02:46:56 volumio volumio[20201]: [Metrics] WebUI: 7s 791.91ms Apr 28 02:46:56 volumio volumio[20201]: info: CoreStateMachine::resetVolumioState Apr 28 02:46:56 volumio volumio[20201]: Forking 3 albumart workers Apr 28 02:46:56 volumio volumio[20201]: info: CoreStateMachine::getcurrentVolume Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:56 volumio volumio[20201]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 28 02:46:56 volumio volumio[20201]: info: Completed loading Core Plugins Apr 28 02:46:56 volumio volumio[20201]: info: Preparing to generate the ALSA configuration file Apr 28 02:46:56 volumio volumio[20201]: info: Volumio Network Manager: Network status updated: 1 Apr 28 02:46:56 volumio volumio-remote-updater[541]: [2025-04-28 02:46:56] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745801212 101 Apr 28 02:46:56 volumio volumio[20201]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 28 02:46:56 volumio volumio[20201]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 28 02:46:56 volumio volumio[20201]: info: CoreStateMachine::setRepeat false single undefined Apr 28 02:46:56 volumio volumio[20201]: info: CoreStateMachine::pushState Apr 28 02:46:56 volumio volumio[20201]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:56 volumio volumio[20201]: info: CoreStateMachine::setRandom true Apr 28 02:46:56 volumio volumio[20201]: info: CoreStateMachine::pushState Apr 28 02:46:56 volumio volumio[20201]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:56 volumio volumio[20201]: info: Setting Device type: Tinkerboard Apr 28 02:46:56 volumio volumio[20201]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:46:56 volumio volumio[20201]: info: CoreStateMachine::pushState Apr 28 02:46:56 volumio volumio[20201]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:56 volumio volumio[20201]: info: CoreStateMachine::updateTrackBlock Apr 28 02:46:56 volumio volumio[20201]: info: CorePlayQueue::getTrackBlock Apr 28 02:46:56 volumio volumio[20201]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:46:56 volumio volumio[20201]: info: Asound.conf file unchanged, so no further update is needed Apr 28 02:46:56 volumio volumio[20201]: info: Output device has changed, restarting MPD Apr 28 02:46:56 volumio volumio[20201]: info: ___________ START PLUGINS ___________ Apr 28 02:46:57 volumio sudo[20359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:46:57 volumio sudo[20357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:46:57 volumio sudo[20359]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:57 volumio kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Apr 28 02:46:57 volumio volumio[20201]: info: ControllerMpd::onStart: Initializing MPD Apr 28 02:46:57 volumio volumio[20201]: info: Creating MPD Configuration file Apr 28 02:46:57 volumio systemd[1]: Stopping Music Player Daemon... Apr 28 02:46:57 volumio sudo[20357]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:57 volumio sudo[20357]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:57 volumio volumio[20201]: info: [1745801217146] CoreMusicLibrary::Adding element Medienserver Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:57 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:46:57 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:46:57 volumio sudo[20376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:46:57 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:57 volumio volumio[20201]: info: [1745801217169] CoreMusicLibrary::Adding element Last_100 Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:57 volumio sudo[20376]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:57 volumio volumio[20201]: info: [1745801217203] CoreMusicLibrary::Adding element Webradio Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:57 volumio volumio[20201]: info: Initializing BBC Radios Apr 28 02:46:57 volumio sudo[20376]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:57 volumio sudo[20381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:46:57 volumio sudo[20381]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:57 volumio sudo[20382]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 28 02:46:57 volumio sudo[20382]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:57 volumio sudo[20382]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:57 volumio volumio[20201]: info: [1745801217365] CoreMusicLibrary::Adding element Mixcloud Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:57 volumio volumio[20201]: Cannot find translation for source Mixcloud Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:57 volumio volumio[20201]: info: [1745801217381] CoreMusicLibrary::Adding element SoundCloud Apr 28 02:46:57 volumio volumio[20201]: Starting albumart workers Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:57 volumio volumio[20201]: Cannot find translation for source Mixcloud Apr 28 02:46:57 volumio volumio[20201]: Cannot find translation for source SoundCloud Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:57 volumio volumio[20201]: info: [1745801217390] CoreMusicLibrary::Adding element YouTube2 Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:57 volumio volumio[20201]: Cannot find translation for source Mixcloud Apr 28 02:46:57 volumio volumio[20201]: Cannot find translation for source SoundCloud Apr 28 02:46:57 volumio volumio[20201]: Cannot find translation for source YouTube2 Apr 28 02:46:57 volumio volumio[20201]: Starting albumart workers Apr 28 02:46:57 volumio volumio[20201]: Starting albumart workers Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:46:57 volumio volumio[20201]: info: [1745801217465] CoreMusicLibrary::Adding element YouTube Music Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:46:57 volumio volumio[20201]: Cannot find translation for source Mixcloud Apr 28 02:46:57 volumio volumio[20201]: Cannot find translation for source SoundCloud Apr 28 02:46:57 volumio volumio[20201]: Cannot find translation for source YouTube2 Apr 28 02:46:57 volumio volumio[20201]: Cannot find translation for source YouTube Music Apr 28 02:46:57 volumio volumio[20201]: info: Volumio Calling Home Apr 28 02:46:57 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 28 02:46:57 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:46:57 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:46:57 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:46:57 volumio sudo[20414]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 28 02:46:57 volumio sudo[20414]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:57 volumio sudo[20414]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:57 volumio volumio[20201]: info: Volumio called home Apr 28 02:46:57 volumio volumio[20201]: info: MPD Permissions set Apr 28 02:46:57 volumio volumio[20201]: info: MPD Permissions set Apr 28 02:46:57 volumio volumio[20201]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:57 volumio volumio[20201]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:57 volumio volumio[20201]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:57 volumio volumio[20201]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:57 volumio volumio[20201]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:57 volumio volumio[20201]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:57 volumio volumio[20201]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:57 volumio volumio[20201]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:57 volumio volumio[20201]: info: Discovery: adding 009929a0-f413-4a13-a312-90def98326b0 Apr 28 02:46:57 volumio volumio[20201]: info: Discovery: Found device Volumio Apr 28 02:46:57 volumio volumio[20201]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:57 volumio volumio[20201]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:58 volumio volumio[20201]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:58 volumio volumio[20201]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 28 02:46:58 volumio volumio[20201]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:58 volumio volumio[20201]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:58 volumio volumio[20201]: info: CoreStateMachine::pushState Apr 28 02:46:58 volumio volumio[20201]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::volumioPushState Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:46:58 volumio volumio[20201]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:58 volumio volumio[20201]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:58 volumio volumio[20201]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:46:58 volumio volumio[20201]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:46:58 volumio volumio[20201]: info: CoreCommandRouter::volumioGetState Apr 28 02:46:58 volumio volumio[20201]: info: CorePlayQueue::getTrack 0 Apr 28 02:46:58 volumio volumio[20201]: info: [yt-cast-receiver] DIAL server listening on port 8098 Apr 28 02:46:59 volumio volumio[20201]: info: [ASDebug] Togle GPIO: OFF Apr 28 02:46:59 volumio volumio[20201]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:46:59 volumio volumio[20201]: Error: EPERM: operation not permitted, write Apr 28 02:46:59 volumio volumio[20201]: at Object.writeSync (fs.js:737:3) Apr 28 02:46:59 volumio volumio[20201]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 28 02:46:59 volumio volumio[20201]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Apr 28 02:46:59 volumio volumio[20201]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Apr 28 02:46:59 volumio volumio[20201]: at listOnTimeout (internal/timers.js:557:17) Apr 28 02:46:59 volumio volumio[20201]: at processTimers (internal/timers.js:500:7) { Apr 28 02:46:59 volumio volumio[20201]: errno: -1, Apr 28 02:46:59 volumio volumio[20201]: syscall: 'write', Apr 28 02:46:59 volumio volumio[20201]: code: 'EPERM' Apr 28 02:46:59 volumio volumio[20201]: } Apr 28 02:46:59 volumio volumio[20201]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:46:59 volumio mpd[20426]: Apr 28 02:46 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 28 02:46:59 volumio systemd[1]: Started Music Player Daemon. Apr 28 02:46:59 volumio sudo[20381]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:59 volumio sudo[20359]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:59 volumio sudo[20480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 02:45 Apr 28 02:46:59 volumio sudo[20480]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:46:59 volumio sudo[20480]: pam_unix(sudo:session): session closed for user root Apr 28 02:46:59 volumio volumio-remote-updater[541]: [2025-04-28 02:46:59] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 28 02:46:59 volumio volumio-remote-updater[541]: [2025-04-28 02:46:59] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 28 02:46:59 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 28 02:46:59 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 28 02:46:59 volumio systemd[1]: Started dynamicswap service. Apr 28 02:46:59 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:46:59 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 28 02:46:59 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1022. Apr 28 02:46:59 volumio systemd[1]: Started dynamicswap service. Apr 28 02:46:59 volumio systemd[1]: Stopped Volumio Backend Module. Apr 28 02:46:59 volumio systemd[1]: Started Volumio Backend Module. Apr 28 02:46:59 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:47:01 volumio volumio[20498]: info: ------------------------------------------- Apr 28 02:47:01 volumio volumio[20498]: info: ----- Volumio3 ---- Apr 28 02:47:01 volumio volumio[20498]: info: ------------------------------------------- Apr 28 02:47:01 volumio volumio[20498]: info: ----- System startup ---- Apr 28 02:47:01 volumio volumio[20498]: info: ------------------------------------------- Apr 28 02:47:01 volumio volumio[20498]: info: MYVOLUMIO Environment detected Apr 28 02:47:01 volumio volumio[20498]: info: Plugin folders cleanup Apr 28 02:47:01 volumio volumio[20498]: info: Scanning into folder /volumio/app/plugins/ Apr 28 02:47:01 volumio volumio[20498]: info: Scanning category audio_interface Apr 28 02:47:01 volumio volumio[20498]: info: Scanning category miscellanea Apr 28 02:47:01 volumio volumio[20498]: info: Scanning category music_service Apr 28 02:47:01 volumio volumio[20498]: info: Scanning category plugins.json Apr 28 02:47:01 volumio volumio[20498]: info: Scanning category system_controller Apr 28 02:47:01 volumio volumio[20498]: info: Scanning category user_interface Apr 28 02:47:01 volumio volumio[20498]: info: Scanning into folder /data/plugins/ Apr 28 02:47:01 volumio volumio[20498]: info: Scanning category audio_interface Apr 28 02:47:01 volumio volumio[20498]: info: Scanning category music_service Apr 28 02:47:01 volumio volumio[20498]: info: Scanning category system_controller Apr 28 02:47:01 volumio volumio[20498]: info: Plugin folders cleanup completed Apr 28 02:47:01 volumio volumio[20498]: info: ------------------------------------------- Apr 28 02:47:01 volumio volumio[20498]: info: ----- Core plugins startup ---- Apr 28 02:47:01 volumio volumio[20498]: info: ------------------------------------------- Apr 28 02:47:01 volumio volumio[20498]: info: Loading plugins from folder /volumio/app/plugins/ Apr 28 02:47:01 volumio volumio[20498]: info: Adding plugin upnp to MyMusic Plugins Apr 28 02:47:01 volumio volumio[20498]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 28 02:47:01 volumio volumio[20498]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 28 02:47:01 volumio volumio[20498]: info: Loading plugins from folder /data/plugins/ Apr 28 02:47:01 volumio volumio[20498]: info: Loading plugin "system"... Apr 28 02:47:01 volumio volumio[20498]: info: Loading plugin "ampswitch"... Apr 28 02:47:01 volumio volumio[20498]: info: Applying required configuration parameters for plugin ampswitch Apr 28 02:47:01 volumio volumio[20498]: info: Loading plugin "appearance"... Apr 28 02:47:02 volumio volumio[20498]: info: Loading plugin "network"... Apr 28 02:47:02 volumio volumio[20498]: info: Refreshing Cached IP Addresses Apr 28 02:47:02 volumio sudo[20525]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 02:47:02 volumio volumio[20498]: info: Loading plugin "services"... Apr 28 02:47:02 volumio volumio[20498]: info: Loading plugin "alsa_controller"... Apr 28 02:47:02 volumio sudo[20527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 02:47:02 volumio sudo[20525]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:02 volumio sudo[20525]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:02 volumio sudo[20527]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:02 volumio sudo[20527]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:02 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:47:02 volumio volumio[20498]: info: Loading plugin "wizard"... Apr 28 02:47:02 volumio volumio[20498]: info: Loading plugin "networkfs"... Apr 28 02:47:02 volumio volumio[20498]: info: Starting Udev Watcher for removable devices Apr 28 02:47:02 volumio volumio[20498]: info: Ignoring mount for partition: BOOT Apr 28 02:47:02 volumio volumio[20498]: info: Ignoring mount for partition: volumio Apr 28 02:47:02 volumio volumio[20498]: info: Ignoring mount for partition: volumio_data Apr 28 02:47:02 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:47:02 volumio volumio[20498]: info: Loading plugin "volumio_command_line_client"... Apr 28 02:47:02 volumio volumio[20498]: info: Loading plugin "upnp"... Apr 28 02:47:02 volumio volumio[20498]: info: [1745801222523] Starting Upmpd Daemon Apr 28 02:47:02 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:47:02 volumio volumio[20498]: info: Loading plugin "my_music"... Apr 28 02:47:02 volumio volumio[20498]: info: Loading plugin "mpd"... Apr 28 02:47:02 volumio volumio[20498]: info: Loading plugin "upnp_browser"... Apr 28 02:47:02 volumio volumio[20498]: info: Loading plugin "alarm-clock"... Apr 28 02:47:03 volumio volumio[20498]: info: Plugin airplay_emulation is not enabled Apr 28 02:47:03 volumio volumio[20498]: info: Loading plugin "last_100"... Apr 28 02:47:03 volumio volumio[20498]: info: Loading plugin "webradio"... Apr 28 02:47:03 volumio volumio[20498]: info: Loading plugin "i2s_dacs"... Apr 28 02:47:03 volumio volumio[20498]: info: I2S DAC not set, start Auto-detection Apr 28 02:47:03 volumio volumio[20498]: info: Loading plugin "volumiodiscovery"... Apr 28 02:47:03 volumio volumio[20498]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:47:03 volumio volumio[20498]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:47:03 volumio volumio[20498]: *** WARNING *** For more information see Apr 28 02:47:03 volumio volumio[20498]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:47:03 volumio node[20498]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:47:03 volumio volumio[20498]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:47:03 volumio volumio[20498]: *** WARNING *** For more information see Apr 28 02:47:03 volumio node[20498]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:47:03 volumio node[20498]: *** WARNING *** For more information see Apr 28 02:47:03 volumio node[20498]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:47:03 volumio node[20498]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:47:03 volumio node[20498]: *** WARNING *** For more information see Apr 28 02:47:03 volumio volumio[20498]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 28 02:47:03 volumio volumio[20498]: info: Discovery: Started advertising with name: Volumio Apr 28 02:47:03 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:47:03 volumio volumio[20498]: info: Loading plugin "mixcloud"... Apr 28 02:47:03 volumio volumio[20498]: info: Loading plugin "soundcloud"... Apr 28 02:47:03 volumio volumio[20498]: info: Loading plugin "youtube2"... Apr 28 02:47:04 volumio volumio-remote-updater[541]: [2025-04-28 02:47:04] [connect] Successful connection Apr 28 02:47:05 volumio volumio[20498]: info: Loading plugin "ytcr"... Apr 28 02:47:06 volumio volumio[20498]: info: Loading plugin "ytmusic"... Apr 28 02:47:07 volumio volumio[20498]: info: Loading plugin "outputs"... Apr 28 02:47:07 volumio volumio[20498]: info: Loading plugin "albumart"... Apr 28 02:47:07 volumio volumio[20498]: info: Plugin example_plugin is not enabled Apr 28 02:47:07 volumio volumio[20498]: info: Loading plugin "inputs"... Apr 28 02:47:07 volumio volumio[20498]: info: Loading plugin "updater_comm"... Apr 28 02:47:07 volumio volumio[20498]: info: Plugin mpdemulation is not enabled Apr 28 02:47:07 volumio volumio[20498]: info: Loading plugin "rest_api"... Apr 28 02:47:07 volumio volumio[20498]: info: Loading plugin "websocket"... Apr 28 02:47:07 volumio volumio[20498]: info: Starting Socket.io Server version 2.3.0 Apr 28 02:47:07 volumio volumio[20498]: info: Plugin fusiondsp is not enabled Apr 28 02:47:07 volumio volumio[20498]: info: Plugin minidlna is not enabled Apr 28 02:47:07 volumio volumio[20498]: info: Loading i18n strings for locale de Apr 28 02:47:07 volumio volumio[20498]: Updating browse sources language Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:47:07 volumio volumio[20498]: Forking 3 albumart workers Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::initPlayerControls Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:47:07 volumio volumio[20498]: Express server listening on port 3000 Apr 28 02:47:07 volumio volumio[20498]: [Metrics] WebUI: 6s 957.62ms Apr 28 02:47:07 volumio volumio[20498]: info: CoreStateMachine::resetVolumioState Apr 28 02:47:07 volumio volumio[20498]: info: CoreStateMachine::getcurrentVolume Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:47:07 volumio volumio[20498]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 28 02:47:07 volumio volumio[20498]: info: Completed loading Core Plugins Apr 28 02:47:07 volumio volumio[20498]: info: Preparing to generate the ALSA configuration file Apr 28 02:47:07 volumio volumio[20498]: info: Volumio Network Manager: Network status updated: 1 Apr 28 02:47:07 volumio volumio-remote-updater[541]: [2025-04-28 02:47:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745801224 101 Apr 28 02:47:07 volumio volumio[20498]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 28 02:47:07 volumio volumio[20498]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 28 02:47:07 volumio volumio[20498]: info: CoreStateMachine::setRepeat false single undefined Apr 28 02:47:07 volumio volumio[20498]: info: CoreStateMachine::pushState Apr 28 02:47:07 volumio volumio[20498]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::volumioPushState Apr 28 02:47:07 volumio volumio[20498]: info: CoreStateMachine::setRandom true Apr 28 02:47:07 volumio volumio[20498]: info: CoreStateMachine::pushState Apr 28 02:47:07 volumio volumio[20498]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:47:07 volumio volumio[20498]: info: CoreCommandRouter::volumioPushState Apr 28 02:47:08 volumio volumio[20498]: info: Setting Device type: Tinkerboard Apr 28 02:47:08 volumio volumio[20498]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:47:08 volumio volumio[20498]: info: CoreStateMachine::pushState Apr 28 02:47:08 volumio volumio[20498]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::volumioPushState Apr 28 02:47:08 volumio volumio[20498]: info: CoreStateMachine::updateTrackBlock Apr 28 02:47:08 volumio volumio[20498]: info: CorePlayQueue::getTrackBlock Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:47:08 volumio volumio[20498]: info: Asound.conf file unchanged, so no further update is needed Apr 28 02:47:08 volumio volumio[20498]: info: Output device has changed, restarting MPD Apr 28 02:47:08 volumio volumio[20498]: info: ___________ START PLUGINS ___________ Apr 28 02:47:08 volumio sudo[20617]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:47:08 volumio sudo[20619]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:47:08 volumio sudo[20619]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:08 volumio sudo[20617]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:08 volumio kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Apr 28 02:47:08 volumio volumio[20498]: info: ControllerMpd::onStart: Initializing MPD Apr 28 02:47:08 volumio volumio[20498]: info: Creating MPD Configuration file Apr 28 02:47:08 volumio sudo[20617]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:08 volumio systemd[1]: Stopping Music Player Daemon... Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:08 volumio volumio[20498]: info: [1745801228244] CoreMusicLibrary::Adding element Medienserver Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:08 volumio volumio[20498]: info: [1745801228273] CoreMusicLibrary::Adding element Last_100 Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:08 volumio volumio[20498]: info: [1745801228277] CoreMusicLibrary::Adding element Webradio Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:47:08 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:47:08 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:47:08 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:47:08 volumio sudo[20641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:47:08 volumio volumio[20498]: info: Initializing BBC Radios Apr 28 02:47:08 volumio sudo[20639]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:47:08 volumio volumio[20498]: Starting albumart workers Apr 28 02:47:08 volumio sudo[20639]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:08 volumio sudo[20641]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:08 volumio volumio[20498]: Starting albumart workers Apr 28 02:47:08 volumio sudo[20639]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:08 volumio volumio[20498]: Starting albumart workers Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:47:08 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 28 02:47:08 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:47:08 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:47:08 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:08 volumio volumio[20498]: info: [1745801228433] CoreMusicLibrary::Adding element Mixcloud Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:08 volumio volumio[20498]: Cannot find translation for source Mixcloud Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:08 volumio volumio[20498]: info: [1745801228452] CoreMusicLibrary::Adding element SoundCloud Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:08 volumio volumio[20498]: Cannot find translation for source Mixcloud Apr 28 02:47:08 volumio volumio[20498]: Cannot find translation for source SoundCloud Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:08 volumio volumio[20498]: info: [1745801228469] CoreMusicLibrary::Adding element YouTube2 Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:08 volumio volumio[20498]: Cannot find translation for source Mixcloud Apr 28 02:47:08 volumio volumio[20498]: Cannot find translation for source SoundCloud Apr 28 02:47:08 volumio volumio[20498]: Cannot find translation for source YouTube2 Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:08 volumio volumio[20498]: info: [1745801228564] CoreMusicLibrary::Adding element YouTube Music Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:08 volumio volumio[20498]: Cannot find translation for source Mixcloud Apr 28 02:47:08 volumio volumio[20498]: Cannot find translation for source SoundCloud Apr 28 02:47:08 volumio volumio[20498]: Cannot find translation for source YouTube2 Apr 28 02:47:08 volumio volumio[20498]: Cannot find translation for source YouTube Music Apr 28 02:47:08 volumio volumio[20498]: info: Volumio Calling Home Apr 28 02:47:08 volumio sudo[20666]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 28 02:47:08 volumio sudo[20666]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:08 volumio sudo[20666]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:08 volumio volumio[20498]: info: Volumio called home Apr 28 02:47:08 volumio volumio[20498]: info: MPD Permissions set Apr 28 02:47:08 volumio volumio[20498]: info: MPD Permissions set Apr 28 02:47:08 volumio volumio[20498]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:08 volumio volumio[20498]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:08 volumio volumio[20498]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:08 volumio volumio[20498]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:08 volumio volumio[20498]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:08 volumio volumio[20498]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:08 volumio volumio[20498]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:08 volumio volumio[20498]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:08 volumio volumio[20498]: info: Discovery: adding 009929a0-f413-4a13-a312-90def98326b0 Apr 28 02:47:08 volumio volumio[20498]: info: Discovery: Found device Volumio Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::volumioGetState Apr 28 02:47:08 volumio volumio[20498]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:08 volumio volumio[20498]: info: CoreCommandRouter::volumioGetState Apr 28 02:47:08 volumio volumio[20498]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:08 volumio volumio[20498]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:09 volumio volumio[20498]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::volumioGetState Apr 28 02:47:09 volumio volumio[20498]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:09 volumio volumio[20498]: info: CoreStateMachine::pushState Apr 28 02:47:09 volumio volumio[20498]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::volumioPushState Apr 28 02:47:09 volumio volumio[20498]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:09 volumio volumio[20498]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:09 volumio volumio[20498]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:09 volumio volumio[20498]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:09 volumio volumio[20498]: info: CoreCommandRouter::volumioGetState Apr 28 02:47:09 volumio volumio[20498]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:09 volumio volumio[20498]: info: [yt-cast-receiver] DIAL server listening on port 8098 Apr 28 02:47:09 volumio mpd[20692]: Apr 28 02:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 28 02:47:09 volumio systemd[1]: Started Music Player Daemon. Apr 28 02:47:09 volumio sudo[20619]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:09 volumio sudo[20641]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:09 volumio volumio[20498]: info: Completed starting Core Plugins Apr 28 02:47:09 volumio volumio[20498]: info: ------------------------------------------- Apr 28 02:47:09 volumio volumio[20498]: info: ----- MyVolumio plugins startup ---- Apr 28 02:47:09 volumio volumio[20498]: info: ------------------------------------------- Apr 28 02:47:09 volumio volumio[20498]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 28 02:47:09 volumio volumio[20498]: error: MPD error: The expression evaluated to a falsy value: Apr 28 02:47:09 volumio volumio[20498]: assert.ok(self.idling) Apr 28 02:47:09 volumio volumio[20498]: error: The expression evaluated to a falsy value: Apr 28 02:47:09 volumio volumio[20498]: assert.ok(self.idling) Apr 28 02:47:09 volumio volumio[20498]: info: MPD running with PID20692 Apr 28 02:47:09 volumio volumio[20498]: ,establishing connection Apr 28 02:47:09 volumio volumio[20498]: error: updateQueue error: null Apr 28 02:47:09 volumio volumio[20498]: error: updateQueue error: null Apr 28 02:47:09 volumio volumio[20498]: info: [ASDebug] Togle GPIO: OFF Apr 28 02:47:09 volumio volumio[20498]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:47:09 volumio volumio[20498]: Error: EPERM: operation not permitted, write Apr 28 02:47:09 volumio volumio[20498]: at Object.writeSync (fs.js:737:3) Apr 28 02:47:09 volumio volumio[20498]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 28 02:47:09 volumio volumio[20498]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Apr 28 02:47:09 volumio volumio[20498]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Apr 28 02:47:09 volumio volumio[20498]: at listOnTimeout (internal/timers.js:557:17) Apr 28 02:47:09 volumio volumio[20498]: at processTimers (internal/timers.js:500:7) { Apr 28 02:47:09 volumio volumio[20498]: errno: -1, Apr 28 02:47:09 volumio volumio[20498]: syscall: 'write', Apr 28 02:47:09 volumio volumio[20498]: code: 'EPERM' Apr 28 02:47:09 volumio volumio[20498]: } Apr 28 02:47:09 volumio volumio[20498]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:47:10 volumio sudo[20735]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 02:46 Apr 28 02:47:10 volumio sudo[20735]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:10 volumio sudo[20735]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:10 volumio volumio-remote-updater[541]: [2025-04-28 02:47:10] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 28 02:47:10 volumio volumio-remote-updater[541]: [2025-04-28 02:47:10] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 28 02:47:10 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 28 02:47:10 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 28 02:47:10 volumio systemd[1]: Started dynamicswap service. Apr 28 02:47:10 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:47:10 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 28 02:47:10 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1023. Apr 28 02:47:10 volumio systemd[1]: Stopped Volumio Backend Module. Apr 28 02:47:10 volumio systemd[1]: Started Volumio Backend Module. Apr 28 02:47:10 volumio systemd[1]: Started dynamicswap service. Apr 28 02:47:10 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:47:11 volumio volumio[20752]: info: ------------------------------------------- Apr 28 02:47:11 volumio volumio[20752]: info: ----- Volumio3 ---- Apr 28 02:47:11 volumio volumio[20752]: info: ------------------------------------------- Apr 28 02:47:11 volumio volumio[20752]: info: ----- System startup ---- Apr 28 02:47:11 volumio volumio[20752]: info: ------------------------------------------- Apr 28 02:47:12 volumio volumio[20752]: info: MYVOLUMIO Environment detected Apr 28 02:47:12 volumio volumio[20752]: info: Plugin folders cleanup Apr 28 02:47:12 volumio volumio[20752]: info: Scanning into folder /volumio/app/plugins/ Apr 28 02:47:12 volumio volumio[20752]: info: Scanning category audio_interface Apr 28 02:47:12 volumio volumio[20752]: info: Scanning category miscellanea Apr 28 02:47:12 volumio volumio[20752]: info: Scanning category music_service Apr 28 02:47:12 volumio volumio[20752]: info: Scanning category plugins.json Apr 28 02:47:12 volumio volumio[20752]: info: Scanning category system_controller Apr 28 02:47:12 volumio volumio[20752]: info: Scanning category user_interface Apr 28 02:47:12 volumio volumio[20752]: info: Scanning into folder /data/plugins/ Apr 28 02:47:12 volumio volumio[20752]: info: Scanning category audio_interface Apr 28 02:47:12 volumio volumio[20752]: info: Scanning category music_service Apr 28 02:47:12 volumio volumio[20752]: info: Scanning category system_controller Apr 28 02:47:12 volumio volumio[20752]: info: Plugin folders cleanup completed Apr 28 02:47:12 volumio volumio[20752]: info: ------------------------------------------- Apr 28 02:47:12 volumio volumio[20752]: info: ----- Core plugins startup ---- Apr 28 02:47:12 volumio volumio[20752]: info: ------------------------------------------- Apr 28 02:47:12 volumio volumio[20752]: info: Loading plugins from folder /volumio/app/plugins/ Apr 28 02:47:12 volumio volumio[20752]: info: Adding plugin upnp to MyMusic Plugins Apr 28 02:47:12 volumio volumio[20752]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 28 02:47:12 volumio volumio[20752]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 28 02:47:12 volumio volumio[20752]: info: Loading plugins from folder /data/plugins/ Apr 28 02:47:12 volumio volumio[20752]: info: Loading plugin "system"... Apr 28 02:47:12 volumio volumio[20752]: info: Loading plugin "ampswitch"... Apr 28 02:47:12 volumio volumio[20752]: info: Applying required configuration parameters for plugin ampswitch Apr 28 02:47:12 volumio volumio[20752]: info: Loading plugin "appearance"... Apr 28 02:47:12 volumio volumio[20752]: info: Loading plugin "network"... Apr 28 02:47:12 volumio volumio[20752]: info: Refreshing Cached IP Addresses Apr 28 02:47:13 volumio sudo[20780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "services"... Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "alsa_controller"... Apr 28 02:47:13 volumio sudo[20782]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 02:47:13 volumio sudo[20780]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:13 volumio sudo[20782]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:13 volumio sudo[20780]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:13 volumio sudo[20782]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:13 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "wizard"... Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "networkfs"... Apr 28 02:47:13 volumio volumio[20752]: info: Starting Udev Watcher for removable devices Apr 28 02:47:13 volumio volumio[20752]: info: Ignoring mount for partition: BOOT Apr 28 02:47:13 volumio volumio[20752]: info: Ignoring mount for partition: volumio Apr 28 02:47:13 volumio volumio[20752]: info: Ignoring mount for partition: volumio_data Apr 28 02:47:13 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "volumio_command_line_client"... Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "upnp"... Apr 28 02:47:13 volumio volumio[20752]: info: [1745801233200] Starting Upmpd Daemon Apr 28 02:47:13 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "my_music"... Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "mpd"... Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "upnp_browser"... Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "alarm-clock"... Apr 28 02:47:13 volumio volumio[20752]: info: Plugin airplay_emulation is not enabled Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "last_100"... Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "webradio"... Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "i2s_dacs"... Apr 28 02:47:13 volumio volumio[20752]: info: I2S DAC not set, start Auto-detection Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "volumiodiscovery"... Apr 28 02:47:13 volumio volumio[20752]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:47:13 volumio volumio[20752]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:47:13 volumio volumio[20752]: *** WARNING *** For more information see Apr 28 02:47:13 volumio volumio[20752]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:47:13 volumio volumio[20752]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:47:13 volumio node[20752]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:47:13 volumio volumio[20752]: *** WARNING *** For more information see Apr 28 02:47:13 volumio node[20752]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:47:13 volumio node[20752]: *** WARNING *** For more information see Apr 28 02:47:13 volumio node[20752]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:47:13 volumio node[20752]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:47:13 volumio node[20752]: *** WARNING *** For more information see Apr 28 02:47:13 volumio volumio[20752]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 28 02:47:13 volumio volumio[20752]: info: Discovery: Started advertising with name: Volumio Apr 28 02:47:13 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:47:13 volumio volumio[20752]: info: Loading plugin "mixcloud"... Apr 28 02:47:14 volumio volumio[20752]: info: Loading plugin "soundcloud"... Apr 28 02:47:14 volumio volumio[20752]: info: Loading plugin "youtube2"... Apr 28 02:47:15 volumio volumio-remote-updater[541]: [2025-04-28 02:47:15] [connect] Successful connection Apr 28 02:47:16 volumio volumio[20752]: info: Loading plugin "ytcr"... Apr 28 02:47:17 volumio volumio[20752]: info: Loading plugin "ytmusic"... Apr 28 02:47:17 volumio volumio[20752]: info: Loading plugin "outputs"... Apr 28 02:47:17 volumio volumio[20752]: info: Loading plugin "albumart"... Apr 28 02:47:17 volumio volumio[20752]: info: Plugin example_plugin is not enabled Apr 28 02:47:17 volumio volumio[20752]: info: Loading plugin "inputs"... Apr 28 02:47:17 volumio volumio[20752]: info: Loading plugin "updater_comm"... Apr 28 02:47:17 volumio volumio[20752]: info: Plugin mpdemulation is not enabled Apr 28 02:47:17 volumio volumio[20752]: info: Loading plugin "rest_api"... Apr 28 02:47:17 volumio volumio[20752]: info: Loading plugin "websocket"... Apr 28 02:47:17 volumio volumio[20752]: info: Starting Socket.io Server version 2.3.0 Apr 28 02:47:17 volumio volumio[20752]: info: Plugin fusiondsp is not enabled Apr 28 02:47:17 volumio volumio[20752]: info: Plugin minidlna is not enabled Apr 28 02:47:17 volumio volumio[20752]: info: Loading i18n strings for locale de Apr 28 02:47:17 volumio volumio[20752]: Updating browse sources language Apr 28 02:47:17 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::initPlayerControls Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:47:18 volumio volumio[20752]: Express server listening on port 3000 Apr 28 02:47:18 volumio volumio[20752]: [Metrics] WebUI: 6s 703.94ms Apr 28 02:47:18 volumio volumio[20752]: Forking 3 albumart workers Apr 28 02:47:18 volumio volumio[20752]: info: CoreStateMachine::resetVolumioState Apr 28 02:47:18 volumio volumio[20752]: info: CoreStateMachine::getcurrentVolume Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:47:18 volumio volumio[20752]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 28 02:47:18 volumio volumio[20752]: info: Completed loading Core Plugins Apr 28 02:47:18 volumio volumio[20752]: info: Preparing to generate the ALSA configuration file Apr 28 02:47:18 volumio volumio[20752]: info: Volumio Network Manager: Network status updated: 1 Apr 28 02:47:18 volumio volumio-remote-updater[541]: [2025-04-28 02:47:18] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745801235 101 Apr 28 02:47:18 volumio volumio[20752]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 28 02:47:18 volumio volumio[20752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 28 02:47:18 volumio volumio[20752]: info: CoreStateMachine::setRepeat false single undefined Apr 28 02:47:18 volumio volumio[20752]: info: CoreStateMachine::pushState Apr 28 02:47:18 volumio volumio[20752]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::volumioPushState Apr 28 02:47:18 volumio volumio[20752]: info: CoreStateMachine::setRandom true Apr 28 02:47:18 volumio volumio[20752]: info: CoreStateMachine::pushState Apr 28 02:47:18 volumio volumio[20752]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::volumioPushState Apr 28 02:47:18 volumio volumio[20752]: info: Setting Device type: Tinkerboard Apr 28 02:47:18 volumio volumio[20752]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:47:18 volumio volumio[20752]: info: CoreStateMachine::pushState Apr 28 02:47:18 volumio volumio[20752]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::volumioPushState Apr 28 02:47:18 volumio volumio[20752]: info: CoreStateMachine::updateTrackBlock Apr 28 02:47:18 volumio volumio[20752]: info: CorePlayQueue::getTrackBlock Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:47:18 volumio volumio[20752]: info: Asound.conf file unchanged, so no further update is needed Apr 28 02:47:18 volumio volumio[20752]: info: Output device has changed, restarting MPD Apr 28 02:47:18 volumio sudo[20869]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:47:18 volumio volumio[20752]: info: ___________ START PLUGINS ___________ Apr 28 02:47:18 volumio sudo[20869]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:18 volumio sudo[20873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:47:18 volumio sudo[20869]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:18 volumio sudo[20873]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:18 volumio kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Apr 28 02:47:18 volumio volumio[20752]: info: ControllerMpd::onStart: Initializing MPD Apr 28 02:47:18 volumio volumio[20752]: info: Creating MPD Configuration file Apr 28 02:47:18 volumio systemd[1]: Stopping Music Player Daemon... Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:18 volumio volumio[20752]: info: [1745801238633] CoreMusicLibrary::Adding element Medienserver Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:18 volumio volumio[20752]: info: [1745801238656] CoreMusicLibrary::Adding element Last_100 Apr 28 02:47:18 volumio sudo[20892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:18 volumio volumio[20752]: info: [1745801238659] CoreMusicLibrary::Adding element Webradio Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:47:18 volumio volumio[20752]: info: Initializing BBC Radios Apr 28 02:47:18 volumio sudo[20893]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:47:18 volumio sudo[20892]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:18 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:47:18 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:47:18 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:47:18 volumio sudo[20892]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:18 volumio sudo[20893]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:47:18 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 28 02:47:18 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:47:18 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:18 volumio volumio[20752]: info: [1745801238807] CoreMusicLibrary::Adding element Mixcloud Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:18 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:47:18 volumio volumio[20752]: Cannot find translation for source Mixcloud Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:18 volumio volumio[20752]: info: [1745801238816] CoreMusicLibrary::Adding element SoundCloud Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:18 volumio volumio[20752]: Cannot find translation for source Mixcloud Apr 28 02:47:18 volumio volumio[20752]: Cannot find translation for source SoundCloud Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:18 volumio volumio[20752]: Starting albumart workers Apr 28 02:47:18 volumio volumio[20752]: info: [1745801238842] CoreMusicLibrary::Adding element YouTube2 Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:18 volumio volumio[20752]: Cannot find translation for source Mixcloud Apr 28 02:47:18 volumio volumio[20752]: Cannot find translation for source SoundCloud Apr 28 02:47:18 volumio volumio[20752]: Cannot find translation for source YouTube2 Apr 28 02:47:18 volumio volumio[20752]: Starting albumart workers Apr 28 02:47:18 volumio volumio[20752]: Starting albumart workers Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:18 volumio volumio[20752]: info: [1745801238891] CoreMusicLibrary::Adding element YouTube Music Apr 28 02:47:18 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:18 volumio volumio[20752]: Cannot find translation for source Mixcloud Apr 28 02:47:18 volumio volumio[20752]: Cannot find translation for source SoundCloud Apr 28 02:47:18 volumio volumio[20752]: Cannot find translation for source YouTube2 Apr 28 02:47:18 volumio volumio[20752]: Cannot find translation for source YouTube Music Apr 28 02:47:18 volumio volumio[20752]: info: Volumio Calling Home Apr 28 02:47:18 volumio sudo[20918]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 28 02:47:18 volumio sudo[20918]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:18 volumio sudo[20918]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:19 volumio volumio[20752]: info: Volumio called home Apr 28 02:47:19 volumio volumio[20752]: info: MPD Permissions set Apr 28 02:47:19 volumio volumio[20752]: info: MPD Permissions set Apr 28 02:47:19 volumio volumio[20752]: info: Discovery: adding 009929a0-f413-4a13-a312-90def98326b0 Apr 28 02:47:19 volumio volumio[20752]: info: Discovery: Found device Volumio Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::volumioGetState Apr 28 02:47:19 volumio volumio[20752]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:19 volumio volumio[20752]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:19 volumio volumio[20752]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:19 volumio volumio[20752]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:19 volumio volumio[20752]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:19 volumio volumio[20752]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:19 volumio volumio[20752]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:19 volumio volumio[20752]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:19 volumio volumio[20752]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::volumioGetState Apr 28 02:47:19 volumio volumio[20752]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:19 volumio volumio[20752]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 28 02:47:19 volumio volumio[20752]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::volumioGetState Apr 28 02:47:19 volumio volumio[20752]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:19 volumio volumio[20752]: info: CoreStateMachine::pushState Apr 28 02:47:19 volumio volumio[20752]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::volumioPushState Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:19 volumio volumio[20752]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:19 volumio volumio[20752]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:19 volumio volumio[20752]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:19 volumio volumio[20752]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:19 volumio volumio[20752]: info: CoreCommandRouter::volumioGetState Apr 28 02:47:19 volumio volumio[20752]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:20 volumio volumio[20752]: info: [yt-cast-receiver] DIAL server listening on port 8098 Apr 28 02:47:20 volumio volumio[20752]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:47:20 volumio volumio[20752]: Error: bind EADDRINUSE 192.168.178.46:50424 Apr 28 02:47:20 volumio volumio[20752]: at dgram.js:357:20 Apr 28 02:47:20 volumio volumio[20752]: at processTicksAndRejections (internal/process/task_queues.js:83:21) { Apr 28 02:47:20 volumio volumio[20752]: errno: -98, Apr 28 02:47:20 volumio volumio[20752]: code: 'EADDRINUSE', Apr 28 02:47:20 volumio volumio[20752]: syscall: 'bind', Apr 28 02:47:20 volumio volumio[20752]: address: '192.168.178.46', Apr 28 02:47:20 volumio volumio[20752]: port: 50424 Apr 28 02:47:20 volumio volumio[20752]: } Apr 28 02:47:20 volumio volumio[20752]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:47:20 volumio mpd[20930]: Apr 28 02:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 28 02:47:20 volumio systemd[1]: Started Music Player Daemon. Apr 28 02:47:20 volumio sudo[20873]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:20 volumio sudo[20893]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:20 volumio sudo[20985]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 02:46 Apr 28 02:47:20 volumio sudo[20985]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:20 volumio sudo[20985]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:20 volumio volumio-remote-updater[541]: [2025-04-28 02:47:20] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Apr 28 02:47:20 volumio volumio-remote-updater[541]: [2025-04-28 02:47:20] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Apr 28 02:47:20 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Apr 28 02:47:20 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Apr 28 02:47:20 volumio systemd[1]: Started dynamicswap service. Apr 28 02:47:20 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:47:21 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Apr 28 02:47:21 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1024. Apr 28 02:47:21 volumio systemd[1]: Started dynamicswap service. Apr 28 02:47:21 volumio systemd[1]: Stopped Volumio Backend Module. Apr 28 02:47:21 volumio systemd[1]: Started Volumio Backend Module. Apr 28 02:47:21 volumio systemd[1]: dynamicswap.service: Succeeded. Apr 28 02:47:22 volumio volumio[21006]: info: ------------------------------------------- Apr 28 02:47:22 volumio volumio[21006]: info: ----- Volumio3 ---- Apr 28 02:47:22 volumio volumio[21006]: info: ------------------------------------------- Apr 28 02:47:22 volumio volumio[21006]: info: ----- System startup ---- Apr 28 02:47:22 volumio volumio[21006]: info: ------------------------------------------- Apr 28 02:47:22 volumio volumio[21006]: info: MYVOLUMIO Environment detected Apr 28 02:47:22 volumio volumio[21006]: info: Plugin folders cleanup Apr 28 02:47:22 volumio volumio[21006]: info: Scanning into folder /volumio/app/plugins/ Apr 28 02:47:22 volumio volumio[21006]: info: Scanning category audio_interface Apr 28 02:47:22 volumio volumio[21006]: info: Scanning category miscellanea Apr 28 02:47:22 volumio volumio[21006]: info: Scanning category music_service Apr 28 02:47:22 volumio volumio[21006]: info: Scanning category plugins.json Apr 28 02:47:22 volumio volumio[21006]: info: Scanning category system_controller Apr 28 02:47:22 volumio volumio[21006]: info: Scanning category user_interface Apr 28 02:47:22 volumio volumio[21006]: info: Scanning into folder /data/plugins/ Apr 28 02:47:22 volumio volumio[21006]: info: Scanning category audio_interface Apr 28 02:47:22 volumio volumio[21006]: info: Scanning category music_service Apr 28 02:47:22 volumio volumio[21006]: info: Scanning category system_controller Apr 28 02:47:22 volumio volumio[21006]: info: Plugin folders cleanup completed Apr 28 02:47:22 volumio volumio[21006]: info: ------------------------------------------- Apr 28 02:47:22 volumio volumio[21006]: info: ----- Core plugins startup ---- Apr 28 02:47:22 volumio volumio[21006]: info: ------------------------------------------- Apr 28 02:47:22 volumio volumio[21006]: info: Loading plugins from folder /volumio/app/plugins/ Apr 28 02:47:22 volumio volumio[21006]: info: Adding plugin upnp to MyMusic Plugins Apr 28 02:47:22 volumio volumio[21006]: info: Adding plugin airplay_emulation to MyMusic Plugins Apr 28 02:47:22 volumio volumio[21006]: info: Adding plugin upnp_browser to MyMusic Plugins Apr 28 02:47:22 volumio volumio[21006]: info: Loading plugins from folder /data/plugins/ Apr 28 02:47:22 volumio volumio[21006]: info: Loading plugin "system"... Apr 28 02:47:22 volumio volumio[21006]: info: Loading plugin "ampswitch"... Apr 28 02:47:22 volumio volumio[21006]: info: Applying required configuration parameters for plugin ampswitch Apr 28 02:47:22 volumio volumio[21006]: info: Loading plugin "appearance"... Apr 28 02:47:23 volumio volumio[21006]: info: Loading plugin "network"... Apr 28 02:47:23 volumio volumio[21006]: info: Refreshing Cached IP Addresses Apr 28 02:47:23 volumio sudo[21034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 28 02:47:23 volumio volumio[21006]: info: Loading plugin "services"... Apr 28 02:47:23 volumio volumio[21006]: info: Loading plugin "alsa_controller"... Apr 28 02:47:23 volumio sudo[21036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 28 02:47:23 volumio sudo[21034]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:23 volumio sudo[21036]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:23 volumio sudo[21034]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:23 volumio sudo[21036]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:23 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:47:23 volumio volumio[21006]: info: Loading plugin "wizard"... Apr 28 02:47:23 volumio volumio[21006]: info: Loading plugin "networkfs"... Apr 28 02:47:23 volumio volumio[21006]: info: Starting Udev Watcher for removable devices Apr 28 02:47:23 volumio volumio[21006]: info: Ignoring mount for partition: BOOT Apr 28 02:47:23 volumio volumio[21006]: info: Ignoring mount for partition: volumio Apr 28 02:47:23 volumio volumio[21006]: info: Ignoring mount for partition: volumio_data Apr 28 02:47:23 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:47:23 volumio volumio[21006]: info: Loading plugin "volumio_command_line_client"... Apr 28 02:47:23 volumio volumio[21006]: info: Loading plugin "upnp"... Apr 28 02:47:23 volumio volumio[21006]: info: [1745801243584] Starting Upmpd Daemon Apr 28 02:47:23 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:47:23 volumio volumio[21006]: info: Loading plugin "my_music"... Apr 28 02:47:23 volumio volumio[21006]: info: Loading plugin "mpd"... Apr 28 02:47:23 volumio volumio[21006]: info: Loading plugin "upnp_browser"... Apr 28 02:47:23 volumio volumio[21006]: info: Loading plugin "alarm-clock"... Apr 28 02:47:24 volumio volumio[21006]: info: Plugin airplay_emulation is not enabled Apr 28 02:47:24 volumio volumio[21006]: info: Loading plugin "last_100"... Apr 28 02:47:24 volumio volumio[21006]: info: Loading plugin "webradio"... Apr 28 02:47:24 volumio volumio[21006]: info: Loading plugin "i2s_dacs"... Apr 28 02:47:24 volumio volumio[21006]: info: I2S DAC not set, start Auto-detection Apr 28 02:47:24 volumio volumio[21006]: info: Loading plugin "volumiodiscovery"... Apr 28 02:47:24 volumio volumio[21006]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:47:24 volumio volumio[21006]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:47:24 volumio volumio[21006]: *** WARNING *** For more information see Apr 28 02:47:24 volumio volumio[21006]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:47:24 volumio volumio[21006]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:47:24 volumio node[21006]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Apr 28 02:47:24 volumio volumio[21006]: *** WARNING *** For more information see Apr 28 02:47:24 volumio node[21006]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:47:24 volumio node[21006]: *** WARNING *** For more information see Apr 28 02:47:24 volumio node[21006]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Apr 28 02:47:24 volumio node[21006]: *** WARNING *** Please fix your application to use the native API of Avahi! Apr 28 02:47:24 volumio node[21006]: *** WARNING *** For more information see Apr 28 02:47:24 volumio volumio[21006]: info: Applying required configuration parameters for plugin volumiodiscovery Apr 28 02:47:24 volumio volumio[21006]: info: Discovery: Started advertising with name: Volumio Apr 28 02:47:24 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Apr 28 02:47:24 volumio volumio[21006]: info: Loading plugin "mixcloud"... Apr 28 02:47:24 volumio volumio[21006]: info: Loading plugin "soundcloud"... Apr 28 02:47:24 volumio volumio[21006]: info: Loading plugin "youtube2"... Apr 28 02:47:25 volumio volumio-remote-updater[541]: [2025-04-28 02:47:25] [connect] Successful connection Apr 28 02:47:26 volumio volumio[21006]: info: Loading plugin "ytcr"... Apr 28 02:47:27 volumio volumio[21006]: info: Loading plugin "ytmusic"... Apr 28 02:47:28 volumio volumio[21006]: info: Loading plugin "outputs"... Apr 28 02:47:28 volumio volumio[21006]: info: Loading plugin "albumart"... Apr 28 02:47:28 volumio volumio[21006]: info: Plugin example_plugin is not enabled Apr 28 02:47:28 volumio volumio[21006]: info: Loading plugin "inputs"... Apr 28 02:47:28 volumio volumio[21006]: info: Loading plugin "updater_comm"... Apr 28 02:47:28 volumio volumio[21006]: info: Plugin mpdemulation is not enabled Apr 28 02:47:28 volumio volumio[21006]: info: Loading plugin "rest_api"... Apr 28 02:47:28 volumio volumio[21006]: info: Loading plugin "websocket"... Apr 28 02:47:28 volumio volumio[21006]: info: Starting Socket.io Server version 2.3.0 Apr 28 02:47:28 volumio volumio[21006]: info: Plugin fusiondsp is not enabled Apr 28 02:47:28 volumio volumio[21006]: info: Plugin minidlna is not enabled Apr 28 02:47:28 volumio volumio[21006]: info: Loading i18n strings for locale de Apr 28 02:47:28 volumio volumio[21006]: Updating browse sources language Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::initPlayerControls Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:47:28 volumio volumio[21006]: Express server listening on port 3000 Apr 28 02:47:28 volumio volumio[21006]: [Metrics] WebUI: 6s 534.28ms Apr 28 02:47:28 volumio volumio[21006]: Forking 3 albumart workers Apr 28 02:47:28 volumio volumio[21006]: info: CoreStateMachine::resetVolumioState Apr 28 02:47:28 volumio volumio[21006]: info: CoreStateMachine::getcurrentVolume Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:47:28 volumio volumio[21006]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Apr 28 02:47:28 volumio volumio[21006]: info: Completed loading Core Plugins Apr 28 02:47:28 volumio volumio[21006]: info: Preparing to generate the ALSA configuration file Apr 28 02:47:28 volumio volumio[21006]: info: Volumio Network Manager: Network status updated: 1 Apr 28 02:47:28 volumio volumio-remote-updater[541]: [2025-04-28 02:47:28] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1745801245 101 Apr 28 02:47:28 volumio volumio[21006]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Apr 28 02:47:28 volumio volumio[21006]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Apr 28 02:47:28 volumio volumio[21006]: info: CoreStateMachine::setRepeat false single undefined Apr 28 02:47:28 volumio volumio[21006]: info: CoreStateMachine::pushState Apr 28 02:47:28 volumio volumio[21006]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::volumioPushState Apr 28 02:47:28 volumio volumio[21006]: info: CoreStateMachine::setRandom true Apr 28 02:47:28 volumio volumio[21006]: info: CoreStateMachine::pushState Apr 28 02:47:28 volumio volumio[21006]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::volumioPushState Apr 28 02:47:28 volumio volumio[21006]: info: Setting Device type: Tinkerboard Apr 28 02:47:28 volumio volumio[21006]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:47:28 volumio volumio[21006]: info: CoreStateMachine::pushState Apr 28 02:47:28 volumio volumio[21006]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::volumioPushState Apr 28 02:47:28 volumio volumio[21006]: info: CoreStateMachine::updateTrackBlock Apr 28 02:47:28 volumio volumio[21006]: info: CorePlayQueue::getTrackBlock Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::volumioRetrievevolume Apr 28 02:47:28 volumio volumio[21006]: info: Asound.conf file unchanged, so no further update is needed Apr 28 02:47:28 volumio volumio[21006]: info: Output device has changed, restarting MPD Apr 28 02:47:28 volumio volumio[21006]: info: ___________ START PLUGINS ___________ Apr 28 02:47:28 volumio sudo[21126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:47:28 volumio sudo[21126]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:28 volumio kernel: rockchip-pinctrl pinctrl: pin 0 is unrouted Apr 28 02:47:28 volumio sudo[21128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:47:28 volumio volumio[21006]: info: ControllerMpd::onStart: Initializing MPD Apr 28 02:47:28 volumio volumio[21006]: info: Creating MPD Configuration file Apr 28 02:47:28 volumio sudo[21126]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:28 volumio sudo[21128]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:28 volumio sudo[21145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:28 volumio volumio[21006]: info: [1745801248978] CoreMusicLibrary::Adding element Medienserver Apr 28 02:47:28 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:29 volumio systemd[1]: Stopping Music Player Daemon... Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:29 volumio volumio[21006]: info: [1745801249008] CoreMusicLibrary::Adding element Last_100 Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:29 volumio volumio[21006]: info: [1745801249012] CoreMusicLibrary::Adding element Webradio Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:47:29 volumio volumio[21006]: info: Initializing BBC Radios Apr 28 02:47:29 volumio sudo[21151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 28 02:47:29 volumio sudo[21145]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:29 volumio sudo[21145]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:29 volumio sudo[21151]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:29 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:47:29 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:47:29 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:29 volumio volumio[21006]: info: [1745801249136] CoreMusicLibrary::Adding element Mixcloud Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:29 volumio volumio[21006]: Cannot find translation for source Mixcloud Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:29 volumio volumio[21006]: info: [1745801249148] CoreMusicLibrary::Adding element SoundCloud Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:29 volumio volumio[21006]: Cannot find translation for source Mixcloud Apr 28 02:47:29 volumio volumio[21006]: Cannot find translation for source SoundCloud Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:29 volumio volumio[21006]: info: [1745801249158] CoreMusicLibrary::Adding element YouTube2 Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:29 volumio volumio[21006]: Cannot find translation for source Mixcloud Apr 28 02:47:29 volumio volumio[21006]: Cannot find translation for source SoundCloud Apr 28 02:47:29 volumio volumio[21006]: Cannot find translation for source YouTube2 Apr 28 02:47:29 volumio volumio[21006]: Starting albumart workers Apr 28 02:47:29 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Apr 28 02:47:29 volumio systemd[1]: mpd.service: Succeeded. Apr 28 02:47:29 volumio systemd[1]: Stopped Music Player Daemon. Apr 28 02:47:29 volumio systemd[1]: Starting Music Player Daemon... Apr 28 02:47:29 volumio volumio[21006]: Starting albumart workers Apr 28 02:47:29 volumio volumio[21006]: Starting albumart workers Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 28 02:47:29 volumio volumio[21006]: info: [1745801249275] CoreMusicLibrary::Adding element YouTube Music Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 28 02:47:29 volumio volumio[21006]: Cannot find translation for source Mixcloud Apr 28 02:47:29 volumio volumio[21006]: Cannot find translation for source SoundCloud Apr 28 02:47:29 volumio volumio[21006]: Cannot find translation for source YouTube2 Apr 28 02:47:29 volumio volumio[21006]: Cannot find translation for source YouTube Music Apr 28 02:47:29 volumio volumio[21006]: info: Volumio Calling Home Apr 28 02:47:29 volumio sudo[21174]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 28 02:47:29 volumio sudo[21174]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 28 02:47:29 volumio sudo[21174]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:29 volumio volumio[21006]: info: Volumio called home Apr 28 02:47:29 volumio volumio[21006]: info: MPD Permissions set Apr 28 02:47:29 volumio volumio[21006]: info: MPD Permissions set Apr 28 02:47:29 volumio volumio[21006]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:29 volumio volumio[21006]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:29 volumio volumio[21006]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:29 volumio volumio[21006]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:29 volumio volumio[21006]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:29 volumio volumio[21006]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:29 volumio volumio[21006]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:29 volumio volumio[21006]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:29 volumio volumio[21006]: info: Discovery: adding 009929a0-f413-4a13-a312-90def98326b0 Apr 28 02:47:29 volumio volumio[21006]: info: Discovery: Found device Volumio Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::volumioGetState Apr 28 02:47:29 volumio volumio[21006]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::volumioGetState Apr 28 02:47:29 volumio volumio[21006]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:29 volumio volumio[21006]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Apr 28 02:47:29 volumio volumio[21006]: info: VolumeController:: Volume=20 Mute =false Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::volumioGetState Apr 28 02:47:29 volumio volumio[21006]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:29 volumio volumio[21006]: info: CoreStateMachine::pushState Apr 28 02:47:29 volumio volumio[21006]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::volumioPushState Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 28 02:47:29 volumio volumio[21006]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 28 02:47:30 volumio volumio[21006]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:30 volumio volumio[21006]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:30 volumio volumio[21006]: info: [ASDebug] CurState: stop PrevState: na Apr 28 02:47:30 volumio volumio[21006]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 28 02:47:30 volumio volumio[21006]: info: CoreCommandRouter::volumioGetState Apr 28 02:47:30 volumio volumio[21006]: info: CorePlayQueue::getTrack 0 Apr 28 02:47:30 volumio volumio[21006]: info: [yt-cast-receiver] DIAL server listening on port 8098 Apr 28 02:47:30 volumio mpd[21186]: Apr 28 02:47 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 28 02:47:30 volumio systemd[1]: Started Music Player Daemon. Apr 28 02:47:30 volumio sudo[21128]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:30 volumio sudo[21151]: pam_unix(sudo:session): session closed for user root Apr 28 02:47:30 volumio volumio[21006]: info: Completed starting Core Plugins Apr 28 02:47:30 volumio volumio[21006]: info: ------------------------------------------- Apr 28 02:47:30 volumio volumio[21006]: info: ----- MyVolumio plugins startup ---- Apr 28 02:47:30 volumio volumio[21006]: info: ------------------------------------------- Apr 28 02:47:30 volumio volumio[21006]: info: [MyVolumio PluginManager] Fetching plans data.... Apr 28 02:47:30 volumio volumio[21006]: error: MPD error: The expression evaluated to a falsy value: Apr 28 02:47:30 volumio volumio[21006]: assert.ok(self.idling) Apr 28 02:47:30 volumio volumio[21006]: error: The expression evaluated to a falsy value: Apr 28 02:47:30 volumio volumio[21006]: assert.ok(self.idling) Apr 28 02:47:30 volumio volumio[21006]: info: MPD running with PID21186 Apr 28 02:47:30 volumio volumio[21006]: ,establishing connection Apr 28 02:47:30 volumio volumio[21006]: error: updateQueue error: null Apr 28 02:47:30 volumio volumio[21006]: error: updateQueue error: null Apr 28 02:47:30 volumio volumio[21006]: info: [ASDebug] Togle GPIO: OFF Apr 28 02:47:30 volumio volumio[21006]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:47:30 volumio volumio[21006]: Error: EPERM: operation not permitted, write Apr 28 02:47:30 volumio volumio[21006]: at Object.writeSync (fs.js:737:3) Apr 28 02:47:30 volumio volumio[21006]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 28 02:47:30 volumio volumio[21006]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Apr 28 02:47:30 volumio volumio[21006]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Apr 28 02:47:30 volumio volumio[21006]: at listOnTimeout (internal/timers.js:557:17) Apr 28 02:47:30 volumio volumio[21006]: at processTimers (internal/timers.js:500:7) { Apr 28 02:47:30 volumio volumio[21006]: errno: -1, Apr 28 02:47:30 volumio volumio[21006]: syscall: 'write', Apr 28 02:47:30 volumio volumio[21006]: code: 'EPERM' Apr 28 02:47:30 volumio volumio[21006]: } Apr 28 02:47:30 volumio volumio[21006]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 28 02:47:31 volumio sudo[21243]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-28 02:46 Apr 28 02:47:31 volumio sudo[21243]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:14:40 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="a9bbde8819c9c7e405795a261e2a2f93"