Jan 25 16:05:47 volumio4-wg ntpd[1006]: CLOCK: time stepped by 24213.947962 Jan 25 16:05:47 volumio4-wg ntpd[1006]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Jan 25 16:05:49 volumio4-wg volumio[1110]: info: Discovery: adding 78d8ab1c-2ba9-40af-9812-b85c49e31c6e Jan 25 16:05:49 volumio4-wg volumio[1110]: info: Discovery: Found device Volumio4-WG Jan 25 16:05:49 volumio4-wg volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 25 16:05:49 volumio4-wg volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 25 16:05:49 volumio4-wg volumio[1110]: info: Discovery: this is already registered, 78d8ab1c-2ba9-40af-9812-b85c49e31c6e Jan 25 16:05:49 volumio4-wg volumio[1110]: info: Discovery: Found device Volumio4-WG Jan 25 16:05:49 volumio4-wg volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 25 16:05:49 volumio4-wg volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 25 16:05:49 volumio4-wg volumio[1349]: Starting albumart workers Jan 25 16:05:49 volumio4-wg volumio[1110]: info: MPD Permissions set Jan 25 16:05:49 volumio4-wg volumio[1110]: info: MPD Permissions set Jan 25 16:05:49 volumio4-wg volumio[1110]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 25 16:05:50 volumio4-wg volumio[1110]: info: Volumio called home Jan 25 16:05:50 volumio4-wg volumio[1351]: Starting albumart workers Jan 25 16:05:50 volumio4-wg mpd[1424]: 2026-01-25T16:05:50 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 25 16:05:50 volumio4-wg systemd[1]: Started mpd.service - Music Player Daemon. Jan 25 16:05:50 volumio4-wg volumio[1350]: Starting albumart workers Jan 25 16:05:50 volumio4-wg sudo[1400]: pam_unix(sudo:session): session closed for user root Jan 25 16:05:50 volumio4-wg sudo[1394]: pam_unix(sudo:session): session closed for user root Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:05:50 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:05:51 volumio4-wg volumio[1110]: info: Completed starting Core Plugins Jan 25 16:05:51 volumio4-wg volumio[1110]: info: ------------------------------------------- Jan 25 16:05:51 volumio4-wg volumio[1110]: info: ----- MyVolumio plugins startup ---- Jan 25 16:05:51 volumio4-wg volumio[1110]: info: ------------------------------------------- Jan 25 16:05:51 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Fetching plans data.... Jan 25 16:05:51 volumio4-wg volumio[1110]: info: Discovery: A device disappeared from network Jan 25 16:05:51 volumio4-wg volumio[1110]: info: Discovery: Device volumio4-wg disappeared from network Jan 25 16:05:51 volumio4-wg volumio[1110]: info: Discovery: A device disappeared from network Jan 25 16:05:51 volumio4-wg volumio[1110]: error: MPD error: The expression evaluated to a falsy value: Jan 25 16:05:51 volumio4-wg volumio[1110]: assert.ok(self.idling) Jan 25 16:05:51 volumio4-wg volumio[1110]: error: The expression evaluated to a falsy value: Jan 25 16:05:51 volumio4-wg volumio[1110]: assert.ok(self.idling) Jan 25 16:05:51 volumio4-wg volumio[1110]: info: MPD running with PID1424 Jan 25 16:05:51 volumio4-wg volumio[1110]: ,establishing connection Jan 25 16:05:51 volumio4-wg volumio[1110]: error: updateQueue error: null Jan 25 16:05:52 volumio4-wg volumio[1110]: error: updateQueue error: null Jan 25 16:05:52 volumio4-wg volumio[1110]: info: Discovery: adding 78d8ab1c-2ba9-40af-9812-b85c49e31c6e Jan 25 16:05:52 volumio4-wg volumio[1110]: info: Discovery: Found device Volumio4-WG Jan 25 16:05:52 volumio4-wg volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 25 16:05:52 volumio4-wg volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 25 16:05:52 volumio4-wg volumio[1110]: info: Discovery: this is already registered, 78d8ab1c-2ba9-40af-9812-b85c49e31c6e Jan 25 16:05:52 volumio4-wg volumio[1110]: info: Discovery: Found device Volumio4-WG Jan 25 16:05:52 volumio4-wg volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 25 16:05:52 volumio4-wg volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 25 16:05:52 volumio4-wg volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 25 16:05:52 volumio4-wg volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 25 16:05:56 volumio4-wg volumio[1110]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jan 25 16:05:57 volumio4-wg systemd[1]: systemd-fsckd.service: Deactivated successfully. Jan 25 16:05:58 volumio4-wg volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 25 16:05:58 volumio4-wg volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Jan 25 16:05:59 volumio4-wg volumio[1110]: info: Adding plugin bluetooth to MyMusic Plugins Jan 25 16:05:59 volumio4-wg volumio[1110]: info: Adding plugin multiroom to MyMusic Plugins Jan 25 16:05:59 volumio4-wg volumio[1110]: info: Adding plugin metavolumio to MyMusic Plugins Jan 25 16:05:59 volumio4-wg volumio[1110]: info: Adding plugin cd_controller to MyMusic Plugins Jan 25 16:05:59 volumio4-wg volumio[1110]: info: Adding plugin qobuzconnect to MyMusic Plugins Jan 25 16:05:59 volumio4-wg volumio[1110]: info: Adding plugin smart_inputs to MyMusic Plugins Jan 25 16:05:59 volumio4-wg volumio[1110]: info: Adding plugin tidalconnect to MyMusic Plugins Jan 25 16:05:59 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Jan 25 16:06:01 volumio4-wg systemd[1]: systemd-hostnamed.service: Deactivated successfully. Jan 25 16:06:01 volumio4-wg systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Jan 25 16:06:01 volumio4-wg systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 16:06:01 volumio4-wg systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 16:06:01 volumio4-wg upmpdcli[1498]: Could not open config: /tmp/upmpdcli.conf Jan 25 16:06:01 volumio4-wg systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 25 16:06:01 volumio4-wg systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 25 16:06:02 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Jan 25 16:06:02 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Jan 25 16:06:02 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:02 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:02 volumio4-wg volumio[1110]: info: Starting MyVolumio Remote Streaming Endpoints Jan 25 16:06:03 volumio4-wg volumio[1110]: info: MyVolumio login type: Token Jan 25 16:06:03 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Jan 25 16:06:03 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Jan 25 16:06:05 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Jan 25 16:06:05 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Jan 25 16:06:05 volumio4-wg volumio[1110]: info: Streaming services startup Jan 25 16:06:05 volumio4-wg volumio[1110]: info: Starting Streaming Daemon Jan 25 16:06:05 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Jan 25 16:06:05 volumio4-wg sudo[1500]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 25 16:06:05 volumio4-wg sudo[1500]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 16:06:05 volumio4-wg sudo[1500]: pam_unix(sudo:session): session closed for user root Jan 25 16:06:05 volumio4-wg volumio[1110]: error: Cannot start Volumio Streaming Daemon Jan 25 16:06:05 volumio4-wg volumio[1110]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 25 16:06:05 volumio4-wg volumio[1110]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 25 16:06:05 volumio4-wg volumio[1110]: error: MyVolumio Custom Token format not valid, refreshing it Jan 25 16:06:06 volumio4-wg volumio[1110]: info: MyVolumio login type: Token Jan 25 16:06:07 volumio4-wg volumio[1110]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Jan 25 16:06:08 volumio4-wg volumio[1110]: info: MyVolumio token set successfully Jan 25 16:06:08 volumio4-wg volumio[1110]: info: MYVOLUMIO: Adding device Jan 25 16:06:08 volumio4-wg volumio[1110]: info: MYVOLUMIO: Evaluating Server Jan 25 16:06:08 volumio4-wg volumio[1110]: info: MyVolumio Plan changed: premium Jan 25 16:06:08 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Jan 25 16:06:08 volumio4-wg volumio[1110]: info: Removing browser output: myVolumio user plan is not superstar Jan 25 16:06:08 volumio4-wg volumio[1110]: info: Removing audio output: Jan 25 16:06:08 volumio4-wg volumio[1110]: info: MYVOLUMIO: Adding device Jan 25 16:06:08 volumio4-wg volumio[1110]: info: MYVOLUMIO: Evaluating Server Jan 25 16:06:09 volumio4-wg volumio[1110]: info: Setting Geolocation for MyVolumio to eu11 Jan 25 16:06:09 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:09 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:09 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:09 volumio4-wg volumio[1110]: info: Remote config written successfully Jan 25 16:06:09 volumio4-wg volumio[1110]: info: Starting Tunnel 1 Jan 25 16:06:09 volumio4-wg volumio[1110]: info: Starting Tunnel Connection Checker Jan 25 16:06:09 volumio4-wg volumio[1110]: info: Completed starting MyVolumio Plugin Jan 25 16:06:09 volumio4-wg volumio[1110]: info: CoreCommandRouter::volumioGetState Jan 25 16:06:09 volumio4-wg volumio[1110]: info: CorePlayQueue::getTrack 0 Jan 25 16:06:09 volumio4-wg volumio[1110]: info: Listing playlists Jan 25 16:06:09 volumio4-wg volumio[1110]: info: Listing playlists Jan 25 16:06:09 volumio4-wg volumio[1110]: info: Setting Geolocation for MyVolumio to eu6 Jan 25 16:06:09 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:09 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:09 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:09 volumio4-wg volumio[1110]: info: MYVolumio Device enabled Jan 25 16:06:09 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Jan 25 16:06:09 volumio4-wg volumio[1110]: info: MyVolumio status changed Jan 25 16:06:09 volumio4-wg volumio[1110]: info: Streaming services startup Jan 25 16:06:09 volumio4-wg volumio[1110]: info: Starting Streaming Daemon Jan 25 16:06:09 volumio4-wg volumio[1110]: info: Successfully Added MyVolumio device Jan 25 16:06:09 volumio4-wg sudo[1545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Jan 25 16:06:09 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Jan 25 16:06:09 volumio4-wg sudo[1545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 16:06:09 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Jan 25 16:06:09 volumio4-wg sudo[1545]: pam_unix(sudo:session): session closed for user root Jan 25 16:06:10 volumio4-wg volumio[1110]: ------------------------------------ BT MESSAGE: [FUNC] onVolumioStart Jan 25 16:06:10 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Jan 25 16:06:14 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Jan 25 16:06:14 volumio4-wg sudo[1563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Jan 25 16:06:14 volumio4-wg sudo[1563]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 16:06:14 volumio4-wg sudo[1563]: pam_unix(sudo:session): session closed for user root Jan 25 16:06:14 volumio4-wg volumio[1110]: info: MRS: MultiRoom plugin initialized Jan 25 16:06:14 volumio4-wg volumio[1110]: info: MRS: STOPPING SNAPCLIENT Jan 25 16:06:14 volumio4-wg volumio[1110]: info: MRS: Snap server stop Jan 25 16:06:14 volumio4-wg volumio[1110]: info: MRS: STOPPING volumioStreaming Jan 25 16:06:14 volumio4-wg sudo[1580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Jan 25 16:06:14 volumio4-wg sudo[1580]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 16:06:14 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Jan 25 16:06:14 volumio4-wg sudo[1586]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Jan 25 16:06:14 volumio4-wg sudo[1586]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 16:06:14 volumio4-wg sudo[1583]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Jan 25 16:06:14 volumio4-wg sudo[1583]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 16:06:14 volumio4-wg sudo[1584]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Jan 25 16:06:14 volumio4-wg sudo[1584]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 16:06:14 volumio4-wg systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jan 25 16:06:14 volumio4-wg sudo[1586]: pam_unix(sudo:session): session closed for user root Jan 25 16:06:14 volumio4-wg sudo[1580]: pam_unix(sudo:session): session closed for user root Jan 25 16:06:14 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Jan 25 16:06:14 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Jan 25 16:06:14 volumio4-wg sudo[1583]: pam_unix(sudo:session): session closed for user root Jan 25 16:06:14 volumio4-wg sudo[1584]: pam_unix(sudo:session): session closed for user root Jan 25 16:06:14 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Jan 25 16:06:15 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Jan 25 16:06:15 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Jan 25 16:06:15 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Jan 25 16:06:15 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Jan 25 16:06:15 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Jan 25 16:06:15 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Jan 25 16:06:15 volumio4-wg volumio[1110]: info: Preparing to generate the ALSA configuration file Jan 25 16:06:15 volumio4-wg volumio[1110]: info: Updating MyVolumio device info Jan 25 16:06:15 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:15 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:15 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:15 volumio4-wg volumio[1110]: info: Updating MyVolumio device info Jan 25 16:06:15 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:15 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:15 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:15 volumio4-wg volumio[1110]: info: Successfully Added MyVolumio device Jan 25 16:06:15 volumio4-wg volumio[1110]: info: MRS: Removed streaming files Jan 25 16:06:15 volumio4-wg volumio[1110]: info: MRS: volumioStreaming STOPPED Jan 25 16:06:15 volumio4-wg volumio[1110]: info: MRS: SNAPSERVER STOPPED Jan 25 16:06:15 volumio4-wg volumio[1110]: info: MRS: SNAPCLIENT STOPPED Jan 25 16:06:15 volumio4-wg volumio[1110]: error: Cannot start Volumio Streaming Daemon Jan 25 16:06:15 volumio4-wg volumio[1110]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Jan 25 16:06:15 volumio4-wg volumio[1110]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Jan 25 16:06:15 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 25 16:06:15 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:15 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 25 16:06:16 volumio4-wg volumio[1110]: info: Asound.conf file unchanged, so no further update is needed Jan 25 16:06:16 volumio4-wg volumio[1110]: info: Output device has changed, restarting MPD Jan 25 16:06:16 volumio4-wg volumio[1110]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 25 16:06:16 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:06:16 volumio4-wg sudo[1617]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 25 16:06:16 volumio4-wg sudo[1617]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Jan 25 16:06:16 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Jan 25 16:06:16 volumio4-wg volumio[1110]: info: MRS: Pushing multiroomSync output for this device Jan 25 16:06:16 volumio4-wg volumio[1110]: info: MRS: Pushing multiroomSync output Jan 25 16:06:16 volumio4-wg volumio[1110]: info: Adding audio output: Jan 25 16:06:16 volumio4-wg volumio[1110]: info: Adding audio output: Jan 25 16:06:16 volumio4-wg sudo[1617]: pam_unix(sudo:session): session closed for user root Jan 25 16:06:16 volumio4-wg sudo[1619]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 25 16:06:16 volumio4-wg sudo[1619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 16:06:16 volumio4-wg systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Jan 25 16:06:16 volumio4-wg systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 16:06:16 volumio4-wg volumio[1110]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Jan 25 16:06:16 volumio4-wg systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Jan 25 16:06:16 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:06:16 volumio4-wg systemd[1]: Stopping mpd.service - Music Player Daemon... Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Jan 25 16:06:16 volumio4-wg volumio[1110]: info: Adding METAVOLUMIO REST API Endpoints Jan 25 16:06:16 volumio4-wg volumio[1110]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Jan 25 16:06:16 volumio4-wg volumio[1110]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Jan 25 16:06:16 volumio4-wg volumio[1110]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Jan 25 16:06:16 volumio4-wg volumio[1110]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Jan 25 16:06:16 volumio4-wg upmpdcli[1625]: Could not open config: /tmp/upmpdcli.conf Jan 25 16:06:16 volumio4-wg systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Jan 25 16:06:16 volumio4-wg systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Jan 25 16:06:16 volumio4-wg volumio[1110]: error: Hi Res Audio Failed Login: Missing Login Data Jan 25 16:06:16 volumio4-wg volumio[1110]: info: Adding HIGHRESAUDIO REST API Endpoints Jan 25 16:06:16 volumio4-wg volumio[1110]: info: Adding saveAccountData_hi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Jan 25 16:06:16 volumio4-wg volumio[1110]: info: Refreshing QOBUZ token Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Jan 25 16:06:16 volumio4-wg volumio[1110]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Jan 25 16:06:16 volumio4-wg volumio[1110]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Jan 25 16:06:16 volumio4-wg systemd[1]: mpd.service: Deactivated successfully. Jan 25 16:06:16 volumio4-wg systemd[1]: Stopped mpd.service - Music Player Daemon. Jan 25 16:06:16 volumio4-wg systemd[1]: mpd.service: Consumed 3.090s CPU time. Jan 25 16:06:16 volumio4-wg systemd[1]: mpd.socket: Deactivated successfully. Jan 25 16:06:16 volumio4-wg systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jan 25 16:06:16 volumio4-wg systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jan 25 16:06:16 volumio4-wg volumio[1110]: info: QobuzConnect: Starting Qobuz Connect socket and service Jan 25 16:06:16 volumio4-wg systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jan 25 16:06:16 volumio4-wg systemd[1]: Starting mpd.service - Music Player Daemon... Jan 25 16:06:17 volumio4-wg sudo[1633]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 25 16:06:17 volumio4-wg sudo[1633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jan 25 16:06:17 volumio4-wg sudo[1633]: pam_unix(sudo:session): session closed for user root Jan 25 16:06:17 volumio4-wg volumio[1110]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Jan 25 16:06:17 volumio4-wg volumio[1110]: info: Adding TIDAL REST API Endpoints Jan 25 16:06:17 volumio4-wg sudo[1636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Jan 25 16:06:17 volumio4-wg sudo[1636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 16:06:17 volumio4-wg sudo[1636]: pam_unix(sudo:session): session closed for user root Jan 25 16:06:17 volumio4-wg volumio[1110]: info: MPD Permissions set Jan 25 16:06:17 volumio4-wg volumio[1110]: info: Successfully Updated MyVolumio device Jan 25 16:06:17 volumio4-wg sudo[1644]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Jan 25 16:06:17 volumio4-wg sudo[1644]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 16:06:17 volumio4-wg sudo[1646]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 25 16:06:17 volumio4-wg systemd[1]: setdatetime-helper.service: Deactivated successfully. Jan 25 16:06:17 volumio4-wg systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jan 25 16:06:17 volumio4-wg sudo[1646]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 25 16:06:17 volumio4-wg systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Jan 25 16:06:17 volumio4-wg sudo[1644]: pam_unix(sudo:session): session closed for user root Jan 25 16:06:17 volumio4-wg 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. Jan 25 16:06:17 volumio4-wg 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. Jan 25 16:06:17 volumio4-wg systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 25 16:06:17 volumio4-wg sudo[1646]: pam_unix(sudo:session): session closed for user root Jan 25 16:06:17 volumio4-wg autossh[1656]: port set to 0, monitoring disabled Jan 25 16:06:17 volumio4-wg autossh[1656]: starting ssh (count 1) Jan 25 16:06:17 volumio4-wg autossh[1656]: ssh child pid is 1659 Jan 25 16:06:18 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 25 16:06:18 volumio4-wg volumiossh-tunnel[1659]: Warning: Permanently added '[eu6.myvolumio.org]:2222' (RSA) to the list of known hosts. Jan 25 16:06:18 volumio4-wg volumio[1110]: info: Successfully Updated MyVolumio device Jan 25 16:06:18 volumio4-wg volumio[1110]: info: Remote SSH Started Jan 25 16:06:18 volumio4-wg mpd[1637]: 2026-01-25T16:06:18 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 25 16:06:18 volumio4-wg systemd[1]: Started mpd.service - Music Player Daemon. Jan 25 16:06:18 volumio4-wg sudo[1619]: pam_unix(sudo:session): session closed for user root Jan 25 16:06:19 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:06:19 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:06:19 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:06:19 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:06:19 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:06:19 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:06:19 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:06:19 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:06:19 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 25 16:06:19 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 25 16:06:19 volumio4-wg volumio[1110]: info: Adding audio output: Jan 25 16:06:19 volumio4-wg volumio[1110]: info: Executing endpoint qc_getconfig Jan 25 16:06:19 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Jan 25 16:06:19 volumio4-wg qobuz-connect[1654]: 20260125 16:06:19.587 [1654.1654] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Jan 25 16:06:19 volumio4-wg qobuz-connect[1654]: 20260125 16:06:19.674 [1654.1654] INFO VolumeManager: [0xea2208]: Setting new playback volume: 75 Jan 25 16:06:19 volumio4-wg qobuz-connect[1654]: 20260125 16:06:19.674 [1654.1654] INFO VolumeManager: [0xea2208]: Setting new mute state: 0 Jan 25 16:06:19 volumio4-wg qobuz-connect[1654]: 20260125 16:06:19.674 [1654.1654] INFO QobuzConnect: [0xea2bd8]: Client initialized! Jan 25 16:06:19 volumio4-wg qobuz-connect[1654]: 20260125 16:06:19.674 [1654.1654] INFO SampleApp: Starting Avahi advertising, name: Volumio4-WG, service name: _qobuz-connect._tcp Jan 25 16:06:19 volumio4-wg qobuz-connect[1654]: 20260125 16:06:19.685 [1654.1654] INFO LocalConfigManager: [0xea1ae8]: Starting Local Configuration server Jan 25 16:06:19 volumio4-wg qobuz-connect[1654]: 20260125 16:06:19.685 [1654.1654] INFO SampleApp: Starting Local configuration server Jan 25 16:06:19 volumio4-wg qobuz-connect[1654]: 20260125 16:06:19.685 [1654.1654] INFO SampleApp: Connected to UNIX socket client 0xe8c818 Jan 25 16:06:19 volumio4-wg volumio[1110]: info: Access Token successfully retrieved Jan 25 16:06:19 volumio4-wg volumio[1110]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 25 16:06:19 volumio4-wg volumio[1110]: info: [1769353579694] CoreMusicLibrary::Adding element QOBUZ Jan 25 16:06:19 volumio4-wg volumio[1110]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 25 16:06:19 volumio4-wg volumio[1110]: Cannot find translation for source QOBUZ Jan 25 16:06:19 volumio4-wg volumio[1110]: info: Stopping AccessToken refresher cron for QOBUZ Jan 25 16:06:19 volumio4-wg qobuz-connect[1654]: 20260125 16:06:19.737 [1654.1654] INFO SampleApp: Playback volume changed: 75 Jan 25 16:06:19 volumio4-wg volumio[1110]: info: AccessToken refresher cron started for QOBUZ Jan 25 16:06:19 volumio4-wg volumio[1110]: info: Adding QOBUZ REST API Endpoints Jan 25 16:06:19 volumio4-wg volumio[1110]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 16:06:19 volumio4-wg volumio[1110]: Error: upnp: Request failed with status code 403/// Jan 25 16:06:19 volumio4-wg volumio[1110]: at /myvolumio/plugins/audio_interface/multiroom/node_modules/sonos/lib/services/Service.js:90:25 Jan 25 16:06:19 volumio4-wg volumio[1110]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jan 25 16:06:19 volumio4-wg volumio[1110]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 25 16:06:23 volumio4-wg sudo[1701]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-25 16:05' Jan 25 16:06:23 volumio4-wg sudo[1701]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"