-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Sat 2025-03-08 19:34:47 CET. -- Mar 08 19:33:00 volumio volumio[960]: info: Initializing connection to go-librespot Websocket Mar 08 19:33:00 volumio go-librespot[1319]: time="2025-03-08T19:33:00+01:00" level=debug msg="new websocket client" Mar 08 19:33:00 volumio volumio[960]: info: Connection to go-librespot Websocket established Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Mar 08 19:33:02 volumio volumio[960]: info: Adding plugin bluetooth to MyMusic Plugins Mar 08 19:33:02 volumio volumio[960]: info: Adding plugin multiroom to MyMusic Plugins Mar 08 19:33:02 volumio volumio[960]: info: Adding plugin metavolumio to MyMusic Plugins Mar 08 19:33:02 volumio volumio[960]: info: Adding plugin cd_controller to MyMusic Plugins Mar 08 19:33:02 volumio volumio[960]: info: Adding plugin smart_inputs to MyMusic Plugins Mar 08 19:33:02 volumio volumio[960]: info: Adding plugin tidalconnect to MyMusic Plugins Mar 08 19:33:02 volumio volumio[960]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Mar 08 19:33:05 volumio volumio[960]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Mar 08 19:33:05 volumio volumio[960]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Mar 08 19:33:05 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:33:05 volumio volumio[960]: info: Starting MyVolumio Remote Streaming Endpoints Mar 08 19:33:05 volumio volumio[960]: info: MyVolumio login type: Token Mar 08 19:33:05 volumio volumio[960]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Mar 08 19:33:05 volumio volumio[960]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Mar 08 19:33:08 volumio volumio[960]: info: Starting Streaming Service Transparent Proxy Mar 08 19:33:08 volumio volumio[960]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Mar 08 19:33:08 volumio volumio[960]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Mar 08 19:33:08 volumio volumio[960]: info: Streaming services startup Mar 08 19:33:08 volumio volumio[960]: info: Starting Streaming Daemon Mar 08 19:33:09 volumio volumio[960]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Mar 08 19:33:09 volumio sudo[1574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 08 19:33:09 volumio volumio[960]: info: Getting Spotify volume Mar 08 19:33:09 volumio sudo[1574]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:33:09 volumio sudo[1574]: pam_unix(sudo:session): session closed for user root Mar 08 19:33:09 volumio volumio[960]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 08 19:33:09 volumio volumio[960]: error: Cannot start Volumio Streaming Daemon Mar 08 19:33:09 volumio volumio[960]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 08 19:33:09 volumio volumio[960]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 08 19:33:09 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:09 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:09 volumio volumio[960]: SPOTIFY: RECEIVED VOLUMIO VOLUME 96 Mar 08 19:33:09 volumio volumio[960]: SPOTIFY: SPOTIFY VOLUME undefined Mar 08 19:33:09 volumio volumio[960]: SPOTIFY: VOLUMIO VOLUME 96 Mar 08 19:33:09 volumio volumio[960]: info: Aligning Spotify Volume to Volumio Volume Mar 08 19:33:09 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:09 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:09 volumio volumio[960]: info: Setting Spotify Volume from Volumio: 96 Mar 08 19:33:10 volumio sudo[1601]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 19:33:10 volumio sudo[1601]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:33:10 volumio sudo[1599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 08 19:33:10 volumio sudo[1599]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:33:10 volumio sudo[1599]: pam_unix(sudo:session): session closed for user root Mar 08 19:33:10 volumio sudo[1601]: pam_unix(sudo:session): session closed for user root Mar 08 19:33:10 volumio volumio[960]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Mar 08 19:33:10 volumio volumio[960]: verbose: New Socket.io Connection to 192.168.100.27 from 192.168.100.86 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Mar 08 19:33:10 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 19:33:10 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 08 19:33:10 volumio ntpd[741]: Soliciting pool server 193.87.160.18 Mar 08 19:33:10 volumio sudo[1614]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 08 19:33:10 volumio sudo[1614]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:33:11 volumio sudo[1614]: pam_unix(sudo:session): session closed for user root Mar 08 19:33:11 volumio sudo[1616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 19:33:11 volumio sudo[1616]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:33:11 volumio sudo[1616]: pam_unix(sudo:session): session closed for user root Mar 08 19:33:11 volumio volumio[960]: verbose: New Socket.io Connection to 192.168.100.27 from 192.168.100.86 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_4 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 9 Mar 08 19:33:11 volumio volumio[960]: SPOTIFY: SETTING SPOTIFY VOLUME 96 Mar 08 19:33:11 volumio volumio[960]: info: Sending Spotify command with payload to local API: /player/volume Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:11 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 19:33:11 volumio volumio[960]: info: Received Get System Info Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:33:11 volumio volumio[960]: info: Discovery: Getting this device information Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:11 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:11 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:11 volumio volumio[960]: info: Listing playlists Mar 08 19:33:11 volumio volumio[960]: info: MyVolumio token set successfully Mar 08 19:33:11 volumio volumio[960]: info: MYVOLUMIO: Adding device Mar 08 19:33:11 volumio volumio[960]: info: MYVOLUMIO: Evaluating Server Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 08 19:33:11 volumio ntpd[741]: Soliciting pool server 185.242.56.5 Mar 08 19:33:11 volumio volumio[960]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 19:33:11 volumio ntpd[741]: Soliciting pool server 185.242.56.3 Mar 08 19:33:12 volumio ntpd[741]: Soliciting pool server 92.240.244.202 Mar 08 19:33:12 volumio ntpd[741]: Soliciting pool server 87.197.97.150 Mar 08 19:33:12 volumio volumio[960]: STREAMING PROXY: Starting server on port 3245 Mar 08 19:33:12 volumio volumio[960]: Node JS runtime: 14 Mar 08 19:33:12 volumio ntpd[741]: Soliciting pool server 84.245.104.80 Mar 08 19:33:12 volumio ntpd[741]: Soliciting pool server 212.55.254.189 Mar 08 19:33:12 volumio volumio[960]: info: MyVolumio status changed Mar 08 19:33:12 volumio volumio[960]: info: Streaming services startup Mar 08 19:33:12 volumio volumio[960]: info: Starting Streaming Daemon Mar 08 19:33:12 volumio volumio[960]: info: Removing browser output: myVolumio user plan is not superstar Mar 08 19:33:12 volumio volumio[960]: info: Removing audio output: Mar 08 19:33:12 volumio volumio[960]: info: Stoppping Tunnel 1 Mar 08 19:33:12 volumio sudo[1675]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Mar 08 19:33:12 volumio sudo[1675]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:33:12 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 19:33:12 volumio volumio[960]: info: Received Get System Info Mar 08 19:33:12 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:33:12 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:33:12 volumio volumio[960]: info: Discovery: Getting this device information Mar 08 19:33:13 volumio sudo[1675]: pam_unix(sudo:session): session closed for user root Mar 08 19:33:13 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:13 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:13 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:33:13 volumio sudo[1678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Mar 08 19:33:13 volumio sudo[1678]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 19:33:13 volumio volumio[960]: error: Cannot start Volumio Streaming Daemon Mar 08 19:33:13 volumio volumio[960]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Mar 08 19:33:13 volumio volumio[960]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Mar 08 19:33:13 volumio sudo[1678]: pam_unix(sudo:session): session closed for user root Mar 08 19:33:13 volumio volumio[960]: info: FusionDsp - no preset used Mar 08 19:33:13 volumio ntpd[741]: Soliciting pool server 62.168.94.161 Mar 08 19:33:13 volumio ntpd[741]: Soliciting pool server 95.105.193.228 Mar 08 19:33:13 volumio volumio[960]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Loudnes.json Mar 08 19:33:13 volumio volumio[960]: info: Setting Geolocation for MyVolumio to eu6 Mar 08 19:33:13 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:33:13 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:33:13 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:33:13 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 08 19:33:13 volumio volumio[960]: info: Remote SSH Stopped Mar 08 19:33:14 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 19:33:14 volumio volumio[960]: info: Received Get System Info Mar 08 19:33:14 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:33:14 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:33:14 volumio volumio[960]: info: Discovery: Getting this device information Mar 08 19:33:14 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:14 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:14 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:33:14 volumio ntpd[741]: Soliciting pool server 91.237.170.89 Mar 08 19:33:14 volumio ntpd[741]: Soliciting pool server 2a02:25b0:aaaa:abcd::421:123 Mar 08 19:33:14 volumio volumio[960]: info: Updating MyVolumio device info Mar 08 19:33:14 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:33:14 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:33:15 volumio volumio[960]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 08 19:33:15 volumio volumio[960]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 08 19:33:19 volumio ntpd[741]: receive: Unexpected origin timestamp 0xeb770bed.aad4ba13 does not match aorg 0000000000.00000000 from server@84.245.104.80 xmt 0xeb770bef.2728683a Mar 08 19:33:19 volumio ntpd[741]: receive: Unexpected origin timestamp 0xeb770bed.aac8a8fc does not match aorg 0000000000.00000000 from server@212.55.254.189 xmt 0xeb770bef.27478e5d Mar 08 19:33:19 volumio ntpd[741]: receive: Unexpected origin timestamp 0xeb770bed.aaaf061b does not match aorg 0000000000.00000000 from server@95.105.193.228 xmt 0xeb770bef.26d46adf Mar 08 19:33:20 volumio volumio[960]: info: MYVOLUMIO: Adding device Mar 08 19:33:20 volumio volumio[960]: info: MYVOLUMIO: Evaluating Server Mar 08 19:33:20 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 19:33:20 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:33:20 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 19:33:21 volumio volumio-remote-updater[537]: No test mode Mar 08 19:33:21 volumio volumio-remote-updater[537]: No alpha test mode Mar 08 19:33:21 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 08 19:33:21 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 19:33:21 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 08 19:33:21 volumio volumio[960]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 19:33:21 volumio volumio[960]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 08 19:33:21 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 08 19:33:21 volumio volumio[960]: info: FusionDsp - no preset used Mar 08 19:33:22 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 19:33:22 volumio volumio[960]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Loudnes.json Mar 08 19:33:22 volumio volumio[960]: info: Setting Geolocation for MyVolumio to eu6 Mar 08 19:33:22 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:33:22 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:33:22 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:33:22 volumio volumio[960]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Mar 08 19:33:22 volumio volumio[960]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Mar 08 19:33:22 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:22 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:22 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 19:33:22 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:33:22 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:33:22 volumio volumio[960]: info: Discovery: Getting this device information Mar 08 19:33:22 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:22 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:22 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:33:22 volumio volumio[960]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} Mar 08 19:33:23 volumio volumio[960]: info: Updating MyVolumio device info Mar 08 19:33:23 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:33:23 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:33:23 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:33:23 volumio volumio[960]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} Mar 08 19:33:23 volumio volumio[960]: error: Failed to check for new versions for plugin YouTube Music: Error: Invalid argument not valid semver ('' received) Mar 08 19:33:24 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 19:33:24 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 08 19:33:26 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:33:26 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:33:26 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 08 19:33:26 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 08 19:33:26 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 08 19:33:26 volumio volumio[960]: info: CoreCommandRouter::volumioGetBrowseSources Mar 08 19:33:26 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 19:33:27 volumio volumio[960]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS armv7l 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Mar 08 19:33:28 volumio volumio[960]: info: [ASDebug] Port: 24 Mar 08 19:33:28 volumio volumio[960]: info: [ASDebug] Inverted: false Mar 08 19:33:28 volumio volumio[960]: info: [ASDebug] Delay: 720 Mar 08 19:33:28 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 08 19:33:28 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::volumioGetVisibleSources Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:30 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Mar 08 19:33:30 volumio volumio[960]: info: Received Get System Info Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:33:30 volumio volumio[960]: info: Discovery: Getting this device information Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:30 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:30 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:30 volumio volumio[960]: info: Listing playlists Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 19:33:30 volumio volumio[960]: info: Received Get System Info Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:33:30 volumio volumio[960]: info: Discovery: Getting this device information Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:30 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 19:33:30 volumio volumio[960]: error: MyVolumio Plugin failed to authenticate in a timely fashion Mar 08 19:33:30 volumio volumio[960]: info: Completed starting MyVolumio Plugin Mar 08 19:33:30 volumio volumio[960]: [Metrics] CommandRouter: 58s 591.43ms Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::volumiosetStartupVolume Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::Close All Modals sent Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::Close All Modals sent Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 19:33:30 volumio volumio[960]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 08 19:33:30 volumio volumio[960]: info: Received Get System Version Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 19:33:30 volumio volumio[960]: info: Received Get System Info Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:33:30 volumio volumio[960]: info: Discovery: Getting this device information Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:30 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:30 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:33:30 volumio volumio[960]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 08 19:33:30 volumio volumio[960]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 08 19:33:30 volumio volumio[960]: info: camilladsp stopping service pid 1230... Mar 08 19:33:30 volumio volumio[960]: info: camilladsp service terminated, instance 1 Mar 08 19:33:30 volumio volumio[960]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 08 19:33:30 volumio volumio[960]: info: FusionDsp - Loudness is ON true Mar 08 19:33:30 volumio volumio[960]: info: camilladsp service started and running in background, instance 1 Mar 08 19:33:31 volumio volumio[960]: info: camilladsp spawned new process with pid 1911, instance 1, run: true Mar 08 19:33:31 volumio volumio[960]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 19:33:31 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 08 19:33:31 volumio volumio[960]: info: FusionDsp - no preset used Mar 08 19:33:32 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 08 19:33:32 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 08 19:33:32 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 08 19:33:32 volumio volumio[960]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json,Loudnes.json Mar 08 19:33:32 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Mar 08 19:33:35 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 19:33:35 volumio volumio[960]: info: Received Get System Info Mar 08 19:33:35 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:33:35 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:33:35 volumio volumio[960]: info: Discovery: Getting this device information Mar 08 19:33:35 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:35 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:35 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:33:36 volumio volumio[960]: info: camilladsp spawned new process with pid 1934, instance 1, run: true Mar 08 19:33:37 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Mar 08 19:33:37 volumio volumio[960]: info: BOOT COMPLETED Mar 08 19:33:39 volumio volumio[960]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 19:33:44 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 08 19:33:44 volumio volumio[960]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Mar 08 19:33:44 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Mar 08 19:33:44 volumio volumio[960]: info: Received Get System Version Mar 08 19:33:44 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 08 19:33:44 volumio volumio[960]: info: Received Get System Info Mar 08 19:33:44 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:33:44 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:33:44 volumio volumio[960]: info: Discovery: Getting this device information Mar 08 19:33:44 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:44 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:44 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:33:44 volumio volumio[1140]: ...................................................................................................................................................................++++ Mar 08 19:33:44 volumio volumio[1140]: e is 65537 (0x010001) Mar 08 19:33:44 volumio volumio[1140]: writing RSA key Mar 08 19:33:45 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 19:33:45 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 08 19:33:46 volumio volumio[960]: error: Failed to check for new versions for plugin YouTube Music: Error: Invalid argument not valid semver ('' received) Mar 08 19:33:52 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:33:52 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:33:52 volumio volumio[960]: info: Discovery: Getting this device information Mar 08 19:33:52 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:33:52 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:33:52 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:34:10 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Mar 08 19:34:10 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Mar 08 19:34:11 volumio volumio[960]: error: Failed to check for new versions for plugin YouTube Music: Error: Invalid argument not valid semver ('' received) Mar 08 19:34:22 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 19:34:22 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 19:34:22 volumio volumio[960]: info: Discovery: Getting this device information Mar 08 19:34:22 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:34:22 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:34:22 volumio volumio[960]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 19:34:34 volumio volumio[960]: info: Enabling plugin ampswitch Mar 08 19:34:34 volumio volumio[960]: info: Loading plugin "ampswitch"... Mar 08 19:34:34 volumio volumio[960]: info: Applying required configuration parameters for plugin ampswitch Mar 08 19:34:34 volumio volumio[960]: info: PLUGIN START: ampswitch Mar 08 19:34:34 volumio volumio[960]: info: Done. Mar 08 19:34:34 volumio volumio[960]: info: CoreCommandRouter::volumioGetState Mar 08 19:34:34 volumio volumio[960]: info: CorePlayQueue::getTrack 0 Mar 08 19:34:34 volumio volumio[960]: info: [ASDebug] CurState: stop PrevState: stop Mar 08 19:34:34 volumio volumio[960]: info: [ASDebug] CurState: stop PrevState: stop Mar 08 19:34:34 volumio volumio[960]: info: [ASDebug] CurState: stop PrevState: stop Mar 08 19:34:37 volumio volumio[960]: info: Starting Uninstall of plugin system_controller - ampswitch Mar 08 19:34:37 volumio volumio[960]: info: Uninstalling plugin ampswitch Mar 08 19:34:37 volumio volumio[960]: info: [ASDebug] Port: 24 Mar 08 19:34:37 volumio volumio[960]: info: [ASDebug] Inverted: false Mar 08 19:34:37 volumio volumio[960]: info: [ASDebug] Delay: 720 Mar 08 19:34:45 volumio volumio[960]: info: Starting Uninstall of plugin system_controller - ampswitch Mar 08 19:34:45 volumio volumio[960]: info: Uninstalling plugin ampswitch Mar 08 19:34:45 volumio volumio[960]: info: [ASDebug] Port: 24 Mar 08 19:34:45 volumio volumio[960]: info: [ASDebug] Inverted: false Mar 08 19:34:45 volumio volumio[960]: info: [ASDebug] Delay: 720 Mar 08 19:34:45 volumio volumio[960]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 19:34:45 volumio volumio[960]: Error: EBADF: bad file descriptor, close Mar 08 19:34:45 volumio volumio[960]: at Object.closeSync (fs.js:439:3) Mar 08 19:34:45 volumio volumio[960]: at Object.closeSync (/volumio/node_modules/graceful-fs/graceful-fs.js:74:20) Mar 08 19:34:45 volumio volumio[960]: at Object.closeSync (/data/plugins/system_controller/ampswitch/node_modules/graceful-fs/graceful-fs.js:51:27) Mar 08 19:34:45 volumio volumio[960]: at Gpio.unexport (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:307:8) Mar 08 19:34:45 volumio volumio[960]: at AmpSwitchController.freeGPIO (/data/plugins/system_controller/ampswitch/index.js:242:19) Mar 08 19:34:45 volumio volumio[960]: at AmpSwitchController.onStop (/data/plugins/system_controller/ampswitch/index.js:90:10) Mar 08 19:34:45 volumio volumio[960]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Mar 08 19:34:45 volumio volumio[960]: at PluginManager.unInstallPlugin (/volumio/app/pluginmanager.js:1449:10) Mar 08 19:34:45 volumio volumio[960]: at CoreCommandRouter.unInstallPlugin (/volumio/app/index.js:1484:22) Mar 08 19:34:45 volumio volumio[960]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1260:45) Mar 08 19:34:45 volumio volumio[960]: at Socket.emit (events.js:315:20) Mar 08 19:34:45 volumio volumio[960]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 08 19:34:45 volumio volumio[960]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Mar 08 19:34:45 volumio volumio[960]: errno: -9, Mar 08 19:34:45 volumio volumio[960]: syscall: 'close', Mar 08 19:34:45 volumio volumio[960]: code: 'EBADF' Mar 08 19:34:45 volumio volumio[960]: } Mar 08 19:34:45 volumio volumio[960]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 19:34:47 volumio sudo[2142]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-08 19:33 Mar 08 19:34:47 volumio sudo[2142]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"