-- Logs begin at Thu 2019-02-14 04:11:59 CST, end at Fri 2026-03-27 23:49:10 CST. -- Mar 27 23:48:00 volumio go-librespot[12868]: time="2026-03-27T23:48:00-06:00" level=trace msg="sent dealer ping" Mar 27 23:48:00 volumio go-librespot[12868]: time="2026-03-27T23:48:00-06:00" level=trace msg="received dealer pong" Mar 27 23:48:12 volumio volumio[12639]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 27 23:48:12 volumio volumio[12639]: info: Downloading plugin at http://127.0.0.1:3000/plugin-serve/volumio-airplay-sender.zip Mar 27 23:48:12 volumio volumio5-onboarding[1153]: time=2026-03-27T23:48:12.156-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 23:48:12 volumio volumio[12639]: info: END DOWNLOAD: http://127.0.0.1:3000/plugin-serve/volumio-airplay-sender.zip Mar 27 23:48:12 volumio volumio[12639]: info: Folder /tmp/plugins removed Mar 27 23:48:12 volumio volumio5-onboarding[1153]: time=2026-03-27T23:48:12.213-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 23:48:12 volumio volumio5-onboarding[1153]: time=2026-03-27T23:48:12.444-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 23:48:12 volumio volumio[12639]: info: Check plugin dependencies Mar 27 23:48:12 volumio volumio5-onboarding[1153]: time=2026-03-27T23:48:12.503-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 23:48:12 volumio volumio[12639]: info: Checking if plugin already exists Mar 27 23:48:12 volumio volumio5-onboarding[1153]: time=2026-03-27T23:48:12.518-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 23:48:12 volumio volumio[12639]: info: Rename folder Mar 27 23:48:12 volumio volumio[12639]: info: Folder /tmp/downloaded_plugin.zip removed Mar 27 23:48:12 volumio volumio5-onboarding[1153]: time=2026-03-27T23:48:12.568-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 23:48:12 volumio volumio[12639]: info: Move to category Mar 27 23:48:17 volumio volumio-remote-updater[566]: [2026-03-27 23:48:17] [disconnect] Disconnect close local:[1008,Pong timeout] remote:[1006] Mar 27 23:48:22 volumio volumio-remote-updater[566]: [2026-03-27 23:48:22] [connect] Successful connection Mar 27 23:48:30 volumio go-librespot[12868]: time="2026-03-27T23:48:30-06:00" level=trace msg="sent dealer ping" Mar 27 23:48:30 volumio go-librespot[12868]: time="2026-03-27T23:48:30-06:00" level=trace msg="received dealer pong" Mar 27 23:48:35 volumio volumio-remote-updater[566]: [2026-03-27 23:48:35] [connect] Successful connection Mar 27 23:48:39 volumio ntpd[749]: Soliciting pool server 2a03:8600::ee Mar 27 23:48:48 volumio volumio5-onboarding[1153]: time=2026-03-27T23:48:48.948-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 23:48:48 volumio volumio[12639]: info: Checking if install.sh is present Mar 27 23:48:48 volumio volumio5-onboarding[1153]: time=2026-03-27T23:48:48.967-06:00 level=ERROR msg="failed reading message" error="websocket: close 1000 (normal)" Mar 27 23:48:49 volumio volumio[12639]: info: Executing install.sh Mar 27 23:48:49 volumio volumio[12639]: verbose: New Socket.io Connection to 192.168.3.154 from 192.168.3.116 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.3.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 1 Mar 27 23:48:49 volumio volumio[12639]: verbose: New Socket.io Connection to 192.168.3.154 from 192.168.3.116 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.3.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 2 Mar 27 23:48:49 volumio volumio[12639]: verbose: New Socket.io Connection to 192.168.3.154 from 192.168.3.116 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.3.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 3 Mar 27 23:48:49 volumio volumio[12639]: verbose: New Socket.io Connection to 192.168.3.154 from 192.168.3.116 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.3.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 4 Mar 27 23:48:49 volumio volumio[12639]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Mar 27 23:48:49 volumio volumio[12639]: verbose: New Socket.io Connection to 192.168.3.154 from 192.168.3.116 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.3.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 6 Mar 27 23:48:49 volumio volumio[12639]: 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: 7 Mar 27 23:48:49 volumio sudo[13289]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/volumio-airplay-sender/install.sh Mar 27 23:48:49 volumio sudo[13289]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 23:48:49 volumio volumio[12639]: 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: 7 Mar 27 23:48:49 volumio sudo[13291]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /tmp/airplay_fifo Mar 27 23:48:49 volumio sudo[13291]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 23:48:49 volumio sudo[13291]: pam_unix(sudo:session): session closed for user root Mar 27 23:48:49 volumio volumio[12639]: 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: 7 Mar 27 23:48:49 volumio volumio[12639]: info: Instalando AirPlay Sender... Mar 27 23:48:49 volumio sudo[13293]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown volumio:volumio /tmp/airplay_fifo Mar 27 23:48:49 volumio volumio[12639]: 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: 7 Mar 27 23:48:49 volumio sudo[13293]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 23:48:49 volumio sudo[13293]: pam_unix(sudo:session): session closed for user root Mar 27 23:48:49 volumio volumio[12639]: 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: 7 Mar 27 23:48:49 volumio volumio[12639]: 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: 7 Mar 27 23:48:49 volumio sudo[13296]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd Mar 27 23:48:49 volumio sudo[13296]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 23:48:49 volumio systemd[1]: Stopping Music Player Daemon... Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::volumioGetState Mar 27 23:48:49 volumio volumio[12639]: info: CorePlayQueue::getTrack 0 Mar 27 23:48:49 volumio systemd[1]: mpd.service: Succeeded. Mar 27 23:48:49 volumio systemd[1]: Stopped Music Player Daemon. Mar 27 23:48:49 volumio systemd[1]: Starting Music Player Daemon... Mar 27 23:48:49 volumio volumio[12639]: verbose: New Socket.io Connection to 192.168.3.154 from 192.168.3.116 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.3.1 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 8 Mar 27 23:48:49 volumio sudo[13298]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 27 23:48:49 volumio sudo[13298]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 27 23:48:49 volumio sudo[13298]: pam_unix(sudo:session): session closed for user root Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::volumioGetVisibleSources Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::volumioGetState Mar 27 23:48:49 volumio volumio[12639]: info: CorePlayQueue::getTrack 0 Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::volumioGetState Mar 27 23:48:49 volumio volumio[12639]: info: CorePlayQueue::getTrack 0 Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 27 23:48:49 volumio volumio[12639]: info: Received Get System Info Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 27 23:48:49 volumio volumio[12639]: info: Discovery: Getting this device information Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::volumioGetState Mar 27 23:48:49 volumio volumio[12639]: info: CorePlayQueue::getTrack 0 Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::volumioGetState Mar 27 23:48:49 volumio volumio[12639]: info: CorePlayQueue::getTrack 0 Mar 27 23:48:49 volumio volumio[12639]: info: Listing playlists Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 27 23:48:49 volumio volumio[12639]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Mar 27 23:48:49 volumio volumio[12639]: info: CoreCommandRouter::volumioGetState Mar 27 23:48:49 volumio volumio[12639]: info: CorePlayQueue::getTrack 0 Mar 27 23:48:50 volumio volumio[12639]: 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 27 23:48:50 volumio volumio-remote-updater[566]: [2026-03-27 23:48:50] [connect] Successful connection Mar 27 23:48:50 volumio volumio-remote-updater[566]: [2026-03-27 23:48:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1774676930 101 Mar 27 23:48:50 volumio volumio[12639]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 11 Mar 27 23:48:50 volumio volumio[12639]: 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 27 23:48:50 volumio volumio[12639]: info: CoreCommandRouter::volumioGetState Mar 27 23:48:50 volumio volumio[12639]: info: CorePlayQueue::getTrack 0 Mar 27 23:48:50 volumio mpd[13300]: Mar 27 23:48 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 27 23:48:50 volumio systemd[1]: Started Music Player Daemon. Mar 27 23:48:50 volumio sudo[13296]: pam_unix(sudo:session): session closed for user root Mar 27 23:48:50 volumio sudo[13289]: pam_unix(sudo:session): session closed for user root Mar 27 23:48:50 volumio volumio[12639]: info: Instalación completada exitosamente. Mar 27 23:48:50 volumio volumio5-onboarding[1153]: time=2026-03-27T23:48:50.801-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 23:48:50 volumio volumio[12639]: info: Install script completed Mar 27 23:48:50 volumio volumio5-onboarding[1153]: time=2026-03-27T23:48:50.811-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 23:48:50 volumio volumio[12639]: info: Adding reference to registry Mar 27 23:48:50 volumio volumio5-onboarding[1153]: time=2026-03-27T23:48:50.829-06:00 level=WARN msg="received plugin install progress but no plugin is being installed" component=state Mar 27 23:48:50 volumio volumio[12639]: info: Done installing plugin. Mar 27 23:48:50 volumio volumio[12639]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 27 23:48:50 volumio volumio[12639]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 27 23:48:50 volumio volumio[12639]: info: Folder /tmp/plugins removed Mar 27 23:48:50 volumio volumio[12639]: info: Folder /tmp/downloaded_plugin.zip removed Mar 27 23:48:50 volumio volumio[12639]: info: Folder /data/temp removed Mar 27 23:49:00 volumio go-librespot[12868]: time="2026-03-27T23:49:00-06:00" level=trace msg="received accesspoint ping" Mar 27 23:49:00 volumio go-librespot[12868]: time="2026-03-27T23:49:00-06:00" level=trace msg="received accesspoint pong ack" Mar 27 23:49:00 volumio go-librespot[12868]: time="2026-03-27T23:49:00-06:00" level=trace msg="sent dealer ping" Mar 27 23:49:00 volumio go-librespot[12868]: time="2026-03-27T23:49:00-06:00" level=trace msg="received dealer pong" Mar 27 23:49:04 volumio volumio[12639]: info: Enabling plugin volumio-airplay-sender Mar 27 23:49:04 volumio volumio[12639]: info: Loading plugin "volumio-airplay-sender"... Mar 27 23:49:05 volumio volumio[12639]: info: PLUGIN START: volumio-airplay-sender Mar 27 23:49:05 volumio volumio[12639]: info: Done. Mar 27 23:49:10 volumio volumio[12639]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 27 23:49:10 volumio volumio[12639]: info: Loading UIConfig manually... Mar 27 23:49:10 volumio volumio[12639]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 23:49:10 volumio volumio[12639]: TypeError: self.commandRouter.getTranslation is not a function Mar 27 23:49:10 volumio volumio[12639]: at /data/plugins/audio_interface/volumio-airplay-sender/index.js:120:28 Mar 27 23:49:10 volumio volumio[12639]: at FSReqCallback.readFileAfterClose [as oncomplete] (internal/fs/read_file_context.js:63:3) Mar 27 23:49:10 volumio volumio[12639]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 27 23:49:10 volumio sudo[13345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-03-27 23:48 Mar 27 23:49:10 volumio sudo[13345]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="f164e8123098cc1274b0ab48da1dc0b2e1d466e1" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 12 Mar 2026 01:30:21 PM CET" VOLUMIO_VERSION="3.913" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b937ce07521b678e27c1fac2a502bb4d"