May 28 03:02:01 volumio volumio[1374]: 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/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 May 28 03:02:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 03:02:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 03:02:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 28 03:02:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 28 03:02:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 28 03:02:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 28 03:02:01 volumio volumio[1374]: info: CoreCommandRouter::volumioGetBrowseSources May 28 03:02:01 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 28 03:02:03 volumio volumio[1374]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 28 03:02:03 volumio volumio[1374]: info: Completed starting MyVolumio Plugin May 28 03:02:03 volumio volumio[1374]: [Metrics] CommandRouter: 70s 319.58ms May 28 03:02:03 volumio volumio[1374]: info: CoreCommandRouter::volumiosetStartupVolume May 28 03:02:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 28 03:02:03 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:02:03 volumio volumio[1374]: info: CoreCommandRouter::Close All Modals sent May 28 03:02:03 volumio volumio[1374]: info: CoreCommandRouter::Close All Modals sent May 28 03:02:04 volumio volumio[1374]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 28 03:02:04 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 28 03:02:04 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 28 03:02:04 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 28 03:02:06 volumio volumio[1374]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 May 28 03:02:07 volumio volumio[1374]: 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/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 May 28 03:02:11 volumio volumio[1374]: info: BOOT COMPLETED May 28 03:02:17 volumio volumio[1374]: info: MyVolumio token set successfully May 28 03:02:17 volumio volumio[1374]: info: MYVOLUMIO: Adding device May 28 03:02:17 volumio volumio[1374]: info: MYVOLUMIO: Evaluating Server May 28 03:02:21 volumio volumio[1374]: info: MyVolumio Plan changed: premium May 28 03:02:21 volumio volumio[1374]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 28 03:02:21 volumio volumio[1374]: info: Removing browser output: myVolumio user plan is not superstar May 28 03:02:21 volumio volumio[1374]: info: Removing audio output: May 28 03:02:21 volumio volumio[1374]: info: MYVOLUMIO: Adding device May 28 03:02:21 volumio volumio[1374]: info: MYVOLUMIO: Evaluating Server May 28 03:02:24 volumio go-librespot[1786]: time="2026-05-28T03:02:24+02:00" level=trace msg="sent dealer ping" May 28 03:02:24 volumio go-librespot[1786]: time="2026-05-28T03:02:24+02:00" level=trace msg="received dealer pong" May 28 03:02:29 volumio volumio[1374]: error: error May 28 03:02:30 volumio volumio[1374]: error: error May 28 03:02:30 volumio volumio[1374]: error: error May 28 03:02:30 volumio volumio[1374]: error: error May 28 03:02:30 volumio volumio[1374]: error: error May 28 03:02:30 volumio volumio[1374]: error: error May 28 03:02:30 volumio volumio[1374]: error: error May 28 03:02:30 volumio volumio[1374]: error: error May 28 03:02:30 volumio volumio[1374]: error: error May 28 03:02:30 volumio volumio[1374]: error: error May 28 03:02:30 volumio volumio[1374]: error: error May 28 03:02:33 volumio volumio[1374]: info: Setting Geolocation for MyVolumio to eu2 May 28 03:02:33 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:02:33 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:02:33 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:02:33 volumio volumio[1374]: info: CoreCommandRouter::volumioGetState May 28 03:02:33 volumio volumio[1374]: info: CorePlayQueue::getTrack 0 May 28 03:02:33 volumio volumio[1374]: info: Setting Geolocation for MyVolumio to eu2 May 28 03:02:33 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:02:33 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:02:33 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:02:35 volumio volumio[1374]: info: Updating MyVolumio device info May 28 03:02:35 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:02:35 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:02:35 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:02:35 volumio volumio[1374]: info: Updating MyVolumio device info May 28 03:02:35 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:02:35 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:02:35 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 28 03:02:37 volumio volumio[1374]: info: Remote config written successfully May 28 03:02:37 volumio volumio[1374]: info: Starting Tunnel 1 May 28 03:02:37 volumio volumio[1374]: info: Starting Tunnel Connection Checker May 28 03:02:37 volumio volumio[1374]: 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/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 May 28 03:02:40 volumio volumio[1374]: info: MYVolumio Device enabled May 28 03:02:40 volumio volumio[1374]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 28 03:02:40 volumio volumio[1374]: info: MyVolumio status changed May 28 03:02:40 volumio volumio[1374]: info: Streaming services startup May 28 03:02:40 volumio volumio[1374]: info: Starting Streaming Daemon May 28 03:02:40 volumio sudo[2329]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 28 03:02:40 volumio sudo[2329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:02:40 volumio sudo[2329]: pam_unix(sudo:session): session closed for user root May 28 03:02:42 volumio volumio[1374]: info: Successfully Added MyVolumio device May 28 03:02:43 volumio volumio[1374]: error: Cannot start Volumio Streaming Daemon May 28 03:02:43 volumio volumio[1374]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 28 03:02:43 volumio volumio[1374]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 28 03:02:45 volumio volumio[1374]: info: Successfully Added MyVolumio device May 28 03:02:48 volumio volumio[1374]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 28 03:02:48 volumio volumio[1374]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 28 03:02:49 volumio volumio[1374]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth May 28 03:02:49 volumio volumio[1374]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth May 28 03:02:49 volumio volumio[1374]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart May 28 03:02:49 volumio volumio[1374]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 28 03:02:52 volumio volumio[1374]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 28 03:02:53 volumio sudo[2355]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 28 03:02:53 volumio sudo[2355]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:02:53 volumio sudo[2355]: pam_unix(sudo:session): session closed for user root May 28 03:02:53 volumio volumio[1374]: info: MRS: MultiRoom plugin initialized May 28 03:02:53 volumio volumio[1374]: info: MRS: STOPPING SNAPCLIENT May 28 03:02:53 volumio volumio[1374]: info: MRS: Snap server stop May 28 03:02:53 volumio volumio[1374]: info: MRS: STOPPING volumioStreaming May 28 03:02:53 volumio sudo[2374]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 28 03:02:53 volumio sudo[2374]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:02:53 volumio sudo[2373]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 28 03:02:53 volumio volumio[1374]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 28 03:02:53 volumio sudo[2373]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:02:53 volumio sudo[2376]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 28 03:02:53 volumio sudo[2376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:02:53 volumio sudo[2378]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 28 03:02:53 volumio volumio[1374]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 28 03:02:53 volumio sudo[2373]: pam_unix(sudo:session): session closed for user root May 28 03:02:53 volumio sudo[2378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 28 03:02:53 volumio sudo[2376]: pam_unix(sudo:session): session closed for user root May 28 03:02:53 volumio sudo[2378]: pam_unix(sudo:session): session closed for user root May 28 03:02:53 volumio volumio[1374]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 28 03:02:53 volumio volumio[1374]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 28 03:02:53 volumio sudo[2374]: pam_unix(sudo:session): session closed for user root May 28 03:02:54 volumio volumio[1374]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 28 03:02:54 volumio volumio[1374]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 28 03:02:54 volumio volumio[1374]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 28 03:02:54 volumio volumio[1374]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 28 03:02:54 volumio volumio[1374]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 28 03:02:54 volumio volumio[1374]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... May 28 03:02:54 volumio go-librespot[1786]: time="2026-05-28T03:02:54+02:00" level=trace msg="sent dealer ping" May 28 03:02:54 volumio volumio[1374]: info: Preparing to generate the ALSA configuration file May 28 03:02:54 volumio go-librespot[1786]: time="2026-05-28T03:02:54+02:00" level=trace msg="received dealer pong" May 28 03:02:54 volumio volumio[1374]: info: Successfully Updated MyVolumio device May 28 03:02:54 volumio volumio[1374]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf May 28 03:02:54 volumio volumio[1374]: info: Reading ALSA contributions from plugins. May 28 03:03:14 volumio volumio[1374]: info: Successfully Updated MyVolumio device May 28 03:03:14 volumio volumio[1374]: info: MRS: Removed streaming files May 28 03:03:14 volumio volumio[1374]: info: MRS: volumioStreaming STOPPED May 28 03:03:14 volumio volumio[1374]: info: MRS: SNAPSERVER STOPPED May 28 03:03:14 volumio volumio[1374]: info: MRS: SNAPCLIENT STOPPED May 28 03:03:19 volumio dbus-daemon[850]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30003ms) May 28 03:03:21 volumio volumio[1374]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 03:03:21 volumio volumio[1374]: Error: write EPIPE May 28 03:03:21 volumio volumio[1374]: at afterWriteDispatched (node:internal/stream_base_commons:160:15) May 28 03:03:21 volumio volumio[1374]: at writeGeneric (node:internal/stream_base_commons:151:3) May 28 03:03:21 volumio volumio[1374]: at Socket._writeGeneric (node:net:952:11) May 28 03:03:21 volumio volumio[1374]: at Socket._write (node:net:964:8) May 28 03:03:21 volumio volumio[1374]: at writeOrBuffer (node:internal/streams/writable:399:12) May 28 03:03:21 volumio volumio[1374]: at _write (node:internal/streams/writable:340:10) May 28 03:03:21 volumio volumio[1374]: at Writable.write (node:internal/streams/writable:344:10) May 28 03:03:21 volumio volumio[1374]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/handshake.js:89:18 May 28 03:03:21 volumio volumio[1374]: at Socket.readable (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/readline.js:12:11) May 28 03:03:21 volumio volumio[1374]: at Socket.emit (node:events:514:28) May 28 03:03:21 volumio volumio[1374]: at emitReadable_ (node:internal/streams/readable:609:12) May 28 03:03:21 volumio volumio[1374]: at process.processTicksAndRejections (node:internal/process/task_queues:81:21) { May 28 03:03:21 volumio volumio[1374]: errno: -32, May 28 03:03:21 volumio volumio[1374]: code: 'EPIPE', May 28 03:03:21 volumio volumio[1374]: syscall: 'write' May 28 03:03:21 volumio volumio[1374]: } May 28 03:03:21 volumio volumio[1374]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 03:03:24 volumio go-librespot[1786]: time="2026-05-28T03:03:24+02:00" level=trace msg="received accesspoint ping" May 28 03:03:24 volumio go-librespot[1786]: time="2026-05-28T03:03:24+02:00" level=trace msg="received accesspoint pong ack" May 28 03:03:24 volumio go-librespot[1786]: time="2026-05-28T03:03:24+02:00" level=trace msg="sent dealer ping" May 28 03:03:24 volumio go-librespot[1786]: time="2026-05-28T03:03:24+02:00" level=trace msg="received dealer pong" May 28 03:03:25 volumio sudo[2475]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-28 03:02' May 28 03:03:25 volumio sudo[2475]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"