Dec 18 10:08:13 volumio ntpd[1199]: CLOCK: time stepped by 43336.790744 Dec 18 10:08:13 volumio ntpd[1199]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 18 10:08:14 volumio volumio[1319]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 18 10:08:15 volumio volumio[1319]: info: Getting Spotify volume Dec 18 10:08:15 volumio volumio[1319]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 18 10:08:17 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:17 volumio volumio[1319]: info: CorePlayQueue::getTrack 0 Dec 18 10:08:18 volumio volumio[1319]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 18 10:08:18 volumio volumio[1319]: SPOTIFY: SPOTIFY VOLUME undefined Dec 18 10:08:18 volumio volumio[1319]: SPOTIFY: VOLUMIO VOLUME 41 Dec 18 10:08:18 volumio volumio[1319]: info: Aligning Spotify Volume to Volumio Volume Dec 18 10:08:18 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:18 volumio volumio[1319]: info: CorePlayQueue::getTrack 0 Dec 18 10:08:18 volumio volumio[1319]: info: Setting Spotify Volume from Volumio: 41 Dec 18 10:08:18 volumio volumio[1319]: info: Discovery: A device disappeared from network Dec 18 10:08:18 volumio volumio[1319]: info: Discovery: Device volumio disappeared from network Dec 18 10:08:18 volumio volumio[1319]: info: Discovery: A device disappeared from network Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 18 10:08:19 volumio volumio[1319]: info: Adding plugin bluetooth to MyMusic Plugins Dec 18 10:08:19 volumio volumio[1319]: info: Adding plugin multiroom to MyMusic Plugins Dec 18 10:08:19 volumio volumio[1319]: info: Adding plugin metavolumio to MyMusic Plugins Dec 18 10:08:19 volumio volumio[1319]: info: Adding plugin cd_controller to MyMusic Plugins Dec 18 10:08:19 volumio volumio[1319]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 18 10:08:19 volumio volumio[1319]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 18 10:08:19 volumio volumio[1319]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 18 10:08:19 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:19 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:19 volumio volumio[1319]: info: Starting MyVolumio Remote Streaming Endpoints Dec 18 10:08:19 volumio volumio[1319]: info: MyVolumio login type: Token Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 18 10:08:19 volumio volumio[1319]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 18 10:08:20 volumio volumio[1319]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 18 10:08:20 volumio volumio[1319]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 18 10:08:20 volumio volumio[1319]: info: Streaming services startup Dec 18 10:08:20 volumio volumio[1319]: info: Starting Streaming Daemon Dec 18 10:08:20 volumio sudo[1705]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 18 10:08:20 volumio sudo[1705]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:20 volumio volumio[1319]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 18 10:08:20 volumio volumio[1319]: SPOTIFY: SETTING SPOTIFY VOLUME 41 Dec 18 10:08:20 volumio volumio[1319]: info: Sending Spotify command with payload to local API: /player/volume Dec 18 10:08:20 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Dec 18 10:08:20 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Dec 18 10:08:20 volumio sudo[1705]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:20 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Dec 18 10:08:20 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. Dec 18 10:08:20 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Dec 18 10:08:20 volumio volumio[1319]: error: Cannot start Volumio Streaming Daemon Dec 18 10:08:20 volumio volumio[1319]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 18 10:08:20 volumio volumio[1319]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 18 10:08:21 volumio volumio[1319]: info: Discovery: adding 6b2e64c0-f5f5-4d51-b3d0-9977c022bbdb Dec 18 10:08:21 volumio volumio[1319]: info: Discovery: Found device Volumio Dec 18 10:08:21 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:21 volumio volumio[1319]: info: CorePlayQueue::getTrack 0 Dec 18 10:08:21 volumio volumio[1319]: info: Discovery: this is already registered, 6b2e64c0-f5f5-4d51-b3d0-9977c022bbdb Dec 18 10:08:21 volumio volumio[1319]: info: Discovery: Found device Volumio Dec 18 10:08:21 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:21 volumio volumio[1319]: info: CorePlayQueue::getTrack 0 Dec 18 10:08:21 volumio nmbd[1234]: [2025/12/18 10:08:21.659947, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Dec 18 10:08:21 volumio nmbd[1234]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.177 for name WORKGROUP<1d>. Dec 18 10:08:21 volumio nmbd[1234]: This response was from IP 192.168.1.14, reporting an IP address of 192.168.1.14. Dec 18 10:08:22 volumio volumio[1319]: verbose: New Socket.io Connection to 192.168.1.177 from 192.168.1.130 UA: Mozilla/5.0 (Linux; Android 16; 24129PN74G Build/BP2A.250605.031.A3; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/143.0.7499.34 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 18 10:08:22 volumio volumio[1319]: (node:1319) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 close listeners added to [TLSSocket]. Use emitter.setMaxListeners() to increase limit Dec 18 10:08:23 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 18 10:08:24 volumio volumio[1319]: info: CoreCommandRouter::volumioGetVisibleSources Dec 18 10:08:24 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 10:08:24 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:24 volumio volumio[1319]: info: CorePlayQueue::getTrack 0 Dec 18 10:08:24 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 18 10:08:24 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 18 10:08:24 volumio volumio[1319]: info: Received Get System Info Dec 18 10:08:24 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 10:08:24 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 10:08:24 volumio volumio[1319]: info: Discovery: Getting this device information Dec 18 10:08:24 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:24 volumio volumio[1319]: info: CorePlayQueue::getTrack 0 Dec 18 10:08:24 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 10:08:24 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:24 volumio volumio[1319]: info: CorePlayQueue::getTrack 0 Dec 18 10:08:24 volumio volumio[1319]: info: Listing playlists Dec 18 10:08:24 volumio volumio[1319]: info: CoreCommandRouter::volumioGetQueue Dec 18 10:08:24 volumio volumio[1319]: info: CoreStateMachine::getQueue Dec 18 10:08:24 volumio volumio[1319]: info: CorePlayQueue::getQueue Dec 18 10:08:25 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 18 10:08:25 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 18 10:08:27 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 18 10:08:27 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 18 10:08:27 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 18 10:08:27 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 18 10:08:27 volumio sudo[1698]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: Cannot compose Albumart path Dec 18 10:08:27 volumio volumio[1319]: info: Upmpdcli Daemon Started Dec 18 10:08:28 volumio volumio[1319]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 18 10:08:28 volumio volumio[1319]: info: MyVolumio token set successfully Dec 18 10:08:28 volumio volumio[1319]: info: MYVOLUMIO: Adding device Dec 18 10:08:28 volumio volumio[1319]: info: MYVOLUMIO: Evaluating Server Dec 18 10:08:28 volumio volumio[1319]: info: MyVolumio Plan changed: premium Dec 18 10:08:28 volumio volumio[1319]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 18 10:08:28 volumio volumio[1319]: info: Removing browser output: myVolumio user plan is not superstar Dec 18 10:08:28 volumio volumio[1319]: info: Removing audio output: Dec 18 10:08:28 volumio volumio[1319]: info: MYVOLUMIO: Adding device Dec 18 10:08:28 volumio volumio[1319]: info: MYVOLUMIO: Evaluating Server Dec 18 10:08:28 volumio volumio[1319]: info: Remote config written successfully Dec 18 10:08:28 volumio volumio[1319]: info: Starting Tunnel 1 Dec 18 10:08:28 volumio volumio[1319]: info: Starting Tunnel Connection Checker Dec 18 10:08:28 volumio volumio[1319]: info: Completed starting MyVolumio Plugin Dec 18 10:08:29 volumio volumio[1319]: info: MYVolumio Device enabled Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 18 10:08:29 volumio volumio[1319]: info: MyVolumio status changed Dec 18 10:08:29 volumio volumio[1319]: info: Streaming services startup Dec 18 10:08:29 volumio volumio[1319]: info: Starting Streaming Daemon Dec 18 10:08:29 volumio sudo[1796]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 18 10:08:29 volumio sudo[1796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:29 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 18 10:08:29 volumio sudo[1796]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:29 volumio volumio[1319]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 18 10:08:29 volumio sudo[1800]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 18 10:08:29 volumio sudo[1800]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:29 volumio sudo[1800]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:29 volumio volumio[1319]: info: MRS: MultiRoom plugin initialized Dec 18 10:08:29 volumio volumio[1319]: info: MRS: STOPPING SNAPCLIENT Dec 18 10:08:29 volumio volumio[1319]: info: MRS: Snap server stop Dec 18 10:08:29 volumio volumio[1319]: info: MRS: STOPPING volumioStreaming Dec 18 10:08:29 volumio sudo[1817]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 18 10:08:29 volumio sudo[1817]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 18 10:08:29 volumio sudo[1822]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 18 10:08:29 volumio sudo[1822]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:29 volumio sudo[1819]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 18 10:08:29 volumio sudo[1819]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:29 volumio sudo[1824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 18 10:08:29 volumio sudo[1824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:29 volumio sudo[1824]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 18 10:08:29 volumio sudo[1817]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:29 volumio sudo[1822]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 18 10:08:29 volumio sudo[1819]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 18 10:08:29 volumio volumio[1319]: info: Preparing to generate the ALSA configuration file Dec 18 10:08:29 volumio volumio[1319]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 18 10:08:29 volumio volumio[1319]: info: Reading ALSA contributions from plugins. Dec 18 10:08:29 volumio volumio[1319]: info: MRS: Removed streaming files Dec 18 10:08:29 volumio volumio[1319]: info: MRS: volumioStreaming STOPPED Dec 18 10:08:29 volumio volumio[1319]: info: MRS: SNAPSERVER STOPPED Dec 18 10:08:29 volumio volumio[1319]: info: MRS: SNAPCLIENT STOPPED Dec 18 10:08:29 volumio volumio[1319]: error: Cannot start Volumio Streaming Daemon Dec 18 10:08:29 volumio volumio[1319]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 18 10:08:29 volumio volumio[1319]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 18 10:08:29 volumio volumio[1319]: info: Asound.conf file written Dec 18 10:08:29 volumio sudo[1831]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 18 10:08:29 volumio sudo[1831]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:29 volumio sudo[1831]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:29 volumio volumio[1319]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 18 10:08:29 volumio volumio[1319]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 18 10:08:29 volumio volumio[1319]: info: Output device has changed, restarting MPD Dec 18 10:08:29 volumio sudo[1837]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 18 10:08:29 volumio volumio[1319]: info: Output device has changed, restarting Shairport Sync Dec 18 10:08:29 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:29 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:29 volumio sudo[1837]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:29 volumio sudo[1837]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:29 volumio sudo[1839]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 18 10:08:29 volumio sudo[1839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:29 volumio volumio[1319]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 18 10:08:29 volumio volumio[1319]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 18 10:08:29 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 18 10:08:29 volumio volumio[1319]: ------------------------------------ BT MESSAGE: [FUNC] onStart Dec 18 10:08:29 volumio volumio[1319]: ------------------------------------ BT MESSAGE: Starting Volumio Bluetooth Service Dec 18 10:08:29 volumio volumio[1319]: ------------------------------------ BT MESSAGE: Boot config /etc/bluetooth/volumio.conf: cache mode = tmp Dec 18 10:08:29 volumio volumio[1319]: ------------------------------------ BT MESSAGE: [metaCache] Created directory: /tmp/bluetooth-cache/ Dec 18 10:08:29 volumio volumio[1319]: ------------------------------------ BT MESSAGE: [metaCache] Directory exists and is ready. Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 18 10:08:29 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Dec 18 10:08:29 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 18 10:08:29 volumio volumio[1319]: info: MRS: Pushing multiroomSync output for this device Dec 18 10:08:29 volumio volumio[1319]: info: MRS: Pushing multiroomSync output Dec 18 10:08:29 volumio volumio[1319]: info: Adding audio output: Dec 18 10:08:29 volumio volumio[1319]: info: Adding audio output: Dec 18 10:08:29 volumio volumio[1319]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 18 10:08:29 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 18 10:08:29 volumio volumio[1319]: info: Adding METAVOLUMIO REST API Endpoints Dec 18 10:08:29 volumio volumio[1319]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 18 10:08:29 volumio volumio[1319]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 18 10:08:29 volumio volumio[1319]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 18 10:08:29 volumio volumio[1319]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 18 10:08:29 volumio volumio[1319]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 18 10:08:29 volumio volumio[1319]: info: Preparing CD Folders Dec 18 10:08:29 volumio volumio[1319]: info: Adding CD REST API Endpoints Dec 18 10:08:29 volumio volumio[1319]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Dec 18 10:08:29 volumio volumio[1319]: info: Starting UDEV Watcher for CD Dec 18 10:08:29 volumio volumio[1319]: info: Detecting CD presence with UDEV Dec 18 10:08:29 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Dec 18 10:08:29 volumio bluetoothd[995]: Adv Monitor app :1.20 disconnected from D-Bus Dec 18 10:08:29 volumio systemd[1]: mpd.service: Deactivated successfully. Dec 18 10:08:29 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 18 10:08:29 volumio systemd[1]: mpd.service: Consumed 2.082s CPU time. Dec 18 10:08:29 volumio systemd[1]: mpd.socket: Deactivated successfully. Dec 18 10:08:29 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 18 10:08:29 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 18 10:08:29 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 18 10:08:29 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Dec 18 10:08:30 volumio sudo[1858]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 18 10:08:30 volumio sudo[1858]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 18 10:08:30 volumio sudo[1858]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:30 volumio mpd[1860]: 2025-12-18T10:08:30 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 18 10:08:30 volumio systemd[1]: Started mpd.service - Music Player Daemon. Dec 18 10:08:30 volumio sudo[1839]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:34 volumio upmpdcli[1880]: writing RSA key Dec 18 10:08:34 volumio volumio[1319]: warn: [cd-plugin] cdspeedctl: device or media not ready Dec 18 10:08:34 volumio volumio[1319]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 18 10:08:34 volumio volumio[1319]: info: Adding inputs REST Endpoints Dec 18 10:08:34 volumio volumio[1319]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Dec 18 10:08:34 volumio volumio[1319]: info: Scanning Audio Inputs Dec 18 10:08:34 volumio volumio[1319]: info: Checking against Known Cards name Dec 18 10:08:34 volumio volumio[1319]: info: Adding Server instance for streaming Dec 18 10:08:34 volumio volumio[1319]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 18 10:08:34 volumio volumio[1319]: error: Hi Res Audio Failed Login: Missing Login Data Dec 18 10:08:34 volumio volumio[1319]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 18 10:08:34 volumio volumio[1319]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 18 10:08:34 volumio volumio[1319]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 18 10:08:34 volumio volumio[1319]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 18 10:08:34 volumio volumio[1319]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 18 10:08:34 volumio volumio[1319]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 18 10:08:34 volumio volumio[1319]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Dec 18 10:08:34 volumio sudo[1887]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 18 10:08:34 volumio sudo[1887]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:34 volumio volumio[1319]: info: QobuzConnect: Starting Qobuz Connect socket and service Dec 18 10:08:34 volumio volumio[1319]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Dec 18 10:08:34 volumio volumio[1319]: info: Adding TIDAL REST API Endpoints Dec 18 10:08:34 volumio volumio[1319]: info: Stopping AccessToken refresher cron for QOBUZ Dec 18 10:08:34 volumio sudo[1894]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 18 10:08:34 volumio sudo[1894]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:34 volumio sudo[1887]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:34 volumio volumio[1319]: info: AccessToken refresher cron started for QOBUZ Dec 18 10:08:34 volumio volumio[1319]: info: Adding QOBUZ REST API Endpoints Dec 18 10:08:34 volumio sudo[1894]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:34 volumio volumio[1319]: info: MRS: Getting audio outputs on start Dec 18 10:08:34 volumio volumio[1319]: info: MRS: Requesting all other devices output Dec 18 10:08:34 volumio sudo[1898]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 18 10:08:34 volumio sudo[1898]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:34 volumio volumio[1319]: info: Setting Geolocation for MyVolumio to eu6 Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:34 volumio volumio[1319]: info: Setting Geolocation for MyVolumio to eu6 Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:34 volumio volumio[1319]: ------------------------------------ BT MESSAGE: Bluetooth adapter powered on Dec 18 10:08:34 volumio volumio[1319]: info: MPD Permissions set Dec 18 10:08:34 volumio sudo[1903]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Dec 18 10:08:34 volumio sudo[1903]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:34 volumio systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:34 volumio sudo[1898]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:34 volumio systemd[1]: Started volumiobt.service - Volumio Bluetooth Module. Dec 18 10:08:34 volumio sudo[1903]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:34 volumio volumiobt[1915]: INFO [BTSTART] Ensuring Bluetooth directory exists... Dec 18 10:08:34 volumio volumio[1319]: error: updateQueue error: null Dec 18 10:08:34 volumio sudo[1916]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /var/lib/bluetooth Dec 18 10:08:34 volumio sudo[1916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:34 volumio volumio[1319]: ------------------------------------ BT MESSAGE: volumiobt.service started successfully Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::volumioStop Dec 18 10:08:34 volumio volumio[1319]: info: CoreStateMachine::stop Dec 18 10:08:34 volumio volumio[1319]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 10:08:34 volumio volumio[1319]: ------------------------------------ BT MESSAGE: [FUNC] dbusStart Dec 18 10:08:34 volumio sudo[1916]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:34 volumio volumio[1319]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 18 10:08:34 volumio sudo[1920]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth Dec 18 10:08:34 volumio sudo[1920]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:34 volumio volumio[1319]: info: Starting Shairport Sync Dec 18 10:08:34 volumio sudo[1925]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 18 10:08:34 volumio sudo[1920]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:34 volumio sudo[1925]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:34 volumio volumiobt[1926]: INFO [BTSTART] Powering on Bluetooth if needed... Dec 18 10:08:34 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 18 10:08:34 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Dec 18 10:08:34 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 18 10:08:34 volumio systemd[1]: shairport-sync.service: Consumed 1.565s CPU time. Dec 18 10:08:34 volumio bluetoothd[995]: Adv Monitor app :1.23 disconnected from D-Bus Dec 18 10:08:34 volumio volumiobt[1931]: INFO [BTSTART] Making Bluetooth discoverable and pairable... Dec 18 10:08:34 volumio volumio[1319]: info: Executing endpoint qc_getconfig Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 18 10:08:34 volumio qobuz-connect[1900]: 20251218 10:08:34.929 [1900.1900] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 18 10:08:34 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 18 10:08:34 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 18 10:08:34 volumio volumiobt[1932]: [176B blob data] Dec 18 10:08:34 volumio volumiobt[1932]: [157B blob data] Dec 18 10:08:34 volumio volumiobt[1932]: [157B blob data] Dec 18 10:08:34 volumio volumiobt[1932]: [157B blob data] Dec 18 10:08:34 volumio volumiobt[1932]: [bluetoothctl]> discoverable on Dec 18 10:08:34 volumio volumiobt[1932]: Warning: setting discoverable while discoverable-timeout not set(0) is not recommended Dec 18 10:08:34 volumio volumiobt[1932]: [bluetoothctl]> pairable on Dec 18 10:08:34 volumio sudo[1925]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:34 volumio volumio[1319]: info: MRS: Found cast device: WiiM-Ultra-ae9dbf7d7000c3af6fbe72515446954c Dec 18 10:08:34 volumio volumio[1319]: info: Adding audio output: Dec 18 10:08:34 volumio volumio[1319]: info: MRS: Found cast device: Mi-Smart-Speaker-b6c833f02f670b8a504d0b6cc6edb210 Dec 18 10:08:34 volumio volumio[1319]: info: Adding audio output: Dec 18 10:08:34 volumio bluetoothd[995]: Adv Monitor app :1.24 disconnected from D-Bus Dec 18 10:08:34 volumio volumiobt[1932]: [bluetoothctl]> Dec 18 10:08:34 volumio volumiobt[1936]: INFO [BTSTART] Registering Bluetooth agent... Dec 18 10:08:34 volumio volumio[1319]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 18 10:08:34 volumio volumio[1319]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 18 10:08:34 volumio volumiobt[1937]: [NEW] Media /org/bluez/hci0 Dec 18 10:08:34 volumio volumiobt[1937]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 18 10:08:34 volumio volumiobt[1937]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 18 10:08:34 volumio volumiobt[1937]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 18 10:08:34 volumio bluetoothd[995]: Adv Monitor app :1.25 disconnected from D-Bus Dec 18 10:08:34 volumio volumio[1319]: info: Shairport-Sync Started Dec 18 10:08:34 volumio sudo[1953]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 18 10:08:34 volumio sudo[1953]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:34 volumio volumio[1319]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Dec 18 10:08:34 volumio volumiobt[1952]: No agent is registered Dec 18 10:08:34 volumio volumiobt[1952]: [NEW] Media /org/bluez/hci0 Dec 18 10:08:34 volumio volumiobt[1952]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Dec 18 10:08:34 volumio volumiobt[1952]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Dec 18 10:08:34 volumio volumiobt[1952]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Dec 18 10:08:34 volumio bluetoothd[995]: Adv Monitor app :1.28 disconnected from D-Bus Dec 18 10:08:34 volumio volumiobt[1956]: INFO [BTSTART] Agent registered successfully. Dec 18 10:08:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 18 10:08:34 volumio volumiobt[1957]: INFO [BTSTART] Starting A2DP agent (a2dp-agent)... Dec 18 10:08:34 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 18 10:08:34 volumio qobuz-connect[1900]: 20251218 10:08:34.992 [1900.1900] INFO VolumeManager: [0x1b46348]: Setting new playback volume: 75 Dec 18 10:08:34 volumio qobuz-connect[1900]: 20251218 10:08:34.992 [1900.1900] INFO VolumeManager: [0x1b46348]: Setting new mute state: 0 Dec 18 10:08:34 volumio qobuz-connect[1900]: 20251218 10:08:34.992 [1900.1900] INFO QobuzConnect: [0x1b46d18]: Client initialized! Dec 18 10:08:34 volumio qobuz-connect[1900]: 20251218 10:08:34.992 [1900.1900] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Dec 18 10:08:34 volumio qobuz-connect[1900]: 20251218 10:08:34.998 [1900.1900] INFO LocalConfigManager: [0x1b45c28]: Starting Local Configuration server Dec 18 10:08:34 volumio qobuz-connect[1900]: 20251218 10:08:34.998 [1900.1900] INFO SampleApp: Starting Local configuration server Dec 18 10:08:34 volumio qobuz-connect[1900]: 20251218 10:08:34.998 [1900.1900] INFO SampleApp: Connected to UNIX socket client 0x1b30818 Dec 18 10:08:35 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:35 volumio systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Dec 18 10:08:35 volumio sudo[1953]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:35 volumio volumio[1319]: info: Remote SSH Started Dec 18 10:08:35 volumio autossh[1962]: port set to 0, monitoring disabled Dec 18 10:08:35 volumio autossh[1962]: starting ssh (count 1) Dec 18 10:08:35 volumio autossh[1962]: ssh child pid is 1965 Dec 18 10:08:35 volumio volumio[1319]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 18 10:08:35 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:35 volumio volumio[1319]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.tuQ60T4OQ0PBAafQhb19T61FX1X2.f55075054c2192eb87872aab03d7b1b8.state.status' Dec 18 10:08:35 volumio qobuz-connect[1900]: 20251218 10:08:35.081 [1900.1900] INFO SampleApp: Playback volume changed: 75 Dec 18 10:08:35 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:35 volumio volumiossh-tunnel[1965]: Warning: Permanently added '[eu1.myvolumio.org]:2222' (RSA) to the list of known hosts. Dec 18 10:08:35 volumio volumio[1319]: info: Successfully Added MyVolumio device Dec 18 10:08:35 volumio volumio[1319]: info: Successfully Added MyVolumio device Dec 18 10:08:35 volumio volumiobt[1958]: INFO:a2dp-agent:Connecting to system bus. Dec 18 10:08:35 volumio volumiobt[1958]: INFO:a2dp-agent:Found Bluetooth adapter at: /org/bluez/hci0 Dec 18 10:08:35 volumio volumiobt[1958]: INFO:a2dp-agent:Enabling infinite discovery on adapter. Dec 18 10:08:35 volumio volumiobt[1958]: INFO:a2dp-agent:Registering agent. Dec 18 10:08:35 volumio volumiobt[1958]: INFO:a2dp-agent:Searching for MediaPlayer1 interface... Dec 18 10:08:35 volumio volumiobt[1958]: WARNING:a2dp-agent:No active MediaPlayer1 found. Metadata updates won't work. Dec 18 10:08:35 volumio volumio[1319]: info: Updating MyVolumio device info Dec 18 10:08:35 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:35 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:35 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:35 volumio volumio[1319]: info: Updating MyVolumio device info Dec 18 10:08:35 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:35 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:35 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:36 volumio volumio[1319]: info: Successfully Updated MyVolumio device Dec 18 10:08:36 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 10:08:36 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:36 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 18 10:08:36 volumio volumio-remote-updater[1009]: Test mode disabled Dec 18 10:08:36 volumio volumio-remote-updater[1009]: Alpha mode disabled Dec 18 10:08:36 volumio volumio-remote-updater[1009]: Alpha legacy test mode disabled Dec 18 10:08:36 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Dec 18 10:08:36 volumio volumio[1319]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Dec 18 10:08:36 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Dec 18 10:08:36 volumio volumio[1319]: info: Successfully Updated MyVolumio device Dec 18 10:08:37 volumio volumio[1319]: info: TidalConnect service stoped! Dec 18 10:08:37 volumio volumio[1319]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 18 10:08:37 volumio volumio[1319]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 18 10:08:37 volumio sudo[1981]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 18 10:08:37 volumio sudo[1981]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:37 volumio sudo[1985]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 18 10:08:37 volumio sudo[1985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:37 volumio systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 18 10:08:37 volumio sudo[1981]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:37 volumio systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 18 10:08:37 volumio systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 18 10:08:37 volumio systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 18 10:08:37 volumio systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 18 10:08:37 volumio sudo[1985]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:37 volumio volumio[1319]: info: Successfully started MPD Monitor Dec 18 10:08:37 volumio mpd_monitor.sh[1989]: MPD Monitor Service: Starting MPD Monitor Service Dec 18 10:08:37 volumio volumio[1319]: info: Executing endpoint tc_getconfig Dec 18 10:08:37 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 18 10:08:37 volumio vtcs[1987]: STARTING TidalConnect services, version: 1.5.2.56 Dec 18 10:08:37 volumio vtcs[1987]: STARTED TidalConnect services. Dec 18 10:08:37 volumio volumio[1319]: info: Executing endpoint tc_connect Dec 18 10:08:37 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 18 10:08:37 volumio volumio[1319]: info: Connecting to TidalConnect Dec 18 10:08:37 volumio volumio[1319]: info: CoreCommandRouter::servicePushState Dec 18 10:08:37 volumio volumio[1319]: info: CoreStateMachine::pushState Dec 18 10:08:37 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 18 10:08:37 volumio volumio[1319]: info: CoreCommandRouter::volumioPushState Dec 18 10:08:37 volumio volumio[1319]: info: MRS: Pushing multiroomSync output update for this device Dec 18 10:08:37 volumio volumio[1319]: info: MRS: Pushing multiroomSync output Dec 18 10:08:37 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:37 volumio volumio[1319]: info: CorePlayQueue::getTrack 0 Dec 18 10:08:37 volumio volumio[1319]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Dec 18 10:08:37 volumio volumio[1319]: info: CoreCommandRouter::servicePushState Dec 18 10:08:37 volumio volumio[1319]: info: CoreStateMachine::pushState Dec 18 10:08:37 volumio volumio[1319]: info: CoreCommandRouter::volumioPushState Dec 18 10:08:37 volumio volumio[1319]: info: MRS: Pushing multiroomSync output update for this device Dec 18 10:08:37 volumio volumio[1319]: info: MRS: Pushing multiroomSync output Dec 18 10:08:37 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:37 volumio volumio[1319]: info: CorePlayQueue::getTrack 0 Dec 18 10:08:37 volumio volumio[1319]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received tidalconnect Dec 18 10:08:37 volumio volumio[1319]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 18 10:08:37 volumio volumio[1319]: SPOTIFY: RECEIVED VOLUMIO VOLUME 41 Dec 18 10:08:37 volumio volumio[1319]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.tuQ60T4OQ0PBAafQhb19T61FX1X2.f55075054c2192eb87872aab03d7b1b8.state.status' Dec 18 10:08:39 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:39 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:39 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 18 10:08:39 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 18 10:08:39 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 18 10:08:39 volumio volumio[1319]: info: CoreCommandRouter::volumioGetBrowseSources Dec 18 10:08:39 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 10:08:40 volumio volumio[1319]: info: TidalConnect service started! Dec 18 10:08:40 volumio volumio[1319]: [Metrics] CommandRouter: 38s 108.34ms Dec 18 10:08:40 volumio volumio[1319]: info: CoreCommandRouter::volumiosetStartupVolume Dec 18 10:08:40 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 18 10:08:40 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 18 10:08:40 volumio volumio[1319]: info: CoreCommandRouter::Close All Modals sent Dec 18 10:08:40 volumio volumio[1319]: info: CoreCommandRouter::Close All Modals sent Dec 18 10:08:41 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 18 10:08:41 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 18 10:08:41 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 18 10:08:47 volumio volumio[1319]: info: BOOT COMPLETED Dec 18 10:08:57 volumio sudo[2047]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 18 10:08:57 volumio sudo[2047]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:57 volumio sudo[2047]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:57 volumio sudo[2049]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 10:08:57 volumio sudo[2049]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:57 volumio sudo[2049]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:57 volumio volumio[1319]: verbose: New Socket.io Connection to 192.168.1.177 from 192.168.1.163 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 18 10:08:57 volumio sudo[2053]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 18 10:08:57 volumio sudo[2053]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:57 volumio sudo[2053]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:57 volumio sudo[2055]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 18 10:08:57 volumio sudo[2055]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 18 10:08:57 volumio sudo[2055]: pam_unix(sudo:session): session closed for user root Dec 18 10:08:57 volumio volumio[1319]: verbose: New Socket.io Connection to 192.168.1.177 from 192.168.1.163 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 18 10:08:57 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 18 10:08:57 volumio volumio[1319]: info: CoreCommandRouter::volumioGetVisibleSources Dec 18 10:08:57 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 18 10:08:57 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:57 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 18 10:08:57 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 18 10:08:57 volumio volumio[1319]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 18 10:08:57 volumio volumio[1319]: info: Received Get System Info Dec 18 10:08:57 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 10:08:57 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 10:08:57 volumio volumio[1319]: info: Discovery: Getting this device information Dec 18 10:08:57 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:57 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 10:08:57 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:57 volumio volumio[1319]: info: Listing playlists Dec 18 10:08:57 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 18 10:08:57 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 18 10:08:57 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 18 10:08:58 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:59 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 18 10:08:59 volumio volumio[1319]: info: CoreCommandRouter::volumioGetQueue Dec 18 10:08:59 volumio volumio[1319]: info: CoreStateMachine::getQueue Dec 18 10:08:59 volumio volumio[1319]: info: CorePlayQueue::getQueue Dec 18 10:08:59 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 18 10:08:59 volumio volumio[1319]: info: Received Get System Info Dec 18 10:08:59 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 10:08:59 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 10:08:59 volumio volumio[1319]: info: Discovery: Getting this device information Dec 18 10:08:59 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:08:59 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 10:09:00 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 18 10:09:00 volumio volumio[1319]: info: Received Get System Info Dec 18 10:09:00 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 10:09:00 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 10:09:00 volumio volumio[1319]: info: Discovery: Getting this device information Dec 18 10:09:00 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:09:00 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 10:09:00 volumio volumio[1319]: info: Executing endpoint metavolumio Dec 18 10:09:00 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 18 10:09:02 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 10:09:02 volumio volumio[1319]: info: CURURI: music-library Dec 18 10:09:02 volumio volumio[1319]: info: Preload queue cleared Dec 18 10:09:04 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 10:09:04 volumio volumio[1319]: info: CURURI: music-library/NAS Dec 18 10:09:04 volumio volumio[1319]: info: Preload queue cleared Dec 18 10:09:05 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 10:09:05 volumio volumio[1319]: info: CURURI: music-library/NAS/Public Dec 18 10:09:05 volumio volumio[1319]: info: Preload queue cleared Dec 18 10:09:06 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 18 10:09:06 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 18 10:09:06 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 18 10:09:07 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 18 10:09:15 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 18 10:09:15 volumio volumio[1319]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 18 10:09:15 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 18 10:09:15 volumio volumio[1319]: info: Received Get System Version Dec 18 10:09:15 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 18 10:09:15 volumio volumio[1319]: info: Received Get System Info Dec 18 10:09:15 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 18 10:09:15 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 18 10:09:15 volumio volumio[1319]: info: Discovery: Getting this device information Dec 18 10:09:15 volumio volumio[1319]: info: CoreCommandRouter::volumioGetState Dec 18 10:09:15 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 18 10:09:17 volumio volumio[1319]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Dec 18 10:09:17 volumio volumio[1319]: info: CURURI: music-library/NAS/Public/Santana Dec 18 10:09:17 volumio volumio[1319]: info: Preload queue cleared Dec 18 10:09:24 volumio volumio[1319]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 18 10:09:24 volumio volumio[1319]: info: CoreStateMachine::ClearQueue Dec 18 10:09:24 volumio volumio[1319]: info: CoreStateMachine::stop Dec 18 10:09:24 volumio volumio[1319]: info: CoreStateMachine::serviceStop Dec 18 10:09:24 volumio volumio[1319]: info: Received STOP, but no service to execute it Dec 18 10:09:24 volumio volumio[1319]: info: CorePlayQueue::clearPlayQueue Dec 18 10:09:24 volumio volumio[1319]: info: CorePlayQueue::saveQueue Dec 18 10:09:24 volumio volumio[1319]: info: CoreCommandRouter::volumioPushQueue Dec 18 10:09:24 volumio volumio[1319]: info: CoreStateMachine::addQueueItems Dec 18 10:09:24 volumio volumio[1319]: info: CorePlayQueue::addQueueItems Dec 18 10:09:24 volumio volumio[1319]: info: Preload queue cleared Dec 18 10:09:24 volumio volumio[1319]: info: Adding Item to queue: music-library/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup Dec 18 10:09:24 volumio volumio[1319]: info: Exploding uri music-library/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup in service mpd Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2F01.%20(Da%20Le)%20Yaleo.flac&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/01. (Da Le) Yaleo.flac Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana%20feat.%20Dave%20Matthews/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2F02.%20Love%20of%20My%20Life.flac&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/02. Love of My Life.flac Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana%20feat.%20Everlast/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2F03.%20Put%20Your%20Lights%20On.flac&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/03. Put Your Lights On.flac Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2F04.%20Africa%20Bamba.flac&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/04. Africa Bamba.flac Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana%20feat.%20Rob%20Thomas/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2F05.%20Smooth.flac&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/05. Smooth.flac Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana%20feat.%20Lauryn%20Hill%20%26%20Cee%E2%80%90Lo/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2F06.%20Do%20You%20Like%20the%20Way.flac&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/06. Do You Like the Way.flac Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2F07.%20Maria%20Maria.flac&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/07. Maria Maria.flac Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2F08.%20Migra.flac&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/08. Migra.flac Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana%20feat.%20Man%C3%A1/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2F09.%20Coraz%C3%B3n%20espinado.flac&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/09. Corazón espinado.flac Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana%20feat.%20Eagle%E2%80%90Eye%20Cherry/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2F10.%20Wishing%20It%20Was.flac&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/10. Wishing It Was.flac Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2F11.%20El%20farol.flac&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/11. El farol.flac Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2F12.%20Primavera.flac&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/12. Primavera.flac Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana%20feat.%20Eric%20Clapton%20%2F%20Santana/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2F13.%20The%20Calling%20_%20Day%20of%20Celebration.flac&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/13. The Calling _ Day of Celebration.flac Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana%20feat.%20Dave%20Matthews/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2FSantana%20-%20Supernatural.cue&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/Santana - Supernatural.cue Dec 18 10:09:24 volumio volumio[1319]: info: ALBUMART /albumart?cacheid=194&web=Santana%20feat.%20Everlast/Supernatural/extralarge&path=%2Fmnt%2FNAS%2FPublic%2FSantana%2FSantana%20-%20Supernatural%20(1999)%20FLAC%20Soup%2FSantana%20-%20Supernatural.cue&metadata=false Dec 18 10:09:24 volumio volumio[1319]: info: URI /mnt/NAS/Public/Santana/Santana - Supernatural (1999) FLAC Soup/Santana - Supernatural.cue Dec 18 10:09:24 volumio volumio[1319]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 10:09:24 volumio volumio[1319]: Error: Unable to resolve or reject the same promise twice Dec 18 10:09:24 volumio volumio[1319]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 18 10:09:24 volumio volumio[1319]: at /volumio/app/plugins/music_service/mpd/index.js:2587:21 Dec 18 10:09:24 volumio volumio[1319]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Dec 18 10:09:24 volumio volumio[1319]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Dec 18 10:09:24 volumio volumio[1319]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Dec 18 10:09:24 volumio volumio[1319]: at Socket.emit (node:events:514:28) Dec 18 10:09:24 volumio volumio[1319]: at addChunk (node:internal/streams/readable:343:12) Dec 18 10:09:24 volumio volumio[1319]: at readableAddChunk (node:internal/streams/readable:312:11) Dec 18 10:09:24 volumio volumio[1319]: at Readable.push (node:internal/streams/readable:253:10) Dec 18 10:09:24 volumio volumio[1319]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 18 10:09:24 volumio volumio[1319]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 18 10:09:24 volumio sudo[2159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-18 10:08' Dec 18 10:09:24 volumio sudo[2159]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"