Dec 24 20:28:49 volumio ntpd[1165]: PROTO: 212.6.50.243 unlink local addr 192.168.1.61 -> Dec 24 20:29:00 volumio ntpd[1165]: PROTO: 129.152.16.145 unlink local addr 192.168.1.61 -> Dec 24 20:29:08 volumio ntpd[1165]: PROTO: 93.94.88.51 unlink local addr 192.168.1.61 -> Dec 24 20:29:14 volumio ntpd[1165]: PROTO: 185.157.229.254 unlink local addr 192.168.1.61 -> Dec 24 20:29:17 volumio ntpd[1165]: PROTO: 89.46.74.148 unlink local addr 192.168.1.61 -> Dec 24 20:29:36 volumio sudo[4142]: volumio : TTY=pts/0 ; PWD=/home/volumio/volumio3-backend ; USER=root ; COMMAND=/usr/bin/cp app/plugins/system_controller/network/index.js /volumio/app/plugins/system_controller/network/index.js Dec 24 20:29:36 volumio sudo[4142]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Dec 24 20:29:36 volumio sudo[4142]: pam_unix(sudo:session): session closed for user root Dec 24 20:29:36 volumio sudo[4145]: volumio : TTY=pts/0 ; PWD=/home/volumio/volumio3-backend ; USER=root ; COMMAND=/usr/bin/killall node Dec 24 20:29:36 volumio sudo[4145]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Dec 24 20:29:36 volumio sudo[4145]: pam_unix(sudo:session): session closed for user root Dec 24 20:29:37 volumio volumio-remote-updater[984]: [2025-12-24 20:29:37] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Dec 24 20:29:37 volumio volumio-remote-updater[984]: [2025-12-24 20:29:37] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Dec 24 20:29:37 volumio sudo[4165]: volumio : TTY=pts/0 ; PWD=/home/volumio/volumio3-backend ; USER=root ; COMMAND=/bin/journalctl -f Dec 24 20:29:37 volumio sudo[4165]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Dec 24 20:29:37 volumio systemd[1]: wireless.service: Killing process 3247 (wpa_supplicant) with signal SIGKILL. Dec 24 20:29:37 volumio systemd[1]: volumio.service: Deactivated successfully. Dec 24 20:29:37 volumio systemd[1]: volumio.service: Consumed 14.178s CPU time. Dec 24 20:29:37 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 24 20:29:37 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 24 20:29:37 volumio systemd[1]: wireless.service: Deactivated successfully. Dec 24 20:29:37 volumio systemd[1]: wireless.service: Consumed 2.590s CPU time. Dec 24 20:29:37 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 1. Dec 24 20:29:37 volumio systemd[1]: Started dynamicswap.service - dynamicswap service. Dec 24 20:29:37 volumio systemd[1]: Stopped volumio.service - Volumio Backend Module. Dec 24 20:29:37 volumio systemd[1]: volumio.service: Consumed 14.178s CPU time. Dec 24 20:29:37 volumio systemd[1]: Started volumio.service - Volumio Backend Module. Dec 24 20:29:37 volumio systemd[1]: dynamicswap.service: Deactivated successfully. Dec 24 20:29:37 volumio volumio[4195]: info: ------------------------------------------- Dec 24 20:29:37 volumio volumio[4195]: info: ----- Volumio3 ---- Dec 24 20:29:37 volumio volumio[4195]: info: ------------------------------------------- Dec 24 20:29:37 volumio volumio[4195]: info: ----- System startup ---- Dec 24 20:29:37 volumio volumio[4195]: info: ------------------------------------------- Dec 24 20:29:37 volumio volumio[4195]: info: MYVOLUMIO Environment detected Dec 24 20:29:37 volumio volumio[4195]: info: Plugin folders cleanup Dec 24 20:29:37 volumio volumio[4195]: info: Scanning into folder /volumio/app/plugins/ Dec 24 20:29:37 volumio volumio[4195]: info: Scanning category audio_interface Dec 24 20:29:37 volumio volumio[4195]: info: Scanning category miscellanea Dec 24 20:29:37 volumio volumio[4195]: info: Scanning category music_service Dec 24 20:29:37 volumio volumio[4195]: info: Scanning category plugins.json Dec 24 20:29:37 volumio volumio[4195]: info: Scanning category system_controller Dec 24 20:29:37 volumio volumio[4195]: info: Scanning category user_interface Dec 24 20:29:37 volumio volumio[4195]: info: Scanning into folder /data/plugins/ Dec 24 20:29:37 volumio volumio[4195]: info: Plugin folders cleanup completed Dec 24 20:29:37 volumio volumio[4195]: info: ------------------------------------------- Dec 24 20:29:37 volumio volumio[4195]: info: ----- Core plugins startup ---- Dec 24 20:29:37 volumio volumio[4195]: info: ------------------------------------------- Dec 24 20:29:37 volumio volumio[4195]: info: Loading plugins from folder /volumio/app/plugins/ Dec 24 20:29:37 volumio volumio[4195]: info: Adding plugin upnp to MyMusic Plugins Dec 24 20:29:37 volumio volumio[4195]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 24 20:29:37 volumio volumio[4195]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 24 20:29:37 volumio volumio[4195]: info: Loading plugins from folder /data/plugins/ Dec 24 20:29:37 volumio volumio[4195]: info: Loading plugin "system"... Dec 24 20:29:37 volumio volumio[4195]: info: Loading plugin "appearance"... Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "network"... Dec 24 20:29:38 volumio volumio[4195]: info: Refreshing Cached IP Addresses Dec 24 20:29:38 volumio sudo[4223]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 24 20:29:38 volumio sudo[4223]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 20:29:38 volumio sudo[4225]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 24 20:29:38 volumio sudo[4225]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 20:29:38 volumio sudo[4223]: pam_unix(sudo:session): session closed for user root Dec 24 20:29:38 volumio sudo[4225]: pam_unix(sudo:session): session closed for user root Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "services"... Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "alsa_controller"... Dec 24 20:29:38 volumio sudo[4233]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 24 20:29:38 volumio sudo[4233]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 24 20:29:38 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "wizard"... Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "networkfs"... Dec 24 20:29:38 volumio volumio[4195]: info: Starting Udev Watcher for removable devices Dec 24 20:29:38 volumio volumio[4195]: info: Ignoring mount for partition: boot Dec 24 20:29:38 volumio volumio[4195]: info: Ignoring mount for partition: volumio Dec 24 20:29:38 volumio volumio[4195]: info: Ignoring mount for partition: volumio_data Dec 24 20:29:38 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "volumio_command_line_client"... Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "upnp"... Dec 24 20:29:38 volumio volumio[4195]: info: [1766604578287] Starting Upmpd Daemon Dec 24 20:29:38 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "my_music"... Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "mpd"... Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "upnp_browser"... Dec 24 20:29:38 volumio volumio[4195]: info: Starting UPNP Browser Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "alarm-clock"... Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "airplay_emulation"... Dec 24 20:29:38 volumio volumio[4195]: info: Starting Shairport Sync Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "last_100"... Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "webradio"... Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "i2s_dacs"... Dec 24 20:29:38 volumio volumio[4195]: info: I2S DAC not set, start Auto-detection Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "volumiodiscovery"... Dec 24 20:29:38 volumio volumio[4195]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 24 20:29:38 volumio volumio[4195]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 24 20:29:38 volumio volumio[4195]: *** WARNING *** For more information see Dec 24 20:29:38 volumio volumio[4195]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 24 20:29:38 volumio volumio[4195]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 24 20:29:38 volumio volumio[4195]: *** WARNING *** For more information see Dec 24 20:29:38 volumio node[4195]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 24 20:29:38 volumio node[4195]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 24 20:29:38 volumio node[4195]: *** WARNING *** For more information see Dec 24 20:29:38 volumio node[4195]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 24 20:29:38 volumio node[4195]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 24 20:29:38 volumio node[4195]: *** WARNING *** For more information see Dec 24 20:29:38 volumio volumio[4195]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 24 20:29:38 volumio volumio[4195]: info: Discovery: Started advertising with name: Volumio Dec 24 20:29:38 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "outputs"... Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "albumart"... Dec 24 20:29:38 volumio volumio[4195]: info: Plugin example_plugin is not enabled Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "inputs"... Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "updater_comm"... Dec 24 20:29:38 volumio volumio[4195]: info: Plugin mpdemulation is not enabled Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "rest_api"... Dec 24 20:29:38 volumio volumio[4195]: info: Loading plugin "websocket"... Dec 24 20:29:38 volumio volumio[4195]: info: Starting Socket.io Server version 1.7.4 Dec 24 20:29:38 volumio volumio[4195]: info: Loading i18n strings for locale it Dec 24 20:29:38 volumio volumio[4195]: Updating browse sources language Dec 24 20:29:38 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::initPlayerControls Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 24 20:29:39 volumio volumio[4195]: Express server listening on port 3000 Dec 24 20:29:39 volumio volumio[4195]: [Metrics] WebUI: 1s 486.76ms Dec 24 20:29:39 volumio volumio[4195]: info: CoreStateMachine::resetVolumioState Dec 24 20:29:39 volumio volumio[4195]: info: CoreStateMachine::getcurrentVolume Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::volumioRetrievevolume Dec 24 20:29:39 volumio volumio[4195]: info: CoreStateMachine::pushState Dec 24 20:29:39 volumio volumio[4195]: info: CorePlayQueue::getTrack 0 Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::volumioPushState Dec 24 20:29:39 volumio volumio[4195]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 24 20:29:39 volumio volumio[4195]: info: Cannot read play queue from file Dec 24 20:29:39 volumio volumio[4195]: info: Volumio Network Manager: Network status updated: 1 Dec 24 20:29:39 volumio volumio[4195]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 20:29:39 volumio volumio[4195]: TypeError: this.executeOnPlugin is not a function Dec 24 20:29:39 volumio volumio[4195]: at Socket. (/volumio/app/plugins/system_controller/network/index.js:1380:14) Dec 24 20:29:39 volumio volumio[4195]: at Socket.emit (node:events:514:28) Dec 24 20:29:39 volumio volumio[4195]: at addChunk (node:internal/streams/readable:343:12) Dec 24 20:29:39 volumio volumio[4195]: at readableAddChunk (node:internal/streams/readable:316:9) Dec 24 20:29:39 volumio volumio[4195]: at Readable.push (node:internal/streams/readable:253:10) Dec 24 20:29:39 volumio volumio[4195]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 24 20:29:39 volumio volumio[4195]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 24 20:29:39 volumio volumio[4255]: Forking 3 albumart workers Dec 24 20:29:39 volumio sudo[4314]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-24 20:28' Dec 24 20:29:39 volumio sudo[4314]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="5250573efb99f2852fa27ea7e92def8586093039" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="d9e2b6784b9276d5a676bf564ce7524ad160509b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 19 15:53:15 UTC 2025" VOLUMIO_VERSION="4.082" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="47d7740e8c55792fe06a847eb7ad6b29"