May 27 17:00:06 volumio ntpd[1322]: CLOCK: time stepped by 71060.243711 May 27 17:00:06 volumio ntpd[1322]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 27 17:00:06 volumio volumio[1508]: info: Getting Spotify volume May 27 17:00:06 volumio volumio[1508]: info: Getting Spotify volume May 27 17:00:06 volumio volumio[1508]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 May 27 17:00:06 volumio volumio[1508]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 May 27 17:00:06 volumio ntpd[1322]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 27 17:00:06 volumio ntpd[1322]: DNS: Pool taking: 23.186.168.128 May 27 17:00:06 volumio ntpd[1322]: DNS: Pool taking: 172.235.154.118 May 27 17:00:06 volumio ntpd[1322]: DNS: Pool taking: 44.190.5.123 May 27 17:00:06 volumio ntpd[1322]: DNS: Pool taking: 23.186.168.125 May 27 17:00:06 volumio ntpd[1322]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 27 17:00:06 volumio volumio[1508]: info: CoreCommandRouter::volumioGetState May 27 17:00:06 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:00:06 volumio volumio[1508]: SPOTIFY: RECEIVED VOLUMIO VOLUME 44 May 27 17:00:06 volumio volumio[1508]: SPOTIFY: SPOTIFY VOLUME undefined May 27 17:00:06 volumio volumio[1508]: SPOTIFY: VOLUMIO VOLUME 44 May 27 17:00:06 volumio volumio[1508]: info: Aligning Spotify Volume to Volumio Volume May 27 17:00:06 volumio volumio[1508]: info: CoreCommandRouter::volumioGetState May 27 17:00:06 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:00:06 volumio volumio[1508]: info: Setting Spotify Volume from Volumio: 44 May 27 17:00:06 volumio volumio[1508]: SPOTIFY: User informations: {"country":"US","display_name":"Irocusuk","email":"irocusuk@gmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/qu6cwl5x9a69uy1efbmc4sf80"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/qu6cwl5x9a69uy1efbmc4sf80","id":"qu6cwl5x9a69uy1efbmc4sf80","images":[],"product":"free","type":"user","uri":"spotify:user:qu6cwl5x9a69uy1efbmc4sf80"} May 27 17:00:06 volumio volumio[1508]: info: Spotify Successfully logged in May 27 17:00:06 volumio volumio[1508]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 27 17:00:06 volumio volumio[1508]: info: [1779919206972] CoreMusicLibrary::Adding element Spotify May 27 17:00:06 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 27 17:00:06 volumio volumio[1508]: Cannot find translation for source Bandcamp Discover May 27 17:00:06 volumio volumio[1508]: Cannot find translation for source Jellyfin May 27 17:00:06 volumio volumio[1508]: Cannot find translation for source YouTube Music May 27 17:00:06 volumio volumio[1508]: Cannot find translation for source Mother Earth Radio May 27 17:00:06 volumio volumio[1508]: Cannot find translation for source Pandora Radio May 27 17:00:06 volumio volumio[1508]: Cannot find translation for source FM/DAB Radio May 27 17:00:06 volumio volumio[1508]: Cannot find translation for source Spotify May 27 17:00:07 volumio volumio[1508]: info: New access token = BQBOvPBjouFB6a8leLoTzELM99SQkL8KVj4Siow31O_FBJcdNAcgeJDlTn_1Ze9cIAg17YJnj_SoJunFW3tgiQrl4o40AILrEGeRly3bRnqrJaBxwGl-v8l8d77st4YEkKKqsK2b9t-Uc6yHVL6jU-3m5G2mLUnmaRrmWQt5IA_qVVKbASUG7UuNVcJxxCQ3PCIXzqOQrSJvqO81cPPuPDdnLVjRSWcResTe7tzVZJ4BZUbNvcUik9hDF4H271Fx6_He8Xv6GwuTmAOdjC836k2xih6PKtVrut48uWFt32qmiIpfC-iZRA May 27 17:00:07 volumio volumio[1508]: info: New access token = BQDbPT1Ysed2Odee1jlu_Hp0LDN-9edEXnN0_sp4NO4I50cLojIdxX3ybpE5hLKeGuSc0R9N143B3U2DE3kC2Yg58bikJwMOp7c4HlzEgqQcDsCdEFdwm-gltZWXACLc9lJSbgcX0b-KsSzqL_VDZbVmqrUif6ed7p3SlZzL-72ITglgAY2bHPoMY0tw2pvr69qHEDci2ZxE1BPImQb0qx1Pz5OsebP1V-i8WyGdwKdVK-jqdEqn8kIjUwQ-bJVYocWvNvLyTRj9MBZejVh9cbR0cTO0y0mALNw89TDcW2d28VDYeKjCsg May 27 17:00:07 volumio volumio[1508]: info: New access token = BQBZT6BTd4F5qrVNQBpnqgZrIHYf3JoIpTKnUsZ140FVFiB3DIVqim8QVzXKsIUJ9zvpVH62e5gajJQ_qdDN6okuASI54ApZJqDJ7-GiurGM6uiSURiM3BwJt3Cz3F2bcEZ1mcBdYD9t_yw1N7TWkDmTq8ZH_PJJt6niHN_S6HzZsef7_ylyFa7MUnlepJV25FymJM9AZVTSZUTq-xIdaZMxYna7fH2SIwqudv_nSR20pZn14Wvh2XXqaGzexK5d-xBXWpTxyAKjEy7IMu7qDwo_bqrMDeLs5XPvzhveT4tBSrOLWcGCzQ May 27 17:00:07 volumio volumio[1508]: info: Discovery: A device disappeared from network May 27 17:00:07 volumio volumio[1508]: info: Discovery: Device volumio disappeared from network May 27 17:00:07 volumio volumio[1508]: info: Discovery: A device disappeared from network May 27 17:00:07 volumio volumio[1508]: info: Discovery: adding f0c727d6-ae68-4bd6-9818-59cc77d3c309 May 27 17:00:07 volumio volumio[1508]: info: Discovery: Found device Volumio May 27 17:00:07 volumio volumio[1508]: info: CoreCommandRouter::volumioGetState May 27 17:00:07 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:00:07 volumio volumio[1508]: info: Discovery: this is already registered, f0c727d6-ae68-4bd6-9818-59cc77d3c309 May 27 17:00:07 volumio volumio[1508]: info: Discovery: Found device Volumio May 27 17:00:07 volumio volumio[1508]: info: CoreCommandRouter::volumioGetState May 27 17:00:07 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:00:07 volumio go-librespot[2143]: time="2026-05-27T17:00:07-05:00" level=debug msg="put connect state because NEW_DEVICE" May 27 17:00:07 volumio volumio[1508]: info: Spotify volume: 100 May 27 17:00:07 volumio volumio[1508]: info: Spotify volume: 100 May 27 17:00:08 volumio volumio[1508]: SPOTIFY: SETTING SPOTIFY VOLUME 44 May 27 17:00:08 volumio volumio[1508]: info: Sending Spotify command with payload to local API: /player/volume May 27 17:00:08 volumio go-librespot[2143]: time="2026-05-27T17:00:08-05:00" level=debug msg="update volume requested to 28835/65535" May 27 17:00:08 volumio go-librespot[2143]: time="2026-05-27T17:00:08-05:00" level=debug msg="put connect state because VOLUME_CHANGED" May 27 17:00:08 volumio go-librespot[2143]: time="2026-05-27T17:00:08-05:00" level=trace msg="emitting websocket event: volume" May 27 17:00:08 volumio volumio[1508]: SPOTIFY: received: {"type":"volume","data":{"value":44,"max":100}} May 27 17:00:08 volumio volumio[1508]: SPOTIFY: RECEIVED SPOTIFY VOLUME 44 May 27 17:00:08 volumio volumio[1508]: SPOTIFY: received: {"type":"volume","data":{"value":44,"max":100}} May 27 17:00:08 volumio volumio[1508]: SPOTIFY: RECEIVED SPOTIFY VOLUME 44 May 27 17:00:08 volumio systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... May 27 17:00:08 volumio systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. May 27 17:00:08 volumio systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. May 27 17:00:08 volumio systemd[1]: dpkg-db-backup.service: Deactivated successfully. May 27 17:00:08 volumio systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. May 27 17:00:12 volumio systemd[1]: systemd-fsckd.service: Deactivated successfully. May 27 17:00:12 volumio volumio[1508]: info: [1779919212923] [Pandora] checkHTTP: ***CRACK THE BEERS WE HAVE INTERNET*** May 27 17:00:12 volumio volumio[1508]: info: [1779919212924] ControllerPandora::initializeMQTT May 27 17:00:12 volumio volumio[1508]: info: [1779919212924] [Pandora] initializeMQTT: MQTT is not enabled in Plugin Configuration May 27 17:00:12 volumio volumio[1508]: info: [1779919212924] ControllerPandora::PandoraHandler::init May 27 17:00:12 volumio volumio[1508]: info: [1779919212924] ControllerPandora::PandoraHandler::loadErrorCodes May 27 17:00:12 volumio volumio[1508]: info: [1779919212925] ControllerPandora::validateAndSetAccountOptions May 27 17:00:12 volumio volumio[1508]: info: [1779919212925] ControllerPandora::PandoraHandler::setAccountOptions May 27 17:00:12 volumio volumio[1508]: info: [1779919212926] ControllerPandora::flushPandora May 27 17:00:12 volumio volumio[1508]: info: CorePlayQueue::getQueue May 27 17:00:12 volumio volumio[1508]: info: CorePlayQueue::clearAddPlayQueue May 27 17:00:12 volumio volumio[1508]: info: CorePlayQueue::saveQueue May 27 17:00:12 volumio volumio[1508]: info: CoreCommandRouter::serviceClearAddPlayTracks May 27 17:00:12 volumio volumio[1508]: error: WARNING: No clearAddPlayTracks method for service jellyfin May 27 17:00:12 volumio volumio[1508]: info: CoreCommandRouter::volumioPushQueue May 27 17:00:13 volumio volumio[1508]: info: [1779919213029] [Pandora] PreventAuthTimeout::init: Timer loaded May 27 17:00:13 volumio volumio[1508]: info: [1779919213029] [Pandora] PreventAuthTimeout::init: Interval set to 10800000 ms May 27 17:00:13 volumio volumio[1508]: info: [1779919213029] [Pandora] PreventAuthTimeout::init: Starting delayed timer after next interval May 27 17:00:13 volumio volumio[1508]: info: [1779919213029] ControllerPandora::PandoraHandler::pandoraLoginAndGetStations May 27 17:00:13 volumio volumio[1508]: info: [1779919213631] [Pandora] PandoraHandler::pandoraLoginAndGetStations::pandoraLogin: [<=- Logged in to Pandora Servers -=>] May 27 17:00:13 volumio volumio[1508]: info: [1779919213635] [Pandora] validateAndSetAccountOptions: ***DEBUG*** self.loggedIn: true May 27 17:00:13 volumio volumio[1508]: info: [1779919213636] [Pandora] ExpireOldTracks::init: Timer loaded May 27 17:00:13 volumio volumio[1508]: info: [1779919213636] [Pandora] ExpireOldTracks::init: Interval set to 300000 ms May 27 17:00:13 volumio volumio[1508]: info: [1779919213636] [Pandora] ExpireOldTracks::init: Starting delayed timer after next interval May 27 17:00:13 volumio volumio[1508]: info: [1779919213636] ControllerPandora::PandoraHandler::fillStationData May 27 17:00:14 volumio volumio[1508]: info: [1779919214022] [Pandora] PandoraHandler::setMaxStationTracks: 16 May 27 17:00:14 volumio volumio[1508]: info: [1779919214022] [Pandora] PandoraHandler::setBandFilter: bandfilter is empty May 27 17:00:14 volumio volumio[1508]: info: [1779919214022] ControllerPandora::flushPandora May 27 17:00:14 volumio volumio[1508]: info: CorePlayQueue::getQueue May 27 17:00:14 volumio volumio[1508]: info: CorePlayQueue::clearAddPlayQueue May 27 17:00:14 volumio volumio[1508]: info: CorePlayQueue::saveQueue May 27 17:00:14 volumio volumio[1508]: info: CoreCommandRouter::serviceClearAddPlayTracks May 27 17:00:14 volumio volumio[1508]: error: WARNING: No clearAddPlayTracks method for service jellyfin May 27 17:00:14 volumio volumio[1508]: info: CoreCommandRouter::volumioPushQueue May 27 17:00:14 volumio volumio[1508]: info: Completed starting Core Plugins May 27 17:00:14 volumio volumio[1508]: info: ------------------------------------------- May 27 17:00:14 volumio volumio[1508]: info: ----- MyVolumio plugins startup ---- May 27 17:00:14 volumio volumio[1508]: info: ------------------------------------------- May 27 17:00:14 volumio volumio[1508]: info: [MyVolumio PluginManager] Fetching plans data.... May 27 17:00:14 volumio systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 27 17:00:15 volumio systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. May 27 17:00:15 volumio systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. May 27 17:00:15 volumio systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 27 17:00:15 volumio sudo[2412]: pam_unix(sudo:session): session closed for user root May 27 17:00:15 volumio volumio[1508]: info: Upmpdcli Daemon Started May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 27 17:00:22 volumio volumio[1508]: info: Adding plugin bluetooth to MyMusic Plugins May 27 17:00:22 volumio volumio[1508]: info: Adding plugin multiroom to MyMusic Plugins May 27 17:00:22 volumio volumio[1508]: info: Adding plugin metavolumio to MyMusic Plugins May 27 17:00:22 volumio volumio[1508]: info: Adding plugin cd_controller to MyMusic Plugins May 27 17:00:22 volumio volumio[1508]: info: Adding plugin qobuzconnect to MyMusic Plugins May 27 17:00:22 volumio volumio[1508]: info: Adding plugin smart_inputs to MyMusic Plugins May 27 17:00:22 volumio volumio[1508]: info: Adding plugin tidalconnect to MyMusic Plugins May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 27 17:00:22 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:22 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:22 volumio volumio[1508]: info: Starting MyVolumio Remote Streaming Endpoints May 27 17:00:22 volumio volumio[1508]: info: MyVolumio login type: Token May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 27 17:00:22 volumio volumio[1508]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 27 17:00:23 volumio upmpdcli[2524]: writing RSA key May 27 17:00:23 volumio volumio[1508]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 27 17:00:23 volumio volumio[1508]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 27 17:00:23 volumio volumio[1508]: info: Streaming services startup May 27 17:00:23 volumio volumio[1508]: info: Starting Streaming Daemon May 27 17:00:23 volumio sudo[2530]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 27 17:00:23 volumio sudo[2530]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 17:00:23 volumio volumio[1508]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 27 17:00:23 volumio sudo[2530]: pam_unix(sudo:session): session closed for user root May 27 17:00:23 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 27 17:00:23 volumio volumio[1508]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined May 27 17:00:23 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates May 27 17:00:23 volumio volumio[1508]: info: Received Get System Version May 27 17:00:23 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 17:00:23 volumio volumio[1508]: info: Received Get System Info May 27 17:00:23 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 27 17:00:23 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 27 17:00:23 volumio volumio[1508]: info: Discovery: Getting this device information May 27 17:00:23 volumio volumio[1508]: info: CoreCommandRouter::volumioGetState May 27 17:00:23 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:00:23 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 27 17:00:23 volumio volumio[1508]: error: Cannot start Volumio Streaming Daemon May 27 17:00:23 volumio volumio[1508]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 27 17:00:23 volumio volumio[1508]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 27 17:00:25 volumio volumio[1508]: error: MyVolumio Custom Token format not valid, refreshing it May 27 17:00:26 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 17:00:26 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:26 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 27 17:00:26 volumio volumio-remote-updater[1123]: Test mode disabled May 27 17:00:26 volumio volumio-remote-updater[1123]: Alpha mode disabled May 27 17:00:26 volumio volumio-remote-updater[1123]: Alpha legacy test mode disabled May 27 17:00:26 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 27 17:00:27 volumio volumio[1508]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

FIXES

NEW ADDITIONS

","title":"Update v4.119","updateavailable":true} May 27 17:00:27 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 27 17:00:30 volumio volumio[1508]: info: MyVolumio login type: Token May 27 17:00:31 volumio volumio[1508]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 27 17:00:31 volumio volumio[1508]: info: MyVolumio token set successfully May 27 17:00:31 volumio volumio[1508]: info: MYVOLUMIO: Adding device May 27 17:00:31 volumio volumio[1508]: info: MYVOLUMIO: Evaluating Server May 27 17:00:31 volumio volumio[1508]: info: MyVolumio status changed May 27 17:00:31 volumio volumio[1508]: info: Streaming services startup May 27 17:00:31 volumio volumio[1508]: info: Starting Streaming Daemon May 27 17:00:31 volumio volumio[1508]: info: Removing browser output: myVolumio user plan is not superstar May 27 17:00:31 volumio volumio[1508]: info: Removing audio output: May 27 17:00:31 volumio volumio[1508]: info: Stoppping Tunnel 1 May 27 17:00:31 volumio sudo[2575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 27 17:00:31 volumio sudo[2575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 17:00:31 volumio sudo[2578]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 27 17:00:31 volumio sudo[2578]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 27 17:00:31 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... May 27 17:00:31 volumio sudo[2575]: pam_unix(sudo:session): session closed for user root May 27 17:00:31 volumio volumio[1508]: error: Cannot start Volumio Streaming Daemon May 27 17:00:31 volumio volumio[1508]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 27 17:00:31 volumio volumio[1508]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 27 17:00:31 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 27 17:00:31 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 27 17:00:31 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 27 17:00:31 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 27 17:00:31 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 27 17:00:31 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 27 17:00:31 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 27 17:00:31 volumio systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. May 27 17:00:31 volumio sudo[2578]: pam_unix(sudo:session): session closed for user root May 27 17:00:31 volumio volumio[1508]: info: Remote SSH Stopped May 27 17:00:32 volumio go-librespot[2143]: time="2026-05-27T17:00:32-05:00" level=trace msg="sent dealer ping" May 27 17:00:32 volumio go-librespot[2143]: time="2026-05-27T17:00:32-05:00" level=trace msg="received dealer pong" May 27 17:00:36 volumio volumio[1508]: info: Setting Geolocation for MyVolumio to us1 May 27 17:00:36 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:36 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:36 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:37 volumio volumio[1508]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 27 17:00:37 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 27 17:00:37 volumio volumio[1508]: info: Updating MyVolumio device info May 27 17:00:37 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:37 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:37 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:37 volumio volumio[1508]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 May 27 17:00:37 volumio volumio[1508]: info: CoreCommandRouter::volumioGetState May 27 17:00:37 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:00:38 volumio volumio[1508]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 27 17:00:41 volumio volumio[1508]: info: MYVOLUMIO: Adding device May 27 17:00:41 volumio volumio[1508]: info: MYVOLUMIO: Evaluating Server May 27 17:00:41 volumio volumio[1508]: info: Setting Geolocation for MyVolumio to us3 May 27 17:00:41 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:41 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:41 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:41 volumio volumio[1508]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 27 17:00:42 volumio volumio[1508]: info: Updating MyVolumio device info May 27 17:00:42 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:42 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:42 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:42 volumio volumio[1508]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 27 17:00:42 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 17:00:42 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 17:00:42 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 27 17:00:42 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 27 17:00:42 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 27 17:00:42 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 27 17:00:42 volumio volumio[1508]: info: CoreCommandRouter::volumioGetBrowseSources May 27 17:00:42 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 27 17:00:43 volumio volumio[1508]: error: MyVolumio Plugin failed to authenticate in a timely fashion May 27 17:00:43 volumio volumio[1508]: info: Completed starting MyVolumio Plugin May 27 17:00:43 volumio volumio[1508]: [Metrics] CommandRouter: 53s 5.44ms May 27 17:00:43 volumio volumio[1508]: info: CoreCommandRouter::volumiosetStartupVolume May 27 17:00:43 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 27 17:00:43 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 27 17:00:43 volumio volumio[1508]: info: CoreCommandRouter::Close All Modals sent May 27 17:00:43 volumio volumio[1508]: info: CoreCommandRouter::Close All Modals sent May 27 17:00:43 volumio volumio[1508]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 May 27 17:00:43 volumio volumio[1508]: info: FusionDsp - ---- read samplerate from file: 44100 May 27 17:00:43 volumio volumio[1508]: info: camilladsp stopping service pid 2216... May 27 17:00:44 volumio volumio[1508]: grep: /proc/2216/cmdline: binary file matches May 27 17:00:44 volumio volumio[1508]: grep: /proc/2216/cmdline: binary file matches May 27 17:00:44 volumio volumio[1508]: grep: /proc/2216/cmdline: binary file matches May 27 17:00:44 volumio volumio[1508]: grep: /proc/2216/cmdline: binary file matches May 27 17:00:44 volumio volumio[1508]: grep: /proc/2216/cmdline: binary file matches May 27 17:00:44 volumio volumio[1508]: info: camilladsp service terminated, instance 1 May 27 17:00:44 volumio volumio[1508]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 27 17:00:44 volumio volumio[1508]: info: camilladsp service started and running in background, instance 1 May 27 17:00:44 volumio volumio[1508]: info: camilladsp respawn in 100 ms (attempt 1/10) May 27 17:00:44 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable May 27 17:00:44 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 27 17:00:44 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect May 27 17:00:48 volumio volumio[1508]: info: camilladsp respawn in 200 ms (attempt 2/10) May 27 17:00:48 volumio kernel: drm-rp1-dsi 1f00130000.dsi: [drm] rp1dsi: Command time (outvact): 33 May 27 17:00:48 volumio kernel: drm-rp1-dsi 1f00130000.dsi: [drm] rp1dsi: Nominal Byte clock 90000000 DPI clock 30000000 (parent rate 90000000) May 27 17:00:50 volumio volumio[1508]: info: BOOT COMPLETED May 27 17:00:51 volumio volumio[1508]: info: CoreCommandRouter::volumioPlay May 27 17:00:51 volumio volumio[1508]: info: CoreStateMachine::play index undefined May 27 17:00:51 volumio volumio[1508]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 17:00:51 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:00:51 volumio volumio[1508]: info: CoreStateMachine::startPlaybackTimer May 27 17:00:51 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:00:51 volumio volumio[1508]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/Eric@80541656596941e29156dd9ea6df0667/song@songId=a220da648fa863ea4c8838742eacd86c May 27 17:00:51 volumio volumio[1508]: info: [jellyfin-conn] Using previously assigned device Id for Eric@FUCKEVERYBODY: 628e0bab-2be7-4df0-be58-f4733aed7523 May 27 17:00:51 volumio volumio[1508]: info: [jellyfin-conn] Login successful: Eric@FUCKEVERYBODY May 27 17:00:51 volumio volumio[1508]: info: [jellyfin-play] Stream URL for Father of Mine: http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364 May 27 17:00:51 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand stop May 27 17:00:51 volumio volumio[1508]: info: sendMpdCommand stop took 1 milliseconds May 27 17:00:51 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand clear May 27 17:00:51 volumio volumio[1508]: info: sendMpdCommand clear took 0 milliseconds May 27 17:00:51 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364&t.flac" May 27 17:00:51 volumio volumio[1508]: info: May 27 17:00:51 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:00:51 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:00:51 volumio volumio[1508]: info: May 27 17:00:51 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:00:51 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:00:51 volumio volumio[1508]: info: May 27 17:00:51 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:00:51 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:00:51 volumio volumio[1508]: info: May 27 17:00:51 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:00:51 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:00:51 volumio volumio[1508]: info: May 27 17:00:51 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:00:51 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:00:51 volumio volumio[1508]: info: May 27 17:00:51 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:00:51 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:00:51 volumio volumio[1508]: info: May 27 17:00:51 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:00:51 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:00:56 volumio volumio[1508]: info: UPDATER: Scheduling automatic update May 27 17:00:56 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStartTime May 27 17:00:56 volumio volumio[1508]: info: CoreCommandRouter::executeOnPlugin: system , getAutoUpdateWindowStopTime May 27 17:00:56 volumio volumio[1508]: info: UPDATER: Auto update will take place at: Thu May 28 2026 04:35:58 GMT-0500 (Central Daylight Time) May 27 17:00:59 volumio volumio[1508]: info: CoreCommandRouter::volumioPlay May 27 17:00:59 volumio volumio[1508]: info: CoreStateMachine::play index undefined May 27 17:00:59 volumio volumio[1508]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 17:00:59 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:00:59 volumio volumio[1508]: info: CoreStateMachine::startPlaybackTimer May 27 17:00:59 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:00:59 volumio volumio[1508]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/Eric@80541656596941e29156dd9ea6df0667/song@songId=a220da648fa863ea4c8838742eacd86c May 27 17:01:00 volumio volumio[1508]: info: CoreCommandRouter::volumioPlay May 27 17:01:00 volumio volumio[1508]: info: CoreStateMachine::play index undefined May 27 17:01:00 volumio volumio[1508]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 17:01:00 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:01:00 volumio volumio[1508]: info: CoreStateMachine::startPlaybackTimer May 27 17:01:00 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:01:00 volumio volumio[1508]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/Eric@80541656596941e29156dd9ea6df0667/song@songId=a220da648fa863ea4c8838742eacd86c May 27 17:01:01 volumio volumio[1508]: info: CoreCommandRouter::volumioPlay May 27 17:01:01 volumio volumio[1508]: info: CoreStateMachine::play index undefined May 27 17:01:01 volumio volumio[1508]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 17:01:01 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:01:01 volumio volumio[1508]: info: CoreStateMachine::startPlaybackTimer May 27 17:01:01 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:01:01 volumio volumio[1508]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/Eric@80541656596941e29156dd9ea6df0667/song@songId=a220da648fa863ea4c8838742eacd86c May 27 17:01:02 volumio go-librespot[2143]: time="2026-05-27T17:01:02-05:00" level=trace msg="sent dealer ping" May 27 17:01:02 volumio go-librespot[2143]: time="2026-05-27T17:01:02-05:00" level=trace msg="received dealer pong" May 27 17:01:02 volumio volumio[1508]: info: CoreCommandRouter::volumioPlay May 27 17:01:02 volumio volumio[1508]: info: CoreStateMachine::play index undefined May 27 17:01:02 volumio volumio[1508]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 17:01:02 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:01:02 volumio volumio[1508]: info: CoreStateMachine::startPlaybackTimer May 27 17:01:02 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:01:02 volumio volumio[1508]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/Eric@80541656596941e29156dd9ea6df0667/song@songId=a220da648fa863ea4c8838742eacd86c May 27 17:01:03 volumio volumio[1508]: info: CoreCommandRouter::volumioPlay May 27 17:01:03 volumio volumio[1508]: info: CoreStateMachine::play index undefined May 27 17:01:03 volumio volumio[1508]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 17:01:03 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:01:03 volumio volumio[1508]: info: CoreStateMachine::startPlaybackTimer May 27 17:01:03 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:01:03 volumio volumio[1508]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/Eric@80541656596941e29156dd9ea6df0667/song@songId=a220da648fa863ea4c8838742eacd86c May 27 17:01:03 volumio volumio[1508]: info: CoreCommandRouter::volumioPlay May 27 17:01:03 volumio volumio[1508]: info: CoreStateMachine::play index undefined May 27 17:01:03 volumio volumio[1508]: info: CoreStateMachine::setConsumeUpdateService undefined May 27 17:01:03 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:01:03 volumio volumio[1508]: info: CoreStateMachine::startPlaybackTimer May 27 17:01:03 volumio volumio[1508]: info: CorePlayQueue::getTrack 0 May 27 17:01:03 volumio volumio[1508]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/Eric@80541656596941e29156dd9ea6df0667/song@songId=a220da648fa863ea4c8838742eacd86c May 27 17:01:05 volumio volumio[1508]: info: [jellyfin-play] Stream URL for Father of Mine: http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364 May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand stop May 27 17:01:05 volumio volumio[1508]: info: [jellyfin-play] Stream URL for Father of Mine: http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364 May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand stop May 27 17:01:05 volumio volumio[1508]: info: [jellyfin-play] Stream URL for Father of Mine: http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364 May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand stop May 27 17:01:05 volumio volumio[1508]: info: [jellyfin-play] Stream URL for Father of Mine: http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364 May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand stop May 27 17:01:05 volumio volumio[1508]: info: [jellyfin-play] Stream URL for Father of Mine: http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364 May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand stop May 27 17:01:05 volumio volumio[1508]: info: [jellyfin-play] Stream URL for Father of Mine: http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364 May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand stop May 27 17:01:05 volumio volumio[1508]: error: updateQueue error: null May 27 17:01:05 volumio volumio[1508]: error: updateQueue error: null May 27 17:01:05 volumio volumio[1508]: error: updateQueue error: null May 27 17:01:05 volumio volumio[1508]: error: updateQueue error: null May 27 17:01:05 volumio volumio[1508]: error: updateQueue error: null May 27 17:01:05 volumio volumio[1508]: error: updateQueue error: null May 27 17:01:05 volumio volumio[1508]: error: updateQueue error: null May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand addid "http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364&t.flac" May 27 17:01:05 volumio volumio[1508]: info: ------------------------------ 14516ms May 27 17:01:05 volumio volumio[1508]: info: ------------------------------ 14516ms May 27 17:01:05 volumio volumio[1508]: info: ------------------------------ 14516ms May 27 17:01:05 volumio volumio[1508]: info: ------------------------------ 14516ms May 27 17:01:05 volumio volumio[1508]: info: ------------------------------ 14516ms May 27 17:01:05 volumio volumio[1508]: info: ------------------------------ 14515ms May 27 17:01:05 volumio volumio[1508]: info: ------------------------------ 14515ms May 27 17:01:05 volumio volumio[1508]: info: sendMpdCommand stop took 60 milliseconds May 27 17:01:05 volumio volumio[1508]: info: sendMpdCommand stop took 49 milliseconds May 27 17:01:05 volumio volumio[1508]: info: sendMpdCommand stop took 15 milliseconds May 27 17:01:05 volumio volumio[1508]: info: sendMpdCommand stop took 12 milliseconds May 27 17:01:05 volumio volumio[1508]: info: sendMpdCommand stop took 9 milliseconds May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand clear May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand clear May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand clear May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand clear May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand clear May 27 17:01:05 volumio volumio[1508]: info: May 27 17:01:05 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:01:05 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:01:05 volumio volumio[1508]: info: May 27 17:01:05 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:01:05 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:01:05 volumio volumio[1508]: info: May 27 17:01:05 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:01:05 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:01:05 volumio volumio[1508]: info: May 27 17:01:05 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:01:05 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:01:05 volumio volumio[1508]: info: May 27 17:01:05 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:01:05 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:01:05 volumio volumio[1508]: info: May 27 17:01:05 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:01:05 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:01:05 volumio volumio[1508]: info: sendMpdCommand stop took 10 milliseconds May 27 17:01:05 volumio volumio[1508]: info: sendMpdCommand addid "http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364&t.flac" took 7 milliseconds May 27 17:01:05 volumio volumio[1508]: info: sendMpdCommand clear took 5 milliseconds May 27 17:01:05 volumio volumio[1508]: info: sendMpdCommand clear took 5 milliseconds May 27 17:01:05 volumio volumio[1508]: info: sendMpdCommand clear took 5 milliseconds May 27 17:01:05 volumio volumio[1508]: info: sendMpdCommand clear took 5 milliseconds May 27 17:01:05 volumio volumio[1508]: info: sendMpdCommand clear took 6 milliseconds May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand clear May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364&t.flac" May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364&t.flac" May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364&t.flac" May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364&t.flac" May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364&t.flac" May 27 17:01:05 volumio volumio[1508]: verbose: MPD COMMAND [object Object] May 27 17:01:05 volumio volumio[1508]: verbose: MPD COMMAND [object Object] May 27 17:01:05 volumio volumio[1508]: verbose: MPD COMMAND [object Object] May 27 17:01:05 volumio volumio[1508]: info: May 27 17:01:05 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:01:05 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:01:05 volumio volumio[1508]: info: May 27 17:01:05 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:01:05 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:01:05 volumio volumio[1508]: info: May 27 17:01:05 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:01:05 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:01:05 volumio volumio[1508]: info: May 27 17:01:05 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:01:05 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:01:05 volumio volumio[1508]: info: May 27 17:01:05 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:01:05 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:01:05 volumio volumio[1508]: info: May 27 17:01:05 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:01:05 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:01:05 volumio volumio[1508]: error: updateQueue error: null May 27 17:01:05 volumio volumio[1508]: error: updateQueue error: null May 27 17:01:05 volumio volumio[1508]: error: updateQueue error: null May 27 17:01:05 volumio volumio[1508]: error: updateQueue error: null May 27 17:01:05 volumio volumio[1508]: error: updateQueue error: null May 27 17:01:05 volumio volumio[1508]: error: updateQueue error: null May 27 17:01:05 volumio volumio[1508]: info: May 27 17:01:05 volumio volumio[1508]: ---------------------------- MPD announces system playlist update May 27 17:01:05 volumio volumio[1508]: info: Ignoring MPD Status Update May 27 17:01:05 volumio volumio[1508]: info: ------------------------------ 13ms May 27 17:01:05 volumio volumio[1508]: info: ------------------------------ 12ms May 27 17:01:05 volumio volumio[1508]: info: ------------------------------ 12ms May 27 17:01:05 volumio volumio[1508]: info: ------------------------------ 11ms May 27 17:01:05 volumio volumio[1508]: info: ------------------------------ 12ms May 27 17:01:05 volumio volumio[1508]: info: ------------------------------ 11ms May 27 17:01:05 volumio volumio[1508]: info: sendMpdCommand clear took 9 milliseconds May 27 17:01:05 volumio volumio[1508]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.52:8096/Audio/a220da648fa863ea4c8838742eacd86c/stream.flac?static=true&mediaSourceId=a220da648fa863ea4c8838742eacd86c&tag=6614c9d72d236cf05db024087aa23364&t.flac" May 27 17:01:05 volumio volumio[1508]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 17:01:05 volumio volumio[1508]: Error: [50@0] {addtagid} No such song May 27 17:01:05 volumio volumio[1508]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:17) May 27 17:01:05 volumio volumio[1508]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) May 27 17:01:05 volumio volumio[1508]: at Socket.emit (node:events:514:28) May 27 17:01:05 volumio volumio[1508]: at addChunk (node:internal/streams/readable:343:12) May 27 17:01:05 volumio volumio[1508]: at readableAddChunk (node:internal/streams/readable:312:11) May 27 17:01:05 volumio volumio[1508]: at Readable.push (node:internal/streams/readable:253:10) May 27 17:01:05 volumio volumio[1508]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) May 27 17:01:05 volumio volumio[1508]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 27 17:01:06 volumio sudo[2711]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-05-27 17:00' May 27 17:01:06 volumio sudo[2711]: 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"