-- Logs begin at Mon 2026-02-23 20:58:16 UTC, end at Mon 2026-02-23 21:00:07 UTC. -- Feb 23 20:59:00 volumio wireless.js[790]: trying... Feb 23 20:59:00 volumio sudo[1104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 20:59:00 volumio sudo[1104]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:00 volumio sudo[1104]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:01 volumio volumio-remote-updater[666]: [2026-02-23 20:59:01] [info] asio async_connect error: system:111 (Connection refused) Feb 23 20:59:01 volumio volumio-remote-updater[666]: [2026-02-23 20:59:01] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Feb 23 20:59:01 volumio volumio-remote-updater[666]: [2026-02-23 20:59:01] [error] handle_connect error: Underlying Transport Error Feb 23 20:59:01 volumio wireless.js[790]: trying... Feb 23 20:59:01 volumio sudo[1113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 20:59:01 volumio sudo[1113]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:01 volumio sudo[1113]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:02 volumio dhcpcd[1000]: wlan0: leased 192.168.1.110 for 25200 seconds Feb 23 20:59:02 volumio avahi-daemon[691]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.110. Feb 23 20:59:02 volumio dhcpcd[1000]: eth0: adding route to 192.168.1.0/24 Feb 23 20:59:02 volumio avahi-daemon[691]: New relevant interface wlan0.IPv4 for mDNS. Feb 23 20:59:02 volumio dhcpcd[1000]: eth0: adding default route via 192.168.1.1 Feb 23 20:59:02 volumio avahi-daemon[691]: Registering new address record for 192.168.1.110 on wlan0.IPv4. Feb 23 20:59:02 volumio dhcpcd[1000]: wlan0: adding host route to 192.168.1.110 via 127.0.0.1 Feb 23 20:59:02 volumio dhcpcd[1000]: wlan0: adding route to 192.168.1.0/24 Feb 23 20:59:02 volumio dhcpcd[1000]: wlan0: adding default route via 192.168.1.1 Feb 23 20:59:02 volumio dhcpcd[1000]: eth0: removing route to 192.168.1.0/24 Feb 23 20:59:02 volumio dhcpcd[1000]: eth0: removing default route via 192.168.1.1 Feb 23 20:59:02 volumio wireless.js[790]: trying... Feb 23 20:59:02 volumio sudo[1143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Feb 23 20:59:02 volumio sudo[1143]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:02 volumio sudo[1143]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:02 volumio wireless.js[790]: Connected to: ----Keenetic-4954_5G Feb 23 20:59:02 volumio wireless.js[790]: ---- Feb 23 20:59:02 volumio sudo[1152]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 20:59:02 volumio sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:03 volumio sudo[1152]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:03 volumio wireless.js[790]: ... joined AP, wlan0 IPv4 is 192.168.1.110, ipV6 is undefined Feb 23 20:59:03 volumio wireless.js[790]: It's done! AP Feb 23 20:59:03 volumio systemd[1]: Started Wireless Services. Feb 23 20:59:03 volumio systemd[1]: Starting Volumio Backend Module... Feb 23 20:59:03 volumio systemd[1]: Started Volumio Backend Module. Feb 23 20:59:03 volumio systemd[1]: Starting Start Volumio Kiosk... Feb 23 20:59:03 volumio systemd[1]: Started Start Volumio Kiosk. Feb 23 20:59:03 volumio systemd[1]: Starting Multi-User System. Feb 23 20:59:03 volumio systemd[1]: Reached target Multi-User System. Feb 23 20:59:03 volumio systemd[1]: Starting Graphical Interface. Feb 23 20:59:03 volumio systemd[1]: Reached target Graphical Interface. Feb 23 20:59:03 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 23 20:59:03 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 23 20:59:03 volumio systemd[1]: Startup finished in 8.936s (kernel) + 29.897s (userspace) = 38.833s. Feb 23 20:59:03 volumio startx[1162]: X.Org X Server 1.18.4 Feb 23 20:59:03 volumio startx[1162]: Release Date: 2016-07-19 Feb 23 20:59:03 volumio startx[1162]: X Protocol Version 11, Revision 0 Feb 23 20:59:03 volumio startx[1162]: Build Operating System: Linux 4.4.26-v7+ armv7l Raspbian Feb 23 20:59:03 volumio startx[1162]: Current Operating System: Linux volumio 4.14.69-v7+ #1141 SMP Mon Sep 10 15:26:29 BST 2018 armv7l Feb 23 20:59:03 volumio startx[1162]: Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=640 bcm2708_fb.fbheight=480 bcm2708_fb.fbdepth=16 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3f000000 vc_mem.mem_size=0x3f600000 splash quiet plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 console=ttyS0,115200 kgdboc=ttyS0,115200 console=tty1 imgpart=/dev/mmcblk0p2 imgfile=/volumio_current.sqsh elevator=noop rootwait bootdelay=5 logo.nologo vt.global_cursor_default=0 loglevel=0 Feb 23 20:59:03 volumio startx[1162]: Build Date: 11 November 2016 11:59:59AM Feb 23 20:59:03 volumio startx[1162]: xorg-server 2:1.18.4-2+rpi1 (https://www.debian.org/support) Feb 23 20:59:03 volumio startx[1162]: Current version of pixman: 0.32.6 Feb 23 20:59:03 volumio startx[1162]: Before reporting problems, check http://wiki.x.org Feb 23 20:59:03 volumio startx[1162]: to make sure that you have the latest version. Feb 23 20:59:03 volumio startx[1162]: Markers: (--) probed, (**) from config file, (==) default setting, Feb 23 20:59:03 volumio startx[1162]: (++) from command line, (!!) notice, (II) informational, Feb 23 20:59:03 volumio startx[1162]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Feb 23 20:59:03 volumio startx[1162]: (==) Log file: "/var/log/Xorg.0.log", Time: Mon Feb 23 20:59:03 2026 Feb 23 20:59:03 volumio startx[1162]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Feb 23 20:59:03 volumio startx[1162]: modprobe: FATAL: Module g2d_23 not found. Feb 23 20:59:04 volumio ntpd[771]: Listen normally on 4 wlan0 192.168.1.110 UDP 123 Feb 23 20:59:04 volumio ntpd[771]: peers refreshed Feb 23 20:59:06 volumio volumio[1161]: info: ------------------------------------------- Feb 23 20:59:06 volumio volumio[1161]: info: ----- Volumio2 ---- Feb 23 20:59:06 volumio volumio[1161]: info: ------------------------------------------- Feb 23 20:59:06 volumio volumio[1161]: info: ----- System startup ---- Feb 23 20:59:06 volumio volumio[1161]: info: ------------------------------------------- Feb 23 20:59:06 volumio volumio[1161]: info: Plugin folders cleanup Feb 23 20:59:06 volumio volumio[1161]: info: Scanning into folder /volumio/app/plugins/ Feb 23 20:59:06 volumio volumio[1161]: info: Scanning category audio_interface Feb 23 20:59:06 volumio volumio[1161]: info: Scanning category miscellanea Feb 23 20:59:06 volumio volumio[1161]: info: Scanning category music_service Feb 23 20:59:06 volumio volumio[1161]: info: Scanning category plugins.json Feb 23 20:59:06 volumio volumio[1161]: info: Scanning category system_controller Feb 23 20:59:06 volumio volumio[1161]: info: Scanning category user_interface Feb 23 20:59:06 volumio volumio[1161]: info: Scanning into folder /data/plugins/ Feb 23 20:59:06 volumio volumio[1161]: info: Scanning category miscellanea Feb 23 20:59:06 volumio volumio[1161]: info: Scanning category music_service Feb 23 20:59:06 volumio volumio[1161]: info: Plugin folders cleanup completed Feb 23 20:59:06 volumio volumio[1161]: info: ------------------------------------------- Feb 23 20:59:06 volumio volumio[1161]: info: ----- Core plugins startup ---- Feb 23 20:59:06 volumio volumio[1161]: info: ------------------------------------------- Feb 23 20:59:06 volumio volumio[1161]: info: Loading plugins from folder /volumio/app/plugins/ Feb 23 20:59:06 volumio volumio[1161]: info: Adding plugin upnp to MyMusic Plugins Feb 23 20:59:06 volumio volumio[1161]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 23 20:59:06 volumio volumio[1161]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 23 20:59:06 volumio volumio[1161]: info: Loading plugins from folder /data/plugins/ Feb 23 20:59:06 volumio volumio[1161]: info: Loading plugin "system"... Feb 23 20:59:06 volumio volumio[1161]: info: Loading plugin "appearance"... Feb 23 20:59:08 volumio volumio[1161]: info: Loading plugin "network"... Feb 23 20:59:08 volumio volumio[1161]: info: Loading plugin "services"... Feb 23 20:59:08 volumio volumio[1161]: info: Loading plugin "alsa_controller"... Feb 23 20:59:09 volumio volumio[1161]: info: Loading plugin "wizard"... Feb 23 20:59:09 volumio volumio[1161]: info: Loading plugin "volumio_command_line_client"... Feb 23 20:59:09 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 20:59:09 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 20:59:09 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 20:59:09 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 20:59:09 volumio volumio[1161]: info: Loading plugin "upnp"... Feb 23 20:59:09 volumio volumio[1161]: info: [1771880349103] Starting Upmpd Daemon Feb 23 20:59:09 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 20:59:09 volumio volumio[1161]: info: Loading plugin "my_music"... Feb 23 20:59:09 volumio volumio[1161]: info: Loading plugin "mpd"... Feb 23 20:59:09 volumio volumio[1161]: info: Loading plugin "upnp_browser"... Feb 23 20:59:10 volumio volumio[1161]: info: Loading plugin "networkfs"... Feb 23 20:59:10 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 20:59:10 volumio volumio[1161]: info: Loading plugin "alarm-clock"... Feb 23 20:59:11 volumio volumio[1161]: info: Loading plugin "airplay_emulation"... Feb 23 20:59:11 volumio volumio-remote-updater[666]: [2026-02-23 20:59:11] [connect] Successful connection Feb 23 20:59:11 volumio volumio[1161]: info: Starting Shairport Sync Feb 23 20:59:11 volumio volumio[1161]: info: Loading plugin "last_100"... Feb 23 20:59:11 volumio volumio[1161]: info: Loading plugin "webradio"... Feb 23 20:59:11 volumio volumio[1161]: info: Loading plugin "i2s_dacs"... Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 20:59:11 volumio volumio[1161]: info: Loading plugin "volumiodiscovery"... Feb 23 20:59:11 volumio volumio[1161]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 20:59:11 volumio node[1161]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 23 20:59:11 volumio node[1161]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 20:59:11 volumio node[1161]: *** WARNING *** For more information see Feb 23 20:59:11 volumio node[1161]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 20:59:11 volumio node[1161]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 20:59:11 volumio node[1161]: *** WARNING *** For more information see Feb 23 20:59:11 volumio volumio[1161]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 20:59:11 volumio volumio[1161]: *** WARNING *** For more information see Feb 23 20:59:11 volumio volumio[1161]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 23 20:59:11 volumio volumio[1161]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 23 20:59:11 volumio volumio[1161]: *** WARNING *** For more information see Feb 23 20:59:11 volumio volumio[1161]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 23 20:59:11 volumio volumio[1161]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/system_controller/volumiodiscovery/config.json' Feb 23 20:59:11 volumio volumio[1161]: Discovery: StartAdv! undefined Feb 23 20:59:11 volumio volumio[1161]: Discovery: Started advertising... Volumio - undefined Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 23 20:59:11 volumio volumio[1161]: info: Plugin auto_play is not enabled Feb 23 20:59:11 volumio volumio[1161]: info: Plugin autostart is not enabled Feb 23 20:59:11 volumio volumio[1161]: info: Loading plugin "albumart"... Feb 23 20:59:11 volumio volumio[1161]: info: Plugin example_plugin is not enabled Feb 23 20:59:11 volumio volumio[1161]: info: Loading plugin "updater_comm"... Feb 23 20:59:11 volumio volumio[1161]: info: Plugin mpdemulation is not enabled Feb 23 20:59:11 volumio volumio[1161]: info: Loading plugin "rest_api"... Feb 23 20:59:11 volumio volumio[1161]: info: Loading plugin "websocket"... Feb 23 20:59:11 volumio volumio[1161]: info: Plugin Systeminfo is not enabled Feb 23 20:59:11 volumio volumio[1161]: info: Loading plugin "touch_display"... Feb 23 20:59:11 volumio volumio[1161]: info: Plugin RoonBridge is not enabled Feb 23 20:59:11 volumio volumio[1161]: info: Plugin lms is not enabled Feb 23 20:59:11 volumio volumio[1161]: info: ___________ START PLUGINS ___________ Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 20:59:11 volumio volumio[1161]: info: [1771880351720] CoreMusicLibrary::Adding element Media Servers Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 20:59:11 volumio volumio[1161]: info: [1771880351761] CoreMusicLibrary::Adding element Last_100 Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 23 20:59:11 volumio volumio[1161]: info: [1771880351768] CoreMusicLibrary::Adding element Webradio Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 20:59:11 volumio volumio[1161]: info: ------------------------------------------- Feb 23 20:59:11 volumio volumio[1161]: info: ----- MyVolumio plugins startup ---- Feb 23 20:59:11 volumio volumio[1161]: info: ------------------------------------------- Feb 23 20:59:11 volumio volumio[1161]: info: Loading plugins from folder /myvolumio/plugins Feb 23 20:59:11 volumio volumio[1161]: info: Loading plugins from folder /data/myvolumio/plugins Feb 23 20:59:11 volumio volumio[1161]: info: Loading i18n strings for locale ru Feb 23 20:59:11 volumio sudo[1320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 23 20:59:11 volumio volumio[1161]: Updating browse sources language Feb 23 20:59:11 volumio volumio[1161]: Cannot find translation for sourceMedia Servers Feb 23 20:59:11 volumio sudo[1320]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:11 volumio systemd[1]: Started Start Volumio Kiosk. Feb 23 20:59:11 volumio sudo[1320]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:11 volumio volumio[1161]: Forking 3 albumart workers Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::initPlayerControls Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 23 20:59:11 volumio volumio[1161]: info: BOOT COMPLETED Feb 23 20:59:11 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 20:59:12 volumio volumio[1161]: Starting albumart workers Feb 23 20:59:12 volumio volumio[1161]: Starting albumart workers Feb 23 20:59:12 volumio volumio[1161]: Starting albumart workers Feb 23 20:59:17 volumio volumio[1161]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Feb 23 20:59:17 volumio volumio[1161]: info: CoreCommandRouter::Close All Modals sent Feb 23 20:59:17 volumio volumio[1161]: info: CoreCommandRouter::Close All Modals sent Feb 23 20:59:17 volumio volumio[1161]: Express server listening on port 3000 Feb 23 20:59:17 volumio volumio[1161]: Volumio Calling Home Feb 23 20:59:17 volumio volumio[1161]: info: CoreStateMachine::resetVolumioState Feb 23 20:59:17 volumio volumio[1161]: info: CoreStateMachine::getcurrentVolume Feb 23 20:59:17 volumio volumio[1161]: info: CoreCommandRouter::volumioRetrievevolume Feb 23 20:59:17 volumio volumio[1161]: info: CoreStateMachine::updateTrackBlock Feb 23 20:59:17 volumio volumio[1161]: info: CorePlayQueue::getTrackBlock Feb 23 20:59:17 volumio volumio[1161]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/plugins.json' Feb 23 20:59:17 volumio volumio[1161]: info: Cannot read play queue from file Feb 23 20:59:17 volumio volumio[1161]: info: Setting Device type: Raspberry PI Feb 23 20:59:17 volumio volumio[1161]: info: Volumio Kiosk Started Feb 23 20:59:17 volumio volumio[1161]: info: Kiosk Started Feb 23 20:59:17 volumio volumio[1161]: info: MPD running with PID696 ,establishing connection Feb 23 20:59:17 volumio volumio[1161]: info: VolumeController:: Volume=undefined Mute =undefined Feb 23 20:59:17 volumio volumio[1161]: info: CoreStateMachine::pushState Feb 23 20:59:17 volumio volumio[1161]: info: CoreStateMachine::getState Feb 23 20:59:17 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Feb 23 20:59:17 volumio volumio[1161]: info: CoreCommandRouter::volumioPushState Feb 23 20:59:17 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 20:59:17 volumio volumio[1161]: info: Feb 23 20:59:17 volumio volumio[1161]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Feb 23 20:59:17 volumio volumio[1161]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Feb 23 20:59:17 volumio volumio[1161]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Feb 23 20:59:17 volumio volumio[1161]: at emitOne (events.js:116:13) Feb 23 20:59:17 volumio volumio[1161]: at Socket.emit (events.js:211:7) Feb 23 20:59:17 volumio volumio[1161]: at addChunk (_stream_readable.js:263:12) Feb 23 20:59:17 volumio volumio[1161]: at readableAddChunk (_stream_readable.js:246:13) Feb 23 20:59:17 volumio volumio[1161]: at Socket.Readable.push (_stream_readable.js:208:10) Feb 23 20:59:17 volumio volumio[1161]: at Pipe.onread (net.js:607:20) Feb 23 20:59:17 volumio volumio[1161]: info: CoreStateMachine::setRepeat true single undefined Feb 23 20:59:17 volumio volumio[1161]: info: CoreStateMachine::pushState Feb 23 20:59:17 volumio volumio[1161]: info: CoreStateMachine::getState Feb 23 20:59:17 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Feb 23 20:59:17 volumio volumio[1161]: info: CoreCommandRouter::volumioPushState Feb 23 20:59:17 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 20:59:17 volumio volumio[1161]: info: CoreStateMachine::setRandom true Feb 23 20:59:17 volumio volumio[1161]: info: CoreStateMachine::pushState Feb 23 20:59:17 volumio volumio[1161]: info: CoreStateMachine::getState Feb 23 20:59:17 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Feb 23 20:59:17 volumio volumio[1161]: info: CoreCommandRouter::volumioPushState Feb 23 20:59:17 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 23 20:59:17 volumio sudo[1366]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Feb 23 20:59:17 volumio sudo[1366]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:17 volumio sudo[1370]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Feb 23 20:59:17 volumio volumio[1161]: { Error: EACCES: permission denied, unlink '/data/backgrounds/morning.jpg' Feb 23 20:59:17 volumio volumio[1161]: errno: -13, Feb 23 20:59:17 volumio volumio[1161]: code: 'EACCES', Feb 23 20:59:17 volumio volumio[1161]: syscall: 'unlink', Feb 23 20:59:17 volumio volumio[1161]: path: '/data/backgrounds/morning.jpg' } Feb 23 20:59:17 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Feb 23 20:59:17 volumio systemd[1]: Started ShairportSync AirTunes receiver. Feb 23 20:59:17 volumio sudo[1366]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:17 volumio sudo[1370]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:17 volumio shairport-sync[1376]: Successful Startup Feb 23 20:59:17 volumio systemd[1]: Stopping ShairportSync AirTunes receiver... Feb 23 20:59:17 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Feb 23 20:59:17 volumio systemd[1]: Started ShairportSync AirTunes receiver. Feb 23 20:59:18 volumio sudo[1370]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:18 volumio shairport-sync[1384]: Successful Startup Feb 23 20:59:18 volumio volumio[1161]: info: Shairport-Sync Started Feb 23 20:59:18 volumio volumio[1161]: Error adding Membership: Error: addMembership EINVAL Feb 23 20:59:18 volumio volumio[1161]: info: Shairport-Sync Started Feb 23 20:59:18 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Feb 23 20:59:18 volumio volumio[1161]: info: CoreStateMachine::getState Feb 23 20:59:18 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Feb 23 20:59:19 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 23 20:59:19 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 23 20:59:19 volumio volumio[1161]: Configuration write error: Error: EACCES: permission denied, open '/data/configuration/system_controller/system/config.json' Feb 23 20:59:19 volumio volumio[1161]: Discovery: adding de1f1542-6424-4ab8-85d9-71da65e340e1 Feb 23 20:59:19 volumio volumio[1161]: info: mDNS: Found device Volumio Feb 23 20:59:19 volumio sudo[1395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 20:59:19 volumio sudo[1395]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:19 volumio sudo[1395]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:19 volumio sudo[1399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 20:59:19 volumio volumio[1161]: Discovery: this is already registered, de1f1542-6424-4ab8-85d9-71da65e340e1 Feb 23 20:59:19 volumio sudo[1399]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:19 volumio volumio[1161]: info: mDNS: Found device Volumio Feb 23 20:59:19 volumio sudo[1399]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:19 volumio sudo[1413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 20:59:19 volumio sudo[1413]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:19 volumio sudo[1413]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:20 volumio sudo[1423]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 23 20:59:20 volumio sudo[1423]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:20 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Feb 23 20:59:20 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 23 20:59:20 volumio sudo[1423]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:20 volumio volumio[1161]: info: Upmpdcli Daemon Started Feb 23 20:59:21 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Feb 23 20:59:21 volumio volumio[1161]: info: CoreStateMachine::getState Feb 23 20:59:21 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Feb 23 20:59:21 volumio volumio[1161]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 20:59:21 volumio sudo[1456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 20:59:21 volumio sudo[1456]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:21 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 20:59:21 volumio sudo[1456]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:21 volumio volumio[1161]: info: Listing playlists Feb 23 20:59:21 volumio sudo[1461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 20:59:21 volumio sudo[1461]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:21 volumio sudo[1461]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:22 volumio volumio[1161]: info: CoreCommandRouter::volumioGetState Feb 23 20:59:22 volumio volumio[1161]: info: CoreStateMachine::getState Feb 23 20:59:22 volumio volumio[1161]: info: CorePlayQueue::getTrack 0 Feb 23 20:59:22 volumio sudo[1481]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 20:59:22 volumio sudo[1481]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:22 volumio sudo[1481]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:22 volumio volumio[1161]: info: CoreCommandRouter::volumioGetBrowseSources Feb 23 20:59:22 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 23 20:59:22 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 23 20:59:22 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 23 20:59:22 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 23 20:59:22 volumio volumio[1161]: info: Listing playlists Feb 23 20:59:22 volumio sudo[1490]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 20:59:22 volumio sudo[1490]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:22 volumio sudo[1490]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:25 volumio volumio[1161]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 23 20:59:25 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 23 20:59:25 volumio sudo[1527]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 23 20:59:25 volumio sudo[1527]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:26 volumio sudo[1527]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:26 volumio sudo[1540]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 23 20:59:26 volumio sudo[1540]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:26 volumio sudo[1540]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:26 volumio sudo[1554]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 23 20:59:26 volumio sudo[1554]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:26 volumio sudo[1554]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:26 volumio sudo[1565]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 23 20:59:26 volumio sudo[1565]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:26 volumio sudo[1565]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:26 volumio volumio-remote-updater[666]: [2026-02-23 20:59:26] [connect] Successful connection Feb 23 20:59:26 volumio sudo[1578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 23 20:59:26 volumio volumio-remote-updater[666]: [2026-02-23 20:59:26] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1771880366 101 Feb 23 20:59:26 volumio sudo[1578]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:26 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 23 20:59:26 volumio sudo[1580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 20:59:26 volumio sudo[1578]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:26 volumio sudo[1580]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:26 volumio sudo[1580]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:26 volumio sudo[1598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 23 20:59:26 volumio sudo[1598]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 20:59:27 volumio volumio[1161]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.457&uuid=5ed6e4962eb6fba0e43ee16ff6856366" http://updates.volumio.org:7070/downloader-v1/track-device Feb 23 20:59:27 volumio volumio[1161]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 23 20:59:27 volumio volumio[1161]: Dload Upload Total Spent Left Speed Feb 23 20:59:27 volumio volumio[1161]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 23 20:59:27 volumio volumio[1161]: retrying in 5 seconds, trial 0 Feb 23 20:59:27 volumio volumio[1161]: Volumio Calling Home Feb 23 20:59:34 volumio sudo[1598]: pam_unix(sudo:session): session closed for user root Feb 23 20:59:37 volumio volumio[1161]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.457&uuid=5ed6e4962eb6fba0e43ee16ff6856366" http://updates.volumio.org:7070/downloader-v1/track-device Feb 23 20:59:37 volumio volumio[1161]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 23 20:59:37 volumio volumio[1161]: Dload Upload Total Spent Left Speed Feb 23 20:59:37 volumio volumio[1161]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 23 20:59:37 volumio volumio[1161]: retrying in 5 seconds, trial 1 Feb 23 20:59:37 volumio volumio[1161]: Volumio Calling Home Feb 23 20:59:41 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 23 20:59:41 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 23 20:59:47 volumio kernel: RTL871X: linked_status_chk(wlan0) disconnect or roaming Feb 23 20:59:47 volumio volumio[1161]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.457&uuid=5ed6e4962eb6fba0e43ee16ff6856366" http://updates.volumio.org:7070/downloader-v1/track-device Feb 23 20:59:47 volumio volumio[1161]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 23 20:59:47 volumio volumio[1161]: Dload Upload Total Spent Left Speed Feb 23 20:59:47 volumio volumio[1161]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 23 20:59:47 volumio volumio[1161]: retrying in 5 seconds, trial 2 Feb 23 20:59:47 volumio volumio[1161]: Volumio Calling Home Feb 23 20:59:53 volumio wpa_supplicant[962]: wlan0: CTRL-EVENT-ASSOC-REJECT status_code=1 Feb 23 20:59:53 volumio dhcpcd[1000]: wlan0: carrier lost Feb 23 20:59:53 volumio dhcpcd[1000]: eth0: adding route to 192.168.1.0/24 Feb 23 20:59:53 volumio dhcpcd[1000]: eth0: adding default route via 192.168.1.1 Feb 23 20:59:53 volumio dhcpcd[1000]: wlan0: deleting host route to 192.168.1.110 via 127.0.0.1 Feb 23 20:59:53 volumio dhcpcd[1000]: wlan0: deleting route to 192.168.1.0/24 Feb 23 20:59:53 volumio dhcpcd[1000]: wlan0: deleting default route via 192.168.1.1 Feb 23 20:59:53 volumio avahi-daemon[691]: Withdrawing address record for 192.168.1.110 on wlan0. Feb 23 20:59:53 volumio avahi-daemon[691]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.110. Feb 23 20:59:53 volumio avahi-daemon[691]: Interface wlan0.IPv4 no longer relevant for mDNS. Feb 23 20:59:53 volumio volumio[1161]: info: mDNS: A device disapperared from network Feb 23 20:59:53 volumio volumio[1161]: info: mDNS: Device volumio disapperared from network Feb 23 20:59:53 volumio kernel: RTL871X: nolinked power save enter Feb 23 20:59:53 volumio dhcpcd[1000]: eth0: removing route to 192.168.1.0/24 Feb 23 20:59:53 volumio dhcpcd[1000]: eth0: removing default route via 192.168.1.1 Feb 23 20:59:54 volumio kernel: RTL871X: nolinked power save leave Feb 23 20:59:55 volumio ntpd[771]: Deleting interface #4 wlan0, 192.168.1.110#123, interface stats: received=0, sent=0, dropped=0, active_time=51 secs Feb 23 20:59:55 volumio ntpd[771]: peers refreshed Feb 23 20:59:57 volumio wpa_supplicant[962]: wlan0: Trying to associate with 50:ff:20:50:b3:57 (SSID='Keenetic-4954_5G' freq=5180 MHz) Feb 23 20:59:57 volumio kernel: RTL871X: rtw_set_802_11_connect(wlan0) fw_state=0x00000008 Feb 23 20:59:57 volumio kernel: RTL871X: start auth Feb 23 20:59:57 volumio kernel: RTL871X: auth success, start assoc Feb 23 20:59:57 volumio kernel: RTL871X: rtw_cfg80211_indicate_connect(wlan0) BSS not found !! Feb 23 20:59:57 volumio kernel: RTL871X: assoc success Feb 23 20:59:57 volumio wpa_supplicant[962]: wlan0: Associated with 50:ff:20:50:b3:57 Feb 23 20:59:58 volumio wpa_supplicant[962]: wlan0: WPA: Key negotiation completed with 50:ff:20:50:b3:57 [PTK=CCMP GTK=CCMP] Feb 23 20:59:58 volumio wpa_supplicant[962]: wlan0: CTRL-EVENT-CONNECTED - Connection to 50:ff:20:50:b3:57 completed [id=1 id_str=] Feb 23 20:59:58 volumio dhcpcd[1000]: wlan0: carrier acquired Feb 23 20:59:58 volumio kernel: RTL871X: send eapol packet Feb 23 20:59:58 volumio kernel: RTL871X: send eapol packet Feb 23 20:59:58 volumio kernel: RTL871X: set pairwise key camid:4, addr:50:ff:20:50:b3:57, kid:0, type:AES Feb 23 20:59:58 volumio kernel: RTL871X: set group key camid:5, addr:50:ff:20:50:b3:57, kid:1, type:AES Feb 23 20:59:58 volumio dhcpcd[1000]: wlan0: soliciting an IPv6 router Feb 23 20:59:58 volumio dhcpcd[1000]: wlan0: rebinding lease of 192.168.1.110 Feb 23 21:00:02 volumio dhcpcd[1000]: wlan0: leased 192.168.1.110 for 25200 seconds Feb 23 21:00:02 volumio dhcpcd[1000]: eth0: adding route to 192.168.1.0/24 Feb 23 21:00:02 volumio avahi-daemon[691]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.110. Feb 23 21:00:02 volumio dhcpcd[1000]: eth0: adding default route via 192.168.1.1 Feb 23 21:00:02 volumio dhcpcd[1000]: wlan0: adding host route to 192.168.1.110 via 127.0.0.1 Feb 23 21:00:02 volumio dhcpcd[1000]: wlan0: adding route to 192.168.1.0/24 Feb 23 21:00:02 volumio dhcpcd[1000]: wlan0: adding default route via 192.168.1.1 Feb 23 21:00:02 volumio avahi-daemon[691]: New relevant interface wlan0.IPv4 for mDNS. Feb 23 21:00:02 volumio avahi-daemon[691]: Registering new address record for 192.168.1.110 on wlan0.IPv4. Feb 23 21:00:03 volumio dhcpcd[1000]: eth0: removing route to 192.168.1.0/24 Feb 23 21:00:03 volumio dhcpcd[1000]: eth0: removing default route via 192.168.1.1 Feb 23 21:00:04 volumio ntpd[771]: Listen normally on 5 wlan0 192.168.1.110 UDP 123 Feb 23 21:00:04 volumio ntpd[771]: peers refreshed Feb 23 21:00:04 volumio volumio[1161]: Discovery: adding de1f1542-6424-4ab8-85d9-71da65e340e1 Feb 23 21:00:04 volumio volumio[1161]: info: mDNS: Found device Volumio Feb 23 21:00:04 volumio sudo[1969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 23 21:00:04 volumio sudo[1969]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 23 21:00:04 volumio sudo[1969]: pam_unix(sudo:session): session closed for user root Feb 23 21:00:06 volumio volumio[1161]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Feb 23 21:00:06 volumio volumio[1161]: info: Saving new wireless network Feb 23 21:00:06 volumio volumio[1161]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 21:00:06 volumio volumio[1161]: ReferenceError: error is not defined Feb 23 21:00:06 volumio volumio[1161]: at /volumio/app/plugins/system_controller/network/index.js:546:52 Feb 23 21:00:06 volumio volumio[1161]: at /volumio/node_modules/graceful-fs/graceful-fs.js:99:16 Feb 23 21:00:06 volumio volumio[1161]: at fs.js:1279:7 Feb 23 21:00:06 volumio volumio[1161]: at FSReqWrap.oncomplete (fs.js:135:15) Feb 23 21:00:06 volumio volumio[1161]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 23 21:00:07 volumio sudo[1999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-23 20:59 Feb 23 21:00:07 volumio sudo[1999]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 8 (jessie)" NAME="Raspbian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" 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="c00a19098320053824010d132d6bfb39774a7d2f" VOLUMIO_FE_VERSION="e2f152c30d370c9c7c86508a02c0323bef5c243b" VOLUMIO_BE_VERSION="909cc76a7609428f005f8e2878227062cb5328a6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon Sep 17 00:18:22 CEST 2018" VOLUMIO_VERSION="2.457" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="cdde21c6e3d40ce352c051f661950d1b"