Mar 28 23:57:00 volumio volumio[1145]: error: MyVolumio Custom Token format not valid, refreshing it Mar 28 23:57:01 volumio volumio-remote-updater[685]: Test mode disabled Mar 28 23:57:01 volumio volumio-remote-updater[685]: Alpha mode disabled Mar 28 23:57:01 volumio volumio-remote-updater[685]: Alpha legacy test mode disabled Mar 28 23:57:01 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 28 23:57:02 volumio volumio[1145]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 28 23:57:02 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 28 23:57:03 volumio volumio[1145]: info: MyVolumio login type: Token Mar 28 23:57:05 volumio volumio[1145]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 28 23:57:06 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 28 23:57:06 volumio volumio[1145]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Mar 28 23:57:07 volumio volumio[1145]: info: MyVolumio token set successfully Mar 28 23:57:07 volumio volumio[1145]: info: MYVOLUMIO: Adding device Mar 28 23:57:07 volumio volumio[1145]: info: MYVOLUMIO: Evaluating Server Mar 28 23:57:09 volumio volumio[1145]: info: MyVolumio Plan changed: premium Mar 28 23:57:09 volumio volumio[1145]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Mar 28 23:57:09 volumio volumio[1145]: info: Removing browser output: myVolumio user plan is not superstar Mar 28 23:57:09 volumio volumio[1145]: info: Removing audio output: Mar 28 23:57:09 volumio volumio[1145]: info: MYVOLUMIO: Adding device Mar 28 23:57:09 volumio volumio[1145]: info: MYVOLUMIO: Evaluating Server Mar 28 23:57:11 volumio volumio[1145]: info: Setting Geolocation for MyVolumio to eu3 Mar 28 23:57:12 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 23:57:12 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 23:57:12 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 23:57:12 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 23:57:12 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 23:57:12 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 28 23:57:12 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 28 23:57:12 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 28 23:57:12 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 28 23:57:12 volumio volumio[1145]: info: CoreCommandRouter::volumioGetBrowseSources Mar 28 23:57:12 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 28 23:57:12 volumio volumio[1145]: info: Successfully Added MyVolumio device Mar 28 23:57:12 volumio volumio[1145]: info: Setting Geolocation for MyVolumio to eu3 Mar 28 23:57:12 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 23:57:12 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 23:57:12 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 23:57:13 volumio volumio[1145]: info: Updating MyVolumio device info Mar 28 23:57:13 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 23:57:13 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 23:57:13 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 23:57:13 volumio volumio[1145]: info: Remote config written successfully Mar 28 23:57:13 volumio volumio[1145]: info: Starting Tunnel 1 Mar 28 23:57:13 volumio volumio[1145]: info: Starting Tunnel Connection Checker Mar 28 23:57:13 volumio volumio[1145]: info: Completed starting MyVolumio Plugin Mar 28 23:57:13 volumio volumio[1145]: info: Successfully Added MyVolumio device Mar 28 23:57:13 volumio volumio[1145]: info: MYVolumio Device enabled Mar 28 23:57:13 volumio volumio[1145]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Mar 28 23:57:13 volumio volumio[1145]: info: MyVolumio status changed Mar 28 23:57:13 volumio volumio[1145]: info: Streaming services startup Mar 28 23:57:13 volumio volumio[1145]: info: Starting Streaming Daemon Mar 28 23:57:14 volumio sudo[1777]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 28 23:57:14 volumio volumio[1145]: info: CoreCommandRouter::volumioGetState Mar 28 23:57:14 volumio sudo[1777]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 23:57:14 volumio volumio[1145]: info: CorePlayQueue::getTrack 0 Mar 28 23:57:14 volumio volumio[1145]: info: Updating MyVolumio device info Mar 28 23:57:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 23:57:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 23:57:14 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 23:57:14 volumio sudo[1777]: pam_unix(sudo:session): session closed for user root Mar 28 23:57:15 volumio volumio[1145]: error: Cannot start Volumio Streaming Daemon Mar 28 23:57:15 volumio volumio[1145]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 28 23:57:15 volumio volumio[1145]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 28 23:57:15 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 28 23:57:15 volumio volumio[1145]: error: [MyVolumio PluginManager] Cache data is invalid! Mar 28 23:57:15 volumio volumio[1145]: info: Successfully Updated MyVolumio device Mar 28 23:57:16 volumio volumio[1145]: info: Successfully Updated MyVolumio device Mar 28 23:57:17 volumio volumio[1145]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Mar 28 23:57:20 volumio volumio[1145]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Mar 28 23:57:20 volumio volumio[1145]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin bluetooth Mar 28 23:57:20 volumio volumio[1145]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Mar 28 23:57:21 volumio volumio[1145]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Mar 28 23:57:42 volumio volumio[1145]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 28 23:57:42 volumio sudo[1810]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 28 23:57:42 volumio sudo[1810]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 23:57:43 volumio sudo[1810]: pam_unix(sudo:session): session closed for user root Mar 28 23:57:44 volumio volumio[1145]: info: MRS: MultiRoom plugin initialized Mar 28 23:57:44 volumio volumio[1145]: info: MRS: STOPPING SNAPCLIENT Mar 28 23:57:44 volumio volumio[1145]: info: MRS: Snap server stop Mar 28 23:57:44 volumio volumio[1145]: info: MRS: STOPPING volumioStreaming Mar 28 23:57:44 volumio sudo[1831]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 28 23:57:45 volumio sudo[1831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 23:57:45 volumio volumio[1145]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 28 23:57:45 volumio sudo[1845]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 28 23:57:45 volumio sudo[1845]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 23:57:45 volumio sudo[1842]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 28 23:57:45 volumio sudo[1831]: pam_unix(sudo:session): session closed for user root Mar 28 23:57:45 volumio sudo[1842]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 23:57:45 volumio sudo[1848]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 28 23:57:45 volumio sudo[1848]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Mar 28 23:57:45 volumio sudo[1848]: pam_unix(sudo:session): session closed for user root Mar 28 23:57:45 volumio volumio[1145]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 28 23:57:45 volumio volumio[1145]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 28 23:57:46 volumio sudo[1845]: pam_unix(sudo:session): session closed for user root Mar 28 23:57:46 volumio sudo[1842]: pam_unix(sudo:session): session closed for user root Mar 28 23:57:46 volumio volumio[1145]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 28 23:57:46 volumio volumio[1145]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 28 23:57:47 volumio volumio[1145]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 28 23:57:47 volumio volumio[1145]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 28 23:57:47 volumio volumio[1145]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 28 23:57:48 volumio volumio[1145]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 28 23:57:48 volumio volumio[1145]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Mar 28 23:57:48 volumio volumio[1145]: info: Preparing to generate the ALSA configuration file Mar 28 23:57:48 volumio volumio[1145]: error: MyVolumio Plugins failed to start in a timely fashion Mar 28 23:57:48 volumio volumio[1145]: [Metrics] CommandRouter: 117s 186.13ms Mar 28 23:57:48 volumio volumio[1145]: info: CoreCommandRouter::volumiosetStartupVolume Mar 28 23:57:48 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 28 23:57:48 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 28 23:57:48 volumio volumio[1145]: info: CoreCommandRouter::Close All Modals sent Mar 28 23:57:49 volumio volumio[1145]: info: CoreCommandRouter::Close All Modals sent Mar 28 23:57:50 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Mar 28 23:57:50 volumio volumio[1145]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Mar 28 23:57:50 volumio volumio[1145]: info: Reading ALSA contributions from plugins. Mar 28 23:57:50 volumio volumio[1145]: info: MRS: Removed streaming files Mar 28 23:57:50 volumio volumio[1145]: info: MRS: volumioStreaming STOPPED Mar 28 23:57:50 volumio volumio[1145]: info: MRS: SNAPSERVER STOPPED Mar 28 23:57:50 volumio volumio[1145]: info: MRS: SNAPCLIENT STOPPED Mar 28 23:57:50 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 28 23:57:50 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 28 23:57:50 volumio volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 28 23:57:50 volumio dbus-daemon[667]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30000ms) Mar 28 23:57:50 volumio volumio[1145]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 23:57:51 volumio volumio[1145]: Error: write EPIPE Mar 28 23:57:51 volumio volumio[1145]: at afterWriteDispatched (node:internal/stream_base_commons:160:15) Mar 28 23:57:51 volumio volumio[1145]: at writeGeneric (node:internal/stream_base_commons:151:3) Mar 28 23:57:51 volumio volumio[1145]: at Socket._writeGeneric (node:net:952:11) Mar 28 23:57:51 volumio volumio[1145]: at Socket._write (node:net:964:8) Mar 28 23:57:51 volumio volumio[1145]: at writeOrBuffer (node:internal/streams/writable:399:12) Mar 28 23:57:51 volumio volumio[1145]: at _write (node:internal/streams/writable:340:10) Mar 28 23:57:51 volumio volumio[1145]: at Writable.write (node:internal/streams/writable:344:10) Mar 28 23:57:51 volumio volumio[1145]: at EventEmitter. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/connection.js:154:16) Mar 28 23:57:51 volumio volumio[1145]: at Object.onceWrapper (node:events:628:28) Mar 28 23:57:51 volumio volumio[1145]: at EventEmitter.emit (node:events:514:28) Mar 28 23:57:51 volumio volumio[1145]: at afterHandshake (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/connection.js:122:10) Mar 28 23:57:51 volumio volumio[1145]: at /myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/handshake.js:90:18 Mar 28 23:57:51 volumio volumio[1145]: at Socket.readable (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/readline.js:12:11) Mar 28 23:57:51 volumio volumio[1145]: at Socket.emit (node:events:514:28) Mar 28 23:57:51 volumio volumio[1145]: at emitReadable_ (node:internal/streams/readable:609:12) Mar 28 23:57:51 volumio volumio[1145]: at process.processTicksAndRejections (node:internal/process/task_queues:81:21) { Mar 28 23:57:51 volumio volumio[1145]: errno: -32, Mar 28 23:57:51 volumio volumio[1145]: code: 'EPIPE', Mar 28 23:57:51 volumio volumio[1145]: syscall: 'write' Mar 28 23:57:51 volumio volumio[1145]: } Mar 28 23:57:51 volumio volumio[1145]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 28 23:58:04 volumio sudo[1883]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-03-28 23:57' Mar 28 23:58:04 volumio sudo[1883]: 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"