-- Logs begin at Mo 2019-04-29 10:00:31 UTC, end at Do 2026-02-12 13:18:08 UTC. -- Feb 12 13:17:00 volumio systemd[1]: Time has been changed Feb 12 13:17:00 volumio kernel: RTL871X: nolinked power save leave Feb 12 13:17:00 volumio sudo[899]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:00 volumio nmbd[916]: [2026/02/12 13:17:00.632482, 0] ../lib/util/become_daemon.c:124(daemon_ready) Feb 12 13:17:00 volumio nmbd[916]: STATUS=daemon 'nmbd' finished starting up and ready to serve connections Feb 12 13:17:00 volumio systemd[1]: Started LSB: start Winbind daemon. Feb 12 13:17:00 volumio winbind[707]: . Feb 12 13:17:00 volumio winbindd[936]: [2026/02/12 13:17:00.652382, 0] ../source3/winbindd/winbindd_cache.c:3235(initialize_winbindd_cache) Feb 12 13:17:00 volumio winbindd[936]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Feb 12 13:17:00 volumio systemd[1]: Started Music Player Daemon. Feb 12 13:17:00 volumio smbd[918]: . Feb 12 13:17:00 volumio systemd[1]: Started LSB: start Samba SMB/CIFS daemon (smbd). Feb 12 13:17:00 volumio wireless.js[816]: Stopped aP Feb 12 13:17:00 volumio winbindd[936]: [2026/02/12 13:17:00.829471, 0] ../lib/util/become_daemon.c:124(daemon_ready) Feb 12 13:17:00 volumio winbindd[936]: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Feb 12 13:17:00 volumio winbindd[947]: [2026/02/12 13:17:00.834105, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 12 13:17:00 volumio winbindd[947]: Unable to open new log file '/var/log/samba/log.wb-VOLUMIO': No such file or directory Feb 12 13:17:00 volumio systemd[1]: Stopped hotspot.service. Feb 12 13:17:00 volumio winbindd[949]: [2026/02/12 13:17:00.855630, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 12 13:17:00 volumio winbindd[949]: Unable to open new log file '/var/log/samba/log.winbindd-idmap': No such file or directory Feb 12 13:17:00 volumio sudo[951]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Feb 12 13:17:00 volumio sudo[951]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:00 volumio sudo[951]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:00 volumio sudo[959]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Feb 12 13:17:00 volumio sudo[959]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:00 volumio sudo[959]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:00 volumio winbindd[965]: [2026/02/12 13:17:00.929795, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 12 13:17:00 volumio winbindd[965]: Unable to open new log file '/var/log/samba/log.wb-BUILTIN': No such file or directory Feb 12 13:17:00 volumio wireless.js[816]: Hotspot is disabled, not starting it Feb 12 13:17:00 volumio systemd[1]: Started Wireless Services. Feb 12 13:17:00 volumio systemd[1]: Starting Volumio Backend Module... Feb 12 13:17:00 volumio systemd[1]: Started Volumio Backend Module. Feb 12 13:17:00 volumio systemd[1]: Starting Volumio Streaming Daemon... Feb 12 13:17:00 volumio systemd[1]: Started Volumio Streaming Daemon. Feb 12 13:17:00 volumio systemd[1]: Starting Start Volumio Kiosk... Feb 12 13:17:00 volumio systemd[1]: Started Start Volumio Kiosk. Feb 12 13:17:00 volumio systemd[1]: Starting Multi-User System. Feb 12 13:17:00 volumio systemd[1]: Reached target Multi-User System. Feb 12 13:17:00 volumio systemd[1]: Starting Graphical Interface. Feb 12 13:17:00 volumio systemd[1]: Reached target Graphical Interface. Feb 12 13:17:00 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Feb 12 13:17:00 volumio smbd[937]: [2026/02/12 13:17:00.972975, 0] ../lib/util/become_daemon.c:124(daemon_ready) Feb 12 13:17:00 volumio smbd[937]: STATUS=daemon 'smbd' finished starting up and ready to serve connections Feb 12 13:17:00 volumio smbd[973]: [2026/02/12 13:17:00.978902, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 12 13:17:00 volumio smbd[973]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 12 13:17:01 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Feb 12 13:17:01 volumio systemd[1]: Startup finished in 10.016s (kernel) + 24.144s (userspace) = 34.161s. Feb 12 13:17:01 volumio startx[974]: X.Org X Server 1.18.4 Feb 12 13:17:01 volumio startx[974]: Release Date: 2016-07-19 Feb 12 13:17:01 volumio startx[974]: X Protocol Version 11, Revision 0 Feb 12 13:17:01 volumio startx[974]: Build Operating System: Linux 4.4.26-v7+ armv7l Raspbian Feb 12 13:17:01 volumio startx[974]: Current Operating System: Linux volumio 4.14.92-v7+ #1187 SMP Wed Jan 9 20:01:54 GMT 2019 armv7l Feb 12 13:17:01 volumio startx[974]: Kernel command line: 8250.nr_uarts=0 bcm2708_fb.fbwidth=1024 bcm2708_fb.fbheight=600 bcm2708_fb.fbswap=1 vc_mem.mem_base=0x3ec00000 vc_mem.mem_size=0x40000000 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 12 13:17:01 volumio startx[974]: Build Date: 11 November 2016 11:59:59AM Feb 12 13:17:01 volumio startx[974]: xorg-server 2:1.18.4-2+rpi1 (https://www.debian.org/support) Feb 12 13:17:01 volumio startx[974]: Current version of pixman: 0.32.6 Feb 12 13:17:01 volumio startx[974]: Before reporting problems, check http://wiki.x.org Feb 12 13:17:01 volumio startx[974]: to make sure that you have the latest version. Feb 12 13:17:01 volumio startx[974]: Markers: (--) probed, (**) from config file, (==) default setting, Feb 12 13:17:01 volumio startx[974]: (++) from command line, (!!) notice, (II) informational, Feb 12 13:17:01 volumio startx[974]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Feb 12 13:17:01 volumio startx[974]: (==) Log file: "/var/log/Xorg.0.log", Time: Thu Feb 12 13:17:01 2026 Feb 12 13:17:01 volumio startx[974]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Feb 12 13:17:01 volumio volumio-streaming-daemon[972]: ############################ Feb 12 13:17:01 volumio volumio-streaming-daemon[972]: # Volumio Streaming Daemon # Feb 12 13:17:01 volumio volumio-streaming-daemon[972]: # Running on port 7777 # Feb 12 13:17:01 volumio volumio-streaming-daemon[972]: ############################ Feb 12 13:17:01 volumio volumio-streaming-daemon[972]: Checking variant in static cache Feb 12 13:17:01 volumio volumio-streaming-daemon[972]: Enabled services: Feb 12 13:17:01 volumio volumio-streaming-daemon[972]: 1) tidal Feb 12 13:17:01 volumio volumio-streaming-daemon[972]: 2) qobuz Feb 12 13:17:01 volumio startx[974]: modprobe: FATAL: Module g2d_23 not found. Feb 12 13:17:03 volumio kernel: RTL871X: nolinked power save enter Feb 12 13:17:04 volumio volumio[971]: info: ------------------------------------------- Feb 12 13:17:04 volumio volumio[971]: info: ----- Volumio2 ---- Feb 12 13:17:04 volumio volumio[971]: info: ------------------------------------------- Feb 12 13:17:04 volumio volumio[971]: info: ----- System startup ---- Feb 12 13:17:04 volumio volumio[971]: info: ------------------------------------------- Feb 12 13:17:04 volumio volumio[971]: info: MYVOLUMIO Environment detected Feb 12 13:17:04 volumio volumio[971]: info: Plugin folders cleanup Feb 12 13:17:04 volumio volumio[971]: info: Scanning into folder /volumio/app/plugins/ Feb 12 13:17:04 volumio volumio[971]: info: Scanning category audio_interface Feb 12 13:17:04 volumio volumio[971]: info: Scanning category miscellanea Feb 12 13:17:04 volumio volumio[971]: info: Scanning category music_service Feb 12 13:17:04 volumio volumio[971]: info: Scanning category plugins.json Feb 12 13:17:04 volumio volumio[971]: info: Scanning category system_controller Feb 12 13:17:04 volumio volumio[971]: info: Scanning category user_interface Feb 12 13:17:04 volumio volumio[971]: info: Scanning into folder /data/plugins/ Feb 12 13:17:04 volumio volumio[971]: info: Scanning category miscellanea Feb 12 13:17:04 volumio volumio[971]: info: Scanning category music_service Feb 12 13:17:04 volumio volumio[971]: info: Plugin folders cleanup completed Feb 12 13:17:04 volumio volumio[971]: info: ------------------------------------------- Feb 12 13:17:04 volumio volumio[971]: info: ----- Core plugins startup ---- Feb 12 13:17:04 volumio volumio[971]: info: ------------------------------------------- Feb 12 13:17:04 volumio volumio[971]: info: Loading plugins from folder /volumio/app/plugins/ Feb 12 13:17:04 volumio volumio[971]: info: Adding plugin upnp to MyMusic Plugins Feb 12 13:17:04 volumio volumio[971]: info: Adding plugin airplay_emulation to MyMusic Plugins Feb 12 13:17:04 volumio volumio[971]: info: Adding plugin upnp_browser to MyMusic Plugins Feb 12 13:17:04 volumio volumio[971]: info: Loading plugins from folder /data/plugins/ Feb 12 13:17:04 volumio volumio[971]: info: Loading plugin "system"... Feb 12 13:17:04 volumio volumio[971]: info: Loading plugin "appearance"... Feb 12 13:17:05 volumio volumio-remote-updater[699]: [2026-02-12 13:17:05] [info] Error getting remote endpoint: system:9 (Bad file descriptor) Feb 12 13:17:05 volumio volumio-remote-updater[699]: [2026-02-12 13:17:05] [info] asio async_shutdown error: system:9 (Bad file descriptor) Feb 12 13:17:05 volumio volumio-remote-updater[699]: [2026-02-12 13:17:05] [error] handle_connect error: Timer Expired Feb 12 13:17:07 volumio volumio[971]: info: Loading plugin "network"... Feb 12 13:17:07 volumio volumio[971]: info: Loading plugin "services"... Feb 12 13:17:07 volumio volumio[971]: info: Loading plugin "alsa_controller"... Feb 12 13:17:07 volumio volumio[971]: info: Loading plugin "wizard"... Feb 12 13:17:07 volumio volumio[971]: info: Loading plugin "volumio_command_line_client"... Feb 12 13:17:07 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 13:17:07 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 13:17:07 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 13:17:07 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 13:17:07 volumio volumio[971]: info: Loading plugin "upnp"... Feb 12 13:17:07 volumio volumio[971]: info: [1770902227881] Starting Upmpd Daemon Feb 12 13:17:07 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 13:17:07 volumio volumio[971]: info: Loading plugin "my_music"... Feb 12 13:17:07 volumio volumio[971]: info: Loading plugin "mpd"... Feb 12 13:17:08 volumio volumio[971]: info: Loading plugin "upnp_browser"... Feb 12 13:17:09 volumio volumio[971]: info: Loading plugin "networkfs"... Feb 12 13:17:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 13:17:10 volumio volumio[971]: info: Loading plugin "alarm-clock"... Feb 12 13:17:10 volumio volumio[971]: info: Loading plugin "airplay_emulation"... Feb 12 13:17:10 volumio volumio[971]: info: Starting Shairport Sync Feb 12 13:17:10 volumio volumio[971]: info: Loading plugin "last_100"... Feb 12 13:17:10 volumio volumio[971]: info: Loading plugin "webradio"... Feb 12 13:17:10 volumio volumio[971]: info: Loading plugin "i2s_dacs"... Feb 12 13:17:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 13:17:10 volumio volumio[971]: info: Loading plugin "volumiodiscovery"... Feb 12 13:17:10 volumio volumio[971]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 13:17:10 volumio node[971]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Feb 12 13:17:10 volumio volumio[971]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 13:17:10 volumio node[971]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 13:17:10 volumio volumio[971]: *** WARNING *** For more information see Feb 12 13:17:10 volumio node[971]: *** WARNING *** For more information see Feb 12 13:17:10 volumio node[971]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 13:17:10 volumio node[971]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 13:17:10 volumio node[971]: *** WARNING *** For more information see Feb 12 13:17:10 volumio volumio[971]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Feb 12 13:17:10 volumio volumio[971]: *** WARNING *** Please fix your application to use the native API of Avahi! Feb 12 13:17:10 volumio volumio[971]: *** WARNING *** For more information see Feb 12 13:17:10 volumio volumio[971]: info: Applying required configuration parameters for plugin volumiodiscovery Feb 12 13:17:10 volumio volumio[971]: Discovery: StartAdv! undefined Feb 12 13:17:10 volumio volumio[971]: Discovery: Started advertising... Volumio - undefined Feb 12 13:17:10 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Feb 12 13:17:10 volumio volumio[971]: info: Loading plugin "autostart"... Feb 12 13:17:12 volumio volumio[971]: info: Applying required configuration parameters for plugin autostart Feb 12 13:17:12 volumio volumio[971]: info: Loading plugin "outputs"... Feb 12 13:17:12 volumio volumio[971]: info: Loading plugin "albumart"... Feb 12 13:17:12 volumio volumio[971]: info: Plugin example_plugin is not enabled Feb 12 13:17:12 volumio volumio[971]: info: Loading plugin "updater_comm"... Feb 12 13:17:12 volumio volumio[971]: info: Plugin mpdemulation is not enabled Feb 12 13:17:12 volumio volumio[971]: info: Loading plugin "rest_api"... Feb 12 13:17:12 volumio volumio[971]: info: Loading plugin "websocket"... Feb 12 13:17:12 volumio volumio[971]: info: Loading plugin "touch_display"... Feb 12 13:17:12 volumio volumio[971]: info: Loading plugin "spop"... Feb 12 13:17:12 volumio volumio[971]: Forking 3 albumart workers Feb 12 13:17:12 volumio volumio-remote-updater[699]: [2026-02-12 13:17:12] [connect] Successful connection Feb 12 13:17:13 volumio smbd[1133]: [2026/02/12 13:17:13.184521, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 12 13:17:13 volumio smbd[1133]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 12 13:17:13 volumio smbd[1140]: [2026/02/12 13:17:13.253828, 0] ../lib/util/debug.c:603(reopen_logs_internal) Feb 12 13:17:13 volumio smbd[1140]: Unable to open new log file '/var/log/samba/log.smbd': No such file or directory Feb 12 13:17:13 volumio volumio[971]: Starting albumart workers Feb 12 13:17:13 volumio volumio[971]: Starting albumart workers Feb 12 13:17:13 volumio volumio[971]: Starting albumart workers Feb 12 13:17:15 volumio volumio[971]: info: Applying required configuration parameters for plugin spop Feb 12 13:17:15 volumio volumio[971]: info: ___________ START PLUGINS ___________ Feb 12 13:17:15 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 13:17:15 volumio volumio[971]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 13:17:15 volumio volumio[971]: info: [1770902235305] CoreMusicLibrary::Adding element Media Servers Feb 12 13:17:15 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 13:17:15 volumio volumio[971]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 13:17:15 volumio volumio[971]: info: [1770902235336] CoreMusicLibrary::Adding element Last_100 Feb 12 13:17:15 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 13:17:15 volumio volumio[971]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Feb 12 13:17:15 volumio volumio[971]: info: [1770902235339] CoreMusicLibrary::Adding element Webradio Feb 12 13:17:15 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 13:17:15 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 13:17:15 volumio sudo[1150]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Feb 12 13:17:15 volumio volumio[971]: info: ------------------------------------------- Feb 12 13:17:15 volumio volumio[971]: info: ----- MyVolumio plugins startup ---- Feb 12 13:17:15 volumio volumio[971]: info: ------------------------------------------- Feb 12 13:17:15 volumio volumio[971]: info: Loading plugins from folder /myvolumio/plugins Feb 12 13:17:15 volumio volumio[971]: info: Loading plugins from folder /data/myvolumio/plugins Feb 12 13:17:15 volumio sudo[1152]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start spop.service Feb 12 13:17:15 volumio volumio[971]: info: Loading plugin "streaming_services"... Feb 12 13:17:15 volumio sudo[1150]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:15 volumio sudo[1152]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:15 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked executable. Please remove executable permission bits. Proceeding anyway. Feb 12 13:17:15 volumio systemd[1]: Configuration file /lib/systemd/system/spop.service is marked world-writable. Please remove world writability permission bits. Proceeding anyway. Feb 12 13:17:15 volumio systemd[1]: Starting Spotify Daemon... Feb 12 13:17:15 volumio systemd[1]: Started Spotify Daemon. Feb 12 13:17:15 volumio systemd[1]: Started Start Volumio Kiosk. Feb 12 13:17:15 volumio sudo[1152]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:15 volumio sudo[1150]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:15 volumio volumio[1161]: 2026-02-12 13:17:15 [SPTF] 13:17:15.625 I [offline_authorizer.cpp:297] Unable to login offline: no such user Feb 12 13:17:15 volumio volumio[1161]: 2026-02-12 13:17:15 [SPTF] 13:17:15.627 I [ap:1752] Connecting to AP ap.spotify.com:4070 Feb 12 13:17:15 volumio volumio[1161]: 2026-02-12 13:17:15 [SPTF] 13:17:15.685 I [ap:1226] Connected to AP: 34.158.1.133:4070 Feb 12 13:17:15 volumio volumio[1161]: 2026-02-12 13:17:15 [SPTF] 13:17:15.780 E [ap:1694] AP Socket Error: Software caused connection abort (103) Feb 12 13:17:15 volumio volumio[1161]: 2026-02-12 13:17:15 [SPTF] 13:17:15.781 E [ap:3953] Connection error: 112 Feb 12 13:17:15 volumio volumio[1161]: 2026-02-12 13:17:15 [SPTF] 13:17:15.781 I [ap:1752] Connecting to AP ap.spotify.com:443 Feb 12 13:17:15 volumio volumio[971]: info: Loading plugin "my_volumio"... Feb 12 13:17:15 volumio volumio[1161]: 2026-02-12 13:17:15 [SPTF] 13:17:15.813 E [ap:1694] AP Socket Error: Connection refused (111) Feb 12 13:17:15 volumio volumio[1161]: 2026-02-12 13:17:15 [SPTF] 13:17:15.813 E [ap:3953] Connection error: 101 Feb 12 13:17:15 volumio volumio[1161]: 2026-02-12 13:17:15 [SPTF] 13:17:15.813 I [ap:1752] Connecting to AP ap.spotify.com:80 Feb 12 13:17:15 volumio volumio[1161]: 2026-02-12 13:17:15 [SPTF] 13:17:15.846 I [ap:1226] Connected to AP: 34.158.1.133:80 Feb 12 13:17:15 volumio volumio[1161]: 2026-02-12 13:17:15 [SPTF] 13:17:15.954 E [ap:1694] AP Socket Error: Software caused connection abort (103) Feb 12 13:17:15 volumio volumio[1161]: 2026-02-12 13:17:15 [SPTF] 13:17:15.954 E [ap:3953] Connection error: 112 Feb 12 13:17:15 volumio volumio[1161]: 2026-02-12 13:17:15 [SPTF] 13:17:15.955 I [ap:1752] Connecting to AP ap.spotify.com:4070 Feb 12 13:17:15 volumio volumio[1161]: 2026-02-12 13:17:15 [SPTF] 13:17:15.986 I [ap:1226] Connected to AP: 34.158.1.133:4070 Feb 12 13:17:16 volumio volumio[1161]: 2026-02-12 13:17:16 [SPTF] 13:17:16.105 E [ap:1694] AP Socket Error: Software caused connection abort (103) Feb 12 13:17:16 volumio volumio[1161]: 2026-02-12 13:17:16 [SPTF] 13:17:16.106 E [ap:3953] Connection error: 112 Feb 12 13:17:16 volumio volumio[1161]: 2026-02-12 13:17:16 [SPTF] 13:17:16.106 I [ap:1752] Connecting to AP ap.spotify.com:443 Feb 12 13:17:16 volumio volumio[1161]: 2026-02-12 13:17:16 [SPTF] 13:17:16.154 I [ap:1226] Connected to AP: 34.158.1.133:443 Feb 12 13:17:16 volumio volumio[1161]: 2026-02-12 13:17:16 [SPTF] 13:17:16.380 E [ap:1694] AP Socket Error: Software caused connection abort (103) Feb 12 13:17:16 volumio volumio[1161]: 2026-02-12 13:17:16 [SPTF] 13:17:16.381 E [ap:3953] Connection error: 112 Feb 12 13:17:16 volumio volumio[1161]: 2026-02-12 13:17:16 [SPTF] 13:17:16.381 I [ap:1752] Connecting to AP ap.spotify.com:80 Feb 12 13:17:16 volumio volumio[1161]: 2026-02-12 13:17:16 [SPTF] 13:17:16.414 I [ap:1226] Connected to AP: 34.158.1.133:80 Feb 12 13:17:16 volumio volumio[971]: info: Streaming services startup Feb 12 13:17:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 13:17:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 13:17:16 volumio volumio[971]: info: Starting MyVolumio Remote Streaming Endpoints Feb 12 13:17:16 volumio sudo[1172]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Feb 12 13:17:16 volumio sudo[1172]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:16 volumio systemd[1]: Stopping Volumio Streaming Daemon... Feb 12 13:17:16 volumio volumio[971]: info: Loading i18n strings for locale de Feb 12 13:17:16 volumio systemd[1]: Starting Volumio Streaming Daemon... Feb 12 13:17:16 volumio systemd[1]: Started Volumio Streaming Daemon. Feb 12 13:17:16 volumio sudo[1172]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:16 volumio volumio[971]: Updating browse sources language Feb 12 13:17:16 volumio volumio-streaming-daemon[1178]: ############################ Feb 12 13:17:16 volumio volumio-streaming-daemon[1178]: # Volumio Streaming Daemon # Feb 12 13:17:16 volumio volumio-streaming-daemon[1178]: # Running on port 7777 # Feb 12 13:17:16 volumio volumio-streaming-daemon[1178]: ############################ Feb 12 13:17:16 volumio volumio-streaming-daemon[1178]: Checking variant in static cache Feb 12 13:17:16 volumio volumio-streaming-daemon[1178]: Enabled services: Feb 12 13:17:16 volumio volumio-streaming-daemon[1178]: 1) tidal Feb 12 13:17:16 volumio volumio-streaming-daemon[1178]: 2) qobuz Feb 12 13:17:16 volumio volumio[971]: Cannot find translation for sourceMedia Servers Feb 12 13:17:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 13:17:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 13:17:16 volumio volumio[971]: info: CoreCommandRouter::initPlayerControls Feb 12 13:17:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 13:17:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getAlsaCards Feb 12 13:17:16 volumio volumio[1161]: 2026-02-12 13:17:16 [SPTF] 13:17:16.614 E [ap:1694] AP Socket Error: Software caused connection abort (103) Feb 12 13:17:16 volumio volumio[1161]: 2026-02-12 13:17:16 [SPTF] 13:17:16.614 E [ap:3953] Connection error: 112 Feb 12 13:17:16 volumio volumio[1161]: 2026-02-12 13:17:16 [WARN] Login failed: Cannot connect to Spotify Feb 12 13:17:16 volumio volumio[1161]: 2026-02-12 13:17:16 [ERR ] Could not get the playlist container. Feb 12 13:17:16 volumio systemd[1]: spop.service: main process exited, code=killed, status=5/TRAP Feb 12 13:17:16 volumio systemd[1]: Unit spop.service entered failed state. Feb 12 13:17:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 13:17:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 13:17:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 13:17:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 13:17:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 13:17:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 12 13:17:16 volumio volumio[971]: info: BOOT COMPLETED Feb 12 13:17:16 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 13:17:16 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 12 13:17:21 volumio volumio[971]: Playing WAVE '/volumio/app/startup.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Feb 12 13:17:21 volumio volumio[971]: info: CoreCommandRouter::Close All Modals sent Feb 12 13:17:21 volumio volumio[971]: info: CoreCommandRouter::Close All Modals sent Feb 12 13:17:21 volumio volumio[971]: Express server listening on port 3000 Feb 12 13:17:21 volumio volumio[971]: Volumio Calling Home Feb 12 13:17:22 volumio volumio[971]: info: MyVolumio not started Feb 12 13:17:22 volumio volumio[971]: info: CoreStateMachine::resetVolumioState Feb 12 13:17:22 volumio volumio[971]: info: CoreStateMachine::getcurrentVolume Feb 12 13:17:22 volumio volumio[971]: info: CoreCommandRouter::volumioRetrievevolume Feb 12 13:17:22 volumio sudo[1203]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 13:17:22 volumio sudo[1203]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:22 volumio sudo[1203]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:22 volumio volumio[971]: info: Setting Device type: Raspberry PI Feb 12 13:17:22 volumio volumio[971]: info: SpopD Daemon Started Feb 12 13:17:22 volumio volumio[971]: info: Volumio Kiosk Started Feb 12 13:17:22 volumio volumio[971]: info: Kiosk Started Feb 12 13:17:22 volumio volumio[971]: info: MPD running with PID723 ,establishing connection Feb 12 13:17:22 volumio volumio[971]: info: VolumeController:: Volume=100 Mute =false Feb 12 13:17:22 volumio volumio[971]: info: CoreStateMachine::pushState Feb 12 13:17:22 volumio volumio[971]: info: CoreStateMachine::getState Feb 12 13:17:22 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Feb 12 13:17:22 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Feb 12 13:17:22 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:17:22 volumio volumio[971]: info: Feb 12 13:17:22 volumio volumio[971]: ---------------------------- Client requests Volume 80 Feb 12 13:17:22 volumio volumio[971]: info: VolumeController::SetAlsaVolume80 Feb 12 13:17:22 volumio volumio[971]: info: CoreStateMachine::pushState Feb 12 13:17:22 volumio volumio[971]: info: CoreStateMachine::getState Feb 12 13:17:22 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Feb 12 13:17:22 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Feb 12 13:17:22 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:17:22 volumio volumio[971]: info: Reloading queue from file Feb 12 13:17:22 volumio volumio[971]: info: Feb 12 13:17:22 volumio volumio[971]: info: message= [50@0] {} No such directory, stack=Error: [50@0] {} No such directory Feb 12 13:17:22 volumio volumio[971]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:14) Feb 12 13:17:22 volumio volumio[971]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:10) Feb 12 13:17:22 volumio volumio[971]: at emitOne (events.js:116:13) Feb 12 13:17:22 volumio volumio[971]: at Socket.emit (events.js:211:7) Feb 12 13:17:22 volumio volumio[971]: at addChunk (_stream_readable.js:263:12) Feb 12 13:17:22 volumio volumio[971]: at readableAddChunk (_stream_readable.js:246:13) Feb 12 13:17:22 volumio volumio[971]: at Socket.Readable.push (_stream_readable.js:208:10) Feb 12 13:17:22 volumio volumio[971]: at Pipe.onread (net.js:607:20) Feb 12 13:17:22 volumio volumio[971]: info: Setting volume on startup at 80 Feb 12 13:17:23 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Feb 12 13:17:23 volumio avahi-daemon[719]: Invalid response packet from host 192.168.178.1. Feb 12 13:17:23 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Feb 12 13:17:23 volumio volumio[971]: info: CoreStateMachine::setRepeat null single undefined Feb 12 13:17:23 volumio volumio[971]: info: CoreStateMachine::pushState Feb 12 13:17:23 volumio volumio[971]: info: CoreStateMachine::getState Feb 12 13:17:23 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Feb 12 13:17:23 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Feb 12 13:17:23 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:17:23 volumio volumio[971]: info: CoreStateMachine::setRandom null Feb 12 13:17:23 volumio volumio[971]: info: CoreStateMachine::pushState Feb 12 13:17:23 volumio volumio[971]: info: CoreStateMachine::getState Feb 12 13:17:23 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Feb 12 13:17:23 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Feb 12 13:17:23 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:17:23 volumio sudo[1218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Feb 12 13:17:23 volumio sudo[1220]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay Feb 12 13:17:23 volumio sudo[1218]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:23 volumio sudo[1220]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:23 volumio sudo[1226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Feb 12 13:17:23 volumio systemd[1]: Starting ShairportSync AirTunes receiver... Feb 12 13:17:23 volumio systemd[1]: Started ShairportSync AirTunes receiver. Feb 12 13:17:23 volumio sudo[1218]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:23 volumio sudo[1220]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:23 volumio sudo[1226]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:23 volumio shairport-sync[1233]: Successful Startup Feb 12 13:17:23 volumio volumio[971]: Discovery: adding 71b92637-5060-4e97-81af-21dc7a2f409f Feb 12 13:17:23 volumio volumio[971]: info: mDNS: Found device Volumio Feb 12 13:17:23 volumio systemd[1]: Starting UPnP Renderer front-end to MPD... Feb 12 13:17:23 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Feb 12 13:17:23 volumio sudo[1226]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:23 volumio sudo[1250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 13:17:23 volumio sudo[1250]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:23 volumio sudo[1250]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:23 volumio volumio[971]: info: Shairport-Sync Started Feb 12 13:17:23 volumio volumio[971]: Error adding Membership: Error: addMembership EINVAL Feb 12 13:17:23 volumio volumio[971]: info: Upmpdcli Daemon Started Feb 12 13:17:23 volumio volumio[971]: info: Shairport-Sync Started Feb 12 13:17:23 volumio volumio[971]: info: CoreCommandRouter::volumioGetState Feb 12 13:17:23 volumio volumio[971]: info: CoreStateMachine::getState Feb 12 13:17:23 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Feb 12 13:17:23 volumio sudo[1259]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 13:17:23 volumio sudo[1259]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:23 volumio sudo[1259]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:24 volumio volumio-streaming-daemon[1178]: Checking variant in static cache Feb 12 13:17:24 volumio volumio[971]: info: Fetching Streaming Services browse cache Feb 12 13:17:24 volumio volumio[971]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesQOBUZ Feb 12 13:17:24 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 13:17:24 volumio volumio[971]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesTIDAL Feb 12 13:17:24 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 13:17:27 volumio volumio[971]: info: Connecting to daemon Feb 12 13:17:27 volumio volumio[971]: info: SPOP command error: Feb 12 13:17:27 volumio volumio[971]: info: message=connect ECONNREFUSED 127.0.0.1:6602, stack=Error: connect ECONNREFUSED 127.0.0.1:6602 Feb 12 13:17:27 volumio volumio[971]: at Object._errnoException (util.js:1022:11) Feb 12 13:17:27 volumio volumio[971]: at _exceptionWithHostPort (util.js:1044:20) Feb 12 13:17:27 volumio volumio[971]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14), code=ECONNREFUSED, errno=ECONNREFUSED, syscall=connect, address=127.0.0.1, port=6602 Feb 12 13:17:27 volumio volumio[971]: info: SPOP status error: Feb 12 13:17:27 volumio volumio[971]: info: message=connect ECONNREFUSED 127.0.0.1:6602, stack=Error: connect ECONNREFUSED 127.0.0.1:6602 Feb 12 13:17:27 volumio volumio[971]: at Object._errnoException (util.js:1022:11) Feb 12 13:17:27 volumio volumio[971]: at _exceptionWithHostPort (util.js:1044:20) Feb 12 13:17:27 volumio volumio[971]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14), code=ECONNREFUSED, errno=ECONNREFUSED, syscall=connect, address=127.0.0.1, port=6602 Feb 12 13:17:27 volumio volumio-remote-updater[699]: [2026-02-12 13:17:27] [connect] Successful connection Feb 12 13:17:27 volumio volumio-remote-updater[699]: [2026-02-12 13:17:27] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.5.1" /socket.io/?EIO=4&transport=websocket&t=1770902247 101 Feb 12 13:17:27 volumio volumio[971]: info: Spotify access token expires at 1770905847531 Feb 12 13:17:27 volumio volumio[971]: info: Spotify access token is BQDkJ6J9S7pm3x87F0zpjC7uKCnsn0ByXdFSv-WfEfEqThm4kF_OsRIjj4GKU17ZPrynHKUsJmqOJqhCCZlw9s6UKXWR-pfuF-S1ZPsxeXGt2-Y8FfieWF60jtz3wy3I8nQI6dy9TYc Feb 12 13:17:27 volumio volumio[971]: info: Spotify credentials grant success Feb 12 13:17:29 volumio volumio[971]: info: CoreCommandRouter::volumioGetVisibleSources Feb 12 13:17:29 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Feb 12 13:17:29 volumio volumio[971]: info: CoreCommandRouter::volumioGetState Feb 12 13:17:29 volumio volumio[971]: info: CoreStateMachine::getState Feb 12 13:17:29 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Feb 12 13:17:29 volumio sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 13:17:29 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Feb 12 13:17:29 volumio sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:29 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Feb 12 13:17:29 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Feb 12 13:17:29 volumio sudo[1339]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:29 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Feb 12 13:17:29 volumio volumio[971]: info: Listing playlists Feb 12 13:17:29 volumio sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 13:17:29 volumio sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:29 volumio sudo[1348]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:29 volumio volumio[971]: info: VolumeController::SetAlsaVolume0 Feb 12 13:17:29 volumio volumio[971]: info: CoreStateMachine::pushState Feb 12 13:17:29 volumio volumio[971]: info: CoreStateMachine::getState Feb 12 13:17:29 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Feb 12 13:17:29 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Feb 12 13:17:29 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:17:29 volumio sudo[1359]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 13:17:29 volumio sudo[1359]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:29 volumio sudo[1359]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:32 volumio volumio[971]: info: AutoStart - getting queue Feb 12 13:17:32 volumio volumio[971]: info: CoreCommandRouter::volumioGetQueue Feb 12 13:17:32 volumio volumio[971]: info: CoreStateMachine::getQueue Feb 12 13:17:32 volumio volumio[971]: info: CorePlayQueue::getQueue Feb 12 13:17:32 volumio volumio[971]: info: AutoStart - start playing -> queue is not empty Feb 12 13:17:32 volumio volumio[971]: info: CoreCommandRouter::volumioPlay Feb 12 13:17:32 volumio volumio[971]: UNSET VOLATILE Feb 12 13:17:32 volumio volumio[971]: info: CoreStateMachine::play index undefined Feb 12 13:17:32 volumio volumio[971]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 13:17:32 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Feb 12 13:17:32 volumio volumio[971]: info: CoreStateMachine::startPlaybackTimer Feb 12 13:17:32 volumio volumio[971]: info: CorePlayQueue::getTrack 0 Feb 12 13:17:32 volumio volumio[971]: info: [1770902252108] ControllerWebradio::clearAddPlayTrack Feb 12 13:17:32 volumio volumio[971]: info: ControllerMpd::sendMpdCommand stop Feb 12 13:17:32 volumio volumio[971]: info: sending command... Feb 12 13:17:32 volumio volumio[971]: info: parsing response... Feb 12 13:17:32 volumio volumio[971]: info: ControllerMpd::sendMpdCommand clear Feb 12 13:17:32 volumio volumio[971]: info: sending command... Feb 12 13:17:32 volumio volumio[971]: info: Feb 12 13:17:32 volumio volumio[971]: ---------------------------- MPD announces system playlist update Feb 12 13:17:32 volumio volumio[971]: info: Ignoring MPD Status Update Feb 12 13:17:32 volumio volumio[971]: info: parsing response... Feb 12 13:17:32 volumio volumio[971]: info: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=1697270" Feb 12 13:17:32 volumio volumio[971]: info: sending command... Feb 12 13:17:32 volumio volumio[971]: info: Feb 12 13:17:32 volumio volumio[971]: info: ------------------------------ 12ms Feb 12 13:17:32 volumio volumio[971]: info: parsing response... Feb 12 13:17:32 volumio volumio[971]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 13:17:32 volumio volumio[971]: info: ControllerMpd::sendMpdCommand play Feb 12 13:17:32 volumio volumio[971]: info: sending command... Feb 12 13:17:32 volumio volumio[971]: info: parsing response... Feb 12 13:17:32 volumio volumio[971]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.565&uuid=770a48de7a5fb783d87eb39bd0c178d5" http://updates.volumio.org:7070/downloader-v1/track-device Feb 12 13:17:32 volumio volumio[971]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 12 13:17:32 volumio volumio[971]: Dload Upload Total Spent Left Speed Feb 12 13:17:32 volumio volumio[971]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 12 13:17:32 volumio volumio[971]: retrying in 5 seconds, trial 0 Feb 12 13:17:32 volumio volumio[971]: Volumio Calling Home Feb 12 13:17:40 volumio volumio[971]: info: CoreCommandRouter::volumioGetQueue Feb 12 13:17:40 volumio volumio[971]: info: CoreStateMachine::getQueue Feb 12 13:17:40 volumio volumio[971]: info: CorePlayQueue::getQueue Feb 12 13:17:42 volumio volumio[971]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.565&uuid=770a48de7a5fb783d87eb39bd0c178d5" http://updates.volumio.org:7070/downloader-v1/track-device Feb 12 13:17:42 volumio volumio[971]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 12 13:17:42 volumio volumio[971]: Dload Upload Total Spent Left Speed Feb 12 13:17:42 volumio volumio[971]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 12 13:17:42 volumio volumio[971]: retrying in 5 seconds, trial 1 Feb 12 13:17:42 volumio volumio[971]: Volumio Calling Home Feb 12 13:17:43 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 12 13:17:45 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Feb 12 13:17:45 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 13:17:45 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: webradio , handleBrowseUri Feb 12 13:17:48 volumio volumio[971]: info: CoreCommandRouter::volumioAddQueueItems Feb 12 13:17:48 volumio volumio[971]: info: CoreStateMachine::addQueueItems Feb 12 13:17:48 volumio volumio[971]: info: CorePlayQueue::addQueueItems Feb 12 13:17:48 volumio volumio[971]: info: Adding Item to queue: http://streams.rpr1.de/rpr-trier-128-mp3?usid=0-0-H-M-D-06 Feb 12 13:17:48 volumio volumio[971]: info: CorePlayQueue::saveQueue Feb 12 13:17:48 volumio volumio[971]: info: CoreCommandRouter::volumioPushQueue Feb 12 13:17:48 volumio volumio[971]: info: CoreStateMachine::updateTrackBlock Feb 12 13:17:48 volumio volumio[971]: info: CorePlayQueue::getTrackBlock Feb 12 13:17:48 volumio volumio[971]: info: CoreCommandRouter::volumioPlay Feb 12 13:17:48 volumio volumio[971]: UNSET VOLATILE Feb 12 13:17:48 volumio volumio[971]: info: CoreStateMachine::play index 64 Feb 12 13:17:48 volumio volumio[971]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 13:17:48 volumio volumio[971]: info: CoreStateMachine::stop Feb 12 13:17:48 volumio volumio[971]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 13:17:48 volumio volumio[971]: UNSET VOLATILE Feb 12 13:17:48 volumio volumio[971]: info: CoreStateMachine::play index undefined Feb 12 13:17:48 volumio volumio[971]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 13:17:48 volumio volumio[971]: info: CorePlayQueue::getTrack 64 Feb 12 13:17:48 volumio volumio[971]: info: CoreStateMachine::startPlaybackTimer Feb 12 13:17:48 volumio volumio[971]: info: CorePlayQueue::getTrack 64 Feb 12 13:17:48 volumio volumio[971]: info: [1770902268403] ControllerWebradio::clearAddPlayTrack Feb 12 13:17:48 volumio volumio[971]: info: ControllerMpd::sendMpdCommand stop Feb 12 13:17:48 volumio volumio[971]: info: sending command... Feb 12 13:17:48 volumio volumio[971]: info: parsing response... Feb 12 13:17:48 volumio volumio[971]: info: ControllerMpd::sendMpdCommand clear Feb 12 13:17:48 volumio volumio[971]: info: sending command... Feb 12 13:17:48 volumio volumio[971]: info: Feb 12 13:17:48 volumio volumio[971]: ---------------------------- MPD announces system playlist update Feb 12 13:17:48 volumio volumio[971]: info: Ignoring MPD Status Update Feb 12 13:17:48 volumio volumio[971]: info: parsing response... Feb 12 13:17:48 volumio volumio[971]: info: ControllerMpd::sendMpdCommand load "http://streams.rpr1.de/rpr-trier-128-mp3?usid=0-0-H-M-D-06" Feb 12 13:17:48 volumio volumio[971]: info: sending command... Feb 12 13:17:48 volumio volumio[971]: info: Feb 12 13:17:48 volumio volumio[971]: info: ------------------------------ 8ms Feb 12 13:17:49 volumio volumio[971]: info: ControllerMpd::sendMpdCommand add "http://streams.rpr1.de/rpr-trier-128-mp3?usid=0-0-H-M-D-06" Feb 12 13:17:49 volumio volumio[971]: info: sending command... Feb 12 13:17:49 volumio volumio[971]: info: Feb 12 13:17:49 volumio volumio[971]: ---------------------------- MPD announces system playlist update Feb 12 13:17:49 volumio volumio[971]: info: Ignoring MPD Status Update Feb 12 13:17:49 volumio volumio[971]: info: parsing response... Feb 12 13:17:49 volumio volumio[971]: info: CoreStateMachine::setConsumeUpdateService mpd Feb 12 13:17:49 volumio volumio[971]: info: ControllerMpd::sendMpdCommand play Feb 12 13:17:49 volumio volumio[971]: info: sending command... Feb 12 13:17:49 volumio volumio[971]: info: ------------------------------ 14ms Feb 12 13:17:49 volumio volumio[971]: info: parsing response... Feb 12 13:17:50 volumio volumio[971]: info: Feb 12 13:17:50 volumio volumio[971]: ---------------------------- MPD announces state update: player Feb 12 13:17:50 volumio kernel: pcm512x 1-004d: No SCLK, using BCLK: -2 Feb 12 13:17:50 volumio volumio[971]: info: ControllerMpd::getState Feb 12 13:17:50 volumio volumio[971]: info: ControllerMpd::sendMpdCommand status Feb 12 13:17:50 volumio volumio[971]: info: sending command... Feb 12 13:17:50 volumio volumio[971]: info: parsing response... Feb 12 13:17:50 volumio volumio[971]: info: ControllerMpd::parseState Feb 12 13:17:50 volumio volumio[971]: info: ControllerMpd::sendMpdCommand playlistinfo Feb 12 13:17:50 volumio volumio[971]: info: sending command... Feb 12 13:17:50 volumio volumio[971]: info: Feb 12 13:17:50 volumio volumio[971]: ---------------------------- MPD announces system playlist update Feb 12 13:17:50 volumio volumio[971]: info: Ignoring MPD Status Update Feb 12 13:17:50 volumio volumio[971]: info: Feb 12 13:17:50 volumio volumio[971]: ---------------------------- MPD announces state update: player Feb 12 13:17:50 volumio volumio[971]: info: ControllerMpd::getState Feb 12 13:17:50 volumio volumio[971]: info: ControllerMpd::sendMpdCommand status Feb 12 13:17:50 volumio volumio[971]: info: parsing response... Feb 12 13:17:50 volumio volumio[971]: info: sending command... Feb 12 13:17:50 volumio volumio[971]: info: ControllerMpd::parseTrackInfo Feb 12 13:17:50 volumio volumio[971]: info: ControllerMpd::pushState Feb 12 13:17:50 volumio volumio[971]: info: CoreCommandRouter::servicePushState Feb 12 13:17:50 volumio volumio[971]: info: CoreStateMachine::syncState Feb 12 13:17:50 volumio volumio[971]: info: CorePlayQueue::getTrack 64 Feb 12 13:17:50 volumio volumio[971]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"RPR1.Trier","artist":"RPR1.Trier","album":null,"uri":"http://streams.rpr1.de/rpr-trier-128-mp3?usid=0-0-H-M-D-06","trackType":""} Feb 12 13:17:50 volumio volumio[971]: info: CURRENT POSITION 64 Feb 12 13:17:50 volumio volumio[971]: info: CoreStateMachine::syncState stateService play Feb 12 13:17:50 volumio volumio[971]: info: CoreStateMachine::syncState currentStatus stop Feb 12 13:17:50 volumio volumio[971]: info: CoreStateMachine::pushState Feb 12 13:17:50 volumio volumio[971]: info: CoreStateMachine::getState Feb 12 13:17:50 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Feb 12 13:17:50 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:17:50 volumio sudo[1529]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 13:17:50 volumio sudo[1529]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:50 volumio volumio[971]: info: ------------------------------ 85ms Feb 12 13:17:50 volumio sudo[1529]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:50 volumio volumio[971]: info: ------------------------------ 75ms Feb 12 13:17:50 volumio volumio[971]: info: parsing response... Feb 12 13:17:50 volumio volumio[971]: info: ControllerMpd::parseState Feb 12 13:17:50 volumio volumio[971]: info: ControllerMpd::sendMpdCommand playlistinfo Feb 12 13:17:50 volumio volumio[971]: info: sending command... Feb 12 13:17:50 volumio volumio[971]: info: parsing response... Feb 12 13:17:50 volumio volumio[971]: info: ControllerMpd::parseTrackInfo Feb 12 13:17:50 volumio volumio[971]: info: ControllerMpd::pushState Feb 12 13:17:50 volumio volumio[971]: info: CoreCommandRouter::servicePushState Feb 12 13:17:50 volumio volumio[971]: info: CoreStateMachine::syncState Feb 12 13:17:50 volumio volumio[971]: info: CorePlayQueue::getTrack 64 Feb 12 13:17:50 volumio volumio[971]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"RPR1.Trier","artist":"RPR1.Trier","album":null,"uri":"http://streams.rpr1.de/rpr-trier-128-mp3?usid=0-0-H-M-D-06","trackType":""} Feb 12 13:17:50 volumio volumio[971]: info: CURRENT POSITION 64 Feb 12 13:17:50 volumio volumio[971]: info: CoreStateMachine::syncState stateService play Feb 12 13:17:50 volumio volumio[971]: info: CoreStateMachine::syncState currentStatus play Feb 12 13:17:50 volumio volumio[971]: info: Received an update from plugin. extracting info from payload Feb 12 13:17:50 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Feb 12 13:17:50 volumio volumio[971]: info: CoreStateMachine::pushState Feb 12 13:17:50 volumio volumio[971]: info: CoreStateMachine::getState Feb 12 13:17:50 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Feb 12 13:17:50 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:17:50 volumio volumio[971]: info: CoreStateMachine::pushState Feb 12 13:17:50 volumio volumio[971]: info: CoreStateMachine::getState Feb 12 13:17:50 volumio volumio[971]: info: CoreCommandRouter::volumioPushState Feb 12 13:17:50 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 13:17:50 volumio sudo[1538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 13:17:50 volumio sudo[1538]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:50 volumio volumio[971]: info: ------------------------------ 146ms Feb 12 13:17:50 volumio sudo[1541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 13:17:50 volumio sudo[1538]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:50 volumio sudo[1541]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:17:50 volumio sudo[1541]: pam_unix(sudo:session): session closed for user root Feb 12 13:17:52 volumio volumio[971]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=2.565&uuid=770a48de7a5fb783d87eb39bd0c178d5" http://updates.volumio.org:7070/downloader-v1/track-device Feb 12 13:17:52 volumio volumio[971]: % Total % Received % Xferd Average Speed Time Time Time Current Feb 12 13:17:52 volumio volumio[971]: Dload Upload Total Spent Left Speed Feb 12 13:17:52 volumio volumio[971]: 0 0 0 0 0 0 0 0 --:--:-- --:--:-- --:--:-- 0curl: (7) Failed to connect to updates.volumio.org port 7070: Connection refused Feb 12 13:17:52 volumio volumio[971]: retrying in 5 seconds, trial 2 Feb 12 13:17:52 volumio volumio[971]: Volumio Calling Home Feb 12 13:18:01 volumio volumio[971]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 12 13:18:01 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 12 13:18:03 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Feb 12 13:18:03 volumio sudo[1654]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Feb 12 13:18:03 volumio sudo[1654]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:18:03 volumio sudo[1654]: pam_unix(sudo:session): session closed for user root Feb 12 13:18:03 volumio sudo[1667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 12 13:18:03 volumio sudo[1667]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:18:03 volumio sudo[1667]: pam_unix(sudo:session): session closed for user root Feb 12 13:18:03 volumio sudo[1681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 12 13:18:03 volumio sudo[1681]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:18:03 volumio sudo[1681]: pam_unix(sudo:session): session closed for user root Feb 12 13:18:03 volumio sudo[1692]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Feb 12 13:18:03 volumio sudo[1692]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:18:03 volumio sudo[1692]: pam_unix(sudo:session): session closed for user root Feb 12 13:18:04 volumio sudo[1705]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Feb 12 13:18:04 volumio volumio[971]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Feb 12 13:18:04 volumio sudo[1705]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:18:04 volumio sudo[1709]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Feb 12 13:18:04 volumio sudo[1705]: pam_unix(sudo:session): session closed for user root Feb 12 13:18:04 volumio sudo[1709]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:18:04 volumio sudo[1709]: pam_unix(sudo:session): session closed for user root Feb 12 13:18:04 volumio sudo[1721]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Feb 12 13:18:04 volumio sudo[1721]: pam_unix(sudo:session): session opened for user root by (uid=0) Feb 12 13:18:06 volumio kernel: RTL871X: nolinked power save leave Feb 12 13:18:07 volumio kernel: RTL871X: nolinked power save enter Feb 12 13:18:07 volumio sudo[1721]: pam_unix(sudo:session): session closed for user root Feb 12 13:18:07 volumio volumio[971]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 13:18:07 volumio volumio[971]: TypeError: Cannot read property 'value' of undefined Feb 12 13:18:07 volumio volumio[971]: at Config.findProp (/volumio/node_modules/v-conf/index.js:92:27) Feb 12 13:18:07 volumio volumio[971]: at Config.has (/volumio/node_modules/v-conf/index.js:118:19) Feb 12 13:18:07 volumio volumio[971]: at ControllerNetwork.searchNetworkInConfig (/volumio/app/plugins/system_controller/network/index.js:375:18) Feb 12 13:18:07 volumio volumio[971]: at ControllerNetwork.enrichNetworks (/volumio/app/plugins/system_controller/network/index.js:363:28) Feb 12 13:18:07 volumio volumio[971]: at /volumio/app/plugins/system_controller/network/index.js:347:21 Feb 12 13:18:07 volumio volumio[971]: at /volumio/app/plugins/system_controller/network/lib/iwlist.js:164:10 Feb 12 13:18:07 volumio volumio[971]: at ChildProcess.exithandler (child_process.js:267:7) Feb 12 13:18:07 volumio volumio[971]: at emitTwo (events.js:126:13) Feb 12 13:18:07 volumio volumio[971]: at ChildProcess.emit (events.js:214:7) Feb 12 13:18:07 volumio volumio[971]: at maybeClose (internal/child_process.js:925:16) Feb 12 13:18:07 volumio volumio[971]: at Process.ChildProcess._handle.onexit (internal/child_process.js:209:5) Feb 12 13:18:08 volumio volumio[971]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 13:18:08 volumio sudo[1767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-02-12 13:17 Feb 12 13:18:08 volumio sudo[1767]: 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="f1b59d96a3893a42f8609371956ecba0438017d7" VOLUMIO_FE_VERSION="95791212f747b95a2cb050c16459a831bfe5c7e7" VOLUMIO_BE_VERSION="1c3c01aeeeb4cff8e6c9a4b6c34e1a558d1b237b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 12 19:57:25 CET 2019" VOLUMIO_VERSION="2.565" VOLUMIO_HARDWARE="pi" VOLUMIO_HASH="64b803aae2d00ba86d00d612c4abb38d"