-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Sat 2025-01-11 16:36:47 UTC. -- Jan 11 16:35:09 volumiorpi3 volumio[896]: info: CoreCommandRouter::volumioGetState Jan 11 16:35:09 volumiorpi3 volumio[896]: info: Listing playlists Jan 11 16:35:09 volumiorpi3 volumio[896]: info: Listing playlists Jan 11 16:35:19 volumiorpi3 volumio[896]: info: CoreCommandRouter::volumioGetState Jan 11 16:35:23 volumiorpi3 volumiossh-tunnel[584]: Warning: Permanently added '[eu4.myvolumio.org]:2222,[167.172.103.77]:2222' (RSA) to the list of known hosts. Jan 11 16:35:29 volumiorpi3 volumio[896]: info: CoreCommandRouter::volumioGetState Jan 11 16:35:29 volumiorpi3 volumio[896]: info: Listing playlists Jan 11 16:35:29 volumiorpi3 volumio[896]: info: Listing playlists Jan 11 16:35:39 volumiorpi3 volumio[896]: info: CoreCommandRouter::volumioGetState Jan 11 16:35:49 volumiorpi3 volumio[896]: info: CoreCommandRouter::volumioGetState Jan 11 16:35:49 volumiorpi3 volumio[896]: info: Listing playlists Jan 11 16:35:49 volumiorpi3 volumio[896]: info: Listing playlists Jan 11 16:35:59 volumiorpi3 volumio[896]: info: CoreCommandRouter::volumioGetState Jan 11 16:35:59 volumiorpi3 nmbd[680]: [2025/01/11 16:35:59.968988, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Jan 11 16:35:59 volumiorpi3 nmbd[680]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.13.132 for name WORKGROUP<1d>. Jan 11 16:35:59 volumiorpi3 nmbd[680]: This response was from IP 192.168.13.9, reporting an IP address of 192.168.13.9. Jan 11 16:36:09 volumiorpi3 volumio[896]: info: CoreCommandRouter::volumioGetState Jan 11 16:36:09 volumiorpi3 volumio[896]: info: Listing playlists Jan 11 16:36:09 volumiorpi3 volumio[896]: info: Listing playlists Jan 11 16:36:19 volumiorpi3 volumio[896]: info: CoreCommandRouter::volumioGetState Jan 11 16:36:29 volumiorpi3 volumio[896]: info: CoreCommandRouter::volumioGetState Jan 11 16:36:29 volumiorpi3 volumio[896]: info: Listing playlists Jan 11 16:36:29 volumiorpi3 volumio[896]: info: Listing playlists Jan 11 16:36:29 volumiorpi3 volumio[896]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 16:36:29 volumiorpi3 volumio-remote-updater[10104]: No test mode Jan 11 16:36:29 volumiorpi3 volumio-remote-updater[10104]: No alpha test mode Jan 11 16:36:30 volumiorpi3 volumio[896]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

 

NEW ADDITIONS

 

","title":"Update v3.785","updateavailable":true} Jan 11 16:36:30 volumiorpi3 volumio[896]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 16:36:30 volumiorpi3 volumio[896]: SyntaxError: /data/configuration/plugins.json: Unexpected token T in JSON at position 0 Jan 11 16:36:30 volumiorpi3 volumio[896]: at JSON.parse () Jan 11 16:36:30 volumiorpi3 volumio[896]: at Object.readFileSync (/volumio/node_modules/jsonfile/index.js:69:17) Jan 11 16:36:30 volumiorpi3 volumio[896]: at PluginManager.getAllPlugNames (/volumio/app/pluginmanager.js:568:21) Jan 11 16:36:30 volumiorpi3 volumio[896]: at PluginManager.getPluginsMatrix (/volumio/app/pluginmanager.js:592:26) Jan 11 16:36:30 volumiorpi3 volumio[896]: at PluginManager.listPluginsBrokenByNewVersion (/volumio/app/pluginmanager.js:1224:22) Jan 11 16:36:30 volumiorpi3 volumio[896]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:934:60) Jan 11 16:36:30 volumiorpi3 volumio[896]: at Socket.emit (events.js:315:20) Jan 11 16:36:30 volumiorpi3 volumio[896]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Jan 11 16:36:30 volumiorpi3 volumio[896]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jan 11 16:36:30 volumiorpi3 volumio[896]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 16:36:31 volumiorpi3 sudo[728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-11 16:35 Jan 11 16:36:31 volumiorpi3 sudo[728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 16:36:31 volumiorpi3 sudo[728]: pam_unix(sudo:session): session closed for user root Jan 11 16:36:31 volumiorpi3 go-librespot[31307]: time="2025-01-11T16:36:31Z" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF" Jan 11 16:36:31 volumiorpi3 volumio-remote-updater[10104]: [2025-01-11 16:36:31] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Jan 11 16:36:31 volumiorpi3 volumio-remote-updater[10104]: [2025-01-11 16:36:31] [error] handle_read_frame error: websocketpp.transport:2 (Underlying Transport Error) Jan 11 16:36:31 volumiorpi3 volumio-remote-updater[10104]: [2025-01-11 16:36:31] [disconnect] Disconnect close local:[1006,Underlying Transport Error] remote:[1006] Jan 11 16:36:31 volumiorpi3 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 11 16:36:31 volumiorpi3 systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 11 16:36:31 volumiorpi3 systemd[1]: Started dynamicswap service. Jan 11 16:36:31 volumiorpi3 systemd[1]: dynamicswap.service: Succeeded. Jan 11 16:36:31 volumiorpi3 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 11 16:36:31 volumiorpi3 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Jan 11 16:36:31 volumiorpi3 systemd[1]: Stopped Volumio Backend Module. Jan 11 16:36:31 volumiorpi3 systemd[1]: Started Volumio Backend Module. Jan 11 16:36:31 volumiorpi3 systemd[1]: Started dynamicswap service. Jan 11 16:36:31 volumiorpi3 systemd[1]: dynamicswap.service: Succeeded. Jan 11 16:36:33 volumiorpi3 volumio[741]: info: ------------------------------------------- Jan 11 16:36:33 volumiorpi3 volumio[741]: info: ----- Volumio3 ---- Jan 11 16:36:33 volumiorpi3 volumio[741]: info: ------------------------------------------- Jan 11 16:36:33 volumiorpi3 volumio[741]: info: ----- System startup ---- Jan 11 16:36:33 volumiorpi3 volumio[741]: info: ------------------------------------------- Jan 11 16:36:34 volumiorpi3 volumio[741]: info: MYVOLUMIO Environment detected Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin music_service/airplay_emulation. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin music_service/inputs. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin music_service/mpd. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin music_service/webradio. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin music_service/example_plugin. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin music_service/last_100. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin music_service/upnp_browser. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin audio_interface/outputs. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin audio_interface/upnp. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin audio_interface/alsa_controller. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin miscellanea/alarm-clock. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin miscellanea/albumart. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin miscellanea/appearance. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin miscellanea/wizard. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin miscellanea/my_music. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin user_interface/websocket. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin user_interface/mpdemulation. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin user_interface/rest_api. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin system_controller/updater_comm. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin system_controller/network. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin system_controller/networkfs. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin system_controller/services. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin system_controller/system. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin system_controller/i2s_dacs. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin system_controller/volumiodiscovery. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Found new core plugin system_controller/volumio_command_line_client. Adding it Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Plugin folders cleanup Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Scanning into folder /volumio/app/plugins/ Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Scanning category audio_interface Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Scanning category miscellanea Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Scanning category music_service Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Scanning category plugins.json Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Scanning category system_controller Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Scanning category user_interface Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Scanning into folder /data/plugins/ Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Scanning category music_service Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Scanning category system_hardware Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Scanning category user_interface Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Plugin folders cleanup completed Jan 11 16:36:34 volumiorpi3 volumio[741]: info: ------------------------------------------- Jan 11 16:36:34 volumiorpi3 volumio[741]: info: ----- Core plugins startup ---- Jan 11 16:36:34 volumiorpi3 volumio[741]: info: ------------------------------------------- Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Loading plugins from folder /volumio/app/plugins/ Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Adding plugin upnp to MyMusic Plugins Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Loading plugins from folder /data/plugins/ Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Loading plugin "system"... Jan 11 16:36:34 volumiorpi3 volumio[741]: info: No id defined. Creating one Jan 11 16:36:34 volumiorpi3 volumio[741]: info: Loading plugin "appearance"... Jan 11 16:36:35 volumiorpi3 volumio[741]: info: Loading plugin "network"... Jan 11 16:36:35 volumiorpi3 volumio[741]: info: Refreshing Cached IP Addresses Jan 11 16:36:36 volumiorpi3 sudo[772]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 16:36:36 volumiorpi3 sudo[772]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 16:36:36 volumiorpi3 volumio[741]: info: Loading plugin "services"... Jan 11 16:36:36 volumiorpi3 sudo[774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 16:36:36 volumiorpi3 sudo[774]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 16:36:36 volumiorpi3 sudo[772]: pam_unix(sudo:session): session closed for user root Jan 11 16:36:36 volumiorpi3 volumio[741]: info: Loading plugin "alsa_controller"... Jan 11 16:36:36 volumiorpi3 sudo[774]: pam_unix(sudo:session): session closed for user root Jan 11 16:36:36 volumiorpi3 sudo[778]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 11 16:36:36 volumiorpi3 sudo[778]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:4:93:Unexpected end of file Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration Jan 11 16:36:36 volumiorpi3 volumio[741]: aplay: device_list:281: control open (0): Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:4:93:Unexpected end of file Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration Jan 11 16:36:36 volumiorpi3 volumio[741]: aplay: device_list:281: control open (1): Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:4:93:Unexpected end of file Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration Jan 11 16:36:36 volumiorpi3 volumio[741]: aplay: device_list:281: control open (2): Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: warn: Unable to locate the audio output device Allo BOSS. Please configure a valid output device. Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:4:93:Unexpected end of file Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration Jan 11 16:36:36 volumiorpi3 volumio[741]: amixer: Mixer attach hw:2 error: Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: info: Hardware Mixer selected but no Hardware mixer available, detecting default mixer Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:4:93:Unexpected end of file Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration Jan 11 16:36:36 volumiorpi3 volumio[741]: aplay: device_list:281: control open (0): Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:4:93:Unexpected end of file Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration Jan 11 16:36:36 volumiorpi3 volumio[741]: aplay: device_list:281: control open (1): Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:4:93:Unexpected end of file Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration Jan 11 16:36:36 volumiorpi3 volumio[741]: aplay: device_list:281: control open (2): Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:4:93:Unexpected end of file Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration Jan 11 16:36:36 volumiorpi3 volumio[741]: amixer: Mixer attach hw:2 error: Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:4:93:Unexpected end of file Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration Jan 11 16:36:36 volumiorpi3 volumio[741]: aplay: device_list:281: control open (0): Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:4:93:Unexpected end of file Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration Jan 11 16:36:36 volumiorpi3 volumio[741]: aplay: device_list:281: control open (1): Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:4:93:Unexpected end of file Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration Jan 11 16:36:36 volumiorpi3 volumio[741]: aplay: device_list:281: control open (2): Invalid argument Jan 11 16:36:36 volumiorpi3 volumio[741]: info: CoreCommandRouter::volumioUpdateVolumeSettings Jan 11 16:36:36 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 16:36:36 volumiorpi3 volumio[741]: info: Loading plugin "wizard"... Jan 11 16:36:36 volumiorpi3 volumio[741]: info: Loading plugin "volumio_command_line_client"... Jan 11 16:36:36 volumiorpi3 volumio[741]: info: Loading plugin "upnp"... Jan 11 16:36:36 volumiorpi3 volumio[741]: info: [1736613396274] Starting Upmpd Daemon Jan 11 16:36:36 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 16:36:36 volumiorpi3 volumio[741]: info: Loading plugin "my_music"... Jan 11 16:36:36 volumiorpi3 volumio[741]: info: Loading plugin "mpd"... Jan 11 16:36:36 volumiorpi3 volumio-remote-updater[10104]: [2025-01-11 16:36:36] [connect] Successful connection Jan 11 16:36:36 volumiorpi3 volumio[741]: info: Creating MPD Configuration file Jan 11 16:36:36 volumiorpi3 volumio[741]: info: Loading plugin "upnp_browser"... Jan 11 16:36:36 volumiorpi3 sudo[791]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 11 16:36:36 volumiorpi3 sudo[794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 11 16:36:36 volumiorpi3 sudo[794]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 16:36:36 volumiorpi3 sudo[791]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 11 16:36:36 volumiorpi3 sudo[791]: pam_unix(sudo:session): session closed for user root Jan 11 16:36:36 volumiorpi3 systemd[1]: Stopping Music Player Daemon... Jan 11 16:36:37 volumiorpi3 volumio[741]: info: Loading plugin "networkfs"... Jan 11 16:36:37 volumiorpi3 wpa_supplicant[793]: RRM: Ignoring radio measurement request: Not RRM network Jan 11 16:36:37 volumiorpi3 volumio[741]: info: Cannot mount NAS NAS at system boot, trial number 1 ,retrying in 5 seconds Jan 11 16:36:37 volumiorpi3 volumio[741]: info: Cannot mount NAS NAS-gesamt at system boot, trial number 1 ,retrying in 5 seconds Jan 11 16:36:37 volumiorpi3 volumio[741]: info: Starting Udev Watcher for removable devices Jan 11 16:36:37 volumiorpi3 volumio[741]: info: Ignoring mount for partition: boot Jan 11 16:36:37 volumiorpi3 volumio[741]: info: Ignoring mount for partition: volumio Jan 11 16:36:37 volumiorpi3 volumio[741]: info: Ignoring mount for partition: volumio_data Jan 11 16:36:37 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 16:36:37 volumiorpi3 volumio[741]: info: Loading plugin "alarm-clock"... Jan 11 16:36:37 volumiorpi3 systemd[1]: mpd.service: Succeeded. Jan 11 16:36:37 volumiorpi3 systemd[1]: Stopped Music Player Daemon. Jan 11 16:36:37 volumiorpi3 systemd[1]: Starting Music Player Daemon... Jan 11 16:36:37 volumiorpi3 volumio[741]: info: Loading plugin "airplay_emulation"... Jan 11 16:36:37 volumiorpi3 volumio[741]: info: Starting Shairport Sync Jan 11 16:36:37 volumiorpi3 volumio[741]: info: Loading plugin "last_100"... Jan 11 16:36:37 volumiorpi3 volumio[741]: info: Loading plugin "webradio"... Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Loading plugin "i2s_dacs"... Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Loading plugin "volumiodiscovery"... Jan 11 16:36:38 volumiorpi3 volumio[741]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 16:36:38 volumiorpi3 node[741]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 11 16:36:38 volumiorpi3 volumio[741]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 16:36:38 volumiorpi3 node[741]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 16:36:38 volumiorpi3 volumio[741]: *** WARNING *** For more information see Jan 11 16:36:38 volumiorpi3 node[741]: *** WARNING *** For more information see Jan 11 16:36:38 volumiorpi3 volumio[741]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 16:36:38 volumiorpi3 node[741]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 11 16:36:38 volumiorpi3 volumio[741]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 16:36:38 volumiorpi3 node[741]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 11 16:36:38 volumiorpi3 volumio[741]: *** WARNING *** For more information see Jan 11 16:36:38 volumiorpi3 node[741]: *** WARNING *** For more information see Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Discovery: Started advertising with name: undefined Jan 11 16:36:38 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Plugin spop is not enabled Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Plugin youtube2 is not enabled Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Plugin now_playing is not enabled Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Loading plugin "outputs"... Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Loading plugin "albumart"... Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Plugin example_plugin is not enabled Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Loading plugin "inputs"... Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Loading plugin "updater_comm"... Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Plugin mpdemulation is not enabled Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Loading plugin "rest_api"... Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Loading plugin "websocket"... Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Plugin podcast is not enabled Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Plugin pirateaudio is not enabled Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Plugin rotaryencoder2 is not enabled Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Plugin touch_display is not enabled Jan 11 16:36:38 volumiorpi3 volumio[741]: info: Loading i18n strings for locale de Jan 11 16:36:38 volumiorpi3 volumio[741]: Updating browse sources language Jan 11 16:36:38 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 16:36:38 volumiorpi3 volumio[741]: Forking 3 albumart workers Jan 11 16:36:38 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 16:36:38 volumiorpi3 volumio[741]: info: CoreCommandRouter::initPlayerControls Jan 11 16:36:38 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:38 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:38 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:38 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:38 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:38 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:38 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:38 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:38 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 11 16:36:38 volumiorpi3 volumio[741]: Express server listening on port 3000 Jan 11 16:36:38 volumiorpi3 sudo[778]: pam_unix(sudo:session): session closed for user root Jan 11 16:36:38 volumiorpi3 volumio[741]: [Metrics] WebUI: 5s 917.24ms Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreStateMachine::resetVolumioState Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreStateMachine::getcurrentVolume Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 16:36:39 volumiorpi3 wpa_supplicant[793]: RRM: Ignoring radio measurement request: Not RRM network Jan 11 16:36:39 volumiorpi3 volumio[741]: info: MPD Permissions set Jan 11 16:36:39 volumiorpi3 volumio[741]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: python-requests/2.21.0 Total Clients: 1 Jan 11 16:36:39 volumiorpi3 volumio[741]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Total Clients: 2 Jan 11 16:36:39 volumiorpi3 volumio-remote-updater[10104]: [2025-01-11 16:36:39] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736613396 101 Jan 11 16:36:39 volumiorpi3 volumio[741]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Total Clients: 3 Jan 11 16:36:39 volumiorpi3 volumio[741]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:4:93:Unexpected end of file Jan 11 16:36:39 volumiorpi3 volumio[741]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it Jan 11 16:36:39 volumiorpi3 volumio[741]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument Jan 11 16:36:39 volumiorpi3 volumio[741]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration Jan 11 16:36:39 volumiorpi3 volumio[741]: amixer: Mixer attach hw:2 error: Invalid argument Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::volumioGetState Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CorePlayQueue::getTrack 0 Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 16:36:39 volumiorpi3 volumio[741]: info: Cannot read play queue from file Jan 11 16:36:39 volumiorpi3 volumio[741]: info: VolumeController:: Volume=undefined Mute =false Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreStateMachine::pushState Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CorePlayQueue::getTrack 0 Jan 11 16:36:39 volumiorpi3 volumio[741]: Starting albumart workers Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::volumioPushState Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreStateMachine::updateTrackBlock Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CorePlayQueue::getTrackBlock Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 16:36:39 volumiorpi3 volumio[741]: info: Setting Device type: Raspberry PI Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreStateMachine::setRepeat null single undefined Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreStateMachine::pushState Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CorePlayQueue::getTrack 0 Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::volumioPushState Jan 11 16:36:39 volumiorpi3 volumio[741]: Starting albumart workers Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreStateMachine::setRandom null Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreStateMachine::pushState Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CorePlayQueue::getTrack 0 Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::volumioPushState Jan 11 16:36:39 volumiorpi3 volumio[741]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:4:93:Unexpected end of file Jan 11 16:36:39 volumiorpi3 volumio[741]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it Jan 11 16:36:39 volumiorpi3 volumio[741]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument Jan 11 16:36:39 volumiorpi3 volumio[741]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration Jan 11 16:36:39 volumiorpi3 volumio[741]: amixer: Mixer attach hw:2 error: Invalid argument Jan 11 16:36:39 volumiorpi3 volumio[741]: info: VolumeController:: Volume=undefined Mute =false Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreStateMachine::pushState Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CorePlayQueue::getTrack 0 Jan 11 16:36:39 volumiorpi3 volumio[741]: info: CoreCommandRouter::volumioPushState Jan 11 16:36:40 volumiorpi3 volumio[741]: Starting albumart workers Jan 11 16:36:40 volumiorpi3 mpd[800]: Jan 11 16:36 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 11 16:36:40 volumiorpi3 volumio[741]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Jan 11 16:36:40 volumiorpi3 volumio[741]: info: CoreStateMachine::getcurrentVolume Jan 11 16:36:40 volumiorpi3 volumio[741]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 16:36:40 volumiorpi3 volumio[741]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Mixer attach hw:2 error: Invalid argument Jan 11 16:36:40 volumiorpi3 volumio[741]: info: VolumeController:: Volume=undefined Mute =false Jan 11 16:36:40 volumiorpi3 volumio[741]: info: CoreStateMachine::pushState Jan 11 16:36:40 volumiorpi3 volumio[741]: info: CorePlayQueue::getTrack 0 Jan 11 16:36:40 volumiorpi3 volumio[741]: info: CoreCommandRouter::volumioPushState Jan 11 16:36:40 volumiorpi3 volumio[741]: info: CoreStateMachine::updateTrackBlock Jan 11 16:36:40 volumiorpi3 volumio[741]: info: CorePlayQueue::getTrackBlock Jan 11 16:36:40 volumiorpi3 volumio[741]: info: CoreCommandRouter::volumioRetrievevolume Jan 11 16:36:40 volumiorpi3 volumio[741]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: ALSA lib conf.c:1887:(_snd_config_load_with_include) _toplevel_:4:93:Unexpected end of file Jan 11 16:36:40 volumiorpi3 volumio[741]: ALSA lib conf.c:3650:(config_file_open) /etc/asound.conf may be old or corrupted: consider to remove or fix it Jan 11 16:36:40 volumiorpi3 volumio[741]: ALSA lib conf.c:3572:(snd_config_hooks_call) function snd_config_hook_load returned error: Invalid argument Jan 11 16:36:40 volumiorpi3 volumio[741]: ALSA lib conf.c:4026:(snd_config_update_r) hooks failed, removing configuration Jan 11 16:36:40 volumiorpi3 volumio[741]: amixer: Mixer attach hw:2 error: Invalid argument Jan 11 16:36:40 volumiorpi3 volumio[741]: info: VolumeController:: Volume=undefined Mute =false Jan 11 16:36:40 volumiorpi3 volumio[741]: info: CoreStateMachine::pushState Jan 11 16:36:40 volumiorpi3 volumio[741]: info: CorePlayQueue::getTrack 0 Jan 11 16:36:40 volumiorpi3 volumio[741]: info: CoreCommandRouter::volumioPushState Jan 11 16:36:40 volumiorpi3 kernel: EXT4-fs error (device mmcblk0p3): mb_free_blocks:1510: group 131, block 4318209:freeing already freed block (bit 25601); block bitmap corrupt. Jan 11 16:36:40 volumiorpi3 kernel: EXT4-fs error (device mmcblk0p3): ext4_mb_generate_buddy:805: group 131, block bitmap and bg descriptor inconsistent: 27753 vs 27754 free clusters Jan 11 16:36:42 volumiorpi3 volumio[741]: info: Cannot mount NAS NAS at system boot, trial number 2 ,retrying in 5 seconds Jan 11 16:36:42 volumiorpi3 volumio[741]: info: Cannot mount NAS NAS-gesamt at system boot, trial number 2 ,retrying in 5 seconds Jan 11 16:36:43 volumiorpi3 volumio[741]: error: Discovery: Generic error: TypeError: argument 8 must be an integer (port) Jan 11 16:36:43 volumiorpi3 volumio[741]: info: Discovery: Started advertising with name: undefined Jan 11 16:36:46 volumiorpi3 kernel: JBD2: Spotted dirty metadata buffer (dev = mmcblk0p3, blocknr = 0). There's a risk of filesystem corruption in case of system crash. Jan 11 16:36:46 volumiorpi3 kernel: JBD2: Spotted dirty metadata buffer (dev = mmcblk0p3, blocknr = 0). There's a risk of filesystem corruption in case of system crash. Jan 11 16:36:46 volumiorpi3 volumio[741]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 16:36:46 volumiorpi3 volumio[741]: TypeError: Cannot read property 'charAt' of undefined Jan 11 16:36:46 volumiorpi3 volumio[741]: at Timeout._onTimeout (/volumio/app/plugins/audio_interface/upnp/index.js:199:24) Jan 11 16:36:46 volumiorpi3 volumio[741]: at listOnTimeout (internal/timers.js:554:17) Jan 11 16:36:46 volumiorpi3 volumio[741]: at processTimers (internal/timers.js:497:7) Jan 11 16:36:46 volumiorpi3 volumio[741]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 16:36:47 volumiorpi3 sudo[908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-11 16:35 Jan 11 16:36:47 volumiorpi3 sudo[908]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b1c3cf61c2a0027c66bab1eb0a3795f80c1f2e95" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e3eb3ee5b16063502f3a1735e11a28cfe54a0f46" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 25 Jun 2023 07:20:58 PM CEST" VOLUMIO_VERSION="3.512" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="946a42b62509f37b5e7e2d86a2932a62"