-- Logs begin at Thu 2019-02-14 14:11:58 +04, end at Sun 2025-12-28 17:57:46 +04. -- Dec 28 17:56:46 volumio systemd-timedated[2721]: Changed local time to Sun Dec 28 17:56:46 2025 Dec 28 17:56:46 volumio volumio[800]: info: Discovery: A device disappeared from network Dec 28 17:56:46 volumio volumio[800]: info: Discovery: Device volumio disappeared from network Dec 28 17:56:46 volumio volumio-time-update[508]: volumio-time-update-util: System time updated successfully. Dec 28 17:56:46 volumio sudo[2719]: pam_unix(sudo:session): session closed for user root Dec 28 17:56:46 volumio systemd[1]: Starting Daily apt download activities... Dec 28 17:56:46 volumio systemd[1]: Started Volumio Time Update Utility. Dec 28 17:56:46 volumio systemd[1]: Reached target Multi-User System. Dec 28 17:56:46 volumio systemd[1]: Reached target Graphical Interface. Dec 28 17:56:46 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Dec 28 17:56:46 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Dec 28 17:56:46 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Dec 28 17:56:46 volumio ntpd[784]: Soliciting pool server 80.69.209.3 Dec 28 17:56:46 volumio volumio[800]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 28 17:56:46 volumio volumio[800]: info: Adding plugin bluetooth to MyMusic Plugins Dec 28 17:56:46 volumio volumio[800]: info: Adding plugin multiroom to MyMusic Plugins Dec 28 17:56:46 volumio volumio[800]: info: Adding plugin metavolumio to MyMusic Plugins Dec 28 17:56:46 volumio volumio[800]: info: Adding plugin cd_controller to MyMusic Plugins Dec 28 17:56:46 volumio volumio[800]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 28 17:56:46 volumio volumio[800]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 28 17:56:46 volumio volumio[800]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 28 17:56:46 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 28 17:56:46 volumio systemd[1]: apt-daily.service: Succeeded. Dec 28 17:56:46 volumio systemd[1]: Started Daily apt download activities. Dec 28 17:56:46 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Dec 28 17:56:47 volumio ntpd[784]: Soliciting pool server 80.69.209.3 Dec 28 17:56:47 volumio ntpd[784]: Soliciting pool server 80.69.209.3 Dec 28 17:56:47 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Dec 28 17:56:47 volumio systemd[1]: Started Daily apt upgrade and clean activities. Dec 28 17:56:47 volumio volumio[800]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 28 17:56:47 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 28 17:56:47 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:56:47 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:56:47 volumio volumio[800]: info: Starting MyVolumio Remote Streaming Endpoints Dec 28 17:56:47 volumio systemd[1]: Startup finished in 13.439s (kernel) + 11min 23.791s (userspace) = 11min 37.230s. Dec 28 17:56:47 volumio volumio[800]: info: MyVolumio login type: Token Dec 28 17:56:47 volumio volumio[800]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 28 17:56:47 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 28 17:56:48 volumio volumio[800]: info: Starting Streaming Service Transparent Proxy Dec 28 17:56:48 volumio volumio[800]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 28 17:56:48 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 28 17:56:48 volumio volumio[800]: info: Streaming services startup Dec 28 17:56:48 volumio volumio[800]: info: Starting Streaming Daemon Dec 28 17:56:48 volumio sudo[2884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 28 17:56:48 volumio sudo[2884]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:56:48 volumio volumio[800]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 28 17:56:48 volumio sudo[2884]: pam_unix(sudo:session): session closed for user root Dec 28 17:56:48 volumio volumio[800]: info: Discovery: adding 8a6e36a5-ebef-46dd-95c2-0ecd8107dcf6 Dec 28 17:56:48 volumio volumio[800]: info: Discovery: Found device Volumio Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:48 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 17:56:48 volumio volumio[800]: info: Received Get System Info Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:56:48 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:48 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 17:56:48 volumio volumio[800]: info: Received Get System Info Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:56:48 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:48 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:56:48 volumio volumio[800]: error: Cannot start Volumio Streaming Daemon Dec 28 17:56:48 volumio volumio[800]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 28 17:56:48 volumio volumio[800]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:56:48 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 28 17:56:48 volumio volumio[800]: STREAMING PROXY: Starting server on port 3245 Dec 28 17:56:48 volumio volumio[800]: Node JS runtime: 14 Dec 28 17:56:49 volumio volumio[800]: error: MyVolumio Custom Token format not valid, refreshing it Dec 28 17:56:49 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 28 17:56:49 volumio volumio[800]: info: MyVolumio login type: Token Dec 28 17:56:50 volumio ntpd[784]: Soliciting pool server 2001:67c:d74:c8::71be Dec 28 17:56:50 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Dec 28 17:56:50 volumio volumio[800]: info: MyVolumio login type: Token Dec 28 17:56:50 volumio volumio[800]: info: MyVolumio login type: Token Dec 28 17:56:51 volumio volumio[800]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 28 17:56:51 volumio volumio[800]: info: MyVolumio token set successfully Dec 28 17:56:51 volumio volumio[800]: info: MYVOLUMIO: Adding device Dec 28 17:56:51 volumio volumio[800]: info: MYVOLUMIO: Evaluating Server Dec 28 17:56:52 volumio volumio[800]: info: MyVolumio Plan changed: premium Dec 28 17:56:52 volumio volumio[800]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Dec 28 17:56:52 volumio volumio[800]: info: Removing browser output: myVolumio user plan is not superstar Dec 28 17:56:52 volumio volumio[800]: info: Removing audio output: Dec 28 17:56:52 volumio volumio[800]: info: MYVOLUMIO: Adding device Dec 28 17:56:52 volumio volumio[800]: info: MYVOLUMIO: Evaluating Server Dec 28 17:56:52 volumio volumio[800]: info: Remote config written successfully Dec 28 17:56:52 volumio volumio[800]: info: Starting Tunnel 1 Dec 28 17:56:52 volumio volumio[800]: info: Starting Tunnel Connection Checker Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:56:52 volumio volumio[800]: info: MYVolumio Device enabled Dec 28 17:56:52 volumio volumio[800]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Dec 28 17:56:52 volumio volumio[800]: info: MyVolumio status changed Dec 28 17:56:52 volumio volumio[800]: info: Streaming services startup Dec 28 17:56:52 volumio volumio[800]: info: Starting Streaming Daemon Dec 28 17:56:52 volumio sudo[2931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 17:56:52 volumio volumio[800]: info: Received Get System Info Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:56:52 volumio sudo[2931]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:56:52 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:52 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 17:56:52 volumio volumio[800]: info: Received Get System Info Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:56:52 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:52 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 17:56:52 volumio volumio[800]: info: Received Get System Info Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:56:52 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:52 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:56:52 volumio sudo[2931]: pam_unix(sudo:session): session closed for user root Dec 28 17:56:52 volumio volumio[800]: error: Cannot start Volumio Streaming Daemon Dec 28 17:56:52 volumio volumio[800]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 28 17:56:52 volumio volumio[800]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 28 17:56:52 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 28 17:56:53 volumio volumio[800]: error: [MyVolumio PluginManager] Cache data is invalid! Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 17:56:53 volumio volumio[800]: info: Received Get System Info Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:56:53 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:53 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 17:56:53 volumio volumio[800]: info: Received Get System Info Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:56:53 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:53 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 17:56:53 volumio volumio[800]: info: Received Get System Info Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:56:53 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:53 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 17:56:53 volumio volumio[800]: info: Received Get System Info Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:56:53 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:53 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 17:56:53 volumio volumio[800]: info: Received Get System Info Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:56:53 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:53 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 17:56:53 volumio volumio[800]: info: Received Get System Info Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:56:53 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:53 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 17:56:53 volumio volumio[800]: info: Received Get System Info Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:56:53 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:53 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 28 17:56:53 volumio volumio[800]: info: Received Get System Info Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:56:53 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:53 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:56:53 volumio ntpd[784]: Soliciting pool server 2001:43f8:d60:300::197 Dec 28 17:56:53 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 28 17:56:53 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Dec 28 17:56:53 volumio volumio[800]: info: [1766930213936] Starting BluetoothController Dec 28 17:56:54 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Dec 28 17:56:55 volumio sudo[2936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Dec 28 17:56:55 volumio sudo[2936]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:56:55 volumio sudo[2936]: pam_unix(sudo:session): session closed for user root Dec 28 17:56:55 volumio volumio[800]: info: MRS: MultiRoom plugin initialized Dec 28 17:56:55 volumio volumio[800]: info: MRS: STOPPING SNAPCLIENT Dec 28 17:56:55 volumio volumio[800]: info: MRS: Snap server stop Dec 28 17:56:55 volumio sudo[2952]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Dec 28 17:56:55 volumio sudo[2952]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:56:55 volumio volumio[800]: info: MRS: STOPPING volumioStreaming Dec 28 17:56:55 volumio sudo[2955]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Dec 28 17:56:55 volumio sudo[2955]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:56:55 volumio sudo[2957]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Dec 28 17:56:55 volumio sudo[2952]: pam_unix(sudo:session): session closed for user root Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Dec 28 17:56:55 volumio sudo[2957]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:56:55 volumio sudo[2960]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Dec 28 17:56:55 volumio sudo[2960]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:56:55 volumio sudo[2955]: pam_unix(sudo:session): session closed for user root Dec 28 17:56:55 volumio sudo[2960]: pam_unix(sudo:session): session closed for user root Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Dec 28 17:56:55 volumio sudo[2957]: pam_unix(sudo:session): session closed for user root Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Dec 28 17:56:55 volumio volumio[800]: info: Preparing to generate the ALSA configuration file Dec 28 17:56:55 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Dec 28 17:56:55 volumio volumio[800]: info: Setting Geolocation for MyVolumio to as1 Dec 28 17:56:55 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:56:55 volumio volumio[800]: info: MRS: Removed streaming files Dec 28 17:56:55 volumio volumio[800]: info: MRS: volumioStreaming STOPPED Dec 28 17:56:55 volumio volumio[800]: info: MRS: SNAPSERVER STOPPED Dec 28 17:56:55 volumio volumio[800]: info: MRS: SNAPCLIENT STOPPED Dec 28 17:56:55 volumio volumio[800]: info: Asound.conf file unchanged, so no further update is needed Dec 28 17:56:55 volumio volumio[800]: info: Output device has changed, restarting MPD Dec 28 17:56:55 volumio sudo[2969]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 28 17:56:55 volumio sudo[2969]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:56:55 volumio volumio[800]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 28 17:56:55 volumio sudo[2969]: pam_unix(sudo:session): session closed for user root Dec 28 17:56:55 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:56:55 volumio sudo[2972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 28 17:56:55 volumio sudo[2972]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Dec 28 17:56:55 volumio volumio[800]: info: Adding METAVOLUMIO REST API Endpoints Dec 28 17:56:55 volumio systemd[1]: Stopping Music Player Daemon... Dec 28 17:56:55 volumio volumio[800]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Dec 28 17:56:55 volumio volumio[800]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Dec 28 17:56:55 volumio volumio[800]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Dec 28 17:56:55 volumio volumio[800]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Dec 28 17:56:55 volumio volumio[800]: error: Hi Res Audio Failed Login: Missing Login Data Dec 28 17:56:55 volumio volumio[800]: info: Adding HIGHRESAUDIO REST API Endpoints Dec 28 17:56:55 volumio volumio[800]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 28 17:56:55 volumio volumio[800]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Dec 28 17:56:55 volumio volumio[800]: info: Refreshing TIDAL token Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Dec 28 17:56:55 volumio volumio[800]: info: [MyVolumio PluginManager] Plugin qobuzconnect disabled by user. Not starting Dec 28 17:56:55 volumio volumio[800]: info: Stopping AccessToken refresher cron for QOBUZ Dec 28 17:56:55 volumio volumio[800]: info: AccessToken refresher cron started for QOBUZ Dec 28 17:56:55 volumio volumio[800]: info: Adding QOBUZ REST API Endpoints Dec 28 17:56:55 volumio volumio[800]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Dec 28 17:56:55 volumio volumio[800]: info: MPD Permissions set Dec 28 17:56:55 volumio systemd[1]: mpd.service: Succeeded. Dec 28 17:56:55 volumio systemd[1]: Stopped Music Player Daemon. Dec 28 17:56:55 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:56:55 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:56:55 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:56:55 volumio systemd[1]: Starting Music Player Daemon... Dec 28 17:56:55 volumio sudo[2976]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 28 17:56:55 volumio sudo[2976]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:56:55 volumio sudo[2976]: pam_unix(sudo:session): session closed for user root Dec 28 17:56:56 volumio ntpd[784]: Soliciting pool server 2001:67c:d74:c8::71be Dec 28 17:56:56 volumio volumio[800]: info: Successfully Added MyVolumio device Dec 28 17:56:56 volumio mpd[2982]: Dec 28 17:56 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 28 17:56:56 volumio systemd[1]: Started Music Player Daemon. Dec 28 17:56:56 volumio sudo[2972]: pam_unix(sudo:session): session closed for user root Dec 28 17:56:56 volumio volumio[800]: error: updateQueue error: null Dec 28 17:56:56 volumio volumio[800]: info: Updating MyVolumio device info Dec 28 17:56:56 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:56:56 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:56:56 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:56:56 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:56:57 volumio volumio[800]: info: Successfully Updated MyVolumio device Dec 28 17:56:57 volumio volumio[800]: info: Access Token successfully retrieved Dec 28 17:56:57 volumio sudo[3004]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Dec 28 17:56:57 volumio sudo[3004]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:56:57 volumio systemd[1]: Started MyVolumio SSH Tunnel. Dec 28 17:56:57 volumio sudo[3004]: pam_unix(sudo:session): session closed for user root Dec 28 17:56:57 volumio volumio[800]: info: Remote SSH Started Dec 28 17:56:57 volumio autossh[3007]: port set to 0, monitoring disabled Dec 28 17:56:57 volumio autossh[3007]: starting ssh (count 1) Dec 28 17:56:57 volumio autossh[3007]: ssh child pid is 3010 Dec 28 17:56:57 volumio volumio[800]: 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 28 17:56:57 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:56:57 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:56:57 volumio volumio[800]: info: Successfully retrieved User Session From TIDAL Dec 28 17:56:58 volumio ntpd[784]: Soliciting pool server 2001:43f8:d60:300::197 Dec 28 17:56:58 volumio volumio[800]: info: Successfully retrieved User Subscription From TIDAL Dec 28 17:56:58 volumio volumio[800]: info: Adding TIDAL to Browse Sources Dec 28 17:56:58 volumio volumio[800]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 28 17:56:58 volumio volumio[800]: info: [1766930218402] CoreMusicLibrary::Adding element TIDAL Dec 28 17:56:58 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:56:58 volumio volumio[800]: Cannot find translation for source TIDAL Dec 28 17:56:58 volumio volumio[800]: info: Adding TIDAL REST API Endpoints Dec 28 17:56:58 volumio volumio[800]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Dec 28 17:56:58 volumio volumio[800]: info: Setting Geolocation for MyVolumio to as1 Dec 28 17:56:58 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:56:58 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:56:58 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:56:58 volumio volumiossh-tunnel[3006]: Warning: Permanently added '[as1.myvolumio.org]:2222,[128.199.166.162]:2222' (RSA) to the list of known hosts. Dec 28 17:56:58 volumio volumio[800]: info: Successfully Added MyVolumio device Dec 28 17:56:59 volumio volumio[800]: info: Updating MyVolumio device info Dec 28 17:56:59 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:56:59 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:56:59 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 28 17:56:59 volumio sudo[3013]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 28 17:56:59 volumio sudo[3013]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:56:59 volumio systemd[1]: Stopping MPD Monitor Service... Dec 28 17:56:59 volumio systemd[1]: mpd_monitor.service: Main process exited, code=killed, status=15/TERM Dec 28 17:56:59 volumio systemd[1]: mpd_monitor.service: Succeeded. Dec 28 17:56:59 volumio systemd[1]: Stopped MPD Monitor Service. Dec 28 17:56:59 volumio systemd[1]: Started MPD Monitor Service. Dec 28 17:56:59 volumio sudo[3013]: pam_unix(sudo:session): session closed for user root Dec 28 17:56:59 volumio mpd_monitor.sh[3015]: MPD Monitor Service: Starting MPD Monitor Service Dec 28 17:56:59 volumio volumio[800]: info: Successfully started MPD Monitor Dec 28 17:56:59 volumio volumio[800]: info: Successfully Updated MyVolumio device Dec 28 17:57:00 volumio volumio[800]: info: Clearing queue after UPNP request Dec 28 17:57:00 volumio volumio[800]: info: CoreStateMachine::ClearQueue Dec 28 17:57:00 volumio volumio[800]: info: CoreStateMachine::stop Dec 28 17:57:00 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 17:57:00 volumio volumio[800]: info: CorePlayQueue::clearPlayQueue Dec 28 17:57:00 volumio volumio[800]: info: CorePlayQueue::saveQueue Dec 28 17:57:00 volumio volumio[800]: info: CoreCommandRouter::volumioPushState Dec 28 17:57:00 volumio volumio[800]: info: CoreCommandRouter::volumioPushQueue Dec 28 17:57:00 volumio volumio[800]: error: Upnp client error: Error: This socket has been ended by the other party Dec 28 17:57:00 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:57:02 volumio volumio[800]: info: Starting UPNP Playback Dec 28 17:57:02 volumio volumio[800]: info: Preparing playback through UPNP Dec 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:57:02 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:57:02 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 28 17:57:02 volumio volumio[800]: 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 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:57:02 volumio volumio[800]: info: Dec 28 17:57:02 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:02 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:02 volumio volumio[800]: info: Dec 28 17:57:02 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:02 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:02 volumio volumio[800]: info: ------------------------------ 8ms Dec 28 17:57:02 volumio volumio[800]: info: Dec 28 17:57:02 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:02 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:02 volumio volumio[800]: info: ------------------------------ 5ms Dec 28 17:57:02 volumio volumio[800]: info: ------------------------------ 2ms Dec 28 17:57:02 volumio volumio[800]: info: Dec 28 17:57:02 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:02 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:02 volumio volumio[800]: info: ------------------------------ 2ms Dec 28 17:57:02 volumio volumio[800]: info: Dec 28 17:57:02 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:02 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:02 volumio volumio[800]: info: ------------------------------ 2ms Dec 28 17:57:02 volumio volumio[800]: info: Dec 28 17:57:02 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:02 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:02 volumio volumio[800]: info: ------------------------------ 2ms Dec 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 28 17:57:02 volumio volumio[800]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 28 17:57:02 volumio volumio[800]: info: Received Get System Version Dec 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 17:57:02 volumio volumio[800]: info: Received Get System Info Dec 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:57:02 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:57:02 volumio volumio[800]: info: Dec 28 17:57:02 volumio volumio[800]: ---------------------------- MPD announces state update: player Dec 28 17:57:02 volumio volumio[800]: info: ControllerMpd::getState Dec 28 17:57:02 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status Dec 28 17:57:02 volumio kernel: usb 1-1: uac_clock_source_is_valid(): cannot get clock validity for id 41 Dec 28 17:57:02 volumio kernel: usb 1-1: clock source 41 is not valid, cannot use Dec 28 17:57:02 volumio kernel: usb 1-1: 1:1: cannot get freq (v2/v3): err -71 Dec 28 17:57:02 volumio kernel: usb 1-1: 1:1: cannot set freq 44100 (v2/v3): err -71 Dec 28 17:57:02 volumio volumio[800]: info: Dec 28 17:57:02 volumio volumio[800]: ---------------------------- MPD announces state update: player Dec 28 17:57:02 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 28 17:57:02 volumio volumio[800]: info: sendMpdCommand status took 27 milliseconds Dec 28 17:57:02 volumio volumio[800]: info: ControllerMpd::getState Dec 28 17:57:02 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status Dec 28 17:57:02 volumio volumio[800]: verbose: ControllerMpd::parseState Dec 28 17:57:02 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 17:57:02 volumio volumio[800]: info: sendMpdCommand clearerror took 5 milliseconds Dec 28 17:57:02 volumio volumio[800]: info: sendMpdCommand status took 6 milliseconds Dec 28 17:57:02 volumio volumio[800]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 28 17:57:02 volumio volumio[800]: verbose: ControllerMpd::parseState Dec 28 17:57:02 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 17:57:02 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo Dec 28 17:57:02 volumio volumio[800]: info: ControllerMpd::pushState Dec 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::servicePushState Dec 28 17:57:02 volumio volumio[800]: verbose: In UPNP mode Dec 28 17:57:02 volumio volumio[800]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Just For A Thrill","artist":"Patricia Barber","album":"Nightclub","uri":"http://192.168.1.4:49152/audirvana/audio_f_0.wav","trackType":"wav"} Dec 28 17:57:02 volumio volumio[800]: verbose: CURRENT POSITION 0 Dec 28 17:57:02 volumio volumio[800]: info: CoreStateMachine::syncState stateService pause Dec 28 17:57:02 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop Dec 28 17:57:02 volumio volumio[800]: info: CoreStateMachine::pushState Dec 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::volumioPushState Dec 28 17:57:02 volumio volumio[800]: info: ------------------------------ 55ms Dec 28 17:57:02 volumio volumio[800]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 28 17:57:02 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo Dec 28 17:57:02 volumio volumio[800]: info: ControllerMpd::pushState Dec 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::servicePushState Dec 28 17:57:02 volumio volumio[800]: verbose: In UPNP mode Dec 28 17:57:02 volumio volumio[800]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":201,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Just For A Thrill","artist":"Patricia Barber","album":"Nightclub","uri":"http://192.168.1.4:49152/audirvana/audio_f_0.wav","trackType":"wav"} Dec 28 17:57:02 volumio volumio[800]: verbose: CURRENT POSITION 0 Dec 28 17:57:02 volumio volumio[800]: info: CoreStateMachine::syncState stateService pause Dec 28 17:57:02 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop Dec 28 17:57:02 volumio volumio[800]: info: CoreStateMachine::pushState Dec 28 17:57:02 volumio volumio[800]: info: CoreCommandRouter::volumioPushState Dec 28 17:57:02 volumio volumio[800]: info: ------------------------------ 55ms Dec 28 17:57:02 volumio volumio[800]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.JKIiXjXNT3SyWbAgzKX9HPq2oIC3.370173f7cde4a17a7e7fdd87fd8cb152.state.status' Dec 28 17:57:03 volumio volumio[800]: info: Starting UPNP Playback Dec 28 17:57:03 volumio volumio[800]: info: Preparing playback through UPNP Dec 28 17:57:03 volumio volumio[800]: info: Consume mode Dec 28 17:57:03 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:57:03 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 17:57:03 volumio volumio[800]: info: Dec 28 17:57:03 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:03 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:03 volumio volumio[800]: info: ------------------------------ 3ms Dec 28 17:57:03 volumio volumio[800]: info: Dec 28 17:57:03 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:03 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:03 volumio volumio[800]: info: ------------------------------ 2ms Dec 28 17:57:03 volumio volumio[800]: info: Dec 28 17:57:03 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:03 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:03 volumio volumio[800]: info: ------------------------------ 3ms Dec 28 17:57:03 volumio volumio[800]: info: Dec 28 17:57:03 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:03 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:03 volumio volumio[800]: info: Dec 28 17:57:03 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:03 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:03 volumio volumio[800]: info: ------------------------------ 4ms Dec 28 17:57:03 volumio volumio[800]: info: ------------------------------ 3ms Dec 28 17:57:03 volumio volumio[800]: info: Dec 28 17:57:03 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:03 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:03 volumio volumio[800]: info: ------------------------------ 2ms Dec 28 17:57:04 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:57:07 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:57:07 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 28 17:57:07 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Dec 28 17:57:07 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 28 17:57:07 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 28 17:57:07 volumio volumio[800]: info: CoreCommandRouter::volumioGetBrowseSources Dec 28 17:57:07 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 28 17:57:08 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:57:09 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:57:12 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:57:16 volumio systemd[1]: systemd-timedated.service: Succeeded. Dec 28 17:57:16 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:57:20 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:57:20 volumio volumio[800]: info: Dec 28 17:57:20 volumio volumio[800]: ---------------------------- MPD announces state update: player Dec 28 17:57:20 volumio volumio[800]: info: ControllerMpd::getState Dec 28 17:57:20 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status Dec 28 17:57:20 volumio volumio[800]: info: sendMpdCommand status took 1 milliseconds Dec 28 17:57:20 volumio volumio[800]: verbose: ControllerMpd::parseState Dec 28 17:57:20 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 17:57:20 volumio volumio[800]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 28 17:57:20 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo Dec 28 17:57:20 volumio volumio[800]: info: ControllerMpd::pushState Dec 28 17:57:20 volumio volumio[800]: info: CoreCommandRouter::servicePushState Dec 28 17:57:20 volumio volumio[800]: info: CoreStateMachine::pushState Dec 28 17:57:20 volumio volumio[800]: info: CoreCommandRouter::volumioPushState Dec 28 17:57:20 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:57:20 volumio volumio[800]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Just For A Thrill","artist":"Patricia Barber","album":"Nightclub","uri":"http://192.168.1.4:49152/audirvana/audio_f_0.wav","trackType":"wav"} Dec 28 17:57:20 volumio volumio[800]: verbose: CURRENT POSITION 0 Dec 28 17:57:20 volumio volumio[800]: info: CoreStateMachine::syncState stateService stop Dec 28 17:57:20 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop Dec 28 17:57:20 volumio volumio[800]: info: CoreStateMachine::pushState Dec 28 17:57:20 volumio volumio[800]: info: CoreCommandRouter::volumioPushState Dec 28 17:57:20 volumio volumio[800]: info: No code Dec 28 17:57:20 volumio volumio[800]: info: CoreStateMachine::pushState Dec 28 17:57:20 volumio volumio[800]: info: CoreCommandRouter::volumioPushState Dec 28 17:57:20 volumio volumio[800]: info: ------------------------------ 52ms Dec 28 17:57:20 volumio volumio[800]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.JKIiXjXNT3SyWbAgzKX9HPq2oIC3.370173f7cde4a17a7e7fdd87fd8cb152.state.status' Dec 28 17:57:20 volumio volumio[800]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.JKIiXjXNT3SyWbAgzKX9HPq2oIC3.370173f7cde4a17a7e7fdd87fd8cb152.state.status' Dec 28 17:57:20 volumio volumio[800]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.JKIiXjXNT3SyWbAgzKX9HPq2oIC3.370173f7cde4a17a7e7fdd87fd8cb152.state.status' Dec 28 17:57:24 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:57:27 volumio volumio[800]: info: Dec 28 17:57:27 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:27 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:27 volumio volumio[800]: info: Starting UPNP Playback Dec 28 17:57:27 volumio volumio[800]: info: Preparing playback through UPNP Dec 28 17:57:27 volumio volumio[800]: info: Consume mode Dec 28 17:57:27 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:57:27 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 17:57:27 volumio volumio[800]: info: ------------------------------ 7ms Dec 28 17:57:28 volumio volumio[800]: info: Clearing queue after UPNP request Dec 28 17:57:28 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:57:28 volumio volumio[800]: info: CoreStateMachine::ClearQueue Dec 28 17:57:28 volumio volumio[800]: info: CoreStateMachine::stop Dec 28 17:57:28 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 17:57:28 volumio volumio[800]: info: CorePlayQueue::clearPlayQueue Dec 28 17:57:28 volumio volumio[800]: info: CorePlayQueue::saveQueue Dec 28 17:57:28 volumio volumio[800]: info: CoreCommandRouter::volumioPushState Dec 28 17:57:28 volumio volumio[800]: info: CoreCommandRouter::volumioPushQueue Dec 28 17:57:28 volumio volumio[800]: info: Dec 28 17:57:28 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:28 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:28 volumio volumio[800]: error: updateQueue error: null Dec 28 17:57:28 volumio volumio[800]: info: ------------------------------ 3ms Dec 28 17:57:28 volumio volumio[800]: info: Starting UPNP Playback Dec 28 17:57:28 volumio volumio[800]: info: Preparing playback through UPNP Dec 28 17:57:28 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:57:28 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:57:28 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 17:57:30 volumio ntpd[784]: Soliciting pool server 2001:43f8:13b0:2000::24 Dec 28 17:57:32 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:57:35 volumio volumio[800]: info: Clearing queue after UPNP request Dec 28 17:57:35 volumio volumio[800]: info: CoreStateMachine::ClearQueue Dec 28 17:57:35 volumio volumio[800]: info: CoreStateMachine::stop Dec 28 17:57:35 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 28 17:57:35 volumio volumio[800]: info: CorePlayQueue::clearPlayQueue Dec 28 17:57:35 volumio volumio[800]: info: CorePlayQueue::saveQueue Dec 28 17:57:35 volumio volumio[800]: info: CoreCommandRouter::volumioPushState Dec 28 17:57:35 volumio volumio[800]: info: CoreCommandRouter::volumioPushQueue Dec 28 17:57:35 volumio volumio[800]: info: Dec 28 17:57:35 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:35 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:35 volumio volumio[800]: error: updateQueue error: null Dec 28 17:57:35 volumio volumio[800]: info: ------------------------------ 3ms Dec 28 17:57:35 volumio volumio[800]: info: Starting UPNP Playback Dec 28 17:57:35 volumio volumio[800]: info: Preparing playback through UPNP Dec 28 17:57:35 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:57:35 volumio volumio[800]: info: CorePlayQueue::getTrack 0 Dec 28 17:57:35 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 17:57:35 volumio volumio[800]: info: Dec 28 17:57:35 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:35 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:35 volumio volumio[800]: info: Dec 28 17:57:35 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:35 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:35 volumio volumio[800]: info: ------------------------------ 4ms Dec 28 17:57:35 volumio volumio[800]: info: ------------------------------ 3ms Dec 28 17:57:35 volumio volumio[800]: info: Dec 28 17:57:35 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:35 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:36 volumio volumio[800]: info: ------------------------------ 3ms Dec 28 17:57:36 volumio volumio[800]: info: Dec 28 17:57:36 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:36 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:36 volumio volumio[800]: info: ------------------------------ 3ms Dec 28 17:57:36 volumio volumio[800]: info: Dec 28 17:57:36 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:36 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:36 volumio volumio[800]: info: ------------------------------ 3ms Dec 28 17:57:36 volumio volumio[800]: info: Dec 28 17:57:36 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:36 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:36 volumio volumio[800]: info: ------------------------------ 3ms Dec 28 17:57:36 volumio volumio[800]: info: Dec 28 17:57:36 volumio volumio[800]: ---------------------------- MPD announces state update: player Dec 28 17:57:36 volumio volumio[800]: info: ControllerMpd::getState Dec 28 17:57:36 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status Dec 28 17:57:36 volumio kernel: usb 1-1: uac_clock_source_is_valid(): cannot get clock validity for id 41 Dec 28 17:57:36 volumio kernel: usb 1-1: clock source 41 is not valid, cannot use Dec 28 17:57:36 volumio kernel: usb 1-1: 1:1: cannot get freq (v2/v3): err -71 Dec 28 17:57:36 volumio kernel: usb 1-1: 1:1: cannot set freq 44100 (v2/v3): err -71 Dec 28 17:57:36 volumio volumio[800]: info: Dec 28 17:57:36 volumio volumio[800]: ---------------------------- MPD announces state update: player Dec 28 17:57:36 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand clearerror Dec 28 17:57:36 volumio volumio[800]: info: sendMpdCommand status took 29 milliseconds Dec 28 17:57:36 volumio volumio[800]: info: ControllerMpd::getState Dec 28 17:57:36 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand status Dec 28 17:57:36 volumio volumio[800]: verbose: ControllerMpd::parseState Dec 28 17:57:36 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 17:57:36 volumio volumio[800]: info: sendMpdCommand clearerror took 6 milliseconds Dec 28 17:57:36 volumio volumio[800]: info: sendMpdCommand status took 9 milliseconds Dec 28 17:57:36 volumio volumio[800]: info: sendMpdCommand playlistinfo took 8 milliseconds Dec 28 17:57:36 volumio volumio[800]: verbose: ControllerMpd::parseState Dec 28 17:57:36 volumio volumio[800]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 28 17:57:36 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo Dec 28 17:57:36 volumio volumio[800]: info: ControllerMpd::pushState Dec 28 17:57:36 volumio volumio[800]: info: CoreCommandRouter::servicePushState Dec 28 17:57:36 volumio volumio[800]: verbose: In UPNP mode Dec 28 17:57:36 volumio volumio[800]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Free","artist":"Special EFX","album":"Body Language","uri":"http://192.168.1.4:49152/audirvana/audio_f_2.wav","trackType":"wav"} Dec 28 17:57:36 volumio volumio[800]: verbose: CURRENT POSITION 0 Dec 28 17:57:36 volumio volumio[800]: info: CoreStateMachine::syncState stateService pause Dec 28 17:57:36 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop Dec 28 17:57:36 volumio volumio[800]: info: CoreStateMachine::pushState Dec 28 17:57:36 volumio volumio[800]: info: CoreCommandRouter::volumioPushState Dec 28 17:57:36 volumio volumio[800]: info: ------------------------------ 65ms Dec 28 17:57:36 volumio volumio[800]: info: sendMpdCommand playlistinfo took 23 milliseconds Dec 28 17:57:36 volumio volumio[800]: verbose: ControllerMpd::parseTrackInfo Dec 28 17:57:36 volumio volumio[800]: info: ControllerMpd::pushState Dec 28 17:57:36 volumio volumio[800]: info: CoreCommandRouter::servicePushState Dec 28 17:57:36 volumio volumio[800]: verbose: In UPNP mode Dec 28 17:57:36 volumio volumio[800]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":272,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Free","artist":"Special EFX","album":"Body Language","uri":"http://192.168.1.4:49152/audirvana/audio_f_2.wav","trackType":"wav"} Dec 28 17:57:36 volumio volumio[800]: verbose: CURRENT POSITION 0 Dec 28 17:57:36 volumio volumio[800]: info: CoreStateMachine::syncState stateService pause Dec 28 17:57:36 volumio volumio[800]: info: CoreStateMachine::syncState currentStatus stop Dec 28 17:57:36 volumio volumio[800]: info: CoreStateMachine::pushState Dec 28 17:57:36 volumio volumio[800]: info: CoreCommandRouter::volumioPushState Dec 28 17:57:36 volumio volumio[800]: info: ------------------------------ 77ms Dec 28 17:57:36 volumio volumio[800]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.JKIiXjXNT3SyWbAgzKX9HPq2oIC3.370173f7cde4a17a7e7fdd87fd8cb152.state.status' Dec 28 17:57:36 volumio volumio[800]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.JKIiXjXNT3SyWbAgzKX9HPq2oIC3.370173f7cde4a17a7e7fdd87fd8cb152.state.status' Dec 28 17:57:36 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:57:36 volumio volumio[800]: info: Starting UPNP Playback Dec 28 17:57:36 volumio volumio[800]: info: Preparing playback through UPNP Dec 28 17:57:36 volumio volumio[800]: info: Consume mode Dec 28 17:57:36 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:57:36 volumio volumio[800]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 28 17:57:37 volumio volumio[800]: info: Dec 28 17:57:37 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:37 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:37 volumio volumio[800]: info: ------------------------------ 4ms Dec 28 17:57:37 volumio volumio[800]: info: Dec 28 17:57:37 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:37 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:37 volumio volumio[800]: info: ------------------------------ 3ms Dec 28 17:57:37 volumio volumio[800]: info: Dec 28 17:57:37 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:37 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:37 volumio volumio[800]: info: ------------------------------ 3ms Dec 28 17:57:37 volumio volumio[800]: info: Dec 28 17:57:37 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:37 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:37 volumio volumio[800]: info: ------------------------------ 2ms Dec 28 17:57:37 volumio volumio[800]: info: Dec 28 17:57:37 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:37 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:37 volumio volumio[800]: info: ------------------------------ 3ms Dec 28 17:57:37 volumio volumio[800]: info: Dec 28 17:57:37 volumio volumio[800]: ---------------------------- MPD announces system playlist update Dec 28 17:57:37 volumio volumio[800]: info: Ignoring MPD Status Update Dec 28 17:57:37 volumio volumio[800]: info: ------------------------------ 3ms Dec 28 17:57:40 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:57:41 volumio volumio[800]: verbose: New Socket.io Connection to 192.168.1.2 from 192.168.1.4 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Edg/143.0.0.0 Engine version: 3 Transport: polling Total Clients: 6 Dec 28 17:57:41 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 28 17:57:41 volumio volumio[800]: info: CoreCommandRouter::volumioGetVisibleSources Dec 28 17:57:41 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 28 17:57:41 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:57:41 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:57:41 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 28 17:57:41 volumio volumio[800]: info: CoreCommandRouter::volumioGetQueue Dec 28 17:57:41 volumio volumio[800]: info: CoreStateMachine::getQueue Dec 28 17:57:41 volumio volumio[800]: info: CorePlayQueue::getQueue Dec 28 17:57:41 volumio volumio[800]: info: Listing playlists Dec 28 17:57:41 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 28 17:57:41 volumio volumio[800]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 28 17:57:41 volumio volumio[800]: info: Received Get System Info Dec 28 17:57:41 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 28 17:57:41 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 28 17:57:41 volumio volumio[800]: info: Discovery: Getting this device information Dec 28 17:57:41 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:57:41 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 28 17:57:41 volumio volumio[800]: info: CoreCommandRouter::volumioGetState Dec 28 17:57:41 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 28 17:57:42 volumio volumio[800]: info: Disabling MyMusic plugin upnp Dec 28 17:57:42 volumio sudo[3269]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Dec 28 17:57:42 volumio sudo[3269]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 28 17:57:42 volumio systemd[1]: Stopping UPnP Renderer front-end to MPD... Dec 28 17:57:42 volumio volumio[800]: error: Upnp client error: Error: This socket has been ended by the other party Dec 28 17:57:44 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 28 17:57:46 volumio volumio[800]: info: Enabling MyMusic plugin upnp Dec 28 17:57:46 volumio volumio[800]: info: Enabling plugin upnp Dec 28 17:57:46 volumio volumio[800]: info: Loading plugin "upnp"... Dec 28 17:57:46 volumio volumio[800]: info: [1766930266081] Starting Upmpd Daemon Dec 28 17:57:46 volumio volumio[800]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 28 17:57:46 volumio volumio[800]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 17:57:46 volumio volumio[800]: Error: listen EADDRINUSE: address already in use :::6599 Dec 28 17:57:46 volumio volumio[800]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Dec 28 17:57:46 volumio volumio[800]: at listenInCluster (net.js:1366:12) Dec 28 17:57:46 volumio volumio[800]: at Server.listen (net.js:1452:7) Dec 28 17:57:46 volumio volumio[800]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Dec 28 17:57:46 volumio volumio[800]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Dec 28 17:57:46 volumio volumio[800]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Dec 28 17:57:46 volumio volumio[800]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Dec 28 17:57:46 volumio volumio[800]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Dec 28 17:57:46 volumio volumio[800]: code: 'EADDRINUSE', Dec 28 17:57:46 volumio volumio[800]: errno: -98, Dec 28 17:57:46 volumio volumio[800]: syscall: 'listen', Dec 28 17:57:46 volumio volumio[800]: address: '::', Dec 28 17:57:46 volumio volumio[800]: port: 6599 Dec 28 17:57:46 volumio volumio[800]: } Dec 28 17:57:46 volumio volumio[800]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 28 17:57:46 volumio sudo[3297]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-28 17:56 Dec 28 17:57:46 volumio sudo[3297]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"