-- Logs begin at Sun 2025-02-23 12:54:52 CET, end at Sun 2025-02-23 13:20:21 CET. -- Feb 23 13:19:00 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:00] [connect] Successful connection Feb 23 13:19:00 volubik-fast volumio[2169]: info: Plugin airplay_emulation is not enabled Feb 23 13:19:00 volubik-fast volumio[2169]: info: Loading plugin "last_100"... Feb 23 13:19:00 volubik-fast volumio[2169]: info: Loading plugin "webradio"... Feb 23 13:19:00 volubik-fast volumio[2169]: info: Loading plugin "i2s_dacs"... Feb 23 13:19:00 volubik-fast volumio[2169]: info: Loading plugin "volumiodiscovery"... Feb 23 13:19:00 volubik-fast volumio[2169]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:00 volubik-fast volumio[2169]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:00 volubik-fast volumio[2169]: *** WARNING *** For more information see Feb 23 13:19:00 volubik-fast volumio[2169]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:00 volubik-fast node[2169]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:00 volubik-fast volumio[2169]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:00 volubik-fast volumio[2169]: *** WARNING *** For more information see Feb 23 13:19:00 volubik-fast node[2169]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:00 volubik-fast node[2169]: *** WARNING *** For more information see Feb 23 13:19:00 volubik-fast node[2169]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:00 volubik-fast node[2169]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:00 volubik-fast node[2169]: *** WARNING *** For more information see Feb 23 13:19:00 volubik-fast volumio[2169]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 13:19:00 volubik-fast volumio[2169]: info: Discovery: Started advertising with name: VolUbik-fast Feb 23 13:19:00 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:00 volubik-fast volumio[2169]: info: Loading plugin "squeezelite_mc"... Feb 23 13:19:01 volubik-fast volumio[2169]: info: Loading plugin "outputs"... Feb 23 13:19:01 volubik-fast volumio[2169]: info: Loading plugin "albumart"... Feb 23 13:19:01 volubik-fast volumio[2169]: info: Plugin example_plugin is not enabled Feb 23 13:19:01 volubik-fast volumio[2169]: info: Loading plugin "inputs"... Feb 23 13:19:01 volubik-fast volumio[2169]: info: Loading plugin "updater_comm"... Feb 23 13:19:01 volubik-fast volumio[2169]: info: Plugin mpdemulation is not enabled Feb 23 13:19:01 volubik-fast volumio[2169]: info: Loading plugin "rest_api"... Feb 23 13:19:01 volubik-fast volumio[2169]: info: Loading plugin "websocket"... Feb 23 13:19:01 volubik-fast volumio[2169]: info: Starting Socket.io Server version 2.3.0 Feb 23 13:19:01 volubik-fast volumio[2169]: info: Loading plugin "lms"... Feb 23 13:19:01 volubik-fast volumio[2169]: info: Loading i18n strings for locale it Feb 23 13:19:01 volubik-fast volumio[2169]: Forking 3 albumart workers Feb 23 13:19:01 volubik-fast volumio[2169]: Updating browse sources language Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::initPlayerControls Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:01 volubik-fast volumio[2169]: Express server listening on port 3000 Feb 23 13:19:01 volubik-fast volumio[2169]: [Metrics] WebUI: 5s 289.29ms Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreStateMachine::resetVolumioState Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreStateMachine::getcurrentVolume Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreStateMachine::pushState Feb 23 13:19:01 volubik-fast volumio[2169]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:19:01 volubik-fast volumio[2169]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:02 volubik-fast volumio[2169]: info: Volumio Network Manager: Network status updated: 0 Feb 23 13:19:02 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740313140 101 Feb 23 13:19:02 volubik-fast volumio[2169]: 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 Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:19:02 volubik-fast volumio[2169]: info: Reloading queue from file Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreStateMachine::setRepeat null single undefined Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreStateMachine::pushState Feb 23 13:19:02 volubik-fast volumio[2169]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreStateMachine::setRandom null Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreStateMachine::pushState Feb 23 13:19:02 volubik-fast volumio[2169]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:02 volubik-fast volumio[2169]: info: Setting Device type: Raspberry PI Feb 23 13:19:02 volubik-fast volumio[2169]: info: Completed loading Core Plugins Feb 23 13:19:02 volubik-fast volumio[2169]: info: Preparing to generate the ALSA configuration file Feb 23 13:19:02 volubik-fast volumio[2169]: info: Asound.conf file unchanged, so no further update is needed Feb 23 13:19:02 volubik-fast volumio[2169]: info: Output device has changed, restarting MPD Feb 23 13:19:02 volubik-fast volumio[2169]: info: ___________ START PLUGINS ___________ Feb 23 13:19:02 volubik-fast volumio[2169]: info: ControllerMpd::onStart: Initializing MPD Feb 23 13:19:02 volubik-fast volumio[2169]: info: Creating MPD Configuration file Feb 23 13:19:02 volubik-fast sudo[2266]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:19:02 volubik-fast sudo[2266]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:02 volubik-fast sudo[2266]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:02 volubik-fast sudo[2268]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:19:02 volubik-fast sudo[2268]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:02 volubik-fast volumio[2169]: info: [1740313142387] CoreMusicLibrary::Adding element Server multimediali Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:02 volubik-fast systemd[1]: Stopping Music Player Daemon... Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:02 volubik-fast volumio[2169]: info: [1740313142419] CoreMusicLibrary::Adding element Last_100 Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:02 volubik-fast sudo[2271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:19:02 volubik-fast sudo[2271]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:02 volubik-fast volumio[2169]: info: [1740313142429] CoreMusicLibrary::Adding element Webradio Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:02 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:19:02 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:19:02 volubik-fast sudo[2274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:19:02 volubik-fast sudo[2274]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:02 volubik-fast volumio[2169]: info: Initializing BBC Radios Feb 23 13:19:02 volubik-fast sudo[2271]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:02 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:19:02 volubik-fast systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 23 13:19:02 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:19:02 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:19:02 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:02 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:19:02 volubik-fast volumio[2169]: info: [squeezelite_mc] Starting proxy server... Feb 23 13:19:02 volubik-fast volumio[2169]: info: Volumio Calling Home Feb 23 13:19:02 volubik-fast sudo[2284]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 13:19:02 volubik-fast sudo[2284]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:02 volubik-fast sudo[2284]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:02 volubik-fast sudo[2290]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Feb 23 13:19:02 volubik-fast sudo[2290]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:02 volubik-fast systemd[1]: Starting Logitech Media Server Daemon... Feb 23 13:19:02 volubik-fast systemd[1]: Started Logitech Media Server Daemon. Feb 23 13:19:02 volubik-fast sudo[2290]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:02 volubik-fast systemd[2299]: logitechmediaserver.service: Changing to the requested working directory failed: Permission denied Feb 23 13:19:02 volubik-fast systemd[2299]: logitechmediaserver.service: Failed at step CHDIR spawning /usr/sbin/squeezeboxserver: Permission denied Feb 23 13:19:02 volubik-fast systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=200/CHDIR Feb 23 13:19:02 volubik-fast systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Feb 23 13:19:02 volubik-fast volumio[2169]: Starting albumart workers Feb 23 13:19:02 volubik-fast volumio[2169]: info: [squeezelite_mc] Proxy server started on port 35475 Feb 23 13:19:02 volubik-fast volumio[2169]: Starting albumart workers Feb 23 13:19:03 volubik-fast volumio[2169]: Starting albumart workers Feb 23 13:19:03 volubik-fast volumio[2169]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:19:03 volubik-fast volumio[2169]: info: CoreStateMachine::pushState Feb 23 13:19:03 volubik-fast volumio[2169]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:03 volubik-fast volumio[2169]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:03 volubik-fast volumio[2169]: info: [squeezelite_mc] Server discovery started Feb 23 13:19:03 volubik-fast volumio[2169]: info: [squeezelite_mc] Player finder started Feb 23 13:19:03 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:03 volubik-fast volumio[2169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:03 volubik-fast volumio[2169]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"None","mixer":null} Feb 23 13:19:03 volubik-fast volumio[2169]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:19:03 volubik-fast volumio[2169]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:19:03 volubik-fast volumio[2169]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 23 13:19:03 volubik-fast volumio[2169]: at doSend (dgram.js:692:16) Feb 23 13:19:03 volubik-fast volumio[2169]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 23 13:19:03 volubik-fast volumio[2169]: at afterDns (dgram.js:638:5) Feb 23 13:19:03 volubik-fast volumio[2169]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 23 13:19:03 volubik-fast volumio[2169]: errno: -101, Feb 23 13:19:03 volubik-fast volumio[2169]: code: 'ENETUNREACH', Feb 23 13:19:03 volubik-fast volumio[2169]: syscall: 'send', Feb 23 13:19:03 volubik-fast volumio[2169]: address: '255.255.255.255', Feb 23 13:19:03 volubik-fast volumio[2169]: port: 3483 Feb 23 13:19:03 volubik-fast volumio[2169]: } Feb 23 13:19:03 volubik-fast volumio[2169]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:19:03 volubik-fast sudo[2302]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:19:03 volubik-fast sudo[2302]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:03 volubik-fast sudo[2302]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:03 volubik-fast wpa_supplicant[932]: wlan0: Failed to initiate sched scan Feb 23 13:19:04 volubik-fast sudo[2337]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 13:18 Feb 23 13:19:04 volubik-fast sudo[2337]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:04 volubik-fast sudo[2337]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:04 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:04] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 23 13:19:04 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:04] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 23 13:19:04 volubik-fast systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 23 13:19:04 volubik-fast sudo[2268]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:04 volubik-fast sudo[2274]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:04 volubik-fast systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 23 13:19:04 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:19:04 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:19:04 volubik-fast mpd[2293]: Feb 23 13:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 13:19:04 volubik-fast systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 23 13:19:04 volubik-fast systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1792. Feb 23 13:19:04 volubik-fast systemd[1]: Started Music Player Daemon. Feb 23 13:19:04 volubik-fast systemd[1]: Stopped Volumio Backend Module. Feb 23 13:19:04 volubik-fast systemd[1]: Started Volumio Backend Module. Feb 23 13:19:04 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:19:04 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:19:06 volubik-fast volumio[2352]: info: ------------------------------------------- Feb 23 13:19:06 volubik-fast volumio[2352]: info: ----- Volumio3 ---- Feb 23 13:19:06 volubik-fast volumio[2352]: info: ------------------------------------------- Feb 23 13:19:06 volubik-fast volumio[2352]: info: ----- System startup ---- Feb 23 13:19:06 volubik-fast volumio[2352]: info: ------------------------------------------- Feb 23 13:19:07 volubik-fast volumio[2352]: info: MYVOLUMIO Environment detected Feb 23 13:19:07 volubik-fast volumio[2352]: info: Plugin folders cleanup Feb 23 13:19:07 volubik-fast volumio[2352]: info: Scanning into folder /volumio/app/plugins/ Feb 23 13:19:07 volubik-fast volumio[2352]: info: Scanning category audio_interface Feb 23 13:19:07 volubik-fast volumio[2352]: info: Scanning category miscellanea Feb 23 13:19:07 volubik-fast volumio[2352]: info: Scanning category music_service Feb 23 13:19:07 volubik-fast volumio[2352]: info: Scanning category plugins.json Feb 23 13:19:07 volubik-fast volumio[2352]: info: Scanning category system_controller Feb 23 13:19:07 volubik-fast volumio[2352]: info: Scanning category user_interface Feb 23 13:19:07 volubik-fast volumio[2352]: info: Scanning into folder /data/plugins/ Feb 23 13:19:07 volubik-fast volumio[2352]: info: Scanning category music_service Feb 23 13:19:07 volubik-fast volumio[2352]: info: Plugin folders cleanup completed Feb 23 13:19:07 volubik-fast volumio[2352]: info: ------------------------------------------- Feb 23 13:19:07 volubik-fast volumio[2352]: info: ----- Core plugins startup ---- Feb 23 13:19:07 volubik-fast volumio[2352]: info: ------------------------------------------- Feb 23 13:19:07 volubik-fast volumio[2352]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 13:19:07 volubik-fast volumio[2352]: info: Adding plugin upnp to MyMusic Plugins Feb 23 13:19:07 volubik-fast volumio[2352]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 13:19:07 volubik-fast volumio[2352]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 13:19:07 volubik-fast volumio[2352]: info: Loading plugins from folder /data/plugins/ Feb 23 13:19:07 volubik-fast volumio[2352]: info: Loading plugin "system"... Feb 23 13:19:07 volubik-fast volumio[2352]: info: Loading plugin "appearance"... Feb 23 13:19:08 volubik-fast volumio[2352]: info: Loading plugin "network"... Feb 23 13:19:08 volubik-fast volumio[2352]: info: Refreshing Cached IP Addresses Feb 23 13:19:08 volubik-fast sudo[2379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 13:19:08 volubik-fast sudo[2379]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:08 volubik-fast sudo[2379]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:08 volubik-fast sudo[2381]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 13:19:08 volubik-fast sudo[2381]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:08 volubik-fast sudo[2381]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:08 volubik-fast volumio[2352]: info: Loading plugin "services"... Feb 23 13:19:08 volubik-fast volumio[2352]: info: Loading plugin "alsa_controller"... Feb 23 13:19:08 volubik-fast sudo[2390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 13:19:08 volubik-fast sudo[2390]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:08 volubik-fast wpa_supplicant[932]: wlan0: CTRL-EVENT-SCAN-FAILED ret=-16 retry=1 Feb 23 13:19:08 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:08 volubik-fast volumio[2352]: info: Loading plugin "wizard"... Feb 23 13:19:08 volubik-fast volumio[2352]: info: Loading plugin "networkfs"... Feb 23 13:19:08 volubik-fast volumio[2352]: info: Starting Udev Watcher for removable devices Feb 23 13:19:08 volubik-fast volumio[2352]: info: Ignoring mount for partition: boot Feb 23 13:19:08 volubik-fast volumio[2352]: info: Ignoring mount for partition: volumio Feb 23 13:19:08 volubik-fast volumio[2352]: info: Ignoring mount for partition: volumio_data Feb 23 13:19:08 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:08 volubik-fast volumio[2352]: info: Loading plugin "volumio_command_line_client"... Feb 23 13:19:08 volubik-fast volumio[2352]: info: Loading plugin "upnp"... Feb 23 13:19:08 volubik-fast volumio[2352]: info: [1740313148804] Starting Upmpd Daemon Feb 23 13:19:08 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:08 volubik-fast volumio[2352]: info: Loading plugin "my_music"... Feb 23 13:19:08 volubik-fast volumio[2352]: info: Loading plugin "mpd"... Feb 23 13:19:09 volubik-fast volumio[2352]: info: Loading plugin "upnp_browser"... Feb 23 13:19:09 volubik-fast sudo[2390]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:09 volubik-fast volumio[2352]: info: Loading plugin "alarm-clock"... Feb 23 13:19:09 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:09] [connect] Successful connection Feb 23 13:19:09 volubik-fast volumio[2352]: info: Plugin airplay_emulation is not enabled Feb 23 13:19:09 volubik-fast volumio[2352]: info: Loading plugin "last_100"... Feb 23 13:19:09 volubik-fast volumio[2352]: info: Loading plugin "webradio"... Feb 23 13:19:09 volubik-fast volumio[2352]: info: Loading plugin "i2s_dacs"... Feb 23 13:19:09 volubik-fast volumio[2352]: info: Loading plugin "volumiodiscovery"... Feb 23 13:19:09 volubik-fast volumio[2352]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:09 volubik-fast volumio[2352]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:09 volubik-fast volumio[2352]: *** WARNING *** For more information see Feb 23 13:19:09 volubik-fast volumio[2352]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:09 volubik-fast node[2352]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:09 volubik-fast volumio[2352]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:09 volubik-fast volumio[2352]: *** WARNING *** For more information see Feb 23 13:19:09 volubik-fast node[2352]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:09 volubik-fast node[2352]: *** WARNING *** For more information see Feb 23 13:19:09 volubik-fast node[2352]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:09 volubik-fast node[2352]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:09 volubik-fast node[2352]: *** WARNING *** For more information see Feb 23 13:19:09 volubik-fast volumio[2352]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 13:19:09 volubik-fast volumio[2352]: info: Discovery: Started advertising with name: VolUbik-fast Feb 23 13:19:09 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:09 volubik-fast volumio[2352]: info: Loading plugin "squeezelite_mc"... Feb 23 13:19:10 volubik-fast wpa_supplicant[932]: wlan0: Failed to initiate sched scan Feb 23 13:19:10 volubik-fast volumio[2352]: info: Loading plugin "outputs"... Feb 23 13:19:10 volubik-fast volumio[2352]: info: Loading plugin "albumart"... Feb 23 13:19:10 volubik-fast volumio[2352]: info: Plugin example_plugin is not enabled Feb 23 13:19:10 volubik-fast volumio[2352]: info: Loading plugin "inputs"... Feb 23 13:19:10 volubik-fast volumio[2352]: info: Loading plugin "updater_comm"... Feb 23 13:19:10 volubik-fast volumio[2352]: info: Plugin mpdemulation is not enabled Feb 23 13:19:10 volubik-fast volumio[2352]: info: Loading plugin "rest_api"... Feb 23 13:19:10 volubik-fast volumio[2352]: info: Loading plugin "websocket"... Feb 23 13:19:10 volubik-fast volumio[2352]: info: Starting Socket.io Server version 2.3.0 Feb 23 13:19:10 volubik-fast volumio[2352]: info: Loading plugin "lms"... Feb 23 13:19:11 volubik-fast volumio[2352]: info: Loading i18n strings for locale it Feb 23 13:19:11 volubik-fast volumio[2352]: Forking 3 albumart workers Feb 23 13:19:11 volubik-fast volumio[2352]: Updating browse sources language Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::initPlayerControls Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:11 volubik-fast volumio[2352]: Express server listening on port 3000 Feb 23 13:19:11 volubik-fast volumio[2352]: [Metrics] WebUI: 5s 280.94ms Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreStateMachine::resetVolumioState Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreStateMachine::getcurrentVolume Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreStateMachine::pushState Feb 23 13:19:11 volubik-fast volumio[2352]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:11 volubik-fast volumio[2352]: info: Volumio Network Manager: Network status updated: 0 Feb 23 13:19:11 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:11] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740313149 101 Feb 23 13:19:11 volubik-fast volumio[2352]: 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 Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:19:11 volubik-fast volumio[2352]: info: Reloading queue from file Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreStateMachine::setRepeat null single undefined Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreStateMachine::pushState Feb 23 13:19:11 volubik-fast volumio[2352]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreStateMachine::setRandom null Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreStateMachine::pushState Feb 23 13:19:11 volubik-fast volumio[2352]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:11 volubik-fast volumio[2352]: info: Setting Device type: Raspberry PI Feb 23 13:19:11 volubik-fast volumio[2352]: info: Completed loading Core Plugins Feb 23 13:19:11 volubik-fast volumio[2352]: info: Preparing to generate the ALSA configuration file Feb 23 13:19:11 volubik-fast volumio[2352]: info: Asound.conf file unchanged, so no further update is needed Feb 23 13:19:11 volubik-fast volumio[2352]: info: Output device has changed, restarting MPD Feb 23 13:19:11 volubik-fast volumio[2352]: info: ___________ START PLUGINS ___________ Feb 23 13:19:11 volubik-fast volumio[2352]: info: ControllerMpd::onStart: Initializing MPD Feb 23 13:19:11 volubik-fast volumio[2352]: info: Creating MPD Configuration file Feb 23 13:19:11 volubik-fast sudo[2446]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:19:11 volubik-fast sudo[2446]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:11 volubik-fast sudo[2446]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:11 volubik-fast sudo[2448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:19:11 volubik-fast sudo[2448]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:11 volubik-fast systemd[1]: Stopping Music Player Daemon... Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:11 volubik-fast sudo[2452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:19:11 volubik-fast volumio[2352]: info: [1740313151983] CoreMusicLibrary::Adding element Server multimediali Feb 23 13:19:11 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:11 volubik-fast sudo[2452]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:11 volubik-fast sudo[2452]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:12 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:19:12 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:19:12 volubik-fast sudo[2454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:19:12 volubik-fast sudo[2454]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:12 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:19:12 volubik-fast volumio[2352]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:12 volubik-fast volumio[2352]: info: [1740313152052] CoreMusicLibrary::Adding element Last_100 Feb 23 13:19:12 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:12 volubik-fast volumio[2352]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:12 volubik-fast volumio[2352]: info: [1740313152056] CoreMusicLibrary::Adding element Webradio Feb 23 13:19:12 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:12 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:12 volubik-fast volumio[2352]: info: Initializing BBC Radios Feb 23 13:19:12 volubik-fast systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 23 13:19:12 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:19:12 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:19:12 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:19:12 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:12 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:19:12 volubik-fast volumio[2352]: info: [squeezelite_mc] Starting proxy server... Feb 23 13:19:12 volubik-fast volumio[2352]: Starting albumart workers Feb 23 13:19:12 volubik-fast volumio[2352]: info: Volumio Calling Home Feb 23 13:19:12 volubik-fast sudo[2461]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 13:19:12 volubik-fast sudo[2461]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:12 volubik-fast sudo[2470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Feb 23 13:19:12 volubik-fast sudo[2470]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:12 volubik-fast sudo[2461]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:12 volubik-fast volumio[2352]: Starting albumart workers Feb 23 13:19:12 volubik-fast systemd[1]: Starting Logitech Media Server Daemon... Feb 23 13:19:12 volubik-fast volumio[2352]: info: [squeezelite_mc] Proxy server started on port 33361 Feb 23 13:19:12 volubik-fast sudo[2470]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:12 volubik-fast systemd[1]: Started Logitech Media Server Daemon. Feb 23 13:19:12 volubik-fast systemd[2479]: logitechmediaserver.service: Changing to the requested working directory failed: Permission denied Feb 23 13:19:12 volubik-fast systemd[2479]: logitechmediaserver.service: Failed at step CHDIR spawning /usr/sbin/squeezeboxserver: Permission denied Feb 23 13:19:12 volubik-fast systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=200/CHDIR Feb 23 13:19:12 volubik-fast systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Feb 23 13:19:12 volubik-fast volumio[2352]: Starting albumart workers Feb 23 13:19:12 volubik-fast volumio[2352]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:19:12 volubik-fast volumio[2352]: info: CoreStateMachine::pushState Feb 23 13:19:12 volubik-fast volumio[2352]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:12 volubik-fast volumio[2352]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:12 volubik-fast volumio[2352]: info: [squeezelite_mc] Server discovery started Feb 23 13:19:12 volubik-fast volumio[2352]: info: [squeezelite_mc] Player finder started Feb 23 13:19:12 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:12 volubik-fast volumio[2352]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:12 volubik-fast volumio[2352]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"None","mixer":null} Feb 23 13:19:12 volubik-fast volumio[2352]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:19:12 volubik-fast volumio[2352]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:19:12 volubik-fast volumio[2352]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 23 13:19:12 volubik-fast volumio[2352]: at doSend (dgram.js:692:16) Feb 23 13:19:12 volubik-fast volumio[2352]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 23 13:19:12 volubik-fast volumio[2352]: at afterDns (dgram.js:638:5) Feb 23 13:19:12 volubik-fast volumio[2352]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 23 13:19:12 volubik-fast volumio[2352]: errno: -101, Feb 23 13:19:12 volubik-fast volumio[2352]: code: 'ENETUNREACH', Feb 23 13:19:12 volubik-fast volumio[2352]: syscall: 'send', Feb 23 13:19:12 volubik-fast volumio[2352]: address: '255.255.255.255', Feb 23 13:19:12 volubik-fast volumio[2352]: port: 3483 Feb 23 13:19:12 volubik-fast volumio[2352]: } Feb 23 13:19:12 volubik-fast volumio[2352]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:19:12 volubik-fast sudo[2482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:19:12 volubik-fast sudo[2482]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:12 volubik-fast sudo[2482]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:13 volubik-fast sudo[2517]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 13:18 Feb 23 13:19:13 volubik-fast sudo[2517]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:13 volubik-fast sudo[2517]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:14 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:14] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 23 13:19:14 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:14] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 23 13:19:14 volubik-fast systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 23 13:19:14 volubik-fast sudo[2448]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:14 volubik-fast sudo[2454]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:14 volubik-fast systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 23 13:19:14 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:19:14 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:19:14 volubik-fast systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 23 13:19:14 volubik-fast systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1793. Feb 23 13:19:14 volubik-fast systemd[1]: Stopped Volumio Backend Module. Feb 23 13:19:14 volubik-fast systemd[1]: Started Volumio Backend Module. Feb 23 13:19:14 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:19:14 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:19:14 volubik-fast mpd[2475]: Feb 23 13:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 13:19:14 volubik-fast systemd[1]: Started Music Player Daemon. Feb 23 13:19:15 volubik-fast wpa_supplicant[932]: wlan0: Failed to initiate sched scan Feb 23 13:19:16 volubik-fast volumio[2530]: info: ------------------------------------------- Feb 23 13:19:16 volubik-fast volumio[2530]: info: ----- Volumio3 ---- Feb 23 13:19:16 volubik-fast volumio[2530]: info: ------------------------------------------- Feb 23 13:19:16 volubik-fast volumio[2530]: info: ----- System startup ---- Feb 23 13:19:16 volubik-fast volumio[2530]: info: ------------------------------------------- Feb 23 13:19:16 volubik-fast volumio[2530]: info: MYVOLUMIO Environment detected Feb 23 13:19:17 volubik-fast volumio[2530]: info: Plugin folders cleanup Feb 23 13:19:17 volubik-fast volumio[2530]: info: Scanning into folder /volumio/app/plugins/ Feb 23 13:19:17 volubik-fast volumio[2530]: info: Scanning category audio_interface Feb 23 13:19:17 volubik-fast volumio[2530]: info: Scanning category miscellanea Feb 23 13:19:17 volubik-fast volumio[2530]: info: Scanning category music_service Feb 23 13:19:17 volubik-fast volumio[2530]: info: Scanning category plugins.json Feb 23 13:19:17 volubik-fast volumio[2530]: info: Scanning category system_controller Feb 23 13:19:17 volubik-fast volumio[2530]: info: Scanning category user_interface Feb 23 13:19:17 volubik-fast volumio[2530]: info: Scanning into folder /data/plugins/ Feb 23 13:19:17 volubik-fast volumio[2530]: info: Scanning category music_service Feb 23 13:19:17 volubik-fast volumio[2530]: info: Plugin folders cleanup completed Feb 23 13:19:17 volubik-fast volumio[2530]: info: ------------------------------------------- Feb 23 13:19:17 volubik-fast volumio[2530]: info: ----- Core plugins startup ---- Feb 23 13:19:17 volubik-fast volumio[2530]: info: ------------------------------------------- Feb 23 13:19:17 volubik-fast volumio[2530]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 13:19:17 volubik-fast volumio[2530]: info: Adding plugin upnp to MyMusic Plugins Feb 23 13:19:17 volubik-fast volumio[2530]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 13:19:17 volubik-fast volumio[2530]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 13:19:17 volubik-fast volumio[2530]: info: Loading plugins from folder /data/plugins/ Feb 23 13:19:17 volubik-fast volumio[2530]: info: Loading plugin "system"... Feb 23 13:19:17 volubik-fast volumio[2530]: info: Loading plugin "appearance"... Feb 23 13:19:18 volubik-fast volumio[2530]: info: Loading plugin "network"... Feb 23 13:19:18 volubik-fast volumio[2530]: info: Refreshing Cached IP Addresses Feb 23 13:19:18 volubik-fast sudo[2560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 13:19:18 volubik-fast sudo[2560]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:18 volubik-fast sudo[2560]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:18 volubik-fast sudo[2562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 13:19:18 volubik-fast sudo[2562]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:18 volubik-fast sudo[2562]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:18 volubik-fast volumio[2530]: info: Loading plugin "services"... Feb 23 13:19:18 volubik-fast volumio[2530]: info: Loading plugin "alsa_controller"... Feb 23 13:19:18 volubik-fast sudo[2571]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 13:19:18 volubik-fast sudo[2571]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:18 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:18 volubik-fast volumio[2530]: info: Loading plugin "wizard"... Feb 23 13:19:18 volubik-fast volumio[2530]: info: Loading plugin "networkfs"... Feb 23 13:19:18 volubik-fast volumio[2530]: info: Starting Udev Watcher for removable devices Feb 23 13:19:18 volubik-fast volumio[2530]: info: Ignoring mount for partition: boot Feb 23 13:19:18 volubik-fast volumio[2530]: info: Ignoring mount for partition: volumio Feb 23 13:19:18 volubik-fast volumio[2530]: info: Ignoring mount for partition: volumio_data Feb 23 13:19:18 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:18 volubik-fast volumio[2530]: info: Loading plugin "volumio_command_line_client"... Feb 23 13:19:18 volubik-fast volumio[2530]: info: Loading plugin "upnp"... Feb 23 13:19:18 volubik-fast volumio[2530]: info: [1740313158337] Starting Upmpd Daemon Feb 23 13:19:18 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:18 volubik-fast volumio[2530]: info: Loading plugin "my_music"... Feb 23 13:19:18 volubik-fast volumio[2530]: info: Loading plugin "mpd"... Feb 23 13:19:18 volubik-fast volumio[2530]: info: Loading plugin "upnp_browser"... Feb 23 13:19:18 volubik-fast sudo[2571]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:18 volubik-fast volumio[2530]: info: Loading plugin "alarm-clock"... Feb 23 13:19:19 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:19] [connect] Successful connection Feb 23 13:19:19 volubik-fast volumio[2530]: info: Plugin airplay_emulation is not enabled Feb 23 13:19:19 volubik-fast volumio[2530]: info: Loading plugin "last_100"... Feb 23 13:19:19 volubik-fast volumio[2530]: info: Loading plugin "webradio"... Feb 23 13:19:19 volubik-fast volumio[2530]: info: Loading plugin "i2s_dacs"... Feb 23 13:19:19 volubik-fast volumio[2530]: info: Loading plugin "volumiodiscovery"... Feb 23 13:19:19 volubik-fast volumio[2530]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:19 volubik-fast volumio[2530]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:19 volubik-fast volumio[2530]: *** WARNING *** For more information see Feb 23 13:19:19 volubik-fast volumio[2530]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:19 volubik-fast node[2530]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:19 volubik-fast volumio[2530]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:19 volubik-fast volumio[2530]: *** WARNING *** For more information see Feb 23 13:19:19 volubik-fast node[2530]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:19 volubik-fast node[2530]: *** WARNING *** For more information see Feb 23 13:19:19 volubik-fast node[2530]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:19 volubik-fast node[2530]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:19 volubik-fast node[2530]: *** WARNING *** For more information see Feb 23 13:19:19 volubik-fast volumio[2530]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 13:19:19 volubik-fast volumio[2530]: info: Discovery: Started advertising with name: VolUbik-fast Feb 23 13:19:19 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:19 volubik-fast volumio[2530]: info: Loading plugin "squeezelite_mc"... Feb 23 13:19:20 volubik-fast volumio[2530]: info: Loading plugin "outputs"... Feb 23 13:19:20 volubik-fast volumio[2530]: info: Loading plugin "albumart"... Feb 23 13:19:20 volubik-fast volumio[2530]: info: Plugin example_plugin is not enabled Feb 23 13:19:20 volubik-fast volumio[2530]: info: Loading plugin "inputs"... Feb 23 13:19:20 volubik-fast volumio[2530]: info: Loading plugin "updater_comm"... Feb 23 13:19:20 volubik-fast volumio[2530]: info: Plugin mpdemulation is not enabled Feb 23 13:19:20 volubik-fast volumio[2530]: info: Loading plugin "rest_api"... Feb 23 13:19:20 volubik-fast volumio[2530]: info: Loading plugin "websocket"... Feb 23 13:19:20 volubik-fast volumio[2530]: info: Starting Socket.io Server version 2.3.0 Feb 23 13:19:20 volubik-fast volumio[2530]: info: Loading plugin "lms"... Feb 23 13:19:20 volubik-fast volumio[2530]: info: Loading i18n strings for locale it Feb 23 13:19:20 volubik-fast volumio[2530]: Updating browse sources language Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:20 volubik-fast volumio[2530]: Forking 3 albumart workers Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::initPlayerControls Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:20 volubik-fast volumio[2530]: Express server listening on port 3000 Feb 23 13:19:20 volubik-fast volumio[2530]: [Metrics] WebUI: 5s 278.57ms Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreStateMachine::resetVolumioState Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreStateMachine::getcurrentVolume Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreStateMachine::pushState Feb 23 13:19:20 volubik-fast volumio[2530]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:19:20 volubik-fast volumio[2530]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:21 volubik-fast volumio[2530]: info: Volumio Network Manager: Network status updated: 0 Feb 23 13:19:21 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:21] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740313159 101 Feb 23 13:19:21 volubik-fast volumio[2530]: 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 Feb 23 13:19:21 volubik-fast volumio[2530]: info: Reloading queue from file Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreStateMachine::setRepeat null single undefined Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreStateMachine::pushState Feb 23 13:19:21 volubik-fast volumio[2530]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreStateMachine::setRandom null Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreStateMachine::pushState Feb 23 13:19:21 volubik-fast volumio[2530]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:21 volubik-fast volumio[2530]: info: Setting Device type: Raspberry PI Feb 23 13:19:21 volubik-fast volumio[2530]: info: Completed loading Core Plugins Feb 23 13:19:21 volubik-fast volumio[2530]: info: Preparing to generate the ALSA configuration file Feb 23 13:19:21 volubik-fast volumio[2530]: info: Discovery: adding 8fd035eb-d0cc-4f10-ab54-6ea1cf680f92 Feb 23 13:19:21 volubik-fast volumio[2530]: info: Discovery: Found device VolUbik-fast Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreCommandRouter::volumioGetState Feb 23 13:19:21 volubik-fast volumio[2530]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:21 volubik-fast volumio[2530]: info: Asound.conf file unchanged, so no further update is needed Feb 23 13:19:21 volubik-fast volumio[2530]: info: Output device has changed, restarting MPD Feb 23 13:19:21 volubik-fast sudo[2624]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:19:21 volubik-fast sudo[2624]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:21 volubik-fast volumio[2530]: info: ___________ START PLUGINS ___________ Feb 23 13:19:21 volubik-fast sudo[2624]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:21 volubik-fast volumio[2530]: info: ControllerMpd::onStart: Initializing MPD Feb 23 13:19:21 volubik-fast volumio[2530]: info: Creating MPD Configuration file Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:21 volubik-fast volumio[2530]: info: [1740313161428] CoreMusicLibrary::Adding element Server multimediali Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:21 volubik-fast sudo[2627]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:19:21 volubik-fast sudo[2627]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:21 volubik-fast sudo[2629]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:19:21 volubik-fast sudo[2629]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:21 volubik-fast sudo[2631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:19:21 volubik-fast volumio[2530]: info: [1740313161466] CoreMusicLibrary::Adding element Last_100 Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:21 volubik-fast volumio[2530]: info: [1740313161470] CoreMusicLibrary::Adding element Webradio Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:21 volubik-fast sudo[2631]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:21 volubik-fast systemd[1]: Stopping Music Player Daemon... Feb 23 13:19:21 volubik-fast volumio[2530]: info: Initializing BBC Radios Feb 23 13:19:21 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:19:21 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:19:21 volubik-fast wpa_supplicant[932]: wlan0: Failed to initiate sched scan Feb 23 13:19:21 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:21 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:19:21 volubik-fast volumio[2530]: info: [squeezelite_mc] Starting proxy server... Feb 23 13:19:21 volubik-fast volumio[2530]: info: Volumio Calling Home Feb 23 13:19:21 volubik-fast sudo[2640]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 13:19:21 volubik-fast sudo[2640]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:21 volubik-fast sudo[2662]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Feb 23 13:19:21 volubik-fast sudo[2640]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:21 volubik-fast sudo[2662]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:21 volubik-fast sudo[2629]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:21 volubik-fast systemd[1]: Starting Logitech Media Server Daemon... Feb 23 13:19:21 volubik-fast systemd[1]: Started Logitech Media Server Daemon. Feb 23 13:19:21 volubik-fast volumio[2530]: info: [squeezelite_mc] Proxy server started on port 33469 Feb 23 13:19:21 volubik-fast systemd[2695]: logitechmediaserver.service: Changing to the requested working directory failed: Permission denied Feb 23 13:19:21 volubik-fast systemd[2695]: logitechmediaserver.service: Failed at step CHDIR spawning /usr/sbin/squeezeboxserver: Permission denied Feb 23 13:19:21 volubik-fast systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=200/CHDIR Feb 23 13:19:21 volubik-fast systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Feb 23 13:19:21 volubik-fast sudo[2662]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:22 volubik-fast volumio[2530]: Starting albumart workers Feb 23 13:19:22 volubik-fast volumio[2530]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:19:22 volubik-fast volumio[2530]: info: CoreStateMachine::pushState Feb 23 13:19:22 volubik-fast volumio[2530]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:22 volubik-fast volumio[2530]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:22 volubik-fast volumio[2530]: info: [squeezelite_mc] Server discovery started Feb 23 13:19:22 volubik-fast volumio[2530]: info: [squeezelite_mc] Player finder started Feb 23 13:19:22 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:22 volubik-fast volumio[2530]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:22 volubik-fast volumio[2530]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"None","mixer":null} Feb 23 13:19:22 volubik-fast volumio[2530]: Starting albumart workers Feb 23 13:19:22 volubik-fast volumio[2530]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:19:22 volubik-fast volumio[2530]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:19:22 volubik-fast volumio[2530]: Starting albumart workers Feb 23 13:19:22 volubik-fast volumio[2530]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 23 13:19:22 volubik-fast volumio[2530]: at doSend (dgram.js:692:16) Feb 23 13:19:22 volubik-fast volumio[2530]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 23 13:19:22 volubik-fast volumio[2530]: at afterDns (dgram.js:638:5) Feb 23 13:19:22 volubik-fast volumio[2530]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 23 13:19:22 volubik-fast volumio[2530]: errno: -101, Feb 23 13:19:22 volubik-fast volumio[2530]: code: 'ENETUNREACH', Feb 23 13:19:22 volubik-fast volumio[2530]: syscall: 'send', Feb 23 13:19:22 volubik-fast volumio[2530]: address: '255.255.255.255', Feb 23 13:19:22 volubik-fast volumio[2530]: port: 3483 Feb 23 13:19:22 volubik-fast volumio[2530]: } Feb 23 13:19:22 volubik-fast volumio[2530]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:19:22 volubik-fast sudo[2705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:19:22 volubik-fast sudo[2705]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:22 volubik-fast sudo[2705]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:23 volubik-fast sudo[2740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 13:18 Feb 23 13:19:23 volubik-fast sudo[2740]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:23 volubik-fast systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Feb 23 13:19:23 volubik-fast systemd[1]: mpd.service: Failed with result 'protocol'. Feb 23 13:19:23 volubik-fast systemd[1]: Failed to start Music Player Daemon. Feb 23 13:19:23 volubik-fast sudo[2627]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:23 volubik-fast sudo[2631]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:23 volubik-fast sudo[2740]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:23 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:19:23 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:23] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 23 13:19:23 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:23] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 23 13:19:23 volubik-fast systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 23 13:19:23 volubik-fast systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 23 13:19:23 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:19:23 volubik-fast sudo[2744]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 13:19:23 volubik-fast sudo[2744]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:23 volubik-fast sudo[2744]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:23 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:19:24 volubik-fast systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 23 13:19:24 volubik-fast systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1794. Feb 23 13:19:24 volubik-fast systemd[1]: Stopped Volumio Backend Module. Feb 23 13:19:24 volubik-fast systemd[1]: Started Volumio Backend Module. Feb 23 13:19:24 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:19:24 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:19:25 volubik-fast mpd[2756]: Feb 23 13:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 13:19:25 volubik-fast systemd[1]: Started Music Player Daemon. Feb 23 13:19:25 volubik-fast mpd_monitor.sh[662]: MPD restarted due to no mpc output. Feb 23 13:19:26 volubik-fast volumio[2758]: info: ------------------------------------------- Feb 23 13:19:26 volubik-fast volumio[2758]: info: ----- Volumio3 ---- Feb 23 13:19:26 volubik-fast volumio[2758]: info: ------------------------------------------- Feb 23 13:19:26 volubik-fast volumio[2758]: info: ----- System startup ---- Feb 23 13:19:26 volubik-fast volumio[2758]: info: ------------------------------------------- Feb 23 13:19:26 volubik-fast volumio[2758]: info: MYVOLUMIO Environment detected Feb 23 13:19:26 volubik-fast volumio[2758]: info: Plugin folders cleanup Feb 23 13:19:26 volubik-fast volumio[2758]: info: Scanning into folder /volumio/app/plugins/ Feb 23 13:19:26 volubik-fast volumio[2758]: info: Scanning category audio_interface Feb 23 13:19:26 volubik-fast volumio[2758]: info: Scanning category miscellanea Feb 23 13:19:26 volubik-fast volumio[2758]: info: Scanning category music_service Feb 23 13:19:26 volubik-fast volumio[2758]: info: Scanning category plugins.json Feb 23 13:19:26 volubik-fast volumio[2758]: info: Scanning category system_controller Feb 23 13:19:26 volubik-fast volumio[2758]: info: Scanning category user_interface Feb 23 13:19:26 volubik-fast volumio[2758]: info: Scanning into folder /data/plugins/ Feb 23 13:19:26 volubik-fast volumio[2758]: info: Scanning category music_service Feb 23 13:19:26 volubik-fast volumio[2758]: info: Plugin folders cleanup completed Feb 23 13:19:26 volubik-fast volumio[2758]: info: ------------------------------------------- Feb 23 13:19:26 volubik-fast volumio[2758]: info: ----- Core plugins startup ---- Feb 23 13:19:26 volubik-fast volumio[2758]: info: ------------------------------------------- Feb 23 13:19:26 volubik-fast volumio[2758]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 13:19:26 volubik-fast volumio[2758]: info: Adding plugin upnp to MyMusic Plugins Feb 23 13:19:26 volubik-fast volumio[2758]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 13:19:26 volubik-fast volumio[2758]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 13:19:26 volubik-fast volumio[2758]: info: Loading plugins from folder /data/plugins/ Feb 23 13:19:26 volubik-fast volumio[2758]: info: Loading plugin "system"... Feb 23 13:19:26 volubik-fast volumio[2758]: info: Loading plugin "appearance"... Feb 23 13:19:27 volubik-fast wpa_supplicant[932]: wlan0: Failed to initiate sched scan Feb 23 13:19:28 volubik-fast volumio[2758]: info: Loading plugin "network"... Feb 23 13:19:28 volubik-fast volumio[2758]: info: Refreshing Cached IP Addresses Feb 23 13:19:28 volubik-fast sudo[2788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 13:19:28 volubik-fast sudo[2788]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:28 volubik-fast sudo[2788]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:28 volubik-fast sudo[2790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 13:19:28 volubik-fast sudo[2790]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:28 volubik-fast sudo[2790]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:28 volubik-fast volumio[2758]: info: Loading plugin "services"... Feb 23 13:19:28 volubik-fast volumio[2758]: info: Loading plugin "alsa_controller"... Feb 23 13:19:28 volubik-fast sudo[2799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 13:19:28 volubik-fast sudo[2799]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:28 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:28 volubik-fast volumio[2758]: info: Loading plugin "wizard"... Feb 23 13:19:28 volubik-fast volumio[2758]: info: Loading plugin "networkfs"... Feb 23 13:19:28 volubik-fast volumio[2758]: info: Starting Udev Watcher for removable devices Feb 23 13:19:28 volubik-fast volumio[2758]: info: Ignoring mount for partition: boot Feb 23 13:19:28 volubik-fast volumio[2758]: info: Ignoring mount for partition: volumio Feb 23 13:19:28 volubik-fast volumio[2758]: info: Ignoring mount for partition: volumio_data Feb 23 13:19:28 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:28 volubik-fast volumio[2758]: info: Loading plugin "volumio_command_line_client"... Feb 23 13:19:28 volubik-fast volumio[2758]: info: Loading plugin "upnp"... Feb 23 13:19:28 volubik-fast volumio[2758]: info: [1740313168248] Starting Upmpd Daemon Feb 23 13:19:28 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:28 volubik-fast volumio[2758]: info: Loading plugin "my_music"... Feb 23 13:19:28 volubik-fast volumio[2758]: info: Loading plugin "mpd"... Feb 23 13:19:28 volubik-fast volumio[2758]: info: Loading plugin "upnp_browser"... Feb 23 13:19:28 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:28] [connect] Successful connection Feb 23 13:19:28 volubik-fast sudo[2799]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:28 volubik-fast volumio[2758]: info: Loading plugin "alarm-clock"... Feb 23 13:19:29 volubik-fast volumio[2758]: info: Plugin airplay_emulation is not enabled Feb 23 13:19:29 volubik-fast volumio[2758]: info: Loading plugin "last_100"... Feb 23 13:19:29 volubik-fast volumio[2758]: info: Loading plugin "webradio"... Feb 23 13:19:29 volubik-fast volumio[2758]: info: Loading plugin "i2s_dacs"... Feb 23 13:19:29 volubik-fast volumio[2758]: info: Loading plugin "volumiodiscovery"... Feb 23 13:19:29 volubik-fast volumio[2758]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:29 volubik-fast volumio[2758]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:29 volubik-fast volumio[2758]: *** WARNING *** For more information see Feb 23 13:19:29 volubik-fast volumio[2758]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:29 volubik-fast node[2758]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:29 volubik-fast volumio[2758]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:29 volubik-fast volumio[2758]: *** WARNING *** For more information see Feb 23 13:19:29 volubik-fast node[2758]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:29 volubik-fast node[2758]: *** WARNING *** For more information see Feb 23 13:19:29 volubik-fast node[2758]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:29 volubik-fast node[2758]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:29 volubik-fast node[2758]: *** WARNING *** For more information see Feb 23 13:19:29 volubik-fast volumio[2758]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 13:19:29 volubik-fast volumio[2758]: info: Discovery: Started advertising with name: VolUbik-fast Feb 23 13:19:29 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:29 volubik-fast volumio[2758]: info: Loading plugin "squeezelite_mc"... Feb 23 13:19:30 volubik-fast volumio[2758]: info: Loading plugin "outputs"... Feb 23 13:19:30 volubik-fast volumio[2758]: info: Loading plugin "albumart"... Feb 23 13:19:30 volubik-fast volumio[2758]: info: Plugin example_plugin is not enabled Feb 23 13:19:30 volubik-fast volumio[2758]: info: Loading plugin "inputs"... Feb 23 13:19:30 volubik-fast volumio[2758]: info: Loading plugin "updater_comm"... Feb 23 13:19:30 volubik-fast volumio[2758]: info: Plugin mpdemulation is not enabled Feb 23 13:19:30 volubik-fast volumio[2758]: info: Loading plugin "rest_api"... Feb 23 13:19:30 volubik-fast volumio[2758]: info: Loading plugin "websocket"... Feb 23 13:19:30 volubik-fast volumio[2758]: info: Starting Socket.io Server version 2.3.0 Feb 23 13:19:30 volubik-fast volumio[2758]: info: Loading plugin "lms"... Feb 23 13:19:30 volubik-fast volumio[2758]: info: Loading i18n strings for locale it Feb 23 13:19:30 volubik-fast volumio[2758]: Updating browse sources language Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:30 volubik-fast volumio[2758]: Forking 3 albumart workers Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::initPlayerControls Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:30 volubik-fast volumio[2758]: Express server listening on port 3000 Feb 23 13:19:30 volubik-fast volumio[2758]: [Metrics] WebUI: 5s 296.01ms Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreStateMachine::resetVolumioState Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreStateMachine::getcurrentVolume Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreStateMachine::pushState Feb 23 13:19:30 volubik-fast volumio[2758]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:19:30 volubik-fast volumio[2758]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:30 volubik-fast volumio[2758]: info: Volumio Network Manager: Network status updated: 0 Feb 23 13:19:30 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:30] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740313168 101 Feb 23 13:19:31 volubik-fast volumio[2758]: 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 Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:19:31 volubik-fast volumio[2758]: info: Reloading queue from file Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreStateMachine::setRepeat null single undefined Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreStateMachine::pushState Feb 23 13:19:31 volubik-fast volumio[2758]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreStateMachine::setRandom null Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreStateMachine::pushState Feb 23 13:19:31 volubik-fast volumio[2758]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:31 volubik-fast volumio[2758]: info: Setting Device type: Raspberry PI Feb 23 13:19:31 volubik-fast volumio[2758]: info: Completed loading Core Plugins Feb 23 13:19:31 volubik-fast volumio[2758]: info: Preparing to generate the ALSA configuration file Feb 23 13:19:31 volubik-fast volumio[2758]: info: Asound.conf file unchanged, so no further update is needed Feb 23 13:19:31 volubik-fast volumio[2758]: info: Output device has changed, restarting MPD Feb 23 13:19:31 volubik-fast volumio[2758]: info: ___________ START PLUGINS ___________ Feb 23 13:19:31 volubik-fast volumio[2758]: info: ControllerMpd::onStart: Initializing MPD Feb 23 13:19:31 volubik-fast volumio[2758]: info: Creating MPD Configuration file Feb 23 13:19:31 volubik-fast sudo[2852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:19:31 volubik-fast sudo[2852]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:31 volubik-fast sudo[2852]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:31 volubik-fast sudo[2854]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:19:31 volubik-fast sudo[2854]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:31 volubik-fast sudo[2857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:19:31 volubik-fast sudo[2857]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:31 volubik-fast volumio[2758]: info: [1740313171301] CoreMusicLibrary::Adding element Server multimediali Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:31 volubik-fast sudo[2857]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:31 volubik-fast systemd[1]: Stopping Music Player Daemon... Feb 23 13:19:31 volubik-fast sudo[2860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:19:31 volubik-fast sudo[2860]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:31 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:19:31 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:31 volubik-fast volumio[2758]: info: [1740313171383] CoreMusicLibrary::Adding element Last_100 Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:31 volubik-fast volumio[2758]: info: [1740313171388] CoreMusicLibrary::Adding element Webradio Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:31 volubik-fast volumio[2758]: info: Initializing BBC Radios Feb 23 13:19:31 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:19:31 volubik-fast systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 23 13:19:31 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:19:31 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:19:31 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:31 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:19:31 volubik-fast volumio[2758]: info: [squeezelite_mc] Starting proxy server... Feb 23 13:19:31 volubik-fast sudo[2870]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 13:19:31 volubik-fast volumio[2758]: info: Volumio Calling Home Feb 23 13:19:31 volubik-fast sudo[2870]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:31 volubik-fast sudo[2870]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:31 volubik-fast sudo[2876]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Feb 23 13:19:31 volubik-fast sudo[2876]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:31 volubik-fast systemd[1]: Starting Logitech Media Server Daemon... Feb 23 13:19:31 volubik-fast systemd[1]: Started Logitech Media Server Daemon. Feb 23 13:19:31 volubik-fast sudo[2876]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:31 volubik-fast systemd[2885]: logitechmediaserver.service: Changing to the requested working directory failed: Permission denied Feb 23 13:19:31 volubik-fast systemd[2885]: logitechmediaserver.service: Failed at step CHDIR spawning /usr/sbin/squeezeboxserver: Permission denied Feb 23 13:19:31 volubik-fast systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=200/CHDIR Feb 23 13:19:31 volubik-fast systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Feb 23 13:19:31 volubik-fast volumio[2758]: info: [squeezelite_mc] Proxy server started on port 38119 Feb 23 13:19:31 volubik-fast volumio[2758]: Starting albumart workers Feb 23 13:19:31 volubik-fast volumio[2758]: Starting albumart workers Feb 23 13:19:32 volubik-fast volumio[2758]: Starting albumart workers Feb 23 13:19:32 volubik-fast volumio[2758]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:19:32 volubik-fast volumio[2758]: info: CoreStateMachine::pushState Feb 23 13:19:32 volubik-fast volumio[2758]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:32 volubik-fast volumio[2758]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:32 volubik-fast volumio[2758]: info: [squeezelite_mc] Server discovery started Feb 23 13:19:32 volubik-fast volumio[2758]: info: [squeezelite_mc] Player finder started Feb 23 13:19:32 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:32 volubik-fast volumio[2758]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:32 volubik-fast volumio[2758]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"None","mixer":null} Feb 23 13:19:32 volubik-fast volumio[2758]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:19:32 volubik-fast volumio[2758]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:19:32 volubik-fast volumio[2758]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 23 13:19:32 volubik-fast volumio[2758]: at doSend (dgram.js:692:16) Feb 23 13:19:32 volubik-fast volumio[2758]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 23 13:19:32 volubik-fast volumio[2758]: at afterDns (dgram.js:638:5) Feb 23 13:19:32 volubik-fast volumio[2758]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 23 13:19:32 volubik-fast volumio[2758]: errno: -101, Feb 23 13:19:32 volubik-fast volumio[2758]: code: 'ENETUNREACH', Feb 23 13:19:32 volubik-fast volumio[2758]: syscall: 'send', Feb 23 13:19:32 volubik-fast volumio[2758]: address: '255.255.255.255', Feb 23 13:19:32 volubik-fast volumio[2758]: port: 3483 Feb 23 13:19:32 volubik-fast volumio[2758]: } Feb 23 13:19:32 volubik-fast volumio[2758]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:19:32 volubik-fast sudo[2888]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:19:32 volubik-fast sudo[2888]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:32 volubik-fast sudo[2888]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:32 volubik-fast wpa_supplicant[932]: wlan0: Failed to initiate sched scan Feb 23 13:19:33 volubik-fast sudo[2923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 13:18 Feb 23 13:19:33 volubik-fast sudo[2923]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:33 volubik-fast sudo[2923]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:33 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:33] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 23 13:19:33 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:33] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 23 13:19:33 volubik-fast systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 23 13:19:33 volubik-fast sudo[2860]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:33 volubik-fast sudo[2854]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:33 volubik-fast systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 23 13:19:33 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:19:33 volubik-fast mpd[2880]: Feb 23 13:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 13:19:33 volubik-fast systemd[1]: Started Music Player Daemon. Feb 23 13:19:33 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:19:33 volubik-fast systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 23 13:19:33 volubik-fast systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1795. Feb 23 13:19:33 volubik-fast systemd[1]: Stopped Volumio Backend Module. Feb 23 13:19:33 volubik-fast systemd[1]: Started Volumio Backend Module. Feb 23 13:19:33 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:19:33 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:19:34 volubik-fast ntpd[812]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Feb 23 13:19:35 volubik-fast volumio[2938]: info: ------------------------------------------- Feb 23 13:19:35 volubik-fast volumio[2938]: info: ----- Volumio3 ---- Feb 23 13:19:35 volubik-fast volumio[2938]: info: ------------------------------------------- Feb 23 13:19:35 volubik-fast volumio[2938]: info: ----- System startup ---- Feb 23 13:19:35 volubik-fast volumio[2938]: info: ------------------------------------------- Feb 23 13:19:36 volubik-fast volumio[2938]: info: MYVOLUMIO Environment detected Feb 23 13:19:36 volubik-fast volumio[2938]: info: Plugin folders cleanup Feb 23 13:19:36 volubik-fast volumio[2938]: info: Scanning into folder /volumio/app/plugins/ Feb 23 13:19:36 volubik-fast volumio[2938]: info: Scanning category audio_interface Feb 23 13:19:36 volubik-fast volumio[2938]: info: Scanning category miscellanea Feb 23 13:19:36 volubik-fast volumio[2938]: info: Scanning category music_service Feb 23 13:19:36 volubik-fast volumio[2938]: info: Scanning category plugins.json Feb 23 13:19:36 volubik-fast volumio[2938]: info: Scanning category system_controller Feb 23 13:19:36 volubik-fast volumio[2938]: info: Scanning category user_interface Feb 23 13:19:36 volubik-fast volumio[2938]: info: Scanning into folder /data/plugins/ Feb 23 13:19:36 volubik-fast volumio[2938]: info: Scanning category music_service Feb 23 13:19:36 volubik-fast volumio[2938]: info: Plugin folders cleanup completed Feb 23 13:19:36 volubik-fast volumio[2938]: info: ------------------------------------------- Feb 23 13:19:36 volubik-fast volumio[2938]: info: ----- Core plugins startup ---- Feb 23 13:19:36 volubik-fast volumio[2938]: info: ------------------------------------------- Feb 23 13:19:36 volubik-fast volumio[2938]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 13:19:36 volubik-fast volumio[2938]: info: Adding plugin upnp to MyMusic Plugins Feb 23 13:19:36 volubik-fast volumio[2938]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 13:19:36 volubik-fast volumio[2938]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 13:19:36 volubik-fast volumio[2938]: info: Loading plugins from folder /data/plugins/ Feb 23 13:19:36 volubik-fast volumio[2938]: info: Loading plugin "system"... Feb 23 13:19:36 volubik-fast volumio[2938]: info: Loading plugin "appearance"... Feb 23 13:19:37 volubik-fast volumio[2938]: info: Loading plugin "network"... Feb 23 13:19:37 volubik-fast volumio[2938]: info: Refreshing Cached IP Addresses Feb 23 13:19:37 volubik-fast sudo[2969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 13:19:37 volubik-fast sudo[2969]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:37 volubik-fast sudo[2969]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:37 volubik-fast sudo[2971]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 13:19:37 volubik-fast sudo[2971]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:37 volubik-fast sudo[2971]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:37 volubik-fast volumio[2938]: info: Loading plugin "services"... Feb 23 13:19:37 volubik-fast volumio[2938]: info: Loading plugin "alsa_controller"... Feb 23 13:19:37 volubik-fast sudo[2984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 13:19:37 volubik-fast sudo[2984]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:37 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:37 volubik-fast volumio[2938]: info: Loading plugin "wizard"... Feb 23 13:19:37 volubik-fast volumio[2938]: info: Loading plugin "networkfs"... Feb 23 13:19:37 volubik-fast volumio[2938]: info: Starting Udev Watcher for removable devices Feb 23 13:19:37 volubik-fast volumio[2938]: info: Ignoring mount for partition: boot Feb 23 13:19:37 volubik-fast volumio[2938]: info: Ignoring mount for partition: volumio Feb 23 13:19:37 volubik-fast volumio[2938]: info: Ignoring mount for partition: volumio_data Feb 23 13:19:37 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:37 volubik-fast volumio[2938]: info: Loading plugin "volumio_command_line_client"... Feb 23 13:19:37 volubik-fast volumio[2938]: info: Loading plugin "upnp"... Feb 23 13:19:37 volubik-fast volumio[2938]: info: [1740313177779] Starting Upmpd Daemon Feb 23 13:19:37 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:37 volubik-fast volumio[2938]: info: Loading plugin "my_music"... Feb 23 13:19:37 volubik-fast volumio[2938]: info: Loading plugin "mpd"... Feb 23 13:19:38 volubik-fast volumio[2938]: info: Loading plugin "upnp_browser"... Feb 23 13:19:38 volubik-fast volumio[2938]: info: Loading plugin "alarm-clock"... Feb 23 13:19:38 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:38] [connect] Successful connection Feb 23 13:19:38 volubik-fast volumio[2938]: info: Plugin airplay_emulation is not enabled Feb 23 13:19:38 volubik-fast volumio[2938]: info: Loading plugin "last_100"... Feb 23 13:19:38 volubik-fast volumio[2938]: info: Loading plugin "webradio"... Feb 23 13:19:38 volubik-fast volumio[2938]: info: Loading plugin "i2s_dacs"... Feb 23 13:19:38 volubik-fast volumio[2938]: info: Loading plugin "volumiodiscovery"... Feb 23 13:19:38 volubik-fast volumio[2938]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:38 volubik-fast volumio[2938]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:38 volubik-fast volumio[2938]: *** WARNING *** For more information see Feb 23 13:19:38 volubik-fast node[2938]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:38 volubik-fast volumio[2938]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:38 volubik-fast volumio[2938]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:38 volubik-fast volumio[2938]: *** WARNING *** For more information see Feb 23 13:19:38 volubik-fast node[2938]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:38 volubik-fast node[2938]: *** WARNING *** For more information see Feb 23 13:19:38 volubik-fast node[2938]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:38 volubik-fast node[2938]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:38 volubik-fast node[2938]: *** WARNING *** For more information see Feb 23 13:19:38 volubik-fast volumio[2938]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 13:19:38 volubik-fast volumio[2938]: info: Discovery: Started advertising with name: VolUbik-fast Feb 23 13:19:38 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:38 volubik-fast volumio[2938]: info: Loading plugin "squeezelite_mc"... Feb 23 13:19:38 volubik-fast wpa_supplicant[932]: wlan0: Failed to initiate sched scan Feb 23 13:19:38 volubik-fast sudo[2984]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:39 volubik-fast volumio[2938]: info: Loading plugin "outputs"... Feb 23 13:19:39 volubik-fast volumio[2938]: info: Loading plugin "albumart"... Feb 23 13:19:39 volubik-fast volumio[2938]: info: Plugin example_plugin is not enabled Feb 23 13:19:39 volubik-fast volumio[2938]: info: Loading plugin "inputs"... Feb 23 13:19:39 volubik-fast volumio[2938]: info: Loading plugin "updater_comm"... Feb 23 13:19:39 volubik-fast volumio[2938]: info: Plugin mpdemulation is not enabled Feb 23 13:19:39 volubik-fast volumio[2938]: info: Loading plugin "rest_api"... Feb 23 13:19:39 volubik-fast volumio[2938]: info: Loading plugin "websocket"... Feb 23 13:19:39 volubik-fast volumio[2938]: info: Starting Socket.io Server version 2.3.0 Feb 23 13:19:39 volubik-fast volumio[2938]: info: Loading plugin "lms"... Feb 23 13:19:40 volubik-fast volumio[2938]: info: Loading i18n strings for locale it Feb 23 13:19:40 volubik-fast volumio[2938]: Forking 3 albumart workers Feb 23 13:19:40 volubik-fast volumio[2938]: Updating browse sources language Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::initPlayerControls Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:40 volubik-fast volumio[2938]: Express server listening on port 3000 Feb 23 13:19:40 volubik-fast volumio[2938]: [Metrics] WebUI: 5s 296.36ms Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreStateMachine::resetVolumioState Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreStateMachine::getcurrentVolume Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreStateMachine::pushState Feb 23 13:19:40 volubik-fast volumio[2938]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:40 volubik-fast volumio[2938]: info: Volumio Network Manager: Network status updated: 0 Feb 23 13:19:40 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:40] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740313178 101 Feb 23 13:19:40 volubik-fast volumio[2938]: 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 Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:19:40 volubik-fast volumio[2938]: info: Reloading queue from file Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreStateMachine::setRepeat null single undefined Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreStateMachine::pushState Feb 23 13:19:40 volubik-fast volumio[2938]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreStateMachine::setRandom null Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreStateMachine::pushState Feb 23 13:19:40 volubik-fast volumio[2938]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:40 volubik-fast volumio[2938]: info: Setting Device type: Raspberry PI Feb 23 13:19:40 volubik-fast volumio[2938]: info: Completed loading Core Plugins Feb 23 13:19:40 volubik-fast volumio[2938]: info: Preparing to generate the ALSA configuration file Feb 23 13:19:40 volubik-fast volumio[2938]: info: Discovery: adding 8fd035eb-d0cc-4f10-ab54-6ea1cf680f92 Feb 23 13:19:40 volubik-fast volumio[2938]: info: Discovery: Found device VolUbik-fast Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::volumioGetState Feb 23 13:19:40 volubik-fast volumio[2938]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:40 volubik-fast volumio[2938]: info: Asound.conf file unchanged, so no further update is needed Feb 23 13:19:40 volubik-fast volumio[2938]: info: Output device has changed, restarting MPD Feb 23 13:19:40 volubik-fast volumio[2938]: info: ___________ START PLUGINS ___________ Feb 23 13:19:40 volubik-fast volumio[2938]: info: ControllerMpd::onStart: Initializing MPD Feb 23 13:19:40 volubik-fast volumio[2938]: info: Creating MPD Configuration file Feb 23 13:19:40 volubik-fast sudo[3033]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:19:40 volubik-fast sudo[3033]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:40 volubik-fast sudo[3033]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:40 volubik-fast sudo[3035]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:19:40 volubik-fast sudo[3035]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:40 volubik-fast volumio[2938]: info: [1740313180870] CoreMusicLibrary::Adding element Server multimediali Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:40 volubik-fast systemd[1]: Stopping Music Player Daemon... Feb 23 13:19:40 volubik-fast sudo[3038]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:19:40 volubik-fast sudo[3041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:19:40 volubik-fast sudo[3041]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:40 volubik-fast sudo[3038]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:40 volubik-fast volumio[2938]: info: [1740313180928] CoreMusicLibrary::Adding element Last_100 Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:40 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:19:40 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:19:40 volubik-fast sudo[3038]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:40 volubik-fast volumio[2938]: info: [1740313180950] CoreMusicLibrary::Adding element Webradio Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:40 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:40 volubik-fast volumio[2938]: info: Initializing BBC Radios Feb 23 13:19:41 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:19:41 volubik-fast systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 23 13:19:41 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:19:41 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:19:41 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:19:41 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:41 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:19:41 volubik-fast volumio[2938]: info: [squeezelite_mc] Starting proxy server... Feb 23 13:19:41 volubik-fast sudo[3048]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 13:19:41 volubik-fast sudo[3048]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:41 volubik-fast volumio[2938]: info: Volumio Calling Home Feb 23 13:19:41 volubik-fast sudo[3048]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:41 volubik-fast sudo[3058]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Feb 23 13:19:41 volubik-fast sudo[3058]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:41 volubik-fast volumio[2938]: Starting albumart workers Feb 23 13:19:41 volubik-fast systemd[1]: Starting Logitech Media Server Daemon... Feb 23 13:19:41 volubik-fast systemd[1]: Started Logitech Media Server Daemon. Feb 23 13:19:41 volubik-fast systemd[3065]: logitechmediaserver.service: Changing to the requested working directory failed: Permission denied Feb 23 13:19:41 volubik-fast systemd[3065]: logitechmediaserver.service: Failed at step CHDIR spawning /usr/sbin/squeezeboxserver: Permission denied Feb 23 13:19:41 volubik-fast systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=200/CHDIR Feb 23 13:19:41 volubik-fast systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Feb 23 13:19:41 volubik-fast volumio[2938]: Starting albumart workers Feb 23 13:19:41 volubik-fast sudo[3058]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:41 volubik-fast volumio[2938]: Starting albumart workers Feb 23 13:19:41 volubik-fast volumio[2938]: info: [squeezelite_mc] Proxy server started on port 39697 Feb 23 13:19:41 volubik-fast volumio[2938]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:19:41 volubik-fast volumio[2938]: info: CoreStateMachine::pushState Feb 23 13:19:41 volubik-fast volumio[2938]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:41 volubik-fast volumio[2938]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:41 volubik-fast volumio[2938]: info: [squeezelite_mc] Server discovery started Feb 23 13:19:41 volubik-fast volumio[2938]: info: [squeezelite_mc] Player finder started Feb 23 13:19:41 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:41 volubik-fast volumio[2938]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:41 volubik-fast volumio[2938]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"None","mixer":null} Feb 23 13:19:41 volubik-fast volumio[2938]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:19:41 volubik-fast volumio[2938]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:19:41 volubik-fast volumio[2938]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 23 13:19:41 volubik-fast volumio[2938]: at doSend (dgram.js:692:16) Feb 23 13:19:41 volubik-fast volumio[2938]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 23 13:19:41 volubik-fast volumio[2938]: at afterDns (dgram.js:638:5) Feb 23 13:19:41 volubik-fast volumio[2938]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 23 13:19:41 volubik-fast volumio[2938]: errno: -101, Feb 23 13:19:41 volubik-fast volumio[2938]: code: 'ENETUNREACH', Feb 23 13:19:41 volubik-fast volumio[2938]: syscall: 'send', Feb 23 13:19:41 volubik-fast volumio[2938]: address: '255.255.255.255', Feb 23 13:19:41 volubik-fast volumio[2938]: port: 3483 Feb 23 13:19:41 volubik-fast volumio[2938]: } Feb 23 13:19:41 volubik-fast volumio[2938]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:19:41 volubik-fast sudo[3070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:19:41 volubik-fast sudo[3070]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:41 volubik-fast sudo[3070]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:42 volubik-fast sudo[3105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 13:18 Feb 23 13:19:42 volubik-fast sudo[3105]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:42 volubik-fast sudo[3105]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:43 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:43] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 23 13:19:43 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:43] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 23 13:19:43 volubik-fast systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 23 13:19:43 volubik-fast sudo[3041]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:43 volubik-fast sudo[3035]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:43 volubik-fast systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 23 13:19:43 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:19:43 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:19:43 volubik-fast systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 23 13:19:43 volubik-fast systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1796. Feb 23 13:19:43 volubik-fast systemd[1]: Stopped Volumio Backend Module. Feb 23 13:19:43 volubik-fast systemd[1]: Started Volumio Backend Module. Feb 23 13:19:43 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:19:43 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:19:43 volubik-fast mpd[3060]: Feb 23 13:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 13:19:43 volubik-fast systemd[1]: Started Music Player Daemon. Feb 23 13:19:44 volubik-fast wpa_supplicant[932]: wlan0: Failed to initiate sched scan Feb 23 13:19:45 volubik-fast volumio[3118]: info: ------------------------------------------- Feb 23 13:19:45 volubik-fast volumio[3118]: info: ----- Volumio3 ---- Feb 23 13:19:45 volubik-fast volumio[3118]: info: ------------------------------------------- Feb 23 13:19:45 volubik-fast volumio[3118]: info: ----- System startup ---- Feb 23 13:19:45 volubik-fast volumio[3118]: info: ------------------------------------------- Feb 23 13:19:45 volubik-fast volumio[3118]: info: MYVOLUMIO Environment detected Feb 23 13:19:45 volubik-fast volumio[3118]: info: Plugin folders cleanup Feb 23 13:19:45 volubik-fast volumio[3118]: info: Scanning into folder /volumio/app/plugins/ Feb 23 13:19:45 volubik-fast volumio[3118]: info: Scanning category audio_interface Feb 23 13:19:45 volubik-fast volumio[3118]: info: Scanning category miscellanea Feb 23 13:19:45 volubik-fast volumio[3118]: info: Scanning category music_service Feb 23 13:19:45 volubik-fast volumio[3118]: info: Scanning category plugins.json Feb 23 13:19:45 volubik-fast volumio[3118]: info: Scanning category system_controller Feb 23 13:19:45 volubik-fast volumio[3118]: info: Scanning category user_interface Feb 23 13:19:45 volubik-fast volumio[3118]: info: Scanning into folder /data/plugins/ Feb 23 13:19:45 volubik-fast volumio[3118]: info: Scanning category music_service Feb 23 13:19:45 volubik-fast volumio[3118]: info: Plugin folders cleanup completed Feb 23 13:19:45 volubik-fast volumio[3118]: info: ------------------------------------------- Feb 23 13:19:45 volubik-fast volumio[3118]: info: ----- Core plugins startup ---- Feb 23 13:19:45 volubik-fast volumio[3118]: info: ------------------------------------------- Feb 23 13:19:45 volubik-fast volumio[3118]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 13:19:45 volubik-fast volumio[3118]: info: Adding plugin upnp to MyMusic Plugins Feb 23 13:19:45 volubik-fast volumio[3118]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 13:19:45 volubik-fast volumio[3118]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 13:19:45 volubik-fast volumio[3118]: info: Loading plugins from folder /data/plugins/ Feb 23 13:19:46 volubik-fast volumio[3118]: info: Loading plugin "system"... Feb 23 13:19:46 volubik-fast volumio[3118]: info: Loading plugin "appearance"... Feb 23 13:19:47 volubik-fast volumio[3118]: info: Loading plugin "network"... Feb 23 13:19:47 volubik-fast volumio[3118]: info: Refreshing Cached IP Addresses Feb 23 13:19:47 volubik-fast sudo[3150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 13:19:47 volubik-fast sudo[3150]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:47 volubik-fast sudo[3150]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:47 volubik-fast sudo[3152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 13:19:47 volubik-fast sudo[3152]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:47 volubik-fast sudo[3152]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:47 volubik-fast volumio[3118]: info: Loading plugin "services"... Feb 23 13:19:47 volubik-fast volumio[3118]: info: Loading plugin "alsa_controller"... Feb 23 13:19:47 volubik-fast sudo[3164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 13:19:47 volubik-fast sudo[3164]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:47 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:47 volubik-fast volumio[3118]: info: Loading plugin "wizard"... Feb 23 13:19:47 volubik-fast volumio[3118]: info: Loading plugin "networkfs"... Feb 23 13:19:47 volubik-fast volumio[3118]: info: Starting Udev Watcher for removable devices Feb 23 13:19:47 volubik-fast volumio[3118]: info: Ignoring mount for partition: boot Feb 23 13:19:47 volubik-fast volumio[3118]: info: Ignoring mount for partition: volumio Feb 23 13:19:47 volubik-fast volumio[3118]: info: Ignoring mount for partition: volumio_data Feb 23 13:19:47 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:47 volubik-fast volumio[3118]: info: Loading plugin "volumio_command_line_client"... Feb 23 13:19:47 volubik-fast volumio[3118]: info: Loading plugin "upnp"... Feb 23 13:19:47 volubik-fast volumio[3118]: info: [1740313187306] Starting Upmpd Daemon Feb 23 13:19:47 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:47 volubik-fast volumio[3118]: info: Loading plugin "my_music"... Feb 23 13:19:47 volubik-fast volumio[3118]: info: Loading plugin "mpd"... Feb 23 13:19:47 volubik-fast volumio[3118]: info: Loading plugin "upnp_browser"... Feb 23 13:19:47 volubik-fast sudo[3164]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:47 volubik-fast volumio[3118]: info: Loading plugin "alarm-clock"... Feb 23 13:19:48 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:48] [connect] Successful connection Feb 23 13:19:48 volubik-fast volumio[3118]: info: Plugin airplay_emulation is not enabled Feb 23 13:19:48 volubik-fast volumio[3118]: info: Loading plugin "last_100"... Feb 23 13:19:48 volubik-fast volumio[3118]: info: Loading plugin "webradio"... Feb 23 13:19:48 volubik-fast volumio[3118]: info: Loading plugin "i2s_dacs"... Feb 23 13:19:48 volubik-fast volumio[3118]: info: Loading plugin "volumiodiscovery"... Feb 23 13:19:48 volubik-fast volumio[3118]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:48 volubik-fast volumio[3118]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:48 volubik-fast volumio[3118]: *** WARNING *** For more information see Feb 23 13:19:48 volubik-fast node[3118]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:48 volubik-fast volumio[3118]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:48 volubik-fast volumio[3118]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:48 volubik-fast volumio[3118]: *** WARNING *** For more information see Feb 23 13:19:48 volubik-fast node[3118]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:48 volubik-fast node[3118]: *** WARNING *** For more information see Feb 23 13:19:48 volubik-fast node[3118]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:48 volubik-fast node[3118]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:48 volubik-fast node[3118]: *** WARNING *** For more information see Feb 23 13:19:48 volubik-fast volumio[3118]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 13:19:48 volubik-fast volumio[3118]: info: Discovery: Started advertising with name: VolUbik-fast Feb 23 13:19:48 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:48 volubik-fast volumio[3118]: info: Loading plugin "squeezelite_mc"... Feb 23 13:19:48 volubik-fast ntpd[812]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Feb 23 13:19:49 volubik-fast volumio[3118]: info: Loading plugin "outputs"... Feb 23 13:19:49 volubik-fast volumio[3118]: info: Loading plugin "albumart"... Feb 23 13:19:49 volubik-fast volumio[3118]: info: Plugin example_plugin is not enabled Feb 23 13:19:49 volubik-fast volumio[3118]: info: Loading plugin "inputs"... Feb 23 13:19:49 volubik-fast volumio[3118]: info: Loading plugin "updater_comm"... Feb 23 13:19:49 volubik-fast volumio[3118]: info: Plugin mpdemulation is not enabled Feb 23 13:19:49 volubik-fast volumio[3118]: info: Loading plugin "rest_api"... Feb 23 13:19:49 volubik-fast volumio[3118]: info: Loading plugin "websocket"... Feb 23 13:19:49 volubik-fast volumio[3118]: info: Starting Socket.io Server version 2.3.0 Feb 23 13:19:49 volubik-fast volumio[3118]: info: Loading plugin "lms"... Feb 23 13:19:49 volubik-fast volumio[3118]: info: Loading i18n strings for locale it Feb 23 13:19:49 volubik-fast volumio[3118]: Forking 3 albumart workers Feb 23 13:19:49 volubik-fast volumio[3118]: Updating browse sources language Feb 23 13:19:49 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:49 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:49 volubik-fast volumio[3118]: info: CoreCommandRouter::initPlayerControls Feb 23 13:19:49 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:49 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:49 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:49 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:49 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:49 volubik-fast volumio[3118]: Express server listening on port 3000 Feb 23 13:19:49 volubik-fast volumio[3118]: [Metrics] WebUI: 5s 312.51ms Feb 23 13:19:49 volubik-fast volumio[3118]: info: CoreStateMachine::resetVolumioState Feb 23 13:19:49 volubik-fast volumio[3118]: info: CoreStateMachine::getcurrentVolume Feb 23 13:19:49 volubik-fast volumio[3118]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:19:49 volubik-fast volumio[3118]: info: CoreStateMachine::pushState Feb 23 13:19:49 volubik-fast volumio[3118]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:49 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:19:49 volubik-fast volumio[3118]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:49 volubik-fast volumio[3118]: info: Volumio Network Manager: Network status updated: 0 Feb 23 13:19:50 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740313188 101 Feb 23 13:19:50 volubik-fast volumio[3118]: 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 Feb 23 13:19:50 volubik-fast wpa_supplicant[932]: wlan0: Failed to initiate sched scan Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:19:50 volubik-fast volumio[3118]: info: Reloading queue from file Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreStateMachine::setRepeat null single undefined Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreStateMachine::pushState Feb 23 13:19:50 volubik-fast volumio[3118]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreStateMachine::setRandom null Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreStateMachine::pushState Feb 23 13:19:50 volubik-fast volumio[3118]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:50 volubik-fast volumio[3118]: info: Setting Device type: Raspberry PI Feb 23 13:19:50 volubik-fast volumio[3118]: info: Completed loading Core Plugins Feb 23 13:19:50 volubik-fast volumio[3118]: info: Preparing to generate the ALSA configuration file Feb 23 13:19:50 volubik-fast volumio[3118]: info: Asound.conf file unchanged, so no further update is needed Feb 23 13:19:50 volubik-fast volumio[3118]: info: Output device has changed, restarting MPD Feb 23 13:19:50 volubik-fast volumio[3118]: info: ___________ START PLUGINS ___________ Feb 23 13:19:50 volubik-fast sudo[3214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:19:50 volubik-fast sudo[3214]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:50 volubik-fast sudo[3214]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:50 volubik-fast volumio[3118]: info: ControllerMpd::onStart: Initializing MPD Feb 23 13:19:50 volubik-fast volumio[3118]: info: Creating MPD Configuration file Feb 23 13:19:50 volubik-fast sudo[3216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:19:50 volubik-fast sudo[3216]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:50 volubik-fast sudo[3219]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:50 volubik-fast systemd[1]: Stopping Music Player Daemon... Feb 23 13:19:50 volubik-fast volumio[3118]: info: [1740313190430] CoreMusicLibrary::Adding element Server multimediali Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:50 volubik-fast sudo[3219]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:50 volubik-fast sudo[3219]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:50 volubik-fast volumio[3118]: info: [1740313190460] CoreMusicLibrary::Adding element Last_100 Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:19:50 volubik-fast volumio[3118]: info: [1740313190465] CoreMusicLibrary::Adding element Webradio Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:50 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:19:50 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:19:50 volubik-fast sudo[3222]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:19:50 volubik-fast sudo[3222]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:50 volubik-fast volumio[3118]: info: Initializing BBC Radios Feb 23 13:19:50 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:19:50 volubik-fast systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 23 13:19:50 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:19:50 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:19:50 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:50 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:19:50 volubik-fast volumio[3118]: info: [squeezelite_mc] Starting proxy server... Feb 23 13:19:50 volubik-fast volumio[3118]: info: Volumio Calling Home Feb 23 13:19:50 volubik-fast sudo[3229]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 13:19:50 volubik-fast sudo[3229]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:50 volubik-fast sudo[3229]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:50 volubik-fast sudo[3238]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Feb 23 13:19:50 volubik-fast sudo[3238]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:50 volubik-fast systemd[1]: Starting Logitech Media Server Daemon... Feb 23 13:19:50 volubik-fast volumio[3118]: Starting albumart workers Feb 23 13:19:50 volubik-fast systemd[1]: Started Logitech Media Server Daemon. Feb 23 13:19:50 volubik-fast systemd[3247]: logitechmediaserver.service: Changing to the requested working directory failed: Permission denied Feb 23 13:19:50 volubik-fast systemd[3247]: logitechmediaserver.service: Failed at step CHDIR spawning /usr/sbin/squeezeboxserver: Permission denied Feb 23 13:19:50 volubik-fast systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=200/CHDIR Feb 23 13:19:50 volubik-fast systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Feb 23 13:19:50 volubik-fast sudo[3238]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:50 volubik-fast ntpd[812]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Feb 23 13:19:50 volubik-fast volumio[3118]: Starting albumart workers Feb 23 13:19:51 volubik-fast volumio[3118]: info: [squeezelite_mc] Proxy server started on port 38845 Feb 23 13:19:51 volubik-fast volumio[3118]: Starting albumart workers Feb 23 13:19:51 volubik-fast volumio[3118]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:19:51 volubik-fast volumio[3118]: info: CoreStateMachine::pushState Feb 23 13:19:51 volubik-fast volumio[3118]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:51 volubik-fast volumio[3118]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:51 volubik-fast volumio[3118]: info: [squeezelite_mc] Server discovery started Feb 23 13:19:51 volubik-fast volumio[3118]: info: [squeezelite_mc] Player finder started Feb 23 13:19:51 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:51 volubik-fast volumio[3118]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:51 volubik-fast volumio[3118]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"None","mixer":null} Feb 23 13:19:51 volubik-fast volumio[3118]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:19:51 volubik-fast volumio[3118]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:19:51 volubik-fast volumio[3118]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 23 13:19:51 volubik-fast volumio[3118]: at doSend (dgram.js:692:16) Feb 23 13:19:51 volubik-fast volumio[3118]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 23 13:19:51 volubik-fast volumio[3118]: at afterDns (dgram.js:638:5) Feb 23 13:19:51 volubik-fast volumio[3118]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 23 13:19:51 volubik-fast volumio[3118]: errno: -101, Feb 23 13:19:51 volubik-fast volumio[3118]: code: 'ENETUNREACH', Feb 23 13:19:51 volubik-fast volumio[3118]: syscall: 'send', Feb 23 13:19:51 volubik-fast volumio[3118]: address: '255.255.255.255', Feb 23 13:19:51 volubik-fast volumio[3118]: port: 3483 Feb 23 13:19:51 volubik-fast volumio[3118]: } Feb 23 13:19:51 volubik-fast volumio[3118]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:19:51 volubik-fast sudo[3250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:19:51 volubik-fast sudo[3250]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:51 volubik-fast sudo[3250]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:51 volubik-fast ntpd[812]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Feb 23 13:19:52 volubik-fast sudo[3285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 13:18 Feb 23 13:19:52 volubik-fast sudo[3285]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:52 volubik-fast sudo[3285]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:52 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:52] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 23 13:19:52 volubik-fast systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 23 13:19:52 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:52] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 23 13:19:52 volubik-fast sudo[3222]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:52 volubik-fast sudo[3216]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:52 volubik-fast systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 23 13:19:52 volubik-fast mpd[3241]: Feb 23 13:19 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 13:19:52 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:19:52 volubik-fast systemd[1]: Started Music Player Daemon. Feb 23 13:19:52 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:19:53 volubik-fast systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 23 13:19:53 volubik-fast systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1797. Feb 23 13:19:53 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:19:53 volubik-fast systemd[1]: Stopped Volumio Backend Module. Feb 23 13:19:53 volubik-fast systemd[1]: Started Volumio Backend Module. Feb 23 13:19:53 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:19:55 volubik-fast volumio[3301]: info: ------------------------------------------- Feb 23 13:19:55 volubik-fast volumio[3301]: info: ----- Volumio3 ---- Feb 23 13:19:55 volubik-fast volumio[3301]: info: ------------------------------------------- Feb 23 13:19:55 volubik-fast volumio[3301]: info: ----- System startup ---- Feb 23 13:19:55 volubik-fast volumio[3301]: info: ------------------------------------------- Feb 23 13:19:55 volubik-fast wpa_supplicant[932]: wlan0: Failed to initiate sched scan Feb 23 13:19:55 volubik-fast volumio[3301]: info: MYVOLUMIO Environment detected Feb 23 13:19:55 volubik-fast volumio[3301]: info: Plugin folders cleanup Feb 23 13:19:55 volubik-fast volumio[3301]: info: Scanning into folder /volumio/app/plugins/ Feb 23 13:19:55 volubik-fast volumio[3301]: info: Scanning category audio_interface Feb 23 13:19:55 volubik-fast volumio[3301]: info: Scanning category miscellanea Feb 23 13:19:55 volubik-fast volumio[3301]: info: Scanning category music_service Feb 23 13:19:55 volubik-fast volumio[3301]: info: Scanning category plugins.json Feb 23 13:19:55 volubik-fast volumio[3301]: info: Scanning category system_controller Feb 23 13:19:55 volubik-fast volumio[3301]: info: Scanning category user_interface Feb 23 13:19:55 volubik-fast volumio[3301]: info: Scanning into folder /data/plugins/ Feb 23 13:19:55 volubik-fast volumio[3301]: info: Scanning category music_service Feb 23 13:19:55 volubik-fast volumio[3301]: info: Plugin folders cleanup completed Feb 23 13:19:55 volubik-fast volumio[3301]: info: ------------------------------------------- Feb 23 13:19:55 volubik-fast volumio[3301]: info: ----- Core plugins startup ---- Feb 23 13:19:55 volubik-fast volumio[3301]: info: ------------------------------------------- Feb 23 13:19:55 volubik-fast volumio[3301]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 13:19:55 volubik-fast volumio[3301]: info: Adding plugin upnp to MyMusic Plugins Feb 23 13:19:55 volubik-fast volumio[3301]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 13:19:55 volubik-fast volumio[3301]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 13:19:55 volubik-fast volumio[3301]: info: Loading plugins from folder /data/plugins/ Feb 23 13:19:55 volubik-fast volumio[3301]: info: Loading plugin "system"... Feb 23 13:19:55 volubik-fast volumio[3301]: info: Loading plugin "appearance"... Feb 23 13:19:56 volubik-fast volumio[3301]: info: Loading plugin "network"... Feb 23 13:19:56 volubik-fast volumio[3301]: info: Refreshing Cached IP Addresses Feb 23 13:19:56 volubik-fast sudo[3331]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 13:19:56 volubik-fast sudo[3331]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:57 volubik-fast sudo[3331]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:57 volubik-fast sudo[3333]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 13:19:57 volubik-fast sudo[3333]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:57 volubik-fast sudo[3333]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:57 volubik-fast volumio[3301]: info: Loading plugin "services"... Feb 23 13:19:57 volubik-fast volumio[3301]: info: Loading plugin "alsa_controller"... Feb 23 13:19:57 volubik-fast sudo[3343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 13:19:57 volubik-fast sudo[3343]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:19:57 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:19:57 volubik-fast volumio[3301]: info: Loading plugin "wizard"... Feb 23 13:19:57 volubik-fast volumio[3301]: info: Loading plugin "networkfs"... Feb 23 13:19:57 volubik-fast volumio[3301]: info: Starting Udev Watcher for removable devices Feb 23 13:19:57 volubik-fast volumio[3301]: info: Ignoring mount for partition: boot Feb 23 13:19:57 volubik-fast volumio[3301]: info: Ignoring mount for partition: volumio Feb 23 13:19:57 volubik-fast volumio[3301]: info: Ignoring mount for partition: volumio_data Feb 23 13:19:57 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:57 volubik-fast volumio[3301]: info: Loading plugin "volumio_command_line_client"... Feb 23 13:19:57 volubik-fast volumio[3301]: info: Loading plugin "upnp"... Feb 23 13:19:57 volubik-fast volumio[3301]: info: [1740313197196] Starting Upmpd Daemon Feb 23 13:19:57 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:57 volubik-fast volumio[3301]: info: Loading plugin "my_music"... Feb 23 13:19:57 volubik-fast volumio[3301]: info: Loading plugin "mpd"... Feb 23 13:19:57 volubik-fast volumio[3301]: info: Loading plugin "upnp_browser"... Feb 23 13:19:57 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:57] [connect] Successful connection Feb 23 13:19:57 volubik-fast sudo[3343]: pam_unix(sudo:session): session closed for user root Feb 23 13:19:57 volubik-fast volumio[3301]: info: Loading plugin "alarm-clock"... Feb 23 13:19:58 volubik-fast volumio[3301]: info: Plugin airplay_emulation is not enabled Feb 23 13:19:58 volubik-fast volumio[3301]: info: Loading plugin "last_100"... Feb 23 13:19:58 volubik-fast volumio[3301]: info: Loading plugin "webradio"... Feb 23 13:19:58 volubik-fast volumio[3301]: info: Loading plugin "i2s_dacs"... Feb 23 13:19:58 volubik-fast volumio[3301]: info: Loading plugin "volumiodiscovery"... Feb 23 13:19:58 volubik-fast volumio[3301]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:58 volubik-fast volumio[3301]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:58 volubik-fast volumio[3301]: *** WARNING *** For more information see Feb 23 13:19:58 volubik-fast node[3301]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:58 volubik-fast volumio[3301]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:58 volubik-fast volumio[3301]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:58 volubik-fast volumio[3301]: *** WARNING *** For more information see Feb 23 13:19:58 volubik-fast node[3301]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:58 volubik-fast node[3301]: *** WARNING *** For more information see Feb 23 13:19:58 volubik-fast node[3301]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:19:58 volubik-fast node[3301]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:19:58 volubik-fast node[3301]: *** WARNING *** For more information see Feb 23 13:19:58 volubik-fast volumio[3301]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 13:19:58 volubik-fast volumio[3301]: info: Discovery: Started advertising with name: VolUbik-fast Feb 23 13:19:58 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:19:58 volubik-fast volumio[3301]: info: Loading plugin "squeezelite_mc"... Feb 23 13:19:59 volubik-fast volumio[3301]: info: Loading plugin "outputs"... Feb 23 13:19:59 volubik-fast volumio[3301]: info: Loading plugin "albumart"... Feb 23 13:19:59 volubik-fast volumio[3301]: info: Plugin example_plugin is not enabled Feb 23 13:19:59 volubik-fast volumio[3301]: info: Loading plugin "inputs"... Feb 23 13:19:59 volubik-fast volumio[3301]: info: Loading plugin "updater_comm"... Feb 23 13:19:59 volubik-fast volumio[3301]: info: Plugin mpdemulation is not enabled Feb 23 13:19:59 volubik-fast volumio[3301]: info: Loading plugin "rest_api"... Feb 23 13:19:59 volubik-fast volumio[3301]: info: Loading plugin "websocket"... Feb 23 13:19:59 volubik-fast volumio[3301]: info: Starting Socket.io Server version 2.3.0 Feb 23 13:19:59 volubik-fast volumio[3301]: info: Loading plugin "lms"... Feb 23 13:19:59 volubik-fast volumio[3301]: Forking 3 albumart workers Feb 23 13:19:59 volubik-fast volumio[3301]: info: Loading i18n strings for locale it Feb 23 13:19:59 volubik-fast volumio[3301]: Updating browse sources language Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreCommandRouter::initPlayerControls Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:19:59 volubik-fast volumio[3301]: Express server listening on port 3000 Feb 23 13:19:59 volubik-fast volumio[3301]: [Metrics] WebUI: 5s 384.80ms Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreStateMachine::resetVolumioState Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreStateMachine::getcurrentVolume Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreStateMachine::pushState Feb 23 13:19:59 volubik-fast volumio[3301]: info: CorePlayQueue::getTrack 0 Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:19:59 volubik-fast volumio[3301]: info: CoreCommandRouter::volumioPushState Feb 23 13:19:59 volubik-fast volumio[3301]: info: Volumio Network Manager: Network status updated: 0 Feb 23 13:19:59 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:19:59] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740313197 101 Feb 23 13:20:00 volubik-fast volumio[3301]: 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 Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:20:00 volubik-fast volumio[3301]: info: Reloading queue from file Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreStateMachine::setRepeat null single undefined Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreStateMachine::pushState Feb 23 13:20:00 volubik-fast volumio[3301]: info: CorePlayQueue::getTrack 0 Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreCommandRouter::volumioPushState Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreStateMachine::setRandom null Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreStateMachine::pushState Feb 23 13:20:00 volubik-fast volumio[3301]: info: CorePlayQueue::getTrack 0 Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreCommandRouter::volumioPushState Feb 23 13:20:00 volubik-fast volumio[3301]: info: Setting Device type: Raspberry PI Feb 23 13:20:00 volubik-fast volumio[3301]: info: Completed loading Core Plugins Feb 23 13:20:00 volubik-fast volumio[3301]: info: Preparing to generate the ALSA configuration file Feb 23 13:20:00 volubik-fast volumio[3301]: info: Discovery: adding 8fd035eb-d0cc-4f10-ab54-6ea1cf680f92 Feb 23 13:20:00 volubik-fast volumio[3301]: info: Discovery: Found device VolUbik-fast Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreCommandRouter::volumioGetState Feb 23 13:20:00 volubik-fast volumio[3301]: info: CorePlayQueue::getTrack 0 Feb 23 13:20:00 volubik-fast volumio[3301]: info: Asound.conf file unchanged, so no further update is needed Feb 23 13:20:00 volubik-fast volumio[3301]: info: Output device has changed, restarting MPD Feb 23 13:20:00 volubik-fast volumio[3301]: info: ___________ START PLUGINS ___________ Feb 23 13:20:00 volubik-fast sudo[3395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:20:00 volubik-fast volumio[3301]: info: ControllerMpd::onStart: Initializing MPD Feb 23 13:20:00 volubik-fast volumio[3301]: info: Creating MPD Configuration file Feb 23 13:20:00 volubik-fast sudo[3395]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:00 volubik-fast sudo[3395]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:00 volubik-fast sudo[3397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:20:00 volubik-fast sudo[3397]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:00 volubik-fast sudo[3400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:20:00 volubik-fast sudo[3400]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:00 volubik-fast systemd[1]: Stopping Music Player Daemon... Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:20:00 volubik-fast sudo[3400]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:20:00 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:20:00 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:20:00 volubik-fast sudo[3404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:20:00 volubik-fast sudo[3404]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:00 volubik-fast volumio[3301]: info: [1740313200414] CoreMusicLibrary::Adding element Server multimediali Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:20:00 volubik-fast volumio[3301]: info: [1740313200445] CoreMusicLibrary::Adding element Last_100 Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:20:00 volubik-fast volumio[3301]: info: [1740313200449] CoreMusicLibrary::Adding element Webradio Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:20:00 volubik-fast volumio[3301]: info: Initializing BBC Radios Feb 23 13:20:00 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:20:00 volubik-fast systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 23 13:20:00 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:20:00 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:20:00 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:20:00 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:20:00 volubik-fast sudo[3409]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 13:20:00 volubik-fast sudo[3409]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:00 volubik-fast sudo[3409]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:00 volubik-fast volumio[3301]: Starting albumart workers Feb 23 13:20:00 volubik-fast volumio[3301]: info: [squeezelite_mc] Starting proxy server... Feb 23 13:20:00 volubik-fast volumio[3301]: Starting albumart workers Feb 23 13:20:00 volubik-fast volumio[3301]: info: Volumio Calling Home Feb 23 13:20:00 volubik-fast volumio[3301]: Starting albumart workers Feb 23 13:20:00 volubik-fast sudo[3421]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Feb 23 13:20:00 volubik-fast sudo[3421]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:00 volubik-fast systemd[1]: Starting Logitech Media Server Daemon... Feb 23 13:20:00 volubik-fast systemd[1]: Started Logitech Media Server Daemon. Feb 23 13:20:00 volubik-fast systemd[3428]: logitechmediaserver.service: Changing to the requested working directory failed: Permission denied Feb 23 13:20:00 volubik-fast systemd[3428]: logitechmediaserver.service: Failed at step CHDIR spawning /usr/sbin/squeezeboxserver: Permission denied Feb 23 13:20:00 volubik-fast sudo[3421]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:00 volubik-fast systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=200/CHDIR Feb 23 13:20:00 volubik-fast systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Feb 23 13:20:01 volubik-fast volumio[3301]: info: [squeezelite_mc] Proxy server started on port 32825 Feb 23 13:20:01 volubik-fast volumio[3301]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:20:01 volubik-fast volumio[3301]: info: CoreStateMachine::pushState Feb 23 13:20:01 volubik-fast volumio[3301]: info: CorePlayQueue::getTrack 0 Feb 23 13:20:01 volubik-fast volumio[3301]: info: CoreCommandRouter::volumioPushState Feb 23 13:20:01 volubik-fast volumio[3301]: info: [squeezelite_mc] Server discovery started Feb 23 13:20:01 volubik-fast volumio[3301]: info: [squeezelite_mc] Player finder started Feb 23 13:20:01 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:01 volubik-fast volumio[3301]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:01 volubik-fast volumio[3301]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"None","mixer":null} Feb 23 13:20:01 volubik-fast wpa_supplicant[932]: wlan0: Failed to initiate sched scan Feb 23 13:20:01 volubik-fast volumio[3301]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:20:01 volubik-fast volumio[3301]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:20:01 volubik-fast volumio[3301]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 23 13:20:01 volubik-fast volumio[3301]: at doSend (dgram.js:692:16) Feb 23 13:20:01 volubik-fast volumio[3301]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 23 13:20:01 volubik-fast volumio[3301]: at afterDns (dgram.js:638:5) Feb 23 13:20:01 volubik-fast volumio[3301]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 23 13:20:01 volubik-fast volumio[3301]: errno: -101, Feb 23 13:20:01 volubik-fast volumio[3301]: code: 'ENETUNREACH', Feb 23 13:20:01 volubik-fast volumio[3301]: syscall: 'send', Feb 23 13:20:01 volubik-fast volumio[3301]: address: '255.255.255.255', Feb 23 13:20:01 volubik-fast volumio[3301]: port: 3483 Feb 23 13:20:01 volubik-fast volumio[3301]: } Feb 23 13:20:01 volubik-fast volumio[3301]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:20:01 volubik-fast sudo[3431]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:20:01 volubik-fast sudo[3431]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:01 volubik-fast sudo[3431]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:02 volubik-fast sudo[3466]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 13:19 Feb 23 13:20:02 volubik-fast sudo[3466]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:02 volubik-fast sudo[3466]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:02 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:20:02] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 23 13:20:02 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:20:02] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 23 13:20:02 volubik-fast systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 23 13:20:02 volubik-fast sudo[3404]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:02 volubik-fast sudo[3397]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:02 volubik-fast systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 23 13:20:02 volubik-fast mpd[3419]: Feb 23 13:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 13:20:02 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:20:02 volubik-fast systemd[1]: Started Music Player Daemon. Feb 23 13:20:02 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:20:03 volubik-fast systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 23 13:20:03 volubik-fast systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1798. Feb 23 13:20:03 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:20:03 volubik-fast systemd[1]: Stopped Volumio Backend Module. Feb 23 13:20:03 volubik-fast systemd[1]: Started Volumio Backend Module. Feb 23 13:20:03 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:20:04 volubik-fast volumio[3482]: info: ------------------------------------------- Feb 23 13:20:04 volubik-fast volumio[3482]: info: ----- Volumio3 ---- Feb 23 13:20:04 volubik-fast volumio[3482]: info: ------------------------------------------- Feb 23 13:20:04 volubik-fast volumio[3482]: info: ----- System startup ---- Feb 23 13:20:04 volubik-fast volumio[3482]: info: ------------------------------------------- Feb 23 13:20:05 volubik-fast volumio[3482]: info: MYVOLUMIO Environment detected Feb 23 13:20:05 volubik-fast volumio[3482]: info: Plugin folders cleanup Feb 23 13:20:05 volubik-fast volumio[3482]: info: Scanning into folder /volumio/app/plugins/ Feb 23 13:20:05 volubik-fast volumio[3482]: info: Scanning category audio_interface Feb 23 13:20:05 volubik-fast volumio[3482]: info: Scanning category miscellanea Feb 23 13:20:05 volubik-fast volumio[3482]: info: Scanning category music_service Feb 23 13:20:05 volubik-fast volumio[3482]: info: Scanning category plugins.json Feb 23 13:20:05 volubik-fast volumio[3482]: info: Scanning category system_controller Feb 23 13:20:05 volubik-fast volumio[3482]: info: Scanning category user_interface Feb 23 13:20:05 volubik-fast volumio[3482]: info: Scanning into folder /data/plugins/ Feb 23 13:20:05 volubik-fast volumio[3482]: info: Scanning category music_service Feb 23 13:20:05 volubik-fast volumio[3482]: info: Plugin folders cleanup completed Feb 23 13:20:05 volubik-fast volumio[3482]: info: ------------------------------------------- Feb 23 13:20:05 volubik-fast volumio[3482]: info: ----- Core plugins startup ---- Feb 23 13:20:05 volubik-fast volumio[3482]: info: ------------------------------------------- Feb 23 13:20:05 volubik-fast volumio[3482]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 13:20:05 volubik-fast volumio[3482]: info: Adding plugin upnp to MyMusic Plugins Feb 23 13:20:05 volubik-fast volumio[3482]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 13:20:05 volubik-fast volumio[3482]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 13:20:05 volubik-fast volumio[3482]: info: Loading plugins from folder /data/plugins/ Feb 23 13:20:05 volubik-fast volumio[3482]: info: Loading plugin "system"... Feb 23 13:20:05 volubik-fast volumio[3482]: info: Loading plugin "appearance"... Feb 23 13:20:06 volubik-fast volumio[3482]: info: Loading plugin "network"... Feb 23 13:20:06 volubik-fast volumio[3482]: info: Refreshing Cached IP Addresses Feb 23 13:20:06 volubik-fast sudo[3511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 13:20:06 volubik-fast sudo[3511]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:06 volubik-fast sudo[3511]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:06 volubik-fast sudo[3513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 13:20:06 volubik-fast sudo[3513]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:06 volubik-fast sudo[3513]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:06 volubik-fast volumio[3482]: info: Loading plugin "services"... Feb 23 13:20:06 volubik-fast volumio[3482]: info: Loading plugin "alsa_controller"... Feb 23 13:20:06 volubik-fast sudo[3522]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 13:20:06 volubik-fast sudo[3522]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:06 volubik-fast wpa_supplicant[932]: wlan0: Failed to initiate sched scan Feb 23 13:20:07 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:20:07 volubik-fast volumio[3482]: info: Loading plugin "wizard"... Feb 23 13:20:07 volubik-fast volumio[3482]: info: Loading plugin "networkfs"... Feb 23 13:20:07 volubik-fast volumio[3482]: info: Starting Udev Watcher for removable devices Feb 23 13:20:07 volubik-fast volumio[3482]: info: Ignoring mount for partition: boot Feb 23 13:20:07 volubik-fast volumio[3482]: info: Ignoring mount for partition: volumio Feb 23 13:20:07 volubik-fast volumio[3482]: info: Ignoring mount for partition: volumio_data Feb 23 13:20:07 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:20:07 volubik-fast volumio[3482]: info: Loading plugin "volumio_command_line_client"... Feb 23 13:20:07 volubik-fast volumio[3482]: info: Loading plugin "upnp"... Feb 23 13:20:07 volubik-fast volumio[3482]: info: [1740313207089] Starting Upmpd Daemon Feb 23 13:20:07 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:20:07 volubik-fast volumio[3482]: info: Loading plugin "my_music"... Feb 23 13:20:07 volubik-fast volumio[3482]: info: Loading plugin "mpd"... Feb 23 13:20:07 volubik-fast volumio[3482]: info: Loading plugin "upnp_browser"... Feb 23 13:20:07 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:20:07] [connect] Successful connection Feb 23 13:20:07 volubik-fast sudo[3522]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:07 volubik-fast volumio[3482]: info: Loading plugin "alarm-clock"... Feb 23 13:20:07 volubik-fast volumio[3482]: info: Plugin airplay_emulation is not enabled Feb 23 13:20:07 volubik-fast volumio[3482]: info: Loading plugin "last_100"... Feb 23 13:20:07 volubik-fast volumio[3482]: info: Loading plugin "webradio"... Feb 23 13:20:08 volubik-fast volumio[3482]: info: Loading plugin "i2s_dacs"... Feb 23 13:20:08 volubik-fast volumio[3482]: info: Loading plugin "volumiodiscovery"... Feb 23 13:20:08 volubik-fast volumio[3482]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:20:08 volubik-fast volumio[3482]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:20:08 volubik-fast volumio[3482]: *** WARNING *** For more information see Feb 23 13:20:08 volubik-fast volumio[3482]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:20:08 volubik-fast node[3482]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:20:08 volubik-fast volumio[3482]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:20:08 volubik-fast volumio[3482]: *** WARNING *** For more information see Feb 23 13:20:08 volubik-fast node[3482]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:20:08 volubik-fast node[3482]: *** WARNING *** For more information see Feb 23 13:20:08 volubik-fast node[3482]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:20:08 volubik-fast node[3482]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:20:08 volubik-fast node[3482]: *** WARNING *** For more information see Feb 23 13:20:08 volubik-fast volumio[3482]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 13:20:08 volubik-fast volumio[3482]: info: Discovery: Started advertising with name: VolUbik-fast Feb 23 13:20:08 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:20:08 volubik-fast volumio[3482]: info: Loading plugin "squeezelite_mc"... Feb 23 13:20:08 volubik-fast volumio[3482]: info: Loading plugin "outputs"... Feb 23 13:20:08 volubik-fast volumio[3482]: info: Loading plugin "albumart"... Feb 23 13:20:08 volubik-fast volumio[3482]: info: Plugin example_plugin is not enabled Feb 23 13:20:08 volubik-fast volumio[3482]: info: Loading plugin "inputs"... Feb 23 13:20:09 volubik-fast volumio[3482]: info: Loading plugin "updater_comm"... Feb 23 13:20:09 volubik-fast volumio[3482]: info: Plugin mpdemulation is not enabled Feb 23 13:20:09 volubik-fast volumio[3482]: info: Loading plugin "rest_api"... Feb 23 13:20:09 volubik-fast volumio[3482]: info: Loading plugin "websocket"... Feb 23 13:20:09 volubik-fast volumio[3482]: info: Starting Socket.io Server version 2.3.0 Feb 23 13:20:09 volubik-fast volumio[3482]: info: Loading plugin "lms"... Feb 23 13:20:09 volubik-fast volumio[3482]: info: Loading i18n strings for locale it Feb 23 13:20:09 volubik-fast volumio[3482]: Forking 3 albumart workers Feb 23 13:20:09 volubik-fast volumio[3482]: Updating browse sources language Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::initPlayerControls Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:20:09 volubik-fast volumio[3482]: Express server listening on port 3000 Feb 23 13:20:09 volubik-fast volumio[3482]: [Metrics] WebUI: 5s 256.53ms Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreStateMachine::resetVolumioState Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreStateMachine::getcurrentVolume Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreStateMachine::pushState Feb 23 13:20:09 volubik-fast volumio[3482]: info: CorePlayQueue::getTrack 0 Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::volumioPushState Feb 23 13:20:09 volubik-fast volumio[3482]: info: Volumio Network Manager: Network status updated: 0 Feb 23 13:20:09 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:20:09] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740313207 101 Feb 23 13:20:09 volubik-fast volumio[3482]: 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 Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:20:09 volubik-fast volumio[3482]: info: Reloading queue from file Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreStateMachine::setRepeat null single undefined Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreStateMachine::pushState Feb 23 13:20:09 volubik-fast volumio[3482]: info: CorePlayQueue::getTrack 0 Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::volumioPushState Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreStateMachine::setRandom null Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreStateMachine::pushState Feb 23 13:20:09 volubik-fast volumio[3482]: info: CorePlayQueue::getTrack 0 Feb 23 13:20:09 volubik-fast volumio[3482]: info: CoreCommandRouter::volumioPushState Feb 23 13:20:09 volubik-fast volumio[3482]: info: Setting Device type: Raspberry PI Feb 23 13:20:09 volubik-fast volumio[3482]: info: Completed loading Core Plugins Feb 23 13:20:09 volubik-fast volumio[3482]: info: Preparing to generate the ALSA configuration file Feb 23 13:20:10 volubik-fast volumio[3482]: info: Discovery: adding 8fd035eb-d0cc-4f10-ab54-6ea1cf680f92 Feb 23 13:20:10 volubik-fast volumio[3482]: info: Discovery: Found device VolUbik-fast Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::volumioGetState Feb 23 13:20:10 volubik-fast volumio[3482]: info: CorePlayQueue::getTrack 0 Feb 23 13:20:10 volubik-fast volumio[3482]: info: Asound.conf file unchanged, so no further update is needed Feb 23 13:20:10 volubik-fast volumio[3482]: info: Output device has changed, restarting MPD Feb 23 13:20:10 volubik-fast volumio[3482]: info: ___________ START PLUGINS ___________ Feb 23 13:20:10 volubik-fast volumio[3482]: info: ControllerMpd::onStart: Initializing MPD Feb 23 13:20:10 volubik-fast volumio[3482]: info: Creating MPD Configuration file Feb 23 13:20:10 volubik-fast sudo[3575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:20:10 volubik-fast sudo[3575]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:10 volubik-fast sudo[3575]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:10 volubik-fast sudo[3577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:20:10 volubik-fast sudo[3577]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:20:10 volubik-fast volumio[3482]: info: [1740313210166] CoreMusicLibrary::Adding element Server multimediali Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:20:10 volubik-fast sudo[3580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:20:10 volubik-fast sudo[3580]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:20:10 volubik-fast volumio[3482]: info: [1740313210197] CoreMusicLibrary::Adding element Last_100 Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:20:10 volubik-fast systemd[1]: Stopping Music Player Daemon... Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:20:10 volubik-fast volumio[3482]: info: [1740313210202] CoreMusicLibrary::Adding element Webradio Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:20:10 volubik-fast sudo[3580]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:20:10 volubik-fast volumio[3482]: info: Initializing BBC Radios Feb 23 13:20:10 volubik-fast sudo[3583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:20:10 volubik-fast sudo[3583]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:10 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:20:10 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:20:10 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:20:10 volubik-fast systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 23 13:20:10 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:20:10 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:20:10 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:20:10 volubik-fast volumio[3482]: info: [squeezelite_mc] Starting proxy server... Feb 23 13:20:10 volubik-fast volumio[3482]: info: Volumio Calling Home Feb 23 13:20:10 volubik-fast sudo[3599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Feb 23 13:20:10 volubik-fast sudo[3599]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:10 volubik-fast systemd[1]: Starting Logitech Media Server Daemon... Feb 23 13:20:10 volubik-fast systemd[1]: Started Logitech Media Server Daemon. Feb 23 13:20:10 volubik-fast systemd[3606]: logitechmediaserver.service: Changing to the requested working directory failed: Permission denied Feb 23 13:20:10 volubik-fast systemd[3606]: logitechmediaserver.service: Failed at step CHDIR spawning /usr/sbin/squeezeboxserver: Permission denied Feb 23 13:20:10 volubik-fast sudo[3599]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:10 volubik-fast systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=200/CHDIR Feb 23 13:20:10 volubik-fast systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Feb 23 13:20:10 volubik-fast sudo[3597]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 13:20:10 volubik-fast sudo[3597]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:10 volubik-fast sudo[3597]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:10 volubik-fast volumio[3482]: info: [squeezelite_mc] Proxy server started on port 45529 Feb 23 13:20:10 volubik-fast volumio[3482]: Starting albumart workers Feb 23 13:20:10 volubik-fast volumio[3482]: Starting albumart workers Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreStateMachine::pushState Feb 23 13:20:10 volubik-fast volumio[3482]: info: CorePlayQueue::getTrack 0 Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::volumioPushState Feb 23 13:20:10 volubik-fast volumio[3482]: info: [squeezelite_mc] Server discovery started Feb 23 13:20:10 volubik-fast volumio[3482]: info: [squeezelite_mc] Player finder started Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:10 volubik-fast volumio[3482]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:10 volubik-fast volumio[3482]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"None","mixer":null} Feb 23 13:20:10 volubik-fast volumio[3482]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:20:10 volubik-fast volumio[3482]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:20:11 volubik-fast volumio[3482]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 23 13:20:11 volubik-fast volumio[3482]: at doSend (dgram.js:692:16) Feb 23 13:20:11 volubik-fast volumio[3482]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 23 13:20:11 volubik-fast volumio[3482]: at afterDns (dgram.js:638:5) Feb 23 13:20:11 volubik-fast volumio[3482]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 23 13:20:11 volubik-fast volumio[3482]: errno: -101, Feb 23 13:20:11 volubik-fast volumio[3482]: code: 'ENETUNREACH', Feb 23 13:20:11 volubik-fast volumio[3482]: syscall: 'send', Feb 23 13:20:11 volubik-fast volumio[3482]: address: '255.255.255.255', Feb 23 13:20:11 volubik-fast volumio[3482]: port: 3483 Feb 23 13:20:11 volubik-fast volumio[3482]: } Feb 23 13:20:11 volubik-fast volumio[3482]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:20:11 volubik-fast volumio[3482]: Starting albumart workers Feb 23 13:20:11 volubik-fast sudo[3611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:20:11 volubik-fast sudo[3611]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:11 volubik-fast sudo[3611]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:12 volubik-fast sudo[3647]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 13:19 Feb 23 13:20:12 volubik-fast sudo[3647]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:12 volubik-fast sudo[3647]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:12 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:20:12] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Feb 23 13:20:12 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:20:12] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Feb 23 13:20:12 volubik-fast systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Feb 23 13:20:12 volubik-fast sudo[3577]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:12 volubik-fast sudo[3583]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:12 volubik-fast systemd[1]: volumio.service: Failed with result 'exit-code'. Feb 23 13:20:12 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:20:12 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:20:12 volubik-fast mpd[3608]: Feb 23 13:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 23 13:20:12 volubik-fast systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Feb 23 13:20:12 volubik-fast systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1799. Feb 23 13:20:12 volubik-fast systemd[1]: Started Music Player Daemon. Feb 23 13:20:12 volubik-fast systemd[1]: Stopped Volumio Backend Module. Feb 23 13:20:12 volubik-fast systemd[1]: Started Volumio Backend Module. Feb 23 13:20:12 volubik-fast systemd[1]: Started dynamicswap service. Feb 23 13:20:12 volubik-fast wpa_supplicant[932]: wlan0: Trying to associate with SSID 'ubikSony' Feb 23 13:20:12 volubik-fast systemd[1]: dynamicswap.service: Succeeded. Feb 23 13:20:12 volubik-fast dhcpcd[945]: wlan0: carrier acquired Feb 23 13:20:12 volubik-fast wpa_supplicant[932]: wlan0: Associated with 86:c7:ea:c1:05:63 Feb 23 13:20:12 volubik-fast wpa_supplicant[932]: wlan0: CTRL-EVENT-CONNECTED - Connection to 86:c7:ea:c1:05:63 completed [id=0 id_str=] Feb 23 13:20:12 volubik-fast wpa_supplicant[932]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Feb 23 13:20:12 volubik-fast dhcpcd[945]: wlan0: IAID eb:a5:15:82 Feb 23 13:20:12 volubik-fast dhcpcd[945]: wlan0: carrier lost Feb 23 13:20:12 volubik-fast dhcpcd[945]: wlan0: carrier acquired Feb 23 13:20:12 volubik-fast dhcpcd[945]: wlan0: IAID eb:a5:15:82 Feb 23 13:20:13 volubik-fast dhcpcd[945]: wlan0: soliciting an IPv6 router Feb 23 13:20:13 volubik-fast dhcpcd[945]: wlan0: soliciting a DHCP lease Feb 23 13:20:14 volubik-fast volumio[3662]: info: ------------------------------------------- Feb 23 13:20:14 volubik-fast volumio[3662]: info: ----- Volumio3 ---- Feb 23 13:20:14 volubik-fast volumio[3662]: info: ------------------------------------------- Feb 23 13:20:14 volubik-fast volumio[3662]: info: ----- System startup ---- Feb 23 13:20:14 volubik-fast volumio[3662]: info: ------------------------------------------- Feb 23 13:20:15 volubik-fast volumio[3662]: info: MYVOLUMIO Environment detected Feb 23 13:20:15 volubik-fast volumio[3662]: info: Plugin folders cleanup Feb 23 13:20:15 volubik-fast volumio[3662]: info: Scanning into folder /volumio/app/plugins/ Feb 23 13:20:15 volubik-fast volumio[3662]: info: Scanning category audio_interface Feb 23 13:20:15 volubik-fast volumio[3662]: info: Scanning category miscellanea Feb 23 13:20:15 volubik-fast volumio[3662]: info: Scanning category music_service Feb 23 13:20:15 volubik-fast volumio[3662]: info: Scanning category plugins.json Feb 23 13:20:15 volubik-fast volumio[3662]: info: Scanning category system_controller Feb 23 13:20:15 volubik-fast volumio[3662]: info: Scanning category user_interface Feb 23 13:20:15 volubik-fast volumio[3662]: info: Scanning into folder /data/plugins/ Feb 23 13:20:15 volubik-fast volumio[3662]: info: Scanning category music_service Feb 23 13:20:15 volubik-fast volumio[3662]: info: Plugin folders cleanup completed Feb 23 13:20:15 volubik-fast volumio[3662]: info: ------------------------------------------- Feb 23 13:20:15 volubik-fast volumio[3662]: info: ----- Core plugins startup ---- Feb 23 13:20:15 volubik-fast volumio[3662]: info: ------------------------------------------- Feb 23 13:20:15 volubik-fast volumio[3662]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 13:20:15 volubik-fast volumio[3662]: info: Adding plugin upnp to MyMusic Plugins Feb 23 13:20:15 volubik-fast volumio[3662]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 13:20:15 volubik-fast volumio[3662]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 13:20:15 volubik-fast volumio[3662]: info: Loading plugins from folder /data/plugins/ Feb 23 13:20:15 volubik-fast volumio[3662]: info: Loading plugin "system"... Feb 23 13:20:15 volubik-fast volumio[3662]: info: Loading plugin "appearance"... Feb 23 13:20:16 volubik-fast volumio[3662]: info: Loading plugin "network"... Feb 23 13:20:16 volubik-fast volumio[3662]: info: Refreshing Cached IP Addresses Feb 23 13:20:16 volubik-fast sudo[3705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 13:20:16 volubik-fast sudo[3705]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:16 volubik-fast sudo[3705]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:16 volubik-fast sudo[3707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 13:20:16 volubik-fast sudo[3707]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:16 volubik-fast volumio[3662]: info: Loading plugin "services"... Feb 23 13:20:16 volubik-fast sudo[3707]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:16 volubik-fast volumio[3662]: info: Loading plugin "alsa_controller"... Feb 23 13:20:16 volubik-fast sudo[3718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 13:20:16 volubik-fast sudo[3718]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:16 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:20:16 volubik-fast volumio[3662]: info: Loading plugin "wizard"... Feb 23 13:20:16 volubik-fast volumio[3662]: info: Loading plugin "networkfs"... Feb 23 13:20:16 volubik-fast volumio[3662]: info: Starting Udev Watcher for removable devices Feb 23 13:20:16 volubik-fast volumio[3662]: info: Ignoring mount for partition: boot Feb 23 13:20:16 volubik-fast volumio[3662]: info: Ignoring mount for partition: volumio Feb 23 13:20:16 volubik-fast volumio[3662]: info: Ignoring mount for partition: volumio_data Feb 23 13:20:16 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:20:16 volubik-fast volumio[3662]: info: Loading plugin "volumio_command_line_client"... Feb 23 13:20:16 volubik-fast volumio[3662]: info: Loading plugin "upnp"... Feb 23 13:20:16 volubik-fast volumio[3662]: info: [1740313216600] Starting Upmpd Daemon Feb 23 13:20:16 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:20:16 volubik-fast volumio[3662]: info: Loading plugin "my_music"... Feb 23 13:20:16 volubik-fast volumio[3662]: info: Loading plugin "mpd"... Feb 23 13:20:16 volubik-fast dhcpcd[945]: wlan0: offered 192.168.43.197 from 192.168.43.1 Feb 23 13:20:16 volubik-fast dhcpcd[945]: wlan0: probing address 192.168.43.197/24 Feb 23 13:20:16 volubik-fast volumio[3662]: info: Loading plugin "upnp_browser"... Feb 23 13:20:17 volubik-fast volumio[3662]: info: Loading plugin "alarm-clock"... Feb 23 13:20:17 volubik-fast sudo[3718]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:17 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:20:17] [connect] Successful connection Feb 23 13:20:17 volubik-fast volumio[3662]: info: Plugin airplay_emulation is not enabled Feb 23 13:20:17 volubik-fast volumio[3662]: info: Loading plugin "last_100"... Feb 23 13:20:17 volubik-fast volumio[3662]: info: Loading plugin "webradio"... Feb 23 13:20:17 volubik-fast volumio[3662]: info: Loading plugin "i2s_dacs"... Feb 23 13:20:17 volubik-fast volumio[3662]: info: Loading plugin "volumiodiscovery"... Feb 23 13:20:17 volubik-fast volumio[3662]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:20:17 volubik-fast volumio[3662]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:20:17 volubik-fast volumio[3662]: *** WARNING *** For more information see Feb 23 13:20:17 volubik-fast node[3662]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 13:20:17 volubik-fast volumio[3662]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:20:17 volubik-fast volumio[3662]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:20:17 volubik-fast volumio[3662]: *** WARNING *** For more information see Feb 23 13:20:17 volubik-fast node[3662]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:20:17 volubik-fast node[3662]: *** WARNING *** For more information see Feb 23 13:20:17 volubik-fast node[3662]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 13:20:17 volubik-fast node[3662]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 13:20:17 volubik-fast node[3662]: *** WARNING *** For more information see Feb 23 13:20:17 volubik-fast volumio[3662]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 13:20:17 volubik-fast volumio[3662]: info: Discovery: Started advertising with name: VolUbik-fast Feb 23 13:20:17 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 13:20:17 volubik-fast volumio[3662]: info: Loading plugin "squeezelite_mc"... Feb 23 13:20:18 volubik-fast volumio[3662]: info: Loading plugin "outputs"... Feb 23 13:20:18 volubik-fast volumio[3662]: info: Loading plugin "albumart"... Feb 23 13:20:18 volubik-fast volumio[3662]: info: Plugin example_plugin is not enabled Feb 23 13:20:18 volubik-fast volumio[3662]: info: Loading plugin "inputs"... Feb 23 13:20:18 volubik-fast volumio[3662]: info: Loading plugin "updater_comm"... Feb 23 13:20:18 volubik-fast volumio[3662]: info: Plugin mpdemulation is not enabled Feb 23 13:20:18 volubik-fast volumio[3662]: info: Loading plugin "rest_api"... Feb 23 13:20:18 volubik-fast volumio[3662]: info: Loading plugin "websocket"... Feb 23 13:20:18 volubik-fast volumio[3662]: info: Starting Socket.io Server version 2.3.0 Feb 23 13:20:18 volubik-fast volumio[3662]: info: Loading plugin "lms"... Feb 23 13:20:18 volubik-fast volumio[3662]: Forking 3 albumart workers Feb 23 13:20:18 volubik-fast volumio[3662]: info: Loading i18n strings for locale it Feb 23 13:20:18 volubik-fast volumio[3662]: Updating browse sources language Feb 23 13:20:18 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::initPlayerControls Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:20:19 volubik-fast volumio[3662]: Express server listening on port 3000 Feb 23 13:20:19 volubik-fast volumio[3662]: [Metrics] WebUI: 5s 325.53ms Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreStateMachine::resetVolumioState Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreStateMachine::getcurrentVolume Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreStateMachine::pushState Feb 23 13:20:19 volubik-fast volumio[3662]: info: CorePlayQueue::getTrack 0 Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::volumioPushState Feb 23 13:20:19 volubik-fast volumio[3662]: info: Volumio Network Manager: Network status updated: 0 Feb 23 13:20:19 volubik-fast volumio-remote-updater[671]: [2025-02-23 13:20:19] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1740313217 101 Feb 23 13:20:19 volubik-fast volumio[3662]: 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 Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:20:19 volubik-fast volumio[3662]: info: Reloading queue from file Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreStateMachine::setRepeat null single undefined Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreStateMachine::pushState Feb 23 13:20:19 volubik-fast volumio[3662]: info: CorePlayQueue::getTrack 0 Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::volumioPushState Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreStateMachine::setRandom null Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreStateMachine::pushState Feb 23 13:20:19 volubik-fast volumio[3662]: info: CorePlayQueue::getTrack 0 Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::volumioPushState Feb 23 13:20:19 volubik-fast volumio[3662]: info: Setting Device type: Raspberry PI Feb 23 13:20:19 volubik-fast volumio[3662]: info: Completed loading Core Plugins Feb 23 13:20:19 volubik-fast volumio[3662]: info: Preparing to generate the ALSA configuration file Feb 23 13:20:19 volubik-fast volumio[3662]: info: Asound.conf file unchanged, so no further update is needed Feb 23 13:20:19 volubik-fast volumio[3662]: info: Output device has changed, restarting MPD Feb 23 13:20:19 volubik-fast volumio[3662]: info: ___________ START PLUGINS ___________ Feb 23 13:20:19 volubik-fast volumio[3662]: info: ControllerMpd::onStart: Initializing MPD Feb 23 13:20:19 volubik-fast volumio[3662]: info: Creating MPD Configuration file Feb 23 13:20:19 volubik-fast sudo[3769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:20:19 volubik-fast sudo[3769]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:19 volubik-fast sudo[3769]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:19 volubik-fast sudo[3771]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:20:19 volubik-fast sudo[3771]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:20:19 volubik-fast volumio[3662]: info: [1740313219757] CoreMusicLibrary::Adding element Server multimediali Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:20:19 volubik-fast sudo[3774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 23 13:20:19 volubik-fast sudo[3774]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:19 volubik-fast systemd[1]: Stopping Music Player Daemon... Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:20:19 volubik-fast volumio[3662]: info: [1740313219793] CoreMusicLibrary::Adding element Last_100 Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 13:20:19 volubik-fast volumio[3662]: info: [1740313219797] CoreMusicLibrary::Adding element Webradio Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 23 13:20:19 volubik-fast sudo[3774]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:19 volubik-fast sudo[3777]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 23 13:20:19 volubik-fast sudo[3777]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:19 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:20:19 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:20:19 volubik-fast volumio[3662]: info: Initializing BBC Radios Feb 23 13:20:19 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:20:19 volubik-fast systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Feb 23 13:20:19 volubik-fast systemd[1]: mpd.service: Succeeded. Feb 23 13:20:19 volubik-fast systemd[1]: Stopped Music Player Daemon. Feb 23 13:20:19 volubik-fast systemd[1]: Starting Music Player Daemon... Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 13:20:19 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 13:20:19 volubik-fast volumio[3662]: info: [squeezelite_mc] Starting proxy server... Feb 23 13:20:20 volubik-fast volumio[3662]: info: Volumio Calling Home Feb 23 13:20:20 volubik-fast sudo[3793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart logitechmediaserver Feb 23 13:20:20 volubik-fast sudo[3793]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:20 volubik-fast sudo[3788]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 23 13:20:20 volubik-fast sudo[3788]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:20 volubik-fast sudo[3788]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:20 volubik-fast volumio[3662]: Starting albumart workers Feb 23 13:20:20 volubik-fast systemd[1]: Starting Logitech Media Server Daemon... Feb 23 13:20:20 volubik-fast volumio[3662]: Starting albumart workers Feb 23 13:20:20 volubik-fast volumio[3662]: Starting albumart workers Feb 23 13:20:20 volubik-fast volumio[3662]: info: [squeezelite_mc] Proxy server started on port 40291 Feb 23 13:20:20 volubik-fast systemd[1]: Started Logitech Media Server Daemon. Feb 23 13:20:20 volubik-fast systemd[3802]: logitechmediaserver.service: Changing to the requested working directory failed: Permission denied Feb 23 13:20:20 volubik-fast systemd[3802]: logitechmediaserver.service: Failed at step CHDIR spawning /usr/sbin/squeezeboxserver: Permission denied Feb 23 13:20:20 volubik-fast sudo[3793]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:20 volubik-fast systemd[1]: logitechmediaserver.service: Main process exited, code=exited, status=200/CHDIR Feb 23 13:20:20 volubik-fast systemd[1]: logitechmediaserver.service: Failed with result 'exit-code'. Feb 23 13:20:20 volubik-fast volumio[3662]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 13:20:20 volubik-fast volumio[3662]: info: CoreStateMachine::pushState Feb 23 13:20:20 volubik-fast volumio[3662]: info: CorePlayQueue::getTrack 0 Feb 23 13:20:20 volubik-fast volumio[3662]: info: CoreCommandRouter::volumioPushState Feb 23 13:20:20 volubik-fast volumio[3662]: info: [squeezelite_mc] Server discovery started Feb 23 13:20:20 volubik-fast volumio[3662]: info: [squeezelite_mc] Player finder started Feb 23 13:20:20 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:20 volubik-fast volumio[3662]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 13:20:20 volubik-fast volumio[3662]: info: [squeezelite_mc] Starting Squeezelite service with params: {"type":"manual","startupOptions":"","card":"2","mixerType":"None","mixer":null} Feb 23 13:20:20 volubik-fast volumio[3662]: info: [squeezelite_mc] Executing cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:20:20 volubik-fast volumio[3662]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:20:20 volubik-fast volumio[3662]: Error: send ENETUNREACH 255.255.255.255:3483 Feb 23 13:20:20 volubik-fast volumio[3662]: at doSend (dgram.js:692:16) Feb 23 13:20:20 volubik-fast volumio[3662]: at defaultTriggerAsyncIdScope (internal/async_hooks.js:430:12) Feb 23 13:20:20 volubik-fast volumio[3662]: at afterDns (dgram.js:638:5) Feb 23 13:20:20 volubik-fast volumio[3662]: at processTicksAndRejections (internal/process/task_queues.js:81:21) { Feb 23 13:20:20 volubik-fast volumio[3662]: errno: -101, Feb 23 13:20:20 volubik-fast volumio[3662]: code: 'ENETUNREACH', Feb 23 13:20:20 volubik-fast volumio[3662]: syscall: 'send', Feb 23 13:20:20 volubik-fast volumio[3662]: address: '255.255.255.255', Feb 23 13:20:20 volubik-fast volumio[3662]: port: 3483 Feb 23 13:20:20 volubik-fast volumio[3662]: } Feb 23 13:20:20 volubik-fast volumio[3662]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 13:20:20 volubik-fast sudo[3805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/cp /data/plugins/music_service/squeezelite_mc/dist/lib/../templates/alsa/100-squeezelite.conf.template.out /etc/alsa/conf.d/100-squeezelite.conf Feb 23 13:20:20 volubik-fast sudo[3805]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:20 volubik-fast sudo[3805]: pam_unix(sudo:session): session closed for user root Feb 23 13:20:21 volubik-fast sudo[3840]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-02-23 13:19 Feb 23 13:20:21 volubik-fast sudo[3840]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 13:20:21 volubik-fast dhcpcd[945]: wlan0: leased 192.168.43.197 for 3600 seconds Feb 23 13:20:21 volubik-fast avahi-daemon[658]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.43.197. Feb 23 13:20:21 volubik-fast avahi-daemon[658]: New relevant interface wlan0.IPv4 for mDNS. Feb 23 13:20:21 volubik-fast dhcpcd[945]: wlan0: adding route to 192.168.43.0/24 Feb 23 13:20:21 volubik-fast avahi-daemon[658]: Registering new address record for 192.168.43.197 on wlan0.IPv4. Feb 23 13:20:21 volubik-fast dhcpcd[945]: wlan0: adding default route via 192.168.43.1 PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"