Jun 10 21:58:05 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jun 10 21:58:06 volumiopi4 volumio[1379]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jun 10 21:58:06 volumiopi4 volumio[1379]: info: CoreCommandRouter::volumioGetState Jun 10 21:58:06 volumiopi4 volumio[1379]: info: CorePlayQueue::getTrack 0 Jun 10 21:58:08 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 21:58:08 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 21:58:08 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Jun 10 21:58:08 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Jun 10 21:58:08 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Jun 10 21:58:08 volumiopi4 volumio[1379]: info: CoreCommandRouter::volumioGetBrowseSources Jun 10 21:58:08 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 10 21:58:12 volumiopi4 volumio[1379]: error: MyVolumio Plugin failed to authenticate in a timely fashion Jun 10 21:58:12 volumiopi4 volumio[1379]: info: Completed starting MyVolumio Plugin Jun 10 21:58:12 volumiopi4 volumio[1379]: [Metrics] CommandRouter: 64s 377.84ms Jun 10 21:58:12 volumiopi4 volumio[1379]: info: CoreCommandRouter::volumiosetStartupVolume Jun 10 21:58:12 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 10 21:58:12 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 21:58:12 volumiopi4 volumio[1379]: info: CoreCommandRouter::Close All Modals sent Jun 10 21:58:12 volumiopi4 volumio[1379]: info: CoreCommandRouter::Close All Modals sent Jun 10 21:58:13 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 10 21:58:13 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 10 21:58:13 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 10 21:58:14 volumiopi4 volumio[1379]: info: MyVolumio token set successfully Jun 10 21:58:14 volumiopi4 volumio[1379]: info: MYVOLUMIO: Adding device Jun 10 21:58:14 volumiopi4 volumio[1379]: info: MYVOLUMIO: Evaluating Server Jun 10 21:58:17 volumiopi4 volumio[1379]: info: MyVolumio Plan changed: premium Jun 10 21:58:17 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jun 10 21:58:17 volumiopi4 volumio[1379]: info: Removing browser output: myVolumio user plan is not superstar Jun 10 21:58:17 volumiopi4 volumio[1379]: info: Removing audio output: Jun 10 21:58:17 volumiopi4 volumio[1379]: info: MYVOLUMIO: Adding device Jun 10 21:58:17 volumiopi4 volumio[1379]: info: MYVOLUMIO: Evaluating Server Jun 10 21:58:18 volumiopi4 volumio[1379]: info: Remote config written successfully Jun 10 21:58:18 volumiopi4 volumio[1379]: info: Starting Tunnel 1 Jun 10 21:58:18 volumiopi4 volumio[1379]: info: Starting Tunnel Connection Checker Jun 10 21:58:18 volumiopi4 volumio[1379]: info: MYVolumio Device enabled Jun 10 21:58:18 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jun 10 21:58:18 volumiopi4 volumio[1379]: info: MyVolumio status changed Jun 10 21:58:18 volumiopi4 volumio[1379]: info: Streaming services startup Jun 10 21:58:18 volumiopi4 volumio[1379]: info: Starting Streaming Daemon Jun 10 21:58:18 volumiopi4 sudo[2080]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jun 10 21:58:18 volumiopi4 sudo[2080]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 21:58:18 volumiopi4 sudo[2080]: pam_unix(sudo:session): session closed for user root Jun 10 21:58:18 volumiopi4 volumio[1379]: error: Cannot start Volumio Streaming Daemon Jun 10 21:58:18 volumiopi4 volumio[1379]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jun 10 21:58:18 volumiopi4 volumio[1379]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jun 10 21:58:18 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jun 10 21:58:18 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Plugin music_service/qobuzconnect is enabled for this plan, but could not be found on the local filesystem! Jun 10 21:58:18 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jun 10 21:58:19 volumiopi4 hostapd[1373]: wlan0: STA 0a:0a:a1:73:3f:6d IEEE 802.11: associated Jun 10 21:58:19 volumiopi4 hostapd[1373]: wlan0: STA 0a:0a:a1:73:3f:6d RADIUS: starting accounting session 925C42DBC02A74E3 Jun 10 21:58:19 volumiopi4 dnsmasq-dhcp[1403]: DHCPREQUEST(wlan0) 192.168.211.142 0a:0a:a1:73:3f:6d Jun 10 21:58:19 volumiopi4 dnsmasq-dhcp[1403]: DHCPACK(wlan0) 192.168.211.142 0a:0a:a1:73:3f:6d Jun 10 21:58:19 volumiopi4 volumio[1379]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jun 10 21:58:19 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jun 10 21:58:23 volumiopi4 dnsmasq-dhcp[1403]: DHCPREQUEST(wlan0) 192.168.211.142 0a:0a:a1:73:3f:6d Jun 10 21:58:23 volumiopi4 dnsmasq-dhcp[1403]: DHCPACK(wlan0) 192.168.211.142 0a:0a:a1:73:3f:6d Jun 10 21:58:23 volumiopi4 dnsmasq-dhcp[1403]: DHCPREQUEST(wlan0) 192.168.211.142 0a:0a:a1:73:3f:6d Jun 10 21:58:23 volumiopi4 dnsmasq-dhcp[1403]: DHCPACK(wlan0) 192.168.211.142 0a:0a:a1:73:3f:6d Jun 10 21:58:26 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jun 10 21:58:26 volumiopi4 sudo[2163]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jun 10 21:58:26 volumiopi4 sudo[2163]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 21:58:26 volumiopi4 sudo[2163]: pam_unix(sudo:session): session closed for user root Jun 10 21:58:33 volumiopi4 volumio[1379]: info: MRS: MultiRoom plugin initialized Jun 10 21:58:33 volumiopi4 volumio[1379]: info: MRS: STOPPING SNAPCLIENT Jun 10 21:58:33 volumiopi4 volumio[1379]: info: MRS: Snap server stop Jun 10 21:58:33 volumiopi4 volumio[1379]: info: MRS: STOPPING volumioStreaming Jun 10 21:58:33 volumiopi4 sudo[2196]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jun 10 21:58:33 volumiopi4 sudo[2196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 21:58:34 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jun 10 21:58:34 volumiopi4 sudo[2198]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jun 10 21:58:34 volumiopi4 sudo[2203]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jun 10 21:58:34 volumiopi4 sudo[2198]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 21:58:34 volumiopi4 sudo[2203]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 21:58:34 volumiopi4 sudo[2200]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jun 10 21:58:34 volumiopi4 sudo[2200]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 10 21:58:34 volumiopi4 sudo[2203]: pam_unix(sudo:session): session closed for user root Jun 10 21:58:34 volumiopi4 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 10 21:58:34 volumiopi4 dbus-daemon[679]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.43' (uid=0 pid=2208 comm="timedatectl show --property=NTPSynchronized --valu") Jun 10 21:58:36 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jun 10 21:58:36 volumiopi4 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 10 21:58:37 volumiopi4 sudo[2196]: pam_unix(sudo:session): session closed for user root Jun 10 21:58:37 volumiopi4 sudo[2200]: pam_unix(sudo:session): session closed for user root Jun 10 21:58:37 volumiopi4 sudo[2198]: pam_unix(sudo:session): session closed for user root Jun 10 21:58:37 volumiopi4 dbus-daemon[679]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 10 21:58:37 volumiopi4 systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 10 21:58:37 volumiopi4 setdatetime-helper.sh[2204]: Time is not synchronized. Attempting to sync... Jun 10 21:58:37 volumiopi4 sudo[2232]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Tue, 10 Jun 2025 19:58:38 GMT#015' Jun 10 21:58:37 volumiopi4 sudo[2232]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 10 21:58:38 volumiopi4 setdatetime-helper.sh[2233]: Tue Jun 10 21:58:38 SAST 2025 Jun 10 21:58:38 volumiopi4 sudo[2232]: pam_unix(sudo:session): session closed for user root Jun 10 21:58:38 volumiopi4 setdatetime-helper.sh[2204]: Time synchronized successfully. Jun 10 21:58:38 volumiopi4 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 10 21:58:38 volumiopi4 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 10 21:58:40 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jun 10 21:58:50 volumiopi4 dbus-daemon[679]: [system] Connection has not authenticated soon enough, closing it (auth_timeout=30000ms, elapsed: 30016ms) Jun 10 21:58:55 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jun 10 21:58:55 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jun 10 21:59:06 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jun 10 21:59:06 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jun 10 21:59:07 volumiopi4 systemd[1]: systemd-timedated.service: Deactivated successfully. Jun 10 21:59:10 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jun 10 21:59:17 volumiopi4 volumio[1379]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jun 10 21:59:17 volumiopi4 volumio[1379]: info: Preparing to generate the ALSA configuration file Jun 10 21:59:17 volumiopi4 volumio[1379]: info: BOOT COMPLETED Jun 10 21:59:20 volumiopi4 volumio[1379]: info: Setting Geolocation for MyVolumio to eu3 Jun 10 21:59:20 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 21:59:20 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 21:59:20 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 21:59:20 volumiopi4 volumio[1379]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Jun 10 21:59:20 volumiopi4 volumio[1379]: info: Reading ALSA contributions from plugins. Jun 10 21:59:20 volumiopi4 volumio[1379]: info: Setting Geolocation for MyVolumio to eu3 Jun 10 21:59:20 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 21:59:20 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 21:59:20 volumiopi4 volumio[1379]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 10 21:59:21 volumiopi4 volumio[1379]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 21:59:21 volumiopi4 volumio[1379]: Error: write EPIPE Jun 10 21:59:21 volumiopi4 volumio[1379]: at afterWriteDispatched (node:internal/stream_base_commons:160:15) Jun 10 21:59:21 volumiopi4 volumio[1379]: at writeGeneric (node:internal/stream_base_commons:151:3) Jun 10 21:59:21 volumiopi4 volumio[1379]: at Socket._writeGeneric (node:net:952:11) Jun 10 21:59:21 volumiopi4 volumio[1379]: at Socket._write (node:net:964:8) Jun 10 21:59:21 volumiopi4 volumio[1379]: at writeOrBuffer (node:internal/streams/writable:399:12) Jun 10 21:59:21 volumiopi4 volumio[1379]: at _write (node:internal/streams/writable:340:10) Jun 10 21:59:21 volumiopi4 volumio[1379]: at Writable.write (node:internal/streams/writable:344:10) Jun 10 21:59:21 volumiopi4 volumio[1379]: at auth (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/handshake.js:67:10) Jun 10 21:59:21 volumiopi4 volumio[1379]: at Socket. (/myvolumio/plugins/audio_interface/bluetooth/node_modules/dbus-next/lib/connection.js:137:32) Jun 10 21:59:21 volumiopi4 volumio[1379]: at Object.onceWrapper (node:events:628:28) Jun 10 21:59:21 volumiopi4 volumio[1379]: at Socket.emit (node:events:514:28) Jun 10 21:59:21 volumiopi4 volumio[1379]: at PipeConnectWrap.afterConnect [as oncomplete] (node:net:1580:10) { Jun 10 21:59:21 volumiopi4 volumio[1379]: errno: -32, Jun 10 21:59:21 volumiopi4 volumio[1379]: code: 'EPIPE', Jun 10 21:59:21 volumiopi4 volumio[1379]: syscall: 'write' Jun 10 21:59:21 volumiopi4 volumio[1379]: } Jun 10 21:59:21 volumiopi4 volumio[1379]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 10 21:59:23 volumiopi4 sudo[2370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-10 21:58' Jun 10 21:59:23 volumiopi4 sudo[2370]: 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="ecd46641d85b721d58d1c7edd71dd0a358c8514c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 7 04:21:21 UTC 2025" VOLUMIO_VERSION="4.011" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="97f38114d6fd5ff4ce2816f49b632994"