-- Logs begin at Thu 2019-02-14 10:11:59 UTC, end at Wed 2024-05-15 14:22:26 UTC. -- May 15 14:21:40 volumio ntpd[663]: receive: Unexpected origin timestamp 0xe77651bd.59b3f7b3 does not match aorg 0000000000.00000000 from server@131.111.8.60 xmt 0xe9ef4374.6042e1f4 May 15 14:21:40 volumio ntpd[663]: receive: Unexpected origin timestamp 0xe77651bd.59b0d70c does not match aorg 0000000000.00000000 from server@176.126.241.154 xmt 0xe9ef4374.609be989 May 15 14:21:40 volumio systemd[1]: Starting Daily apt download activities... May 15 14:21:40 volumio ntpd[663]: receive: Unexpected origin timestamp 0xe77651bd.59af6121 does not match aorg 0000000000.00000000 from server@77.68.81.77 xmt 0xe9ef4374.6180b710 May 15 14:21:40 volumio ntpd[663]: receive: Unexpected origin timestamp 0xe77651bd.59aa702e does not match aorg 0000000000.00000000 from server@85.199.214.99 xmt 0xe9ef4374.62158b0d May 15 14:21:41 volumio systemd[1]: apt-daily.service: Succeeded. May 15 14:21:41 volumio systemd[1]: Started Daily apt download activities. May 15 14:21:41 volumio systemd[1]: Starting Daily apt upgrade and clean activities... May 15 14:21:41 volumio ntpd[663]: Soliciting pool server 162.159.200.1 May 15 14:21:41 volumio ntpd[663]: Soliciting pool server 213.5.132.231 May 15 14:21:41 volumio systemd[1]: apt-daily-upgrade.service: Succeeded. May 15 14:21:41 volumio systemd[1]: Started Daily apt upgrade and clean activities. May 15 14:21:42 volumio volumio[924]: info: Discovery: A device disappeared from network May 15 14:21:42 volumio volumio[924]: info: Discovery: Device volumio disappeared from network May 15 14:21:42 volumio volumio[924]: info: Discovery: adding d6320542-3449-42c9-90d5-a809a221577e May 15 14:21:42 volumio volumio[924]: info: Discovery: Found device Volumio May 15 14:21:42 volumio volumio[924]: info: CoreCommandRouter::volumioGetState May 15 14:21:42 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 15 14:21:43 volumio volumio[924]: info: Adding plugin bluetooth to MyMusic Plugins May 15 14:21:43 volumio volumio[924]: info: Adding plugin multiroom to MyMusic Plugins May 15 14:21:43 volumio volumio[924]: info: Adding plugin metavolumio to MyMusic Plugins May 15 14:21:43 volumio volumio[924]: info: Adding plugin cd_controller to MyMusic Plugins May 15 14:21:43 volumio volumio[924]: info: Adding plugin smart_inputs to MyMusic Plugins May 15 14:21:43 volumio volumio[924]: info: Adding plugin tidalconnect to MyMusic Plugins May 15 14:21:43 volumio volumio[924]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 15 14:21:43 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan May 15 14:21:44 volumio volumio[924]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 15 14:21:44 volumio volumio[924]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 15 14:21:44 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:44 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:44 volumio volumio[924]: info: Starting MyVolumio Remote Streaming Endpoints May 15 14:21:44 volumio volumio[924]: info: MyVolumio login type: Token May 15 14:21:44 volumio volumio[924]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 15 14:21:44 volumio volumio[924]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 15 14:21:45 volumio volumio[924]: info: Starting Streaming Service Transparent Proxy May 15 14:21:45 volumio volumio[924]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 15 14:21:45 volumio volumio[924]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 15 14:21:45 volumio volumio[924]: info: Streaming services startup May 15 14:21:45 volumio volumio[924]: info: Starting Streaming Daemon May 15 14:21:45 volumio sudo[1258]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 15 14:21:45 volumio sudo[1258]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:45 volumio systemd[1]: Stopping Volumio Streaming Daemon... May 15 14:21:45 volumio systemd[1]: volumio-streaming-daemon.service: Main process exited, code=killed, status=15/TERM May 15 14:21:45 volumio systemd[1]: volumio-streaming-daemon.service: Succeeded. May 15 14:21:45 volumio volumio[924]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 15 14:21:45 volumio systemd[1]: Stopped Volumio Streaming Daemon. May 15 14:21:45 volumio systemd[1]: Started Volumio Streaming Daemon. May 15 14:21:45 volumio sudo[1258]: pam_unix(sudo:session): session closed for user root May 15 14:21:45 volumio volumio-streaming-daemon[1265]: ############################ May 15 14:21:45 volumio volumio-streaming-daemon[1265]: # Volumio Streaming Daemon # May 15 14:21:45 volumio volumio-streaming-daemon[1265]: # Running on port 7777 # May 15 14:21:45 volumio volumio-streaming-daemon[1265]: ############################ May 15 14:21:45 volumio volumio-streaming-daemon[1265]: Environment: production May 15 14:21:45 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 14:21:45 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 14:21:45 volumio volumio[924]: info: Discovery: Getting this device information May 15 14:21:45 volumio volumio[924]: info: CoreCommandRouter::volumioGetState May 15 14:21:45 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:21:45 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 14:21:45 volumio volumio[924]: error: MyVolumio Custom Token format not valid, refreshing it May 15 14:21:45 volumio volumio[924]: STREAMING PROXY: Starting server on port 3245 May 15 14:21:45 volumio volumio[924]: info: MyVolumio login type: Token May 15 14:21:46 volumio volumio[924]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 15 14:21:46 volumio volumio[924]: info: MYVOLUMIO: Adding device May 15 14:21:46 volumio volumio[924]: info: MYVOLUMIO: Evaluating Server May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::getUIConfigOnPlugin May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus May 15 14:21:46 volumio volumio[924]: info: Getting Alsa Cards List without I2S DAC May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode May 15 14:21:46 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus May 15 14:21:47 volumio volumio[924]: info: MyVolumio Plan changed: premium May 15 14:21:47 volumio volumio[924]: info: [MyVolumio PluginManager] Subscribed plan changed to premium May 15 14:21:47 volumio volumio[924]: info: Removing browser output: myVolumio user plan is not superstar May 15 14:21:47 volumio volumio[924]: info: Removing audio output: May 15 14:21:47 volumio volumio[924]: info: MYVOLUMIO: Adding device May 15 14:21:47 volumio volumio[924]: info: MYVOLUMIO: Evaluating Server May 15 14:21:47 volumio volumio[924]: info: Remote config written successfully May 15 14:21:47 volumio volumio[924]: info: Starting Tunnel 1 May 15 14:21:47 volumio volumio[924]: info: Starting Tunnel Connection Checker May 15 14:21:47 volumio volumio[924]: info: MYVolumio Device enabled May 15 14:21:47 volumio volumio[924]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... May 15 14:21:47 volumio volumio[924]: info: MyVolumio status changed May 15 14:21:47 volumio volumio[924]: info: Streaming services startup May 15 14:21:47 volumio volumio[924]: info: Re-Starting Streaming Daemon May 15 14:21:47 volumio volumio-streaming-daemon[1265]: Restarting daemon May 15 14:21:47 volumio volumio-streaming-daemon[1265]: Environment: production May 15 14:21:47 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 15 14:21:47 volumio volumio[924]: error: [MyVolumio PluginManager] Cache data is invalid! May 15 14:21:47 volumio volumio[924]: info: Setting Geolocation for MyVolumio to eu3 May 15 14:21:47 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:47 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:47 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:47 volumio volumio-streaming-daemon[1265]: Environment: production May 15 14:21:48 volumio volumio-streaming-daemon[1265]: Enabled services: May 15 14:21:48 volumio volumio-streaming-daemon[1265]: 1) tidal May 15 14:21:48 volumio volumio-streaming-daemon[1265]: 2) qobuz May 15 14:21:48 volumio volumio[924]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... May 15 14:21:48 volumio volumio[924]: info: [1715782908250] Starting BluetoothController May 15 14:21:48 volumio volumio[924]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom May 15 14:21:49 volumio sudo[1316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod +x /myvolumio/plugins/audio_interface/multiroom/scripts/check_snapcast May 15 14:21:49 volumio sudo[1316]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:49 volumio sudo[1316]: pam_unix(sudo:session): session closed for user root May 15 14:21:49 volumio sudo[1318]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom May 15 14:21:49 volumio sudo[1318]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:49 volumio sudo[1318]: pam_unix(sudo:session): session closed for user root May 15 14:21:49 volumio volumio[924]: info: MRS: MultiRoom plugin initialized May 15 14:21:49 volumio volumio[924]: info: MRS: STOPPING SNAPCLIENT May 15 14:21:49 volumio volumio[924]: info: MRS: Snap server stop May 15 14:21:49 volumio sudo[1336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient May 15 14:21:49 volumio sudo[1336]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:49 volumio volumio[924]: info: MRS: STOPPING volumioStreaming May 15 14:21:49 volumio sudo[1339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver May 15 14:21:49 volumio sudo[1339]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:49 volumio sudo[1341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming May 15 14:21:49 volumio sudo[1341]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... May 15 14:21:49 volumio sudo[1336]: pam_unix(sudo:session): session closed for user root May 15 14:21:49 volumio sudo[1345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* May 15 14:21:49 volumio sudo[1345]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:49 volumio sudo[1345]: pam_unix(sudo:session): session closed for user root May 15 14:21:49 volumio sudo[1339]: pam_unix(sudo:session): session closed for user root May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... May 15 14:21:49 volumio sudo[1341]: pam_unix(sudo:session): session closed for user root May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Loading plugin "tidal"... May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... May 15 14:21:49 volumio volumio[924]: info: Preparing to generate the ALSA configuration file May 15 14:21:49 volumio volumio[924]: info: Updating MyVolumio device info May 15 14:21:49 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:49 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:49 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:49 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid May 15 14:21:49 volumio volumio[924]: info: Setting Geolocation for MyVolumio to eu3 May 15 14:21:49 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:49 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:49 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:49 volumio volumio[924]: info: Successfully Added MyVolumio device May 15 14:21:49 volumio volumio[924]: info: MRS: Removed streaming files May 15 14:21:49 volumio volumio[924]: info: MRS: volumioStreaming STOPPED May 15 14:21:49 volumio volumio[924]: info: MRS: SNAPSERVER STOPPED May 15 14:21:49 volumio volumio[924]: info: MRS: SNAPCLIENT STOPPED May 15 14:21:49 volumio volumio[924]: info: Snap server and client installed May 15 14:21:49 volumio volumio[924]: info: Asound.conf file unchanged, so no further update is needed May 15 14:21:49 volumio volumio[924]: info: Output device has changed, restarting MPD May 15 14:21:49 volumio sudo[1348]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 15 14:21:49 volumio sudo[1348]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:49 volumio volumio[924]: info: Output device has changed, restarting Shairport Sync May 15 14:21:49 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:21:49 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:49 volumio sudo[1348]: pam_unix(sudo:session): session closed for user root May 15 14:21:49 volumio sudo[1351]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 15 14:21:49 volumio sudo[1351]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:49 volumio systemd[1]: Stopping Music Player Daemon... May 15 14:21:49 volumio volumio[924]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 15 14:21:49 volumio volumio[924]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration May 15 14:21:49 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:21:49 volumio systemd[1]: mpd.service: Succeeded. May 15 14:21:49 volumio systemd[1]: Stopped Music Player Daemon. May 15 14:21:49 volumio systemd[1]: Starting Music Player Daemon... May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth May 15 14:21:49 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio May 15 14:21:49 volumio volumio[924]: info: Adding METAVOLUMIO REST API Endpoints May 15 14:21:49 volumio volumio[924]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui May 15 14:21:49 volumio volumio[924]: info: Adding Manifest REST API Endpoints May 15 14:21:49 volumio volumio[924]: info: Adding manifestui REST Endpoint for plugin: miscellanea/manifestui May 15 14:21:49 volumio volumio[924]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller May 15 14:21:49 volumio volumio[924]: info: Preparing CD Folders May 15 14:21:49 volumio volumio[924]: info: Adding CD REST API Endpoints May 15 14:21:49 volumio volumio[924]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller May 15 14:21:49 volumio volumio[924]: info: Starting UDEV Watcher for CD May 15 14:21:49 volumio sudo[1360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio May 15 14:21:49 volumio sudo[1360]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:49 volumio volumio[924]: info: Detecting CD presence with UDEV May 15 14:21:49 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices May 15 14:21:49 volumio sudo[1360]: pam_unix(sudo:session): session closed for user root May 15 14:21:50 volumio volumio[924]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs May 15 14:21:50 volumio volumio[924]: info: Adding inputs REST Endpoints May 15 14:21:50 volumio volumio[924]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs May 15 14:21:50 volumio volumio[924]: info: Scanning Audio Inputs May 15 14:21:50 volumio volumio[924]: info: Checking against Known Cards name May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 15 14:21:50 volumio volumio[924]: info: [1715782910287] CoreMusicLibrary::Adding element HiFiBerry ADC May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 14:21:50 volumio volumio[924]: Cannot find translation for sourceMedia Servers May 15 14:21:50 volumio volumio[924]: Cannot find translation for sourceHiFiBerry ADC May 15 14:21:50 volumio volumio[924]: info: Checking against Known Cards name May 15 14:21:50 volumio volumio[924]: info: Adding Server instance for streaming May 15 14:21:50 volumio volumio[924]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio May 15 14:21:50 volumio volumio[924]: error: Hi Res Audio Failed Login: Missing Login Data May 15 14:21:50 volumio volumio[924]: info: Adding HIGHRESAUDIO REST API Endpoints May 15 14:21:50 volumio volumio[924]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio May 15 14:21:50 volumio volumio[924]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal May 15 14:21:50 volumio volumio[924]: info: Refreshing TIDAL token May 15 14:21:50 volumio volumio[924]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz May 15 14:21:50 volumio volumio[924]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect May 15 14:21:50 volumio volumio[924]: info: Stopping AccessToken refresher cron for QOBUZ May 15 14:21:50 volumio sudo[1367]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service May 15 14:21:50 volumio sudo[1367]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:50 volumio volumio[924]: info: AccessToken refresher cron started for QOBUZ May 15 14:21:50 volumio volumio[924]: info: Adding QOBUZ REST API Endpoints May 15 14:21:50 volumio volumio[924]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz May 15 14:21:50 volumio sudo[1367]: pam_unix(sudo:session): session closed for user root May 15 14:21:50 volumio volumio[924]: info: Fetching Streaming Services browse cache May 15 14:21:50 volumio volumio[924]: info: Bluetooth name changed to Volumio May 15 14:21:50 volumio volumio[924]: info: MPD Permissions set May 15 14:21:50 volumio volumio-streaming-daemon[1265]: Environment: production May 15 14:21:50 volumio volumio-streaming-daemon[1265]: Environment: production May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 15 14:21:50 volumio volumio[924]: info: Starting Shairport Sync May 15 14:21:50 volumio sudo[1376]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 15 14:21:50 volumio sudo[1376]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:50 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... May 15 14:21:50 volumio mpd[1363]: May 15 14:21 : exception: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 15 14:21:50 volumio mpd[1363]: May 15 14:21 : exception: Input plugin 'tidal' is unavailable: No Tidal application token configured May 15 14:21:50 volumio mpd[1363]: May 15 14:21 : exception: Input plugin 'qobuz' is unavailable: No Qobuz app_id configured May 15 14:21:50 volumio systemd[1]: shairport-sync.service: Succeeded. May 15 14:21:50 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. May 15 14:21:50 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. May 15 14:21:50 volumio systemd[1]: Started Music Player Daemon. May 15 14:21:50 volumio sudo[1351]: pam_unix(sudo:session): session closed for user root May 15 14:21:50 volumio sudo[1376]: pam_unix(sudo:session): session closed for user root May 15 14:21:50 volumio volumio[924]: info: Shairport-Sync Started May 15 14:21:50 volumio volumio[924]: error: updateQueue error: null May 15 14:21:50 volumio volumio[924]: info: Updating MyVolumio device info May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:50 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 15 14:21:50 volumio volumio[924]: error: No access token received for TIDAL May 15 14:21:50 volumio volumio[924]: info: Successfully Added MyVolumio device May 15 14:21:50 volumio volumio[924]: info: Successfully Updated MyVolumio device May 15 14:21:50 volumio volumio[924]: info: Fetching Streaming Services browse cache May 15 14:21:51 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan May 15 14:21:51 volumio volumio[924]: info: Successfully Updated MyVolumio device May 15 14:21:52 volumio sudo[1389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service May 15 14:21:52 volumio sudo[1389]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:52 volumio systemd[1]: Started MyVolumio SSH Tunnel. May 15 14:21:52 volumio sudo[1389]: pam_unix(sudo:session): session closed for user root May 15 14:21:52 volumio volumio[924]: info: Remote SSH Started May 15 14:21:52 volumio autossh[1392]: port set to 0, monitoring disabled May 15 14:21:52 volumio autossh[1392]: starting ssh (count 1) May 15 14:21:52 volumio autossh[1392]: ssh child pid is 1395 May 15 14:21:52 volumio volumio[924]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 5 May 15 14:21:52 volumio volumio[924]: info: CoreCommandRouter::volumioGetState May 15 14:21:52 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:21:52 volumio volumiossh-tunnel[1391]: Warning: Permanently added '[eu3.myvolumio.org]:2222,[167.71.136.233]:2222' (RSA) to the list of known hosts. May 15 14:21:52 volumio sudo[1397]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service May 15 14:21:52 volumio sudo[1397]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:53 volumio systemd[1]: Started Volumio Bluetooth Module. May 15 14:21:53 volumio sudo[1397]: pam_unix(sudo:session): session closed for user root May 15 14:21:53 volumio volumio[924]: info: Volumio BT Module successfully started May 15 14:21:53 volumio sudo[1400]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* May 15 14:21:53 volumio sudo[1400]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:53 volumio volumiobt[1399]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory May 15 14:21:53 volumio sudo[1400]: pam_unix(sudo:session): session closed for user root May 15 14:21:53 volumio volumio[924]: info: TidalConnect service stoped! May 15 14:21:53 volumio volumio[924]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect May 15 14:21:53 volumio volumio[924]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect May 15 14:21:53 volumio sudo[1412]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service May 15 14:21:53 volumio sudo[1412]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:53 volumio systemd[1]: Started Volumio Tidal Connect Service. May 15 14:21:53 volumio sudo[1412]: pam_unix(sudo:session): session closed for user root May 15 14:21:53 volumio volumio[924]: info: Executing endpoint tc_getconfig May 15 14:21:53 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig May 15 14:21:53 volumio vtcs[1414]: STARTING TidalConnect services, version: 1.1.7.5 May 15 14:21:53 volumio vtcs[1414]: STARTED TidalConnect services. May 15 14:21:53 volumio volumio[924]: info: Executing endpoint tc_connect May 15 14:21:53 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect May 15 14:21:53 volumio volumio[924]: info: Connecting to TidalConnect May 15 14:21:53 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:21:53 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:21:53 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:21:53 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:21:53 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:21:53 volumio volumio[924]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 15 14:21:53 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:21:53 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:21:53 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:21:53 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:21:53 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:21:53 volumio volumio[924]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 15 14:21:53 volumio volumio[924]: info: CoreCommandRouter::volumioGetQueue May 15 14:21:53 volumio volumio[924]: info: CoreStateMachine::getQueue May 15 14:21:53 volumio volumio[924]: info: CorePlayQueue::getQueue May 15 14:21:54 volumio sudo[1432]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio May 15 14:21:54 volumio sudo[1432]: pam_unix(sudo:session): session opened for user root by (uid=0) May 15 14:21:54 volumio volumiobt[1399]: pulseaudio: no process found May 15 14:21:54 volumio sudo[1432]: pam_unix(sudo:session): session closed for user root May 15 14:21:54 volumio dbus-daemon[526]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.19' (uid=1000 pid=1436 comm="/usr/bin/pulseaudio --high-priority=true ") May 15 14:21:54 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... May 15 14:21:54 volumio volumio[924]: ------------------------------------ BT MESSAGE: BT STATUS: running May 15 14:21:54 volumio dbus-daemon[526]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' May 15 14:21:54 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. May 15 14:21:54 volumio rtkit-daemon[1437]: Successfully called chroot. May 15 14:21:54 volumio rtkit-daemon[1437]: Successfully dropped privileges. May 15 14:21:54 volumio rtkit-daemon[1437]: Successfully limited resources. May 15 14:21:54 volumio rtkit-daemon[1437]: Running. May 15 14:21:54 volumio rtkit-daemon[1437]: Canary thread running. May 15 14:21:54 volumio rtkit-daemon[1437]: Watchdog thread running. May 15 14:21:54 volumio dbus-daemon[526]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.20' (uid=0 pid=1437 comm="/usr/lib/rtkit/rtkit-daemon ") May 15 14:21:54 volumio systemd[1]: Starting Authorization Manager... May 15 14:21:54 volumio volumio[924]: ------------------------------------ BT MESSAGE: BT STATUS: running May 15 14:21:54 volumio polkitd[1440]: started daemon version 0.105 using authority implementation `local' version `0.105' May 15 14:21:54 volumio dbus-daemon[526]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' May 15 14:21:54 volumio systemd[1]: Started Authorization Manager. May 15 14:21:54 volumio pulseaudio[1436]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 15 14:21:54 volumio pulseaudio[1436]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 May 15 14:21:54 volumio volumiobt[1399]: Applying permissions May 15 14:21:54 volumio volumiobt[1399]: Setting BT discoverable and pairable May 15 14:21:54 volumio pulseaudio[1436]: 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 May 15 14:21:54 volumio bluetoothd[733]: Endpoint registered: sender=:1.40 path=/MediaEndpoint/A2DPSource May 15 14:21:54 volumio bluetoothd[733]: Endpoint registered: sender=:1.40 path=/MediaEndpoint/A2DPSink May 15 14:21:54 volumio kernel: Bluetooth: RFCOMM TTY layer initialized May 15 14:21:54 volumio kernel: Bluetooth: RFCOMM socket layer initialized May 15 14:21:54 volumio kernel: Bluetooth: RFCOMM ver 1.11 May 15 14:21:54 volumio volumiobt[1399]: [CHG] Controller E4:5F:01:8C:FF:75 UUIDs: 00001112-0000-1000-8000-00805f9b34fb May 15 14:21:54 volumio volumiobt[1399]: [117B blob data] May 15 14:21:54 volumio volumiobt[1399]: [117B blob data] May 15 14:21:54 volumio volumiobt[1399]: [117B blob data] May 15 14:21:54 volumio volumiobt[1399]: [117B blob data] May 15 14:21:54 volumio volumiobt[1399]: [117B blob data] May 15 14:21:54 volumio volumiobt[1399]: [117B blob data] May 15 14:21:54 volumio volumiobt[1399]: [117B blob data] May 15 14:21:54 volumio volumiobt[1399]: [117B blob data] May 15 14:21:54 volumio volumiobt[1399]: [55B blob data] May 15 14:21:54 volumio volumiobt[1399]: [bluetooth]# -e power on May 15 14:21:54 volumio volumiobt[1399]: Invalid command in menu main: -e May 15 14:21:54 volumio volumiobt[1399]: May 15 14:21:54 volumio volumiobt[1399]: Use "help" for a list of available commands in a menu. May 15 14:21:54 volumio volumiobt[1399]: Use "menu " if you want to enter any submenu. May 15 14:21:54 volumio volumiobt[1399]: Use "back" if you want to return to menu main. May 15 14:21:54 volumio volumiobt[1399]: [bluetooth]# agent on May 15 14:21:54 volumio volumiobt[1399]: Agent is already registered May 15 14:21:54 volumio volumiobt[1399]: [bluetooth]# discoverable on May 15 14:21:54 volumio volumiobt[1399]: [bluetooth]# pairable on May 15 14:21:54 volumio volumiobt[1399]: [bluetooth]# agent NoInputNoOutput May 15 14:21:54 volumio volumiobt[1399]: Agent is already registered May 15 14:21:54 volumio volumiobt[1399]: [bluetooth]# default-agent May 15 14:21:54 volumio volumiobt[1399]: [bluetooth]# quit May 15 14:21:54 volumio volumiobt[1399]: [59B blob data] May 15 14:21:56 volumio volumio[924]: info: TidalConnect service started! May 15 14:21:57 volumio volumio[924]: info: CoreCommandRouter::volumioPlay May 15 14:21:57 volumio volumio[924]: info: CoreStateMachine::play index undefined May 15 14:21:57 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:21:57 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:21:57 volumio volumio[924]: info: CoreStateMachine::startPlaybackTimer May 15 14:21:57 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:21:57 volumio volumio[924]: info: [1715782917188] ControllerTidal::clearAddPlayTrack May 15 14:21:58 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan May 15 14:21:59 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 15 14:21:59 volumio volumio[924]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Total Clients: 6 May 15 14:21:59 volumio volumio[924]: info: CoreCommandRouter::volumioGetState May 15 14:21:59 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:22:03 volumio volumio[924]: verbose: New Socket.io Connection to 192.168.0.100 from 192.168.0.101 UA: okhttp/4.9.2 Total Clients: 7 May 15 14:22:03 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted May 15 14:22:03 volumio volumio[924]: info: CoreCommandRouter::volumioGetVisibleSources May 15 14:22:04 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 15 14:22:04 volumio volumio[924]: info: CoreCommandRouter::volumioGetState May 15 14:22:04 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:22:04 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom May 15 14:22:04 volumio volumio[924]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom May 15 14:22:04 volumio volumio[924]: info: Received Get System Info May 15 14:22:04 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo May 15 14:22:04 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice May 15 14:22:04 volumio volumio[924]: info: Discovery: Getting this device information May 15 14:22:04 volumio volumio[924]: info: CoreCommandRouter::volumioGetState May 15 14:22:04 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:22:04 volumio volumio[924]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 15 14:22:04 volumio volumio[924]: info: CoreCommandRouter::volumioGetState May 15 14:22:04 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:22:04 volumio volumio[924]: info: Listing playlists May 15 14:22:04 volumio volumio[924]: info: CoreCommandRouter::volumioGetQueue May 15 14:22:04 volumio volumio[924]: info: CoreStateMachine::getQueue May 15 14:22:04 volumio volumio[924]: info: CorePlayQueue::getQueue May 15 14:22:06 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan May 15 14:22:13 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan May 15 14:22:15 volumio volumio[924]: info: CoreCommandRouter::volumioGetState May 15 14:22:15 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:22:15 volumio volumio[924]: info: CoreCommandRouter::volumioGetState May 15 14:22:15 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:22:15 volumio volumio[924]: info: Currently active: tidal May 15 14:22:15 volumio volumio[924]: info: Stopping currently active service May 15 14:22:15 volumio volumio[924]: info: CoreCommandRouter::volumioStop May 15 14:22:15 volumio volumio[924]: info: CoreStateMachine::stop May 15 14:22:15 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:22:15 volumio volumio[924]: info: TidalConnect Active May 15 14:22:15 volumio volumio[924]: info: CoreCommandRouter::volumioGetState May 15 14:22:15 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:22:15 volumio volumio[924]: info: Currently active: tidal May 15 14:22:15 volumio volumio[924]: info: Setting Volatile state to tidalconnect May 15 14:22:15 volumio volumio[924]: info: CoreStateMachine::setConsumeUpdateService undefined May 15 14:22:15 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:15 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:15 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:15 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:22:15 volumio volumio[924]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 15 14:22:15 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:15 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:15 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:15 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:22:15 volumio volumio[924]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 15 14:22:15 volumio volumio[924]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.SrovegPEUsPFGIHdXSj7mkjYTkz1.aa71a3551671203d5f46e2062e7d95c6.state.status' May 15 14:22:15 volumio volumio[924]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.SrovegPEUsPFGIHdXSj7mkjYTkz1.aa71a3551671203d5f46e2062e7d95c6.state.status' May 15 14:22:16 volumio vtcs[1414]: [2024-05-15 14:22:16.752] [tisoc] [warning] [PlaybackControllerImpl.cpp:472] Illegal state:1 May 15 14:22:16 volumio volumio[924]: info: Pushing metadata May 15 14:22:16 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:16 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:16 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:16 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:22:16 volumio volumio[924]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 15 14:22:16 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:16 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:16 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:16 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:22:16 volumio volumio[924]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect May 15 14:22:16 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:16 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:16 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:16 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:16 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:16 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:16 volumio volumio[924]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.SrovegPEUsPFGIHdXSj7mkjYTkz1.aa71a3551671203d5f46e2062e7d95c6.state.status' May 15 14:22:16 volumio volumio[924]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.SrovegPEUsPFGIHdXSj7mkjYTkz1.aa71a3551671203d5f46e2062e7d95c6.state.status' May 15 14:22:17 volumio volumio[924]: info: Pushing metadata May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:17 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:17 volumio vtcs[1414]: [2024-05-15 14:22:17.259] [tisoc] [warning] [logger.cpp:22] [audio_worker.cpp:326] Attempting to initialize a decoder without initial data! May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:17 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:17 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:17 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:17 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:17 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:17 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:17 volumio vtcs[1414]: VTCS: PCM: volumioOutput RATE: 44100 FORMAT: S16_LE CHANNELS: 2 PERIODSIZE: 4096 BUFFERSIZE: 32768 May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:17 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:17 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:17 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:18 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:18 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:18 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:18 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:18 volumio volumio[924]: info: CoreCommandRouter::servicePushState May 15 14:22:18 volumio volumio[924]: info: CoreStateMachine::pushState May 15 14:22:18 volumio volumio[924]: info: CoreCommandRouter::volumioPushState May 15 14:22:21 volumio wpa_supplicant[802]: wlan0: Failed to initiate sched scan May 15 14:22:26 volumio volumio[924]: info: CorePlayQueue::getTrack 0 May 15 14:22:26 volumio volumio[924]: info: CorePlayQueue::getTrack 10 May 15 14:22:26 volumio volumio[924]: info: Prefetching next song May 15 14:22:26 volumio volumio[924]: info: [1715782946233] ControllerTidal::prefetch May 15 14:22:26 volumio volumio[924]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 14:22:26 volumio volumio[924]: TypeError: Cannot read property 'highestSoundQuality' of undefined May 15 14:22:26 volumio volumio[924]: at ControllerTidal.getProperSoundQuality (/myvolumio/plugins/music_service/tidal/tidal_real:1:18659) May 15 14:22:26 volumio volumio[924]: at ControllerTidal.prefetch (/myvolumio/plugins/music_service/tidal/tidal_real:1:19363) May 15 14:22:26 volumio volumio[924]: at CoreStateMachine.increasePlaybackTimer (/volumio/app/statemachine.js:449:18) May 15 14:22:26 volumio volumio[924]: at listOnTimeout (internal/timers.js:554:17) May 15 14:22:26 volumio volumio[924]: at processTimers (internal/timers.js:497:7) May 15 14:22:26 volumio volumio[924]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 15 14:22:26 volumio sudo[1483]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-05-15 14:21 May 15 14:22:26 volumio sudo[1483]: 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="91e88ea6c5c4ec4f00aff63f7c3a2944b14ec939" VOLUMIO_FE_VERSION="c433755e569617abac52264d17ad61be303ef463" VOLUMIO_FE3_VERSION="2419ddd8e895c5bdc456962163c9dc7c0f187aa7" VOLUMIO_BE_VERSION="8e6823482d6af342172ec9fdc1c15b0dd02653d2" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 04 Dec 2022 08:41:29 PM CET" VOLUMIO_VERSION="3.396" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d460c75d04818d0f55c67627fa362668"