-- Logs begin at Mon 2019-11-18 01:00:49 CET, end at Tue 2025-09-16 22:17:58 CEST. -- Sep 16 22:16:23 volumio systemd-timedated[1836]: Changed local time to Tue Sep 16 22:16:23 2025 Sep 16 22:16:23 volumio avahi-daemon[923]: Server startup complete. Host name is volumio-2.local. Local service cookie is 776048760. Sep 16 22:16:23 volumio sudo[1823]: pam_unix(sudo:session): session closed for user root Sep 16 22:16:23 volumio volumio[1189]: info: Discovery: A device disappeared from network Sep 16 22:16:23 volumio volumio[1189]: info: Discovery: Device volumio disappeared from network Sep 16 22:16:23 volumio volumio-time-update[897]: volumio-time-update-util: System time updated successfully. Sep 16 22:16:23 volumio volumio[1189]: info: Discovery: A device disappeared from network Sep 16 22:16:23 volumio systemd[1]: Starting Daily apt download activities... Sep 16 22:16:23 volumio systemd[1]: Starting Daily man-db regeneration... Sep 16 22:16:23 volumio systemd[1]: Started Volumio Time Update Utility. Sep 16 22:16:23 volumio systemd[1]: Reached target Multi-User System. Sep 16 22:16:23 volumio systemd[1]: Reached target Graphical Interface. Sep 16 22:16:23 volumio systemd[1]: Starting Update UTMP about System Runlevel Changes... Sep 16 22:16:23 volumio systemd[1]: systemd-update-utmp-runlevel.service: Succeeded. Sep 16 22:16:23 volumio systemd[1]: Started Update UTMP about System Runlevel Changes. Sep 16 22:16:23 volumio systemd[1]: man-db.service: Succeeded. Sep 16 22:16:23 volumio systemd[1]: Started Daily man-db regeneration. Sep 16 22:16:23 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:16:23 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:16:23 volumio dhcpcd[1009]: wlan0: probing for an IPv4LL address Sep 16 22:16:23 volumio ntpd[1814]: Soliciting pool server 141.144.241.16 Sep 16 22:16:23 volumio systemd[1]: apt-daily.service: Succeeded. Sep 16 22:16:23 volumio systemd[1]: Started Daily apt download activities. Sep 16 22:16:23 volumio systemd[1]: Starting Daily apt upgrade and clean activities... Sep 16 22:16:23 volumio ntpd[1814]: Soliciting pool server 94.130.35.4 Sep 16 22:16:23 volumio volumio[1189]: info: Discovery: adding 3fbbab14-e4a8-44d0-9471-40024378c0b5 Sep 16 22:16:23 volumio volumio[1189]: info: Discovery: Found device Volumio Sep 16 22:16:23 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:16:23 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:16:23 volumio volumio[1189]: info: Discovery: this is already registered, 3fbbab14-e4a8-44d0-9471-40024378c0b5 Sep 16 22:16:23 volumio volumio[1189]: info: Discovery: Found device Volumio Sep 16 22:16:23 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:16:23 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:16:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 16 22:16:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 22:16:24 volumio volumio[1189]: info: Received Get System Info Sep 16 22:16:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:16:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:16:24 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:16:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:16:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:16:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:16:24 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. Sep 16 22:16:24 volumio systemd[1]: Started Daily apt upgrade and clean activities. Sep 16 22:16:24 volumio systemd[1]: Startup finished in 9.620s (kernel) + 13.427s (userspace) = 23.048s. Sep 16 22:16:24 volumio ntpd[1814]: Soliciting pool server 185.255.121.15 Sep 16 22:16:24 volumio ntpd[1814]: Soliciting pool server 217.160.19.219 Sep 16 22:16:24 volumio ntpd[1814]: Soliciting pool server 217.79.189.239 Sep 16 22:16:25 volumio volumio-remote-updater[912]: [2025-09-16 22:16:25] [connect] Successful connection Sep 16 22:16:25 volumio volumio-remote-updater[912]: [2025-09-16 22:16:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1758053785 101 Sep 16 22:16:25 volumio volumio[1189]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Sep 16 22:16:25 volumio ntpd[1814]: Soliciting pool server 62.154.155.69 Sep 16 22:16:25 volumio ntpd[1814]: Soliciting pool server 152.53.119.33 Sep 16 22:16:25 volumio ntpd[1814]: Soliciting pool server 78.47.93.191 Sep 16 22:16:25 volumio ntpd[1814]: Soliciting pool server 49.13.14.46 Sep 16 22:16:25 volumio volumio[1189]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Sep 16 22:16:26 volumio ntpd[1814]: Soliciting pool server 178.63.52.31 Sep 16 22:16:26 volumio ntpd[1814]: Soliciting pool server 141.98.136.83 Sep 16 22:16:26 volumio ntpd[1814]: Soliciting pool server 185.13.148.71 Sep 16 22:16:27 volumio ntpd[1814]: Soliciting pool server 5.75.147.220 Sep 16 22:16:27 volumio ntpd[1814]: Soliciting pool server 79.133.44.140 Sep 16 22:16:28 volumio dhcpcd[1009]: wlan0: using IPv4LL address 169.254.245.236 Sep 16 22:16:28 volumio avahi-daemon[923]: Registering new address record for 169.254.245.236 on wlan0.IPv4. Sep 16 22:16:28 volumio dhcpcd[1009]: wlan0: adding route to 169.254.0.0/16 Sep 16 22:16:28 volumio ntpd[1814]: Soliciting pool server 188.245.32.133 Sep 16 22:16:28 volumio ntpd[1814]: Soliciting pool server 2a02:8106:21:9400::8 Sep 16 22:16:29 volumio volumio[1189]: info: Volumio Network Manager: Network status updated: 1 Sep 16 22:16:29 volumio sudo[2161]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 16 22:16:29 volumio sudo[2161]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:16:29 volumio sudo[2161]: pam_unix(sudo:session): session closed for user root Sep 16 22:16:29 volumio sudo[2164]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 16 22:16:29 volumio sudo[2164]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:16:29 volumio sudo[2164]: pam_unix(sudo:session): session closed for user root Sep 16 22:16:29 volumio sudo[2167]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Sep 16 22:16:29 volumio sudo[2167]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:16:29 volumio systemd[1]: Started UPnP Renderer front-end to MPD. Sep 16 22:16:29 volumio sudo[2167]: pam_unix(sudo:session): session closed for user root Sep 16 22:16:29 volumio volumio[1189]: info: Upmpdcli Daemon Started Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Sep 16 22:16:29 volumio volumio[1189]: info: Adding plugin bluetooth to MyMusic Plugins Sep 16 22:16:29 volumio volumio[1189]: info: Adding plugin multiroom to MyMusic Plugins Sep 16 22:16:29 volumio volumio[1189]: info: Adding plugin metavolumio to MyMusic Plugins Sep 16 22:16:29 volumio volumio[1189]: info: Adding plugin cd_controller to MyMusic Plugins Sep 16 22:16:29 volumio volumio[1189]: info: Adding plugin qobuzconnect to MyMusic Plugins Sep 16 22:16:29 volumio volumio[1189]: info: Adding plugin smart_inputs to MyMusic Plugins Sep 16 22:16:29 volumio volumio[1189]: info: Adding plugin tidalconnect to MyMusic Plugins Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Sep 16 22:16:29 volumio ntpd[1814]: Listen normally on 5 wlan0 169.254.245.236:123 Sep 16 22:16:29 volumio ntpd[1814]: new interface(s) found: waking up resolver Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Sep 16 22:16:29 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:16:29 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:16:29 volumio volumio[1189]: info: Starting MyVolumio Remote Streaming Endpoints Sep 16 22:16:29 volumio volumio[1189]: info: MyVolumio not started Sep 16 22:16:29 volumio volumio[1189]: info: Initializing device activation check Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Sep 16 22:16:29 volumio volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Sep 16 22:16:30 volumio volumio[1189]: info: Starting Streaming Service Transparent Proxy Sep 16 22:16:30 volumio volumio[1189]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Sep 16 22:16:30 volumio volumio[1189]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Sep 16 22:16:30 volumio volumio[1189]: info: Streaming services startup Sep 16 22:16:30 volumio volumio[1189]: info: Starting Streaming Daemon Sep 16 22:16:30 volumio sudo[2181]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 16 22:16:30 volumio sudo[2181]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:16:30 volumio sudo[2181]: pam_unix(sudo:session): session closed for user root Sep 16 22:16:30 volumio volumio[1189]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Sep 16 22:16:30 volumio volumio[1189]: error: Cannot start Volumio Streaming Daemon Sep 16 22:16:30 volumio volumio[1189]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 16 22:16:30 volumio volumio[1189]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 16 22:16:30 volumio volumio[1189]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Sep 16 22:16:30 volumio volumio[2169]: Generating RSA private key, 4096 bit long modulus (2 primes) Sep 16 22:16:30 volumio volumio[2169]: .++++ Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:16:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:16:30 volumio volumio[1189]: info: Received Get System Info Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:16:30 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:16:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:16:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::volumioGetVisibleSources Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:16:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:16:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 16 22:16:30 volumio volumio[1189]: info: Received Get System Info Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:16:30 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:16:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:16:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:16:30 volumio volumio[1189]: info: Listing playlists Sep 16 22:16:30 volumio volumio[1189]: STREAMING PROXY: Starting server on port 3245 Sep 16 22:16:30 volumio volumio[1189]: Node JS runtime: 14 Sep 16 22:16:30 volumio volumio[1189]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=x86_amd64&variante=volumio&version=3.832&uuid=ca50c294b676bbdeb4d75fa5e3d0acaf" http://updates.volumio.org/downloader-v1/track-device Sep 16 22:16:30 volumio volumio[1189]: % Total % Received % Xferd Average Speed Time Time Time Current Sep 16 22:16:30 volumio volumio[1189]: Dload Upload Total Spent Left Speed Sep 16 22:16:30 volumio volumio[1189]: [132B blob data] Sep 16 22:16:30 volumio volumio[1189]: retrying in 5 seconds, trial 0 Sep 16 22:16:30 volumio volumio[1189]: info: Volumio Calling Home Sep 16 22:16:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 16 22:16:30 volumio volumio[2169]: ...................................................................................................++++ Sep 16 22:16:30 volumio volumio[2169]: e is 65537 (0x010001) Sep 16 22:16:30 volumio volumio[2169]: writing RSA key Sep 16 22:16:30 volumio volumio[1189]: info: Volumio called home Sep 16 22:16:32 volumio ntpd[1814]: receive: Unexpected origin timestamp 0xec74441f.a9168a5d does not match aorg 0000000000.00000000 from server@5.75.147.220 xmt 0xec744420.7eb5d4fa Sep 16 22:16:32 volumio ntpd[1814]: receive: Unexpected origin timestamp 0xec74441f.a911014e does not match aorg 0000000000.00000000 from server@188.245.32.133 xmt 0xec744420.7f19225a Sep 16 22:16:32 volumio ntpd[1814]: receive: Unexpected origin timestamp 0xec74441f.a9245278 does not match aorg 0000000000.00000000 from server@188.245.97.96 xmt 0xec744420.7eff0bfe Sep 16 22:16:32 volumio ntpd[1814]: receive: Unexpected origin timestamp 0xec74441f.a92063a5 does not match aorg 0000000000.00000000 from server@185.255.121.15 xmt 0xec744420.7f0e647c Sep 16 22:16:32 volumio ntpd[1814]: receive: Unexpected origin timestamp 0xec74441f.a91e0bd0 does not match aorg 0000000000.00000000 from server@78.47.93.191 xmt 0xec744420.7f1b0cd3 Sep 16 22:16:32 volumio ntpd[1814]: receive: Unexpected origin timestamp 0xec74441f.a918f8b2 does not match aorg 0000000000.00000000 from server@185.13.148.71 xmt 0xec744420.7f51eb14 Sep 16 22:16:32 volumio ntpd[1814]: receive: Unexpected origin timestamp 0xec74441f.a92195f8 does not match aorg 0000000000.00000000 from server@94.130.35.4 xmt 0xec744420.7f0e9f2d Sep 16 22:16:32 volumio ntpd[1814]: receive: Unexpected origin timestamp 0xec74441f.a917e730 does not match aorg 0000000000.00000000 from server@178.63.52.31 xmt 0xec744420.7f17a8eb Sep 16 22:16:32 volumio ntpd[1814]: receive: Unexpected origin timestamp 0xec74441f.a91b6531 does not match aorg 0000000000.00000000 from server@49.13.14.46 xmt 0xec744420.7f209d27 Sep 16 22:16:32 volumio ntpd[1814]: receive: Unexpected origin timestamp 0xec74441f.a91f4020 does not match aorg 0000000000.00000000 from server@217.160.19.219 xmt 0xec744420.7f68d55a Sep 16 22:16:32 volumio ntpd[1814]: receive: Unexpected origin timestamp 0xec74441f.a91cb6dd does not match aorg 0000000000.00000000 from server@152.53.119.33 xmt 0xec744420.7eec3441 Sep 16 22:16:33 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 16 22:16:41 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 16 22:16:41 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 16 22:16:41 volumio volumio[1189]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Sep 16 22:16:41 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Sep 16 22:16:41 volumio volumio[1189]: info: Received Get System Version Sep 16 22:16:41 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 22:16:41 volumio volumio[1189]: info: Received Get System Info Sep 16 22:16:41 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:16:41 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:16:41 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:16:41 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:16:41 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:16:41 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:16:43 volumio systemd[1]: systemd-fsckd.service: Succeeded. Sep 16 22:16:43 volumio systemd[1]: systemd-hostnamed.service: Succeeded. Sep 16 22:16:45 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 16 22:16:45 volumio volumio[1189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 16 22:16:45 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:16:45 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:16:50 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:16:50 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:16:50 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Sep 16 22:16:50 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Sep 16 22:16:50 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Sep 16 22:16:50 volumio volumio[1189]: info: CoreCommandRouter::volumioGetBrowseSources Sep 16 22:16:50 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 22:16:51 volumio volumio[1189]: error: MyVolumio Plugin failed to authenticate in a timely fashion Sep 16 22:16:51 volumio volumio[1189]: info: Completed starting MyVolumio Plugin Sep 16 22:16:51 volumio volumio[1189]: [Metrics] CommandRouter: 32s 88.02ms Sep 16 22:16:51 volumio volumio[1189]: info: CoreCommandRouter::volumiosetStartupVolume Sep 16 22:16:51 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:16:51 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:16:51 volumio volumio[1189]: info: CoreCommandRouter::Close All Modals sent Sep 16 22:16:51 volumio volumio[1189]: info: CoreCommandRouter::Close All Modals sent Sep 16 22:16:51 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 16 22:16:51 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:16:51 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 16 22:16:51 volumio volumio-remote-updater[912]: No test mode Sep 16 22:16:51 volumio volumio-remote-updater[912]: No alpha test mode Sep 16 22:16:51 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Sep 16 22:16:51 volumio volumio[1189]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Sep 16 22:16:51 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Sep 16 22:16:52 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Sep 16 22:16:52 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 16 22:16:52 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Sep 16 22:16:53 volumio systemd[1]: systemd-timedated.service: Succeeded. Sep 16 22:16:58 volumio volumio[1189]: info: BOOT COMPLETED Sep 16 22:17:11 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , setMyVolumioToken Sep 16 22:17:11 volumio volumio[1189]: info: MyVolumio login type: Token Sep 16 22:17:11 volumio volumio[1189]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Sep 16 22:17:12 volumio volumio[1189]: error: error Sep 16 22:17:12 volumio volumio[1189]: error: error Sep 16 22:17:12 volumio volumio[1189]: error: error Sep 16 22:17:12 volumio volumio[1189]: error: error Sep 16 22:17:12 volumio volumio[1189]: error: error Sep 16 22:17:12 volumio volumio[1189]: error: error Sep 16 22:17:12 volumio volumio[1189]: error: error Sep 16 22:17:12 volumio volumio[1189]: error: error Sep 16 22:17:12 volumio volumio[1189]: error: error Sep 16 22:17:13 volumio volumio[1189]: info: MyVolumio token set successfully Sep 16 22:17:13 volumio volumio[1189]: info: MYVOLUMIO: Adding device Sep 16 22:17:13 volumio volumio[1189]: info: MYVOLUMIO: Evaluating Server Sep 16 22:17:13 volumio volumio[1189]: info: MyVolumio Plan changed: premium Sep 16 22:17:13 volumio volumio[1189]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Sep 16 22:17:13 volumio volumio[1189]: info: Removing browser output: myVolumio user plan is not superstar Sep 16 22:17:13 volumio volumio[1189]: info: Removing audio output: Sep 16 22:17:13 volumio volumio[1189]: info: MYVOLUMIO: Adding device Sep 16 22:17:13 volumio volumio[1189]: info: MYVOLUMIO: Evaluating Server Sep 16 22:17:13 volumio volumio[1189]: info: Remote config written successfully Sep 16 22:17:13 volumio volumio[1189]: info: Starting Tunnel 1 Sep 16 22:17:13 volumio volumio[1189]: info: Starting Tunnel Connection Checker Sep 16 22:17:13 volumio volumio[1189]: info: MYVolumio Device disabled Sep 16 22:17:13 volumio volumio[1189]: info: MyVolumio status changed Sep 16 22:17:13 volumio volumio[1189]: info: Streaming services startup Sep 16 22:17:13 volumio volumio[1189]: info: Starting Streaming Daemon Sep 16 22:17:13 volumio sudo[2390]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 16 22:17:13 volumio sudo[2390]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:13 volumio sudo[2390]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:13 volumio volumio[1189]: error: Cannot start Volumio Streaming Daemon Sep 16 22:17:13 volumio volumio[1189]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 16 22:17:13 volumio volumio[1189]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 16 22:17:18 volumio sudo[2400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Sep 16 22:17:18 volumio sudo[2400]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:18 volumio systemd[1]: Started MyVolumio SSH Tunnel. Sep 16 22:17:18 volumio sudo[2400]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:18 volumio volumio[1189]: info: Remote SSH Started Sep 16 22:17:18 volumio autossh[2403]: port set to 0, monitoring disabled Sep 16 22:17:18 volumio autossh[2403]: starting ssh (count 1) Sep 16 22:17:18 volumio autossh[2403]: ssh child pid is 2406 Sep 16 22:17:18 volumio volumio[1189]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Sep 16 22:17:18 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:18 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:18 volumio volumiossh-tunnel[2402]: Warning: Permanently added '[eu1.myvolumio.org]:2222,[68.183.69.189]:2222' (RSA) to the list of known hosts. Sep 16 22:17:18 volumio volumio[1189]: info: Setting Geolocation for MyVolumio to eu7 Sep 16 22:17:18 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:18 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:18 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:19 volumio volumio[1189]: info: MYVolumio Device enabled Sep 16 22:17:19 volumio volumio[1189]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Sep 16 22:17:19 volumio volumio[1189]: info: MyVolumio status changed Sep 16 22:17:19 volumio volumio[1189]: info: Streaming services startup Sep 16 22:17:19 volumio volumio[1189]: info: Starting Streaming Daemon Sep 16 22:17:19 volumio sudo[2408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Sep 16 22:17:19 volumio sudo[2408]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:19 volumio volumio[1189]: error: [MyVolumio PluginManager] Could not read cache file: ENOENT: no such file or directory, open '/data/myvolumio/cache.data' Sep 16 22:17:19 volumio sudo[2408]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:19 volumio volumio[1189]: error: Cannot start Volumio Streaming Daemon Sep 16 22:17:19 volumio volumio[1189]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Sep 16 22:17:19 volumio volumio[1189]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Sep 16 22:17:19 volumio volumio[1189]: info: Setting Geolocation for MyVolumio to eu4 Sep 16 22:17:19 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:19 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:19 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:19 volumio volumio[1189]: info: Successfully Added MyVolumio device Sep 16 22:17:19 volumio volumio[1189]: info: Updating MyVolumio device info Sep 16 22:17:19 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:19 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:19 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:20 volumio volumio[1189]: info: Successfully Added MyVolumio device Sep 16 22:17:20 volumio volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Sep 16 22:17:20 volumio volumio[1189]: info: [1758053840384] Starting BluetoothController Sep 16 22:17:20 volumio volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Sep 16 22:17:20 volumio sudo[2425]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Sep 16 22:17:20 volumio sudo[2425]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:20 volumio sudo[2425]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:20 volumio volumio[1189]: info: MRS: MultiRoom plugin initialized Sep 16 22:17:20 volumio volumio[1189]: info: MRS: STOPPING SNAPCLIENT Sep 16 22:17:20 volumio volumio[1189]: info: MRS: Snap server stop Sep 16 22:17:20 volumio sudo[2441]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Sep 16 22:17:20 volumio sudo[2441]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:20 volumio volumio[1189]: info: MRS: STOPPING volumioStreaming Sep 16 22:17:20 volumio sudo[2444]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Sep 16 22:17:20 volumio sudo[2444]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:20 volumio sudo[2441]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:20 volumio sudo[2447]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Sep 16 22:17:20 volumio sudo[2447]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:20 volumio sudo[2444]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:20 volumio volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Sep 16 22:17:20 volumio sudo[2447]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:20 volumio sudo[2450]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Sep 16 22:17:20 volumio sudo[2450]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:20 volumio sudo[2450]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:20 volumio volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Sep 16 22:17:20 volumio volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Sep 16 22:17:20 volumio volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Sep 16 22:17:20 volumio volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Sep 16 22:17:20 volumio volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Sep 16 22:17:20 volumio volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Sep 16 22:17:20 volumio volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Sep 16 22:17:20 volumio volumio[1189]: info: [MyVolumio PluginManager] Loading plugin "qobuzconnect"... Sep 16 22:17:20 volumio volumio[1189]: info: Preparing to generate the ALSA configuration file Sep 16 22:17:20 volumio volumio[1189]: info: Updating MyVolumio device info Sep 16 22:17:20 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:20 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:20 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:20 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Sep 16 22:17:20 volumio volumio[1189]: info: Successfully Updated MyVolumio device Sep 16 22:17:20 volumio volumio[1189]: info: MRS: Removed streaming files Sep 16 22:17:20 volumio volumio[1189]: info: MRS: volumioStreaming STOPPED Sep 16 22:17:20 volumio volumio[1189]: info: MRS: SNAPSERVER STOPPED Sep 16 22:17:20 volumio volumio[1189]: info: MRS: SNAPCLIENT STOPPED Sep 16 22:17:20 volumio volumio[1189]: info: Asound.conf file unchanged, so no further update is needed Sep 16 22:17:20 volumio volumio[1189]: info: Output device has changed, restarting MPD Sep 16 22:17:21 volumio sudo[2453]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 22:17:21 volumio sudo[2453]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio sudo[2453]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio volumio[1189]: info: Output device has changed, restarting Shairport Sync Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:21 volumio sudo[2456]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 22:17:21 volumio sudo[2456]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio systemd[1]: Stopping Music Player Daemon... Sep 16 22:17:21 volumio systemd[1]: mpd.service: Succeeded. Sep 16 22:17:21 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 22:17:21 volumio volumio[1189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 22:17:21 volumio volumio[1189]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Sep 16 22:17:21 volumio systemd[1]: Starting Music Player Daemon... Sep 16 22:17:21 volumio volumio[1189]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Sep 16 22:17:21 volumio volumio[1189]: info: MRS: Pushing multiroomSync output for this device Sep 16 22:17:21 volumio volumio[1189]: info: MRS: Pushing multiroomSync output Sep 16 22:17:21 volumio volumio[1189]: info: Adding audio output: Sep 16 22:17:21 volumio volumio[1189]: info: Adding audio output: Sep 16 22:17:21 volumio volumio[1189]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio sudo[2465]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Sep 16 22:17:21 volumio sudo[2465]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio volumio[1189]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Sep 16 22:17:21 volumio volumio[1189]: info: Adding METAVOLUMIO REST API Endpoints Sep 16 22:17:21 volumio volumio[1189]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Sep 16 22:17:21 volumio volumio[1189]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Sep 16 22:17:21 volumio volumio[1189]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Sep 16 22:17:21 volumio volumio[1189]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Sep 16 22:17:21 volumio volumio[1189]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Sep 16 22:17:21 volumio volumio[1189]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Sep 16 22:17:21 volumio volumio[1189]: info: Preparing CD Folders Sep 16 22:17:21 volumio volumio[1189]: info: Adding CD REST API Endpoints Sep 16 22:17:21 volumio volumio[1189]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Sep 16 22:17:21 volumio volumio[1189]: info: Starting UDEV Watcher for CD Sep 16 22:17:21 volumio volumio[1189]: info: Detecting CD presence with UDEV Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Sep 16 22:17:21 volumio sudo[2461]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 16 22:17:21 volumio sudo[2461]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio sudo[2461]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio volumio[1189]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Sep 16 22:17:21 volumio volumio[1189]: info: Adding inputs REST Endpoints Sep 16 22:17:21 volumio volumio[1189]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Sep 16 22:17:21 volumio volumio[1189]: info: Scanning Audio Inputs Sep 16 22:17:21 volumio volumio[1189]: info: Checking against Known Cards name Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Sep 16 22:17:21 volumio volumio[1189]: info: [1758053841133] CoreMusicLibrary::Adding element HD-Audio Generic Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 22:17:21 volumio volumio[1189]: Cannot find translation for source HD-Audio Generic Sep 16 22:17:21 volumio volumio[1189]: info: Checking against Known Cards name Sep 16 22:17:21 volumio volumio[1189]: info: Checking against Known Cards name Sep 16 22:17:21 volumio volumio[1189]: info: Adding Server instance for streaming Sep 16 22:17:21 volumio volumio[1189]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Sep 16 22:17:21 volumio volumio[1189]: error: Hi Res Audio Failed Login: Missing Login Data Sep 16 22:17:21 volumio volumio[1189]: info: Adding HIGHRESAUDIO REST API Endpoints Sep 16 22:17:21 volumio volumio[1189]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Sep 16 22:17:21 volumio volumio[1189]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Sep 16 22:17:21 volumio volumio[1189]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Sep 16 22:17:21 volumio volumio[1189]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Sep 16 22:17:21 volumio volumio[1189]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuzconnect Sep 16 22:17:21 volumio volumio[1189]: info: Adding qc_getconfig REST Endpoint for plugin: music_service/qobuzconnect Sep 16 22:17:21 volumio sudo[2475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Sep 16 22:17:21 volumio sudo[2475]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: QobuzConnect: Starting Qobuz Connect socket and service Sep 16 22:17:21 volumio sudo[2475]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio volumio[1189]: info: QobuzConnect: Opened /tmp/qbz-connect.socket socket, listening for connections Sep 16 22:17:21 volumio sudo[2482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 16 22:17:21 volumio sudo[2482]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio volumio[1189]: info: Stopping AccessToken refresher cron Sep 16 22:17:21 volumio sudo[2482]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio volumio[1189]: info: AccessToken refresher cron started Sep 16 22:17:21 volumio volumio[1189]: info: Stopping AccessToken refresher cron for QOBUZ Sep 16 22:17:21 volumio volumio[1189]: info: AccessToken refresher cron started for QOBUZ Sep 16 22:17:21 volumio sudo[2484]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 16 22:17:21 volumio sudo[2484]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio volumio[1189]: info: Adding TIDAL REST API Endpoints Sep 16 22:17:21 volumio volumio[1189]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Sep 16 22:17:21 volumio volumio[1189]: info: Adding QOBUZ REST API Endpoints Sep 16 22:17:21 volumio volumio[1189]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Sep 16 22:17:21 volumio volumio[1189]: info: MPD Permissions set Sep 16 22:17:21 volumio sudo[2465]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio volumio[1189]: info: Bluetooth name changed to Volumio Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 16 22:17:21 volumio systemd[1]: Started Volumio Qobuz Connect Service. Sep 16 22:17:21 volumio sudo[2484]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: Starting Shairport Sync Sep 16 22:17:21 volumio volumio[1189]: info: Preparing to generate the ALSA configuration file Sep 16 22:17:21 volumio sudo[2494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 22:17:21 volumio sudo[2494]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio volumio[1189]: info: Asound.conf file unchanged, so no further update is needed Sep 16 22:17:21 volumio volumio[1189]: info: Output device has changed, restarting MPD Sep 16 22:17:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 16 22:17:21 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 16 22:17:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 16 22:17:21 volumio sudo[2499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 22:17:21 volumio sudo[2499]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio volumio[1189]: info: Output device has changed, restarting Shairport Sync Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:21 volumio sudo[2499]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio sudo[2502]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 22:17:21 volumio sudo[2502]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 16 22:17:21 volumio volumio[1189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 22:17:21 volumio volumio[1189]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio sudo[2494]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 16 22:17:21 volumio systemd[1]: mpd.service: Succeeded. Sep 16 22:17:21 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 22:17:21 volumio volumio[1189]: info: QobuzConnect: setDeactiveState invoked Sep 16 22:17:21 volumio systemd[1]: Starting Music Player Daemon... Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:21 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:21 volumio volumio[1189]: info: Preparing to generate the ALSA configuration file Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: MPD Permissions set Sep 16 22:17:21 volumio volumio[1189]: info: Shairport-Sync Started Sep 16 22:17:21 volumio sudo[2516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 16 22:17:21 volumio sudo[2516]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio sudo[2510]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 16 22:17:21 volumio sudo[2510]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio sudo[2510]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio sudo[2516]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio sudo[2520]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 16 22:17:21 volumio sudo[2520]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio qobuz-connect[2486]: 20250916 22:17:21.396 [2486.2486] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 16 22:17:21 volumio volumio[1189]: info: Executing endpoint qc_getconfig Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: Asound.conf file unchanged, so no further update is needed Sep 16 22:17:21 volumio volumio[1189]: info: Output device has changed, restarting MPD Sep 16 22:17:21 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 16 22:17:21 volumio systemd[1]: qobuz-connect.service: Main process exited, code=killed, status=2/INT Sep 16 22:17:21 volumio systemd[1]: qobuz-connect.service: Succeeded. Sep 16 22:17:21 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 16 22:17:21 volumio systemd[1]: Started Volumio Qobuz Connect Service. Sep 16 22:17:21 volumio sudo[2520]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio sudo[2531]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 16 22:17:21 volumio sudo[2531]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio sudo[2531]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio volumio[1189]: info: Output device has changed, restarting Shairport Sync Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:21 volumio sudo[2534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 16 22:17:21 volumio sudo[2534]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 16 22:17:21 volumio systemd[1]: mpd.service: Succeeded. Sep 16 22:17:21 volumio systemd[1]: Stopped Music Player Daemon. Sep 16 22:17:21 volumio volumio[1189]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 16 22:17:21 volumio volumio[1189]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: QobuzConnect: setDeactiveState invoked Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:21 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:21 volumio systemd[1]: Starting Music Player Daemon... Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 16 22:17:21 volumio volumio[1189]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 16 22:17:21 volumio volumio[1189]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 16 22:17:21 volumio volumio[1189]: info: QobuzConnect: setDeactiveState invoked Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:21 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:21 volumio sudo[2546]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Sep 16 22:17:21 volumio volumio[1189]: info: MPD Permissions set Sep 16 22:17:21 volumio sudo[2546]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio volumio[1189]: info: Executing endpoint qc_getconfig Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 16 22:17:21 volumio volumio[1189]: info: Starting Shairport Sync Sep 16 22:17:21 volumio qobuz-connect[2528]: 20250916 22:17:21.511 [2528.2528] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 16 22:17:21 volumio sudo[2546]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio qobuz-connect[2528]: 20250916 22:17:21.519 [2528.2528] INFO VolumeManager: [0x562ad89ab560]: Setting new playback volume: 75 Sep 16 22:17:21 volumio qobuz-connect[2528]: 20250916 22:17:21.519 [2528.2528] INFO VolumeManager: [0x562ad89ab560]: Setting new mute state: 0 Sep 16 22:17:21 volumio qobuz-connect[2528]: 20250916 22:17:21.519 [2528.2528] INFO QobuzConnect: [0x562ad89ac780]: Client initialized! Sep 16 22:17:21 volumio qobuz-connect[2528]: 20250916 22:17:21.519 [2528.2528] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Sep 16 22:17:21 volumio sudo[2548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Sep 16 22:17:21 volumio sudo[2539]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 16 22:17:21 volumio qobuz-connect[2528]: 20250916 22:17:21.522 [2528.2528] INFO LocalConfigManager: [0x562ad89aa850]: Starting Local Configuration server Sep 16 22:17:21 volumio qobuz-connect[2528]: 20250916 22:17:21.522 [2528.2528] INFO SampleApp: Starting Local configuration server Sep 16 22:17:21 volumio qobuz-connect[2528]: 20250916 22:17:21.523 [2528.2528] INFO SampleApp: Connected to UNIX socket client 0x562ad8992280 Sep 16 22:17:21 volumio sudo[2539]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio sudo[2548]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio sudo[2539]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio volumio[1189]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 16 22:17:21 volumio volumio[1189]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio sudo[2553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 22:17:21 volumio sudo[2553]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio qobuz-connect[2528]: 20250916 22:17:21.535 [2528.2528] INFO SampleApp: Stopping Local configuration server Sep 16 22:17:21 volumio systemd[1]: Stopping Volumio Qobuz Connect Service... Sep 16 22:17:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 16 22:17:21 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 16 22:17:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 16 22:17:21 volumio qobuz-connect[2528]: 20250916 22:17:21.545 [2528.2528] INFO SampleApp: shat down connection on UNIX socket Sep 16 22:17:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 16 22:17:21 volumio sudo[2553]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio systemd[1]: qobuz-connect.service: Succeeded. Sep 16 22:17:21 volumio systemd[1]: Stopped Volumio Qobuz Connect Service. Sep 16 22:17:21 volumio systemd[1]: Started Volumio Qobuz Connect Service. Sep 16 22:17:21 volumio sudo[2548]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 16 22:17:21 volumio volumio[1189]: info: Shairport-Sync Started Sep 16 22:17:21 volumio volumio[1189]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Sep 16 22:17:21 volumio volumio[1189]: info: QobuzConnect: setDeactiveState invoked Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:21 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:21 volumio volumio[1189]: info: Starting Shairport Sync Sep 16 22:17:21 volumio volumio[1189]: info: Executing endpoint qc_getconfig Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Sep 16 22:17:21 volumio volumio[1189]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Sep 16 22:17:21 volumio volumio[1189]: info: QobuzConnect: QOBUZ Connect daemon connected Sep 16 22:17:21 volumio qobuz-connect[2561]: 20250916 22:17:21.581 [2561.2561] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Sep 16 22:17:21 volumio qobuz-connect[2561]: 20250916 22:17:21.582 [2561.2561] INFO VolumeManager: [0x55c65393a560]: Setting new playback volume: 75 Sep 16 22:17:21 volumio qobuz-connect[2561]: 20250916 22:17:21.582 [2561.2561] INFO VolumeManager: [0x55c65393a560]: Setting new mute state: 0 Sep 16 22:17:21 volumio qobuz-connect[2561]: 20250916 22:17:21.582 [2561.2561] INFO QobuzConnect: [0x55c65393b780]: Client initialized! Sep 16 22:17:21 volumio qobuz-connect[2561]: 20250916 22:17:21.582 [2561.2561] INFO SampleApp: Starting Avahi advertising, name: Volumio, service name: _qobuz-connect._tcp Sep 16 22:17:21 volumio sudo[2568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 16 22:17:21 volumio sudo[2568]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:21 volumio qobuz-connect[2561]: 20250916 22:17:21.586 [2561.2561] INFO LocalConfigManager: [0x55c653939850]: Starting Local Configuration server Sep 16 22:17:21 volumio qobuz-connect[2561]: 20250916 22:17:21.586 [2561.2561] INFO SampleApp: Starting Local configuration server Sep 16 22:17:21 volumio qobuz-connect[2561]: 20250916 22:17:21.586 [2561.2561] INFO SampleApp: Connected to UNIX socket client 0x55c653921280 Sep 16 22:17:21 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 16 22:17:21 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 16 22:17:21 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 16 22:17:21 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 16 22:17:21 volumio sudo[2568]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio volumio[1189]: info: Shairport-Sync Started Sep 16 22:17:21 volumio mpd[2554]: Sep 16 22:17 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 16 22:17:21 volumio systemd[1]: Started Music Player Daemon. Sep 16 22:17:21 volumio sudo[2502]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio sudo[2456]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio sudo[2534]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:21 volumio qobuz-connect[2561]: 20250916 22:17:21.731 [2561.2561] INFO SampleApp: Playback volume changed: 75 Sep 16 22:17:21 volumio volumio[1189]: error: MPD error: The expression evaluated to a falsy value: Sep 16 22:17:21 volumio volumio[1189]: assert.ok(self.idling) Sep 16 22:17:21 volumio volumio[1189]: error: The expression evaluated to a falsy value: Sep 16 22:17:21 volumio volumio[1189]: assert.ok(self.idling) Sep 16 22:17:21 volumio volumio[1189]: error: MPD error: The expression evaluated to a falsy value: Sep 16 22:17:21 volumio volumio[1189]: assert.ok(self.idling) Sep 16 22:17:21 volumio volumio[1189]: error: The expression evaluated to a falsy value: Sep 16 22:17:21 volumio volumio[1189]: assert.ok(self.idling) Sep 16 22:17:21 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:21 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:21 volumio volumio[1189]: error: updateQueue error: null Sep 16 22:17:22 volumio volumio[1189]: info: Successfully Updated MyVolumio device Sep 16 22:17:23 volumio volumio[1189]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: Received Get System Info Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:17:24 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetVisibleSources Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 16 22:17:24 volumio volumio[1189]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 16 22:17:24 volumio volumio[1189]: info: Received Get System Info Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:17:24 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: Listing playlists Sep 16 22:17:24 volumio volumio[1189]: info: Received Get System Info Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:17:24 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio sudo[2586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Sep 16 22:17:24 volumio sudo[2586]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:24 volumio systemd[1]: Started Volumio Bluetooth Module. Sep 16 22:17:24 volumio sudo[2586]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:24 volumio volumio[1189]: info: Volumio BT Module successfully started Sep 16 22:17:24 volumio sudo[2589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Sep 16 22:17:24 volumio sudo[2589]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:24 volumio volumiobt[2588]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Sep 16 22:17:24 volumio sudo[2589]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:24 volumio volumio[1189]: info: TidalConnect service stoped! Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 16 22:17:24 volumio volumio[1189]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Sep 16 22:17:24 volumio volumio[1189]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Sep 16 22:17:24 volumio sudo[2601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Sep 16 22:17:24 volumio sudo[2601]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:24 volumio systemd[1]: Started Volumio Tidal Connect Service. Sep 16 22:17:24 volumio sudo[2601]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:24 volumio volumio[1189]: info: Executing endpoint tc_getconfig Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Sep 16 22:17:24 volumio vtcs[2603]: STARTING TidalConnect services, version: 1.5.1-beta1 Sep 16 22:17:24 volumio vtcs[2603]: STARTED TidalConnect services. Sep 16 22:17:24 volumio volumio[1189]: info: Executing endpoint tc_connect Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Sep 16 22:17:24 volumio volumio[1189]: info: Connecting to TidalConnect Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Sep 16 22:17:24 volumio volumio[1189]: info: CoreStateMachine::pushState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Sep 16 22:17:24 volumio volumio[1189]: info: MRS: Pushing multiroomSync output update for this device Sep 16 22:17:24 volumio volumio[1189]: info: MRS: Pushing multiroomSync output Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Sep 16 22:17:24 volumio volumio[1189]: verbose: CURRENT POSITION 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreStateMachine::syncState stateService stop Sep 16 22:17:24 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus stop Sep 16 22:17:24 volumio volumio[1189]: info: CoreStateMachine::pushState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Sep 16 22:17:24 volumio volumio[1189]: info: MRS: Pushing multiroomSync output update for this device Sep 16 22:17:24 volumio volumio[1189]: info: MRS: Pushing multiroomSync output Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: No code Sep 16 22:17:24 volumio volumio[1189]: info: CoreStateMachine::pushState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Sep 16 22:17:24 volumio volumio[1189]: info: MRS: Pushing multiroomSync output update for this device Sep 16 22:17:24 volumio volumio[1189]: info: MRS: Pushing multiroomSync output Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::servicePushState Sep 16 22:17:24 volumio volumio[1189]: info: CoreStateMachine::pushState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Sep 16 22:17:24 volumio volumio[1189]: info: MRS: Pushing multiroomSync output update for this device Sep 16 22:17:24 volumio volumio[1189]: info: MRS: Pushing multiroomSync output Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Sep 16 22:17:24 volumio volumio[1189]: verbose: CURRENT POSITION 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreStateMachine::syncState stateService stop Sep 16 22:17:24 volumio volumio[1189]: info: CoreStateMachine::syncState currentStatus stop Sep 16 22:17:24 volumio volumio[1189]: info: CoreStateMachine::pushState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Sep 16 22:17:24 volumio volumio[1189]: info: MRS: Pushing multiroomSync output update for this device Sep 16 22:17:24 volumio volumio[1189]: info: MRS: Pushing multiroomSync output Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: No code Sep 16 22:17:24 volumio volumio[1189]: info: CoreStateMachine::pushState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioPushState Sep 16 22:17:24 volumio volumio[1189]: info: MRS: Pushing multiroomSync output update for this device Sep 16 22:17:24 volumio volumio[1189]: info: MRS: Pushing multiroomSync output Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:24 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:24 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:25 volumio sudo[2622]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Sep 16 22:17:25 volumio sudo[2622]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 16 22:17:25 volumio volumiobt[2588]: pulseaudio: no process found Sep 16 22:17:25 volumio sudo[2622]: pam_unix(sudo:session): session closed for user root Sep 16 22:17:25 volumio dbus-daemon[914]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.29' (uid=1000 pid=2626 comm="/usr/bin/pulseaudio --high-priority=true ") Sep 16 22:17:25 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Sep 16 22:17:25 volumio volumio[1189]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 16 22:17:25 volumio dbus-daemon[914]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Sep 16 22:17:25 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Sep 16 22:17:25 volumio rtkit-daemon[2627]: Successfully called chroot. Sep 16 22:17:25 volumio rtkit-daemon[2627]: Successfully dropped privileges. Sep 16 22:17:25 volumio rtkit-daemon[2627]: Successfully limited resources. Sep 16 22:17:25 volumio rtkit-daemon[2627]: Running. Sep 16 22:17:25 volumio rtkit-daemon[2627]: Canary thread running. Sep 16 22:17:25 volumio rtkit-daemon[2627]: Watchdog thread running. Sep 16 22:17:25 volumio dbus-daemon[914]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.30' (uid=0 pid=2627 comm="/usr/lib/rtkit/rtkit-daemon ") Sep 16 22:17:25 volumio systemd[1]: Starting Authorization Manager... Sep 16 22:17:25 volumio volumio[1189]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 16 22:17:25 volumio polkitd[2630]: started daemon version 0.105 using authority implementation `local' version `0.105' Sep 16 22:17:25 volumio dbus-daemon[914]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Sep 16 22:17:25 volumio systemd[1]: Started Authorization Manager. Sep 16 22:17:25 volumio pulseaudio[2626]: Failed to open cookie file '/home/volumio/.config/pulse/cookie': No such file or directory Sep 16 22:17:25 volumio pulseaudio[2626]: Failed to load authentication key '/home/volumio/.config/pulse/cookie': No such file or directory Sep 16 22:17:25 volumio pulseaudio[2626]: Failed to open cookie file '/home/volumio/.pulse-cookie': No such file or directory Sep 16 22:17:25 volumio pulseaudio[2626]: Failed to load authentication key '/home/volumio/.pulse-cookie': No such file or directory Sep 16 22:17:25 volumio pulseaudio[2626]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 16 22:17:25 volumio pulseaudio[2626]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Sep 16 22:17:25 volumio bluetoothd[918]: Endpoint registered: sender=:1.50 path=/MediaEndpoint/A2DPSource Sep 16 22:17:25 volumio bluetoothd[918]: Endpoint registered: sender=:1.50 path=/MediaEndpoint/A2DPSink Sep 16 22:17:25 volumio volumiobt[2588]: Applying permissions Sep 16 22:17:25 volumio volumiobt[2588]: Setting BT discoverable and pairable Sep 16 22:17:25 volumio pulseaudio[2626]: Failed to register as a handsfree audio agent with ofono: org.freedesktop.DBus.Error.ServiceUnknown: The name org.ofono was not provided by any .service files Sep 16 22:17:25 volumio kernel: Bluetooth: RFCOMM TTY layer initialized Sep 16 22:17:25 volumio kernel: Bluetooth: RFCOMM socket layer initialized Sep 16 22:17:25 volumio kernel: Bluetooth: RFCOMM ver 1.11 Sep 16 22:17:25 volumio volumiobt[2588]: Agent registered Sep 16 22:17:25 volumio volumiobt[2588]: [bluetooth]# -e power on Sep 16 22:17:25 volumio volumiobt[2588]: Invalid command in menu main: -e Sep 16 22:17:25 volumio volumiobt[2588]: Sep 16 22:17:25 volumio volumiobt[2588]: Use "help" for a list of available commands in a menu. Sep 16 22:17:25 volumio volumiobt[2588]: Use "menu " if you want to enter any submenu. Sep 16 22:17:25 volumio volumiobt[2588]: Use "back" if you want to return to menu main. Sep 16 22:17:25 volumio volumiobt[2588]: [bluetooth]# agent on Sep 16 22:17:25 volumio volumiobt[2588]: Agent is already registered Sep 16 22:17:25 volumio volumiobt[2588]: [bluetooth]# discoverable on Sep 16 22:17:25 volumio volumiobt[2588]: [bluetooth]# pairable on Sep 16 22:17:25 volumio volumiobt[2588]: [bluetooth]# agent NoInputNoOutput Sep 16 22:17:25 volumio volumiobt[2588]: Agent is already registered Sep 16 22:17:25 volumio volumiobt[2588]: [bluetooth]# default-agent Sep 16 22:17:25 volumio volumiobt[2588]: [bluetooth]# quit Sep 16 22:17:25 volumio volumiobt[2588]: [59B blob data] Sep 16 22:17:26 volumio volumio[1189]: info: MRS: Getting audio outputs on start Sep 16 22:17:26 volumio volumio[1189]: info: MRS: Requesting all other devices output Sep 16 22:17:27 volumio volumio[1189]: info: TidalConnect service started! Sep 16 22:17:28 volumio volumio[1189]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:28 volumio volumio[1189]: info: Received Get System Info Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:17:28 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:28 volumio volumio[1189]: info: Received Get System Info Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:17:28 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::volumioGetVisibleSources Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 16 22:17:28 volumio volumio[1189]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 16 22:17:28 volumio volumio[1189]: info: Received Get System Info Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:17:28 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:28 volumio volumio[1189]: info: Listing playlists Sep 16 22:17:28 volumio volumio[1189]: info: Received Get System Info Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:17:28 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:28 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:28 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 16 22:17:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 16 22:17:30 volumio volumio[1189]: info: Received Get System Info Sep 16 22:17:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:17:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:17:30 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:17:30 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:30 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:30 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:17:33 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 16 22:17:38 volumio volumio[1189]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/90.0.4430.212 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:38 volumio volumio[1189]: info: Received Get System Info Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:17:38 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:38 volumio volumio[1189]: info: Received Get System Info Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:17:38 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::volumioGetVisibleSources Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 16 22:17:38 volumio volumio[1189]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Sep 16 22:17:38 volumio volumio[1189]: info: Received Get System Info Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:17:38 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:38 volumio volumio[1189]: info: Listing playlists Sep 16 22:17:38 volumio volumio[1189]: info: Received Get System Info Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:17:38 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:38 volumio volumio[1189]: info: Received Get System Info Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 16 22:17:38 volumio volumio[1189]: info: Discovery: Getting this device information Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:38 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:38 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 16 22:17:39 volumio volumio[1189]: info: CoreCommandRouter::volumioGetState Sep 16 22:17:39 volumio volumio[1189]: info: CorePlayQueue::getTrack 0 Sep 16 22:17:42 volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 22:17:42 volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 22:17:42 volumio volumio[1189]: info: Retrieving Cloud Streaming UI Sep 16 22:17:42 volumio volumio[1189]: info: Getting Tidal Cloud Configuration Sep 16 22:17:42 volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 22:17:42 volumio volumio[1189]: info: Getting Qobuz Cloud Configuration Sep 16 22:17:42 volumio volumio[1189]: info: Asking plugin for UI Config Sep 16 22:17:42 volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 22:17:42 volumio volumio[1189]: info: Getting Spotify Cloud Configuration Sep 16 22:17:42 volumio volumio[1189]: info: Asking plugin for UI Config Sep 16 22:17:42 volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 22:17:42 volumio volumio[1189]: info: Saving Spotify Acccount Sep 16 22:17:42 volumio volumio[1189]: info: Got it Sep 16 22:17:42 volumio volumio[1189]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Sep 16 22:17:42 volumio volumio[1189]: info: Got it Sep 16 22:17:42 volumio volumio[1189]: info: Got Tidal Cloud Configuration Sep 16 22:17:42 volumio volumio[1189]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 16 22:17:42 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 16 22:17:42 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Sep 16 22:17:42 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 22:17:42 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 16 22:17:42 volumio volumio[1189]: info: CoreCommandRouter::volumioGetBrowseSources Sep 16 22:17:42 volumio volumio[1189]: info: CoreCommandRouter::volumioGetBrowseSources Sep 16 22:17:42 volumio volumio[1189]: info: CoreCommandRouter::volumioGetBrowseSources Sep 16 22:17:42 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 16 22:17:43 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Sep 16 22:17:46 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 16 22:17:48 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 16 22:17:50 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 16 22:17:53 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Sep 16 22:17:53 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Sep 16 22:17:54 volumio volumio[1189]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L MiLanDS723Plus.local. -m SMB3_11 Sep 16 22:17:54 volumio volumio[1189]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L MILAN918P.local. -m SMB3_11 Sep 16 22:17:54 volumio volumio[1189]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L tnbackup.local. -m SMB3_11 Sep 16 22:17:54 volumio volumio[1189]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L milantn1.local. -m SMB3_11 Sep 16 22:17:54 volumio volumio[1189]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L mlsrv.local. -m SMB3_11 Sep 16 22:17:54 volumio volumio[1189]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L B450iMate.local. -m SMB3_11 Sep 16 22:17:54 volumio volumio[1189]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L fritz.box. -m SMB3_11 Sep 16 22:17:54 volumio volumio[1189]: info: Executing SMB command: smbclient --no-pass --debuglevel=0 -L fritz.box. -m SMB3_11 Sep 16 22:17:54 volumio volumio[1189]: info: Error browsing device tnbackup for shares: Command failed: smbclient --no-pass --debuglevel=0 -L tnbackup.local. -m SMB3_11 Sep 16 22:17:54 volumio volumio[1189]: Unable to initialize messaging context Sep 16 22:17:54 volumio volumio[1189]: info: Error browsing device MiLanDS723Plus for shares: Command failed: smbclient --no-pass --debuglevel=0 -L MiLanDS723Plus.local. -m SMB3_11 Sep 16 22:17:54 volumio volumio[1189]: Unable to initialize messaging context Sep 16 22:17:54 volumio volumio[1189]: info: Error browsing device MILAN918P for shares: Command failed: smbclient --no-pass --debuglevel=0 -L MILAN918P.local. -m SMB3_11 Sep 16 22:17:54 volumio volumio[1189]: Unable to initialize messaging context Sep 16 22:17:54 volumio volumio[1189]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Sep 16 22:17:58 volumio volumio[1189]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 22:17:58 volumio volumio[1189]: Error: dns service error: unknown Sep 16 22:17:58 volumio volumio[1189]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Sep 16 22:17:58 volumio volumio[1189]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Sep 16 22:17:58 volumio volumio[1189]: errorCode: -65537 Sep 16 22:17:58 volumio volumio[1189]: } Sep 16 22:17:58 volumio volumio[1189]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 16 22:17:58 volumio sudo[2734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-16 22:16 Sep 16 22:17:58 volumio sudo[2734]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 11:08:46 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="9ef0bfa9b61509be20c9051562554f31"