Mar 24 17:51:12 volumio-club go-librespot[2000]: time="2026-03-24T17:51:12+01:00" level=trace msg="sent dealer ping" Mar 24 17:51:12 volumio-club go-librespot[2000]: time="2026-03-24T17:51:12+01:00" level=trace msg="received dealer pong" Mar 24 17:51:18 volumio-club volumio-remote-updater[869]: [2026-03-24 17:51:18] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Mar 24 17:51:23 volumio-club volumio-remote-updater[869]: [2026-03-24 17:51:23] [connect] Successful connection Mar 24 17:51:35 volumio-club volumio-remote-updater[869]: [2026-03-24 17:51:35] [connect] Successful connection Mar 24 17:51:38 volumio-club volumio5-onboarding[1850]: time=2026-03-24T17:51:38.715+01:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 24 17:51:38 volumio-club volumio[1335]: info: Checking if install.sh is present Mar 24 17:51:38 volumio-club volumio5-onboarding[1850]: time=2026-03-24T17:51:38.724+01:00 level=ERROR msg="failed reading message" error="websocket: close 1000 (normal)" Mar 24 17:51:38 volumio-club volumio[1335]: info: Discovery: Disconnected from remote: 192.168.0.183 Mar 24 17:51:38 volumio-club volumio[1335]: info: Executing install.sh Mar 24 17:51:38 volumio-club volumio[1335]: verbose: New Socket.io Connection to 192.168.0.198:3000 from 192.168.0.183 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 1 Mar 24 17:51:38 volumio-club sudo[10220]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/user_interface/now_playing/install.sh Mar 24 17:51:38 volumio-club sudo[10220]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:51:38 volumio-club volumio[1335]: verbose: New Socket.io Connection to 192.168.0.198 from 192.168.0.84 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:148.0) Gecko/20100101 Firefox/148.0 Engine version: 3 Transport: polling Total Clients: 2 Mar 24 17:51:38 volumio-club volumio[1335]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Mar 24 17:51:38 volumio-club volumio[1335]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Mar 24 17:51:38 volumio-club volumio[1335]: verbose: New Socket.io Connection to 192.168.0.198 from 192.168.0.84 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:148.0) Gecko/20100101 Firefox/148.0 Engine version: 3 Transport: polling Total Clients: 5 Mar 24 17:51:38 volumio-club volumio[1335]: verbose: New Socket.io Connection to 192.168.0.198:3000 from 192.168.0.183 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Mar 24 17:51:38 volumio-club su[10223]: (to volumio) root on none Mar 24 17:51:38 volumio-club su[10223]: pam_unix(su:session): session opened for user volumio(uid=1000) by (uid=0) Mar 24 17:51:38 volumio-club volumio[1335]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 7 Mar 24 17:51:38 volumio-club volumio-remote-updater[869]: [2026-03-24 17:51:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1774371095 101 Mar 24 17:51:38 volumio-club volumio[1335]: verbose: New Socket.io Connection to 192.168.0.198 from 192.168.0.84 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:148.0) Gecko/20100101 Firefox/148.0 Engine version: 3 Transport: polling Total Clients: 8 Mar 24 17:51:38 volumio-club volumio[1335]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Mar 24 17:51:38 volumio-club volumio[1335]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 9 Mar 24 17:51:38 volumio-club systemd[1]: Created slice user-1000.slice - User Slice of UID 1000. Mar 24 17:51:38 volumio-club systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000... Mar 24 17:51:38 volumio-club systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000. Mar 24 17:51:39 volumio-club systemd[1]: Starting user@1000.service - User Manager for UID 1000... Mar 24 17:51:39 volumio-club (systemd)[10225]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0) Mar 24 17:51:39 volumio-club systemd[10225]: Queued start job for default target default.target. Mar 24 17:51:39 volumio-club systemd[10225]: Created slice app.slice - User Application Slice. Mar 24 17:51:39 volumio-club systemd[10225]: Reached target paths.target - Paths. Mar 24 17:51:39 volumio-club systemd[10225]: Reached target timers.target - Timers. Mar 24 17:51:39 volumio-club systemd[10225]: Starting dbus.socket - D-Bus User Message Bus Socket... Mar 24 17:51:39 volumio-club systemd[10225]: Listening on dirmngr.socket - GnuPG network certificate management daemon. Mar 24 17:51:39 volumio-club systemd[10225]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers). Mar 24 17:51:39 volumio-club systemd[10225]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted). Mar 24 17:51:39 volumio-club systemd[10225]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation). Mar 24 17:51:39 volumio-club systemd[10225]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache. Mar 24 17:51:39 volumio-club volumio[1335]: info: Discovery: Connected to remote: 192.168.0.183 Mar 24 17:51:39 volumio-club systemd[10225]: Listening on dbus.socket - D-Bus User Message Bus Socket. Mar 24 17:51:39 volumio-club systemd[10225]: Reached target sockets.target - Sockets. Mar 24 17:51:39 volumio-club systemd[10225]: Reached target basic.target - Basic System. Mar 24 17:51:39 volumio-club systemd[1]: Started user@1000.service - User Manager for UID 1000. Mar 24 17:51:39 volumio-club systemd[10225]: Started mpris-proxy.service - Bluetooth mpris proxy. Mar 24 17:51:39 volumio-club systemd[10225]: Reached target default.target - Main User Target. Mar 24 17:51:39 volumio-club systemd[10225]: Startup finished in 514ms. Mar 24 17:51:39 volumio-club volumio[1335]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Mar 24 17:51:39 volumio-club systemd[1]: Started session-c1.scope - Session c1 of User volumio. Mar 24 17:51:39 volumio-club systemd[10225]: Created slice session.slice - User Core Session Slice. Mar 24 17:51:39 volumio-club systemd[10225]: Starting dbus.service - D-Bus User Message Bus... Mar 24 17:51:39 volumio-club systemd[10225]: Started dbus.service - D-Bus User Message Bus. Mar 24 17:51:39 volumio-club volumio[1335]: info: CoreCommandRouter::volumioGetState Mar 24 17:51:39 volumio-club volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Mar 24 17:51:39 volumio-club volumio[1335]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 24 17:51:39 volumio-club volumio[1335]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 12 Mar 24 17:51:39 volumio-club volumio[1335]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Mar 24 17:51:39 volumio-club volumio[1335]: info: CoreCommandRouter::volumioGetState Mar 24 17:51:40 volumio-club volumio[1335]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Mar 24 17:51:41 volumio-club volumio[1335]: verbose: New Socket.io Connection to 192.168.0.198:3000 from 192.168.0.183 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Mar 24 17:51:41 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Mar 24 17:51:41 volumio-club volumio[1335]: info: CoreCommandRouter::volumioGetState Mar 24 17:51:42 volumio-club go-librespot[2000]: time="2026-03-24T17:51:42+01:00" level=trace msg="sent dealer ping" Mar 24 17:51:42 volumio-club go-librespot[2000]: time="2026-03-24T17:51:42+01:00" level=trace msg="received dealer pong" Mar 24 17:51:43 volumio-club volumio[1335]: verbose: New Socket.io Connection to 192.168.0.198 from 192.168.0.84 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:148.0) Gecko/20100101 Firefox/148.0 Engine version: 3 Transport: polling Total Clients: 16 Mar 24 17:51:43 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 17:51:43 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 24 17:51:44 volumio-club volumio[1335]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 17:51:44 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 17:51:44 volumio-club volumio[1335]: info: CoreCommandRouter::volumioGetState Mar 24 17:51:44 volumio-club volumio[1335]: info: CoreCommandRouter::volumioGetState Mar 24 17:51:44 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 17:51:44 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 17:51:44 volumio-club volumio[1335]: info: Received Get System Info Mar 24 17:51:44 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 17:51:44 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 17:51:44 volumio-club volumio[1335]: info: Discovery: Getting this device information Mar 24 17:51:44 volumio-club volumio[1335]: info: CoreCommandRouter::volumioGetState Mar 24 17:51:44 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 17:51:44 volumio-club volumio[1335]: info: CoreCommandRouter::volumioGetState Mar 24 17:51:44 volumio-club volumio[1335]: info: Listing playlists Mar 24 17:52:03 volumio-club volumio[1335]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/146.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::volumioGetVisibleSources Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::volumioGetState Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 24 17:52:03 volumio-club volumio[1335]: info: Received Get System Info Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 24 17:52:03 volumio-club volumio[1335]: info: Discovery: Getting this device information Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::volumioGetState Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::volumioGetState Mar 24 17:52:03 volumio-club volumio[1335]: info: Listing playlists Mar 24 17:52:03 volumio-club volumio[1335]: info: Stopping peppyspectrum service Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreStateMachine::stop Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreStateMachine::stPlaybackTimer Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreStateMachine::updateTrackBlock Mar 24 17:52:03 volumio-club volumio[1335]: info: CorePlayQueue::getTrackBlock Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreStateMachine::pushState Mar 24 17:52:03 volumio-club volumio[1335]: info: CorePlayQueue::getTrack 0 Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::volumioPushState Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreStateMachine::serviceStop Mar 24 17:52:03 volumio-club volumio[1335]: info: CorePlayQueue::getTrack 0 Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::serviceStop Mar 24 17:52:03 volumio-club volumio[1335]: info: [1774371123615] ControllerWebradio::stop Mar 24 17:52:03 volumio-club volumio[1335]: verbose: ControllerMpd::sendMpdCommand stop Mar 24 17:52:03 volumio-club volumio[1335]: info: Disabling plugin peppyspectrum Mar 24 17:52:03 volumio-club volumio[1335]: info: Preparing to generate the ALSA configuration file Mar 24 17:52:03 volumio-club volumio[1335]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 24 17:52:03 volumio-club volumio[1335]: info: Reading ALSA contributions from plugins. Mar 24 17:52:03 volumio-club volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Mar 24 17:52:03 volumio-club volumio[1335]: info: PeppySpectrum ---peppyspectrum status stop Mar 24 17:52:03 volumio-club volumio[1335]: info: Mar 24 17:52:03 volumio-club volumio[1335]: ---------------------------- MPD announces state update: player Mar 24 17:52:03 volumio-club volumio[1335]: info: sendMpdCommand stop took 56 milliseconds Mar 24 17:52:03 volumio-club volumio[1335]: info: ControllerMpd::getState Mar 24 17:52:03 volumio-club volumio[1335]: verbose: ControllerMpd::sendMpdCommand status Mar 24 17:52:03 volumio-club sudo[10319]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 24 17:52:03 volumio-club sudo[10319]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:52:03 volumio-club sudo[10321]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppyspectrum.service Mar 24 17:52:03 volumio-club volumio[1335]: info: sendMpdCommand status took 27 milliseconds Mar 24 17:52:03 volumio-club volumio[1335]: verbose: ControllerMpd::parseState Mar 24 17:52:03 volumio-club sudo[10321]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:52:03 volumio-club volumio[1335]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 24 17:52:03 volumio-club volumio[1335]: info: sendMpdCommand playlistinfo took 28 milliseconds Mar 24 17:52:03 volumio-club volumio[1335]: verbose: ControllerMpd::parseTrackInfo Mar 24 17:52:03 volumio-club volumio[1335]: info: ControllerMpd::pushState Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::servicePushState Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreStateMachine::pushState Mar 24 17:52:03 volumio-club volumio[1335]: info: CorePlayQueue::getTrack 0 Mar 24 17:52:03 volumio-club volumio[1335]: info: CoreCommandRouter::volumioPushState Mar 24 17:52:03 volumio-club volumio[1335]: info: CorePlayQueue::getTrack 0 Mar 24 17:52:03 volumio-club volumio[1335]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current webradio Received mpd Mar 24 17:52:03 volumio-club volumio[1335]: info: ------------------------------ 72ms Mar 24 17:52:03 volumio-club volumio[1335]: SPOTIFY: RECEIVED VOLUMIO VOLUME 68 Mar 24 17:52:03 volumio-club volumio[1335]: info: touch_display: Setting screensaver timeout to 500 seconds. Mar 24 17:52:03 volumio-club systemd[1]: Stopping peppyspectrum.service - peppyspectrum Daemon... Mar 24 17:52:03 volumio-club volumio[1335]: info: Asound.conf file written Mar 24 17:52:03 volumio-club sudo[10328]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 24 17:52:03 volumio-club sudo[10328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:52:03 volumio-club systemd[1]: peppyspectrum.service: Deactivated successfully. Mar 24 17:52:03 volumio-club systemd[1]: Stopped peppyspectrum.service - peppyspectrum Daemon. Mar 24 17:52:03 volumio-club systemd[1]: peppyspectrum.service: Consumed 1min 3.962s CPU time. Mar 24 17:52:03 volumio-club sudo[10319]: pam_unix(sudo:session): session closed for user root Mar 24 17:52:03 volumio-club sudo[10321]: pam_unix(sudo:session): session closed for user root Mar 24 17:52:05 volumio-club sudo[10328]: pam_unix(sudo:session): session closed for user root Mar 24 17:52:05 volumio-club volumio[1335]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Mar 24 17:52:05 volumio-club volumio[1335]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Mar 24 17:52:05 volumio-club volumio[1335]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Mar 24 17:52:05 volumio-club volumio[1335]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Mar 24 17:52:05 volumio-club volumio[1335]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock PLL:0' : Device or resource busy Mar 24 17:52:05 volumio-club volumio[1335]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DSP:0' : Device or resource busy Mar 24 17:52:05 volumio-club volumio[1335]: /usr/sbin/alsactl: set_control:1475: Cannot write control '2:0:0:Max Overclock DAC:0' : Device or resource busy Mar 24 17:52:05 volumio-club volumio[1335]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Mar 24 17:52:05 volumio-club volumio[1335]: info: Output device has changed, restarting MPD Mar 24 17:52:05 volumio-club volumio[1335]: info: Output device has changed, restarting Shairport Sync Mar 24 17:52:05 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:52:05 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 17:52:05 volumio-club sudo[10338]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 24 17:52:05 volumio-club sudo[10338]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:52:05 volumio-club sudo[10338]: pam_unix(sudo:session): session closed for user root Mar 24 17:52:05 volumio-club sudo[10340]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 24 17:52:05 volumio-club sudo[10340]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:52:05 volumio-club volumio[1335]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 24 17:52:05 volumio-club volumio[1335]: info: Done. Mar 24 17:52:05 volumio-club systemd[1]: Stopping mpd.service - Music Player Daemon... Mar 24 17:52:05 volumio-club systemd[1]: mpd.service: Deactivated successfully. Mar 24 17:52:05 volumio-club systemd[1]: Stopped mpd.service - Music Player Daemon. Mar 24 17:52:05 volumio-club systemd[1]: mpd.service: Consumed 54.293s CPU time. Mar 24 17:52:05 volumio-club systemd[1]: mpd.socket: Deactivated successfully. Mar 24 17:52:05 volumio-club systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Mar 24 17:52:05 volumio-club systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Mar 24 17:52:05 volumio-club systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Mar 24 17:52:05 volumio-club systemd[1]: Starting mpd.service - Music Player Daemon... Mar 24 17:52:05 volumio-club sudo[10348]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 24 17:52:05 volumio-club sudo[10348]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Mar 24 17:52:05 volumio-club sudo[10348]: pam_unix(sudo:session): session closed for user root Mar 24 17:52:05 volumio-club volumio[1335]: info: MPD Permissions set Mar 24 17:52:05 volumio-club volumio[1335]: info: peppyspectrum Daemon Stop Mar 24 17:52:05 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:52:05 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:52:05 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:52:05 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:52:05 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:52:05 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:52:05 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:52:05 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:52:05 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 24 17:52:05 volumio-club volumio[1335]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 24 17:52:05 volumio-club volumio[1335]: info: Starting Shairport Sync Mar 24 17:52:05 volumio-club sudo[10357]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 24 17:52:05 volumio-club sudo[10357]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 24 17:52:05 volumio-club systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Mar 24 17:52:05 volumio-club systemd[1]: shairport-sync.service: Deactivated successfully. Mar 24 17:52:05 volumio-club systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 17:52:05 volumio-club systemd[1]: shairport-sync.service: Consumed 8.373s CPU time. Mar 24 17:52:06 volumio-club systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Mar 24 17:52:06 volumio-club sudo[10357]: pam_unix(sudo:session): session closed for user root Mar 24 17:52:06 volumio-club volumio[1335]: info: Shairport-Sync Started Mar 24 17:52:06 volumio-club volumio[1335]: info: Enabling plugin now_playing Mar 24 17:52:06 volumio-club volumio[1335]: info: Loading plugin "now_playing"... Mar 24 17:52:07 volumio-club volumio[1335]: error: !!!! WARNING !!!! Mar 24 17:52:07 volumio-club volumio[1335]: error: The plugin user_interface/now_playing failed to load, setting it to stopped. Error: Error: Cannot find module 'geo-tz' Mar 24 17:52:07 volumio-club volumio[1335]: Require stack: Mar 24 17:52:07 volumio-club volumio[1335]: - /data/plugins/user_interface/now_playing/dist/index.js Mar 24 17:52:07 volumio-club volumio[1335]: - /volumio/app/pluginmanager.js Mar 24 17:52:07 volumio-club volumio[1335]: - /volumio/app/index.js Mar 24 17:52:07 volumio-club volumio[1335]: - /volumio/index.js Mar 24 17:52:07 volumio-club volumio[1335]: error: Stack trace: Error: Cannot find module 'geo-tz' Mar 24 17:52:07 volumio-club volumio[1335]: Require stack: Mar 24 17:52:07 volumio-club volumio[1335]: - /data/plugins/user_interface/now_playing/dist/index.js Mar 24 17:52:07 volumio-club volumio[1335]: - /volumio/app/pluginmanager.js Mar 24 17:52:07 volumio-club volumio[1335]: - /volumio/app/index.js Mar 24 17:52:07 volumio-club volumio[1335]: - /volumio/index.js Mar 24 17:52:07 volumio-club volumio[1335]: at Module._resolveFilename (node:internal/modules/cjs/loader:1048:15) Mar 24 17:52:07 volumio-club volumio[1335]: at Module._load (node:internal/modules/cjs/loader:901:27) Mar 24 17:52:07 volumio-club volumio[1335]: at Module.require (node:internal/modules/cjs/loader:1115:19) Mar 24 17:52:07 volumio-club volumio[1335]: at require (node:internal/modules/helpers:130:18) Mar 24 17:52:07 volumio-club volumio[1335]: at Object. (/data/plugins/user_interface/now_playing/dist/index.js:56:34) Mar 24 17:52:07 volumio-club volumio[1335]: at Module._compile (node:internal/modules/cjs/loader:1233:14) Mar 24 17:52:07 volumio-club volumio[1335]: at Module._extensions..js (node:internal/modules/cjs/loader:1287:10) Mar 24 17:52:07 volumio-club volumio[1335]: at Module.load (node:internal/modules/cjs/loader:1091:32) Mar 24 17:52:07 volumio-club volumio[1335]: at Module._load (node:internal/modules/cjs/loader:938:12) Mar 24 17:52:07 volumio-club volumio[1335]: at Module.require (node:internal/modules/cjs/loader:1115:19) Mar 24 17:52:07 volumio-club volumio[1335]: at require (node:internal/modules/helpers:130:18) Mar 24 17:52:07 volumio-club volumio[1335]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:235:29) Mar 24 17:52:07 volumio-club volumio[1335]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Mar 24 17:52:07 volumio-club volumio[1335]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Mar 24 17:52:07 volumio-club volumio[1335]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) Mar 24 17:52:07 volumio-club volumio[1335]: error: !!!! WARNING !!!! Mar 24 17:52:07 volumio-club volumio[1335]: info: Done. Mar 24 17:52:07 volumio-club mpd[10350]: 2026-03-24T17:52:07 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 24 17:52:07 volumio-club systemd[1]: Started mpd.service - Music Player Daemon. Mar 24 17:52:07 volumio-club sudo[10340]: pam_unix(sudo:session): session closed for user root Mar 24 17:52:07 volumio-club volumio[1335]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 17:52:07 volumio-club volumio[1335]: TypeError: Cannot read properties of null (reading 'printToastMessage') Mar 24 17:52:07 volumio-club volumio[1335]: at /volumio/app/index.js:1214:32 Mar 24 17:52:07 volumio-club volumio[1335]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Mar 24 17:52:07 volumio-club volumio[1335]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Mar 24 17:52:07 volumio-club volumio[1335]: at CoreCommandRouter.pushToastMessage (/volumio/app/index.js:1212:13) Mar 24 17:52:07 volumio-club volumio[1335]: at /volumio/app/plugins/music_service/mpd/index.js:811:30 Mar 24 17:52:07 volumio-club volumio[1335]: at /volumio/app/plugins/music_service/mpd/index.js:955:9 Mar 24 17:52:07 volumio-club volumio[1335]: at ChildProcess.exithandler (node:child_process:413:7) Mar 24 17:52:07 volumio-club volumio[1335]: at ChildProcess.emit (node:events:514:28) Mar 24 17:52:07 volumio-club volumio[1335]: at maybeClose (node:internal/child_process:1105:16) Mar 24 17:52:07 volumio-club volumio[1335]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Mar 24 17:52:07 volumio-club volumio[1335]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 24 17:52:07 volumio-club sudo[10407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-24 17:51' Mar 24 17:52:07 volumio-club sudo[10407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="dc61260dec5515dafd2b634881860b4c46c919ff" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Mar 6 16:46:58 UTC 2026" VOLUMIO_VERSION="4.103" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="aa741395b8bfc648ff5d04e312f53d2c"