-- Logs begin at Tue 2024-10-22 12:20:25 EEST, end at Tue 2024-10-22 12:22:41 EEST. -- Oct 22 12:21:00 volumio volumio[1169]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Oct 22 12:21:00 volumio volumio[1169]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Oct 22 12:21:00 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 12:21:00 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 12:21:00 volumio volumio[1169]: info: Starting MyVolumio Remote Streaming Endpoints Oct 22 12:21:00 volumio volumio[1169]: info: MyVolumio login type: Token Oct 22 12:21:00 volumio volumio[1169]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Oct 22 12:21:00 volumio volumio[1169]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Oct 22 12:21:00 volumio volumio[1169]: info: Starting Streaming Service Transparent Proxy Oct 22 12:21:00 volumio volumio[1169]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Oct 22 12:21:00 volumio volumio[1169]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Oct 22 12:21:00 volumio volumio[1169]: info: Streaming services startup Oct 22 12:21:00 volumio volumio[1169]: info: Starting Streaming Daemon Oct 22 12:21:00 volumio sudo[1658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 22 12:21:00 volumio sudo[1658]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:00 volumio sudo[1658]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:00 volumio volumio[1169]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Oct 22 12:21:00 volumio volumio[1169]: error: Cannot start Volumio Streaming Daemon Oct 22 12:21:00 volumio volumio[1169]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 22 12:21:00 volumio volumio[1169]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 22 12:21:01 volumio volumio[1169]: STREAMING PROXY: Starting server on port 3245 Oct 22 12:21:01 volumio volumio[1169]: Node JS runtime: 14 Oct 22 12:21:01 volumio volumio[1169]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Oct 22 12:21:02 volumio volumio[1169]: info: MyVolumio token set successfully Oct 22 12:21:02 volumio volumio[1169]: info: MYVOLUMIO: Adding device Oct 22 12:21:02 volumio volumio[1169]: info: MYVOLUMIO: Evaluating Server Oct 22 12:21:02 volumio volumio[1169]: info: MyVolumio Plan changed: premium Oct 22 12:21:02 volumio volumio[1169]: info: [MyVolumio PluginManager] Subscribed plan changed to premium Oct 22 12:21:02 volumio volumio[1169]: info: Removing browser output: myVolumio user plan is not superstar Oct 22 12:21:02 volumio volumio[1169]: info: Removing audio output: Oct 22 12:21:02 volumio volumio[1169]: info: MYVOLUMIO: Adding device Oct 22 12:21:02 volumio volumio[1169]: info: MYVOLUMIO: Evaluating Server Oct 22 12:21:02 volumio volumio[1169]: info: Remote config written successfully Oct 22 12:21:02 volumio volumio[1169]: info: Starting Tunnel 1 Oct 22 12:21:02 volumio volumio[1169]: info: Starting Tunnel Connection Checker Oct 22 12:21:02 volumio volumio[1169]: info: Completed starting MyVolumio Plugin Oct 22 12:21:03 volumio volumio[1169]: info: MYVolumio Device enabled Oct 22 12:21:03 volumio volumio[1169]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Oct 22 12:21:03 volumio volumio[1169]: info: MyVolumio status changed Oct 22 12:21:03 volumio volumio[1169]: info: Streaming services startup Oct 22 12:21:03 volumio volumio[1169]: info: Starting Streaming Daemon Oct 22 12:21:03 volumio sudo[1875]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Oct 22 12:21:03 volumio sudo[1875]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:03 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Oct 22 12:21:03 volumio volumio[1169]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Oct 22 12:21:03 volumio sudo[1875]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:03 volumio volumio[1169]: info: [1729588863192] Starting BluetoothController Oct 22 12:21:03 volumio volumio[1169]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Oct 22 12:21:03 volumio volumio[1169]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Oct 22 12:21:03 volumio sudo[1878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Oct 22 12:21:03 volumio sudo[1878]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:03 volumio sudo[1878]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:03 volumio volumio[1169]: info: MRS: MultiRoom plugin initialized Oct 22 12:21:03 volumio volumio[1169]: info: MRS: STOPPING SNAPCLIENT Oct 22 12:21:03 volumio volumio[1169]: info: MRS: Snap server stop Oct 22 12:21:03 volumio sudo[1894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Oct 22 12:21:03 volumio sudo[1894]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:03 volumio volumio[1169]: info: MRS: STOPPING volumioStreaming Oct 22 12:21:03 volumio sudo[1897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Oct 22 12:21:03 volumio sudo[1897]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:03 volumio sudo[1894]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:03 volumio sudo[1897]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:03 volumio volumio[1169]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Oct 22 12:21:03 volumio sudo[1900]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Oct 22 12:21:03 volumio sudo[1900]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:03 volumio sudo[1902]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Oct 22 12:21:03 volumio sudo[1902]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:03 volumio sudo[1902]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:03 volumio sudo[1900]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:03 volumio volumio[1169]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Oct 22 12:21:03 volumio volumio[1169]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Oct 22 12:21:03 volumio volumio[1169]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Oct 22 12:21:03 volumio volumio[1169]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Oct 22 12:21:03 volumio volumio[1169]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Oct 22 12:21:03 volumio volumio[1169]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Oct 22 12:21:03 volumio volumio[1169]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Oct 22 12:21:03 volumio volumio[1169]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Oct 22 12:21:03 volumio volumio[1169]: info: Preparing to generate the ALSA configuration file Oct 22 12:21:04 volumio volumio[1169]: info: Setting Geolocation for MyVolumio to eu6 Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: Setting Geolocation for MyVolumio to eu10 Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: MRS: Removed streaming files Oct 22 12:21:04 volumio volumio[1169]: info: MRS: volumioStreaming STOPPED Oct 22 12:21:04 volumio volumio[1169]: info: MRS: SNAPSERVER STOPPED Oct 22 12:21:04 volumio volumio[1169]: info: MRS: SNAPCLIENT STOPPED Oct 22 12:21:04 volumio volumio[1169]: error: Cannot start Volumio Streaming Daemon Oct 22 12:21:04 volumio volumio[1169]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Oct 22 12:21:04 volumio volumio[1169]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Oct 22 12:21:04 volumio volumio[1169]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 22 12:21:04 volumio volumio[1169]: info: Reading ALSA contributions from plugins. Oct 22 12:21:04 volumio volumio[1169]: info: Asound.conf file written Oct 22 12:21:04 volumio sudo[1906]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 22 12:21:04 volumio sudo[1906]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:04 volumio sudo[1906]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:04 volumio volumio[1169]: info: Output device has changed, restarting MPD Oct 22 12:21:04 volumio sudo[1911]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 22 12:21:04 volumio sudo[1911]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:04 volumio volumio[1169]: info: Output device has changed, restarting Shairport Sync Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 12:21:04 volumio sudo[1911]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:04 volumio sudo[1914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 22 12:21:04 volumio sudo[1914]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:04 volumio systemd[1]: Stopping Music Player Daemon... Oct 22 12:21:04 volumio volumio[1169]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 22 12:21:04 volumio volumio[1169]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:04 volumio systemd[1]: mpd.service: Succeeded. Oct 22 12:21:04 volumio systemd[1]: Stopped Music Player Daemon. Oct 22 12:21:04 volumio volumio[1169]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 22 12:21:04 volumio systemd[1]: Starting Music Player Daemon... Oct 22 12:21:04 volumio volumio[1169]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Oct 22 12:21:04 volumio volumio[1169]: info: MRS: Pushing multiroomSync output for this device Oct 22 12:21:04 volumio volumio[1169]: info: MRS: Pushing multiroomSync output Oct 22 12:21:04 volumio volumio[1169]: info: Adding audio output: Oct 22 12:21:04 volumio volumio[1169]: info: Adding audio output: Oct 22 12:21:04 volumio volumio[1169]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:04 volumio sudo[1923]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/hciconfig hci0 name Volumio Oct 22 12:21:04 volumio volumio[1169]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Oct 22 12:21:04 volumio sudo[1923]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:04 volumio volumio[1169]: info: Adding METAVOLUMIO REST API Endpoints Oct 22 12:21:04 volumio volumio[1169]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Oct 22 12:21:04 volumio volumio[1169]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Oct 22 12:21:04 volumio volumio[1169]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Oct 22 12:21:04 volumio volumio[1169]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Oct 22 12:21:04 volumio volumio[1169]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Oct 22 12:21:04 volumio volumio[1169]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Oct 22 12:21:04 volumio volumio[1169]: info: Preparing CD Folders Oct 22 12:21:04 volumio volumio[1169]: info: Adding CD REST API Endpoints Oct 22 12:21:04 volumio volumio[1169]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Oct 22 12:21:04 volumio volumio[1169]: info: Starting UDEV Watcher for CD Oct 22 12:21:04 volumio volumio[1169]: info: Detecting CD presence with UDEV Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Oct 22 12:21:04 volumio sudo[1920]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 22 12:21:04 volumio sudo[1920]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:04 volumio sudo[1920]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:04 volumio volumio[1169]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Oct 22 12:21:04 volumio volumio[1169]: info: Adding inputs REST Endpoints Oct 22 12:21:04 volumio volumio[1169]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Oct 22 12:21:04 volumio volumio[1169]: info: Scanning Audio Inputs Oct 22 12:21:04 volumio volumio[1169]: info: Checking against Known Cards name Oct 22 12:21:04 volumio volumio[1169]: info: Checking against Known Cards name Oct 22 12:21:04 volumio volumio[1169]: info: Adding Server instance for streaming Oct 22 12:21:04 volumio volumio[1169]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Oct 22 12:21:04 volumio volumio[1169]: error: Hi Res Audio Failed Login: Missing Login Data Oct 22 12:21:04 volumio volumio[1169]: info: Adding HIGHRESAUDIO REST API Endpoints Oct 22 12:21:04 volumio volumio[1169]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Oct 22 12:21:04 volumio volumio[1169]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Oct 22 12:21:04 volumio volumio[1169]: info: Refreshing TIDAL token Oct 22 12:21:04 volumio volumio[1169]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Oct 22 12:21:04 volumio volumio[1169]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Oct 22 12:21:04 volumio sudo[1933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 22 12:21:04 volumio sudo[1933]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:04 volumio volumio[1169]: info: Stopping AccessToken refresher cron for QOBUZ Oct 22 12:21:04 volumio volumio[1169]: info: AccessToken refresher cron started for QOBUZ Oct 22 12:21:04 volumio volumio[1169]: info: Adding QOBUZ REST API Endpoints Oct 22 12:21:04 volumio volumio[1169]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Oct 22 12:21:04 volumio sudo[1933]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:04 volumio sudo[1923]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:04 volumio volumio[1169]: info: MPD Permissions set Oct 22 12:21:04 volumio volumio[1169]: info: Bluetooth name changed to Volumio Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 22 12:21:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:04 volumio volumio[1169]: info: Starting Shairport Sync Oct 22 12:21:04 volumio sudo[1943]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 22 12:21:04 volumio sudo[1943]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:04 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 22 12:21:04 volumio systemd[1]: shairport-sync.service: Succeeded. Oct 22 12:21:04 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 22 12:21:04 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 22 12:21:04 volumio sudo[1943]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:04 volumio volumio[1169]: info: Shairport-Sync Started Oct 22 12:21:04 volumio volumio[1169]: info: Successfully Added MyVolumio device Oct 22 12:21:04 volumio volumio[1169]: info: Successfully Added MyVolumio device Oct 22 12:21:04 volumio mpd[1929]: Oct 22 12:21 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 22 12:21:04 volumio systemd[1]: Started Music Player Daemon. Oct 22 12:21:04 volumio sudo[1914]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:04 volumio volumio[1169]: error: updateQueue error: null Oct 22 12:21:04 volumio volumio[1169]: info: Access Token successfully retrieved Oct 22 12:21:05 volumio volumio[1169]: info: Updating MyVolumio device info Oct 22 12:21:05 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 12:21:05 volumio volumio[1169]: info: Updating MyVolumio device info Oct 22 12:21:05 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 12:21:05 volumio volumio[1169]: info: Successfully retrieved User Session From TIDAL Oct 22 12:21:05 volumio volumio[1169]: info: Successfully retrieved User Subscription From TIDAL Oct 22 12:21:05 volumio volumio[1169]: info: Adding TIDAL to Browse Sources Oct 22 12:21:05 volumio volumio[1169]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Oct 22 12:21:05 volumio volumio[1169]: info: [1729588865309] CoreMusicLibrary::Adding element TIDAL Oct 22 12:21:05 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 22 12:21:05 volumio volumio[1169]: Cannot find translation for source TIDAL Oct 22 12:21:05 volumio volumio[1169]: info: Stopping AccessToken refresher cron Oct 22 12:21:05 volumio volumio[1169]: info: AccessToken refresher cron started Oct 22 12:21:05 volumio volumio[1169]: info: Adding TIDAL REST API Endpoints Oct 22 12:21:05 volumio volumio[1169]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Oct 22 12:21:05 volumio volumio[1169]: info: Successfully Updated MyVolumio device Oct 22 12:21:06 volumio volumio[1169]: info: CoreCommandRouter::volumioPlay Oct 22 12:21:06 volumio volumio[1169]: info: CoreStateMachine::play index undefined Oct 22 12:21:06 volumio volumio[1169]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 22 12:21:06 volumio volumio[1169]: info: CorePlayQueue::getTrack 0 Oct 22 12:21:06 volumio volumio[1169]: info: CoreStateMachine::startPlaybackTimer Oct 22 12:21:06 volumio volumio[1169]: info: CorePlayQueue::getTrack 0 Oct 22 12:21:06 volumio volumio[1169]: info: [1729588866078] ControllerTidal::clearAddPlayTrack Oct 22 12:21:06 volumio volumio[1169]: info: Getting stream with soundQuality HIGHEST_POSSIBLE Oct 22 12:21:06 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand stop Oct 22 12:21:06 volumio volumio[1169]: info: sendMpdCommand stop took 1 milliseconds Oct 22 12:21:06 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand clear Oct 22 12:21:06 volumio volumio[1169]: info: Oct 22 12:21:06 volumio volumio[1169]: ---------------------------- MPD announces system playlist update Oct 22 12:21:06 volumio volumio[1169]: info: Ignoring MPD Status Update Oct 22 12:21:06 volumio volumio[1169]: info: sendMpdCommand clear took 1 milliseconds Oct 22 12:21:06 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/68729520&soundQuality=HIGHEST_POSSIBLE" Oct 22 12:21:06 volumio volumio[1169]: error: updateQueue error: null Oct 22 12:21:06 volumio volumio[1169]: info: Oct 22 12:21:06 volumio volumio[1169]: ---------------------------- MPD announces system playlist update Oct 22 12:21:06 volumio volumio[1169]: info: Ignoring MPD Status Update Oct 22 12:21:06 volumio volumio[1169]: info: ------------------------------ 2ms Oct 22 12:21:06 volumio volumio[1169]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/68729520&soundQuality=HIGHEST_POSSIBLE" took 1 milliseconds Oct 22 12:21:06 volumio volumio[1169]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 22 12:21:06 volumio volumio[1169]: verbose: ControllerMpd::sendMpdCommand play Oct 22 12:21:06 volumio volumio[1169]: info: ------------------------------ 2ms Oct 22 12:21:06 volumio volumio[1169]: info: sendMpdCommand play took 1 milliseconds Oct 22 12:21:06 volumio volumio[1169]: STREAMING PROXY: Handling url /?data=tidal://song/68729520&soundQuality=HIGHEST_POSSIBLE Oct 22 12:21:06 volumio volumio[1169]: info: Executing endpoint getStreamUrltidal Oct 22 12:21:06 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Oct 22 12:21:06 volumio volumio[1169]: info: getStreamUrl took 120 milliseconds Oct 22 12:21:06 volumio volumio[1169]: error: Could not get TIDAL Stream URL Oct 22 12:21:06 volumio volumio[1169]: error: Failed to retrieve real stream URL Oct 22 12:21:06 volumio volumio[1169]: STREAMING PROXY: Error: Failed to retrieve proper real streamUrl Oct 22 12:21:06 volumio volumio[1169]: info: Successfully Updated MyVolumio device Oct 22 12:21:07 volumio sudo[1953]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumiobt.service Oct 22 12:21:07 volumio sudo[1953]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:07 volumio systemd[1]: Started Volumio Bluetooth Module. Oct 22 12:21:07 volumio sudo[1953]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:07 volumio volumio[1169]: info: Volumio BT Module successfully started Oct 22 12:21:07 volumio sudo[1956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /var/lib/bluetooth/* Oct 22 12:21:07 volumio sudo[1956]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:07 volumio volumiobt[1955]: /bin/chmod: cannot access '/var/lib/bluetooth/*': No such file or directory Oct 22 12:21:07 volumio sudo[1956]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:07 volumio volumio[1169]: info: TidalConnect service stoped! Oct 22 12:21:07 volumio volumio[1169]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 22 12:21:07 volumio volumio[1169]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 22 12:21:07 volumio sudo[1968]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 22 12:21:07 volumio sudo[1968]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:07 volumio systemd[1]: Started Volumio Tidal Connect Service. Oct 22 12:21:07 volumio sudo[1968]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:07 volumio volumio[1169]: info: Executing endpoint tc_getconfig Oct 22 12:21:07 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 22 12:21:07 volumio vtcs[1970]: STARTING TidalConnect services, version: 1.4.0.34 Oct 22 12:21:07 volumio vtcs[1970]: STARTED TidalConnect services. Oct 22 12:21:07 volumio volumio[1169]: info: Executing endpoint tc_connect Oct 22 12:21:07 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 22 12:21:07 volumio volumio[1169]: info: Connecting to TidalConnect Oct 22 12:21:07 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Oct 22 12:21:07 volumio volumio[1169]: info: CoreStateMachine::pushState Oct 22 12:21:07 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:21:07 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Oct 22 12:21:07 volumio volumio[1169]: info: MRS: Pushing multiroomSync output update for this device Oct 22 12:21:07 volumio volumio[1169]: info: MRS: Pushing multiroomSync output Oct 22 12:21:07 volumio volumio[1169]: info: CorePlayQueue::getTrack 0 Oct 22 12:21:07 volumio volumio[1169]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 22 12:21:07 volumio volumio[1169]: info: CoreCommandRouter::servicePushState Oct 22 12:21:07 volumio volumio[1169]: info: CoreStateMachine::pushState Oct 22 12:21:07 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Oct 22 12:21:07 volumio volumio[1169]: info: MRS: Pushing multiroomSync output update for this device Oct 22 12:21:07 volumio volumio[1169]: info: MRS: Pushing multiroomSync output Oct 22 12:21:07 volumio volumio[1169]: info: CorePlayQueue::getTrack 0 Oct 22 12:21:07 volumio volumio[1169]: info: CONSUME SERVICE: Received update from a service different from the one supposed to be playing music. Skipping notification. Current mpd Received tidalconnect Oct 22 12:21:07 volumio sudo[1994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Oct 22 12:21:07 volumio sudo[1994]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:07 volumio systemd[1]: Started MyVolumio SSH Tunnel. Oct 22 12:21:07 volumio sudo[1994]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:07 volumio volumio[1169]: info: Remote SSH Started Oct 22 12:21:07 volumio autossh[1997]: port set to 0, monitoring disabled Oct 22 12:21:07 volumio autossh[1997]: starting ssh (count 1) Oct 22 12:21:07 volumio autossh[1997]: ssh child pid is 2000 Oct 22 12:21:07 volumio volumio[1169]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Oct 22 12:21:07 volumio volumio[1169]: info: CoreCommandRouter::volumioGetState Oct 22 12:21:07 volumio volumio[1169]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.jNWpOcNYI5ROQ3RiSEsZuf9NmPj2.52e5dc543d0f1069559fc9f86951faa8.state.status' Oct 22 12:21:08 volumio volumiossh-tunnel[1996]: Warning: Permanently added '[eu10.myvolumio.org]:2222,[46.101.141.203]:2222' (RSA) to the list of known hosts. Oct 22 12:21:08 volumio sudo[2001]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall pulseaudio Oct 22 12:21:08 volumio sudo[2001]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 22 12:21:08 volumio volumiobt[1955]: pulseaudio: no process found Oct 22 12:21:08 volumio sudo[2001]: pam_unix(sudo:session): session closed for user root Oct 22 12:21:08 volumio dbus-daemon[790]: [system] Activating via systemd: service name='org.freedesktop.RealtimeKit1' unit='rtkit-daemon.service' requested by ':1.21' (uid=1000 pid=2005 comm="/usr/bin/pulseaudio --high-priority=true ") Oct 22 12:21:08 volumio systemd[1]: Starting RealtimeKit Scheduling Policy Service... Oct 22 12:21:08 volumio volumio[1169]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 22 12:21:08 volumio dbus-daemon[790]: [system] Successfully activated service 'org.freedesktop.RealtimeKit1' Oct 22 12:21:08 volumio rtkit-daemon[2006]: Successfully called chroot. Oct 22 12:21:08 volumio rtkit-daemon[2006]: Successfully dropped privileges. Oct 22 12:21:08 volumio rtkit-daemon[2006]: Successfully limited resources. Oct 22 12:21:08 volumio rtkit-daemon[2006]: Running. Oct 22 12:21:08 volumio rtkit-daemon[2006]: Canary thread running. Oct 22 12:21:08 volumio rtkit-daemon[2006]: Watchdog thread running. Oct 22 12:21:08 volumio systemd[1]: Started RealtimeKit Scheduling Policy Service. Oct 22 12:21:08 volumio dbus-daemon[790]: [system] Activating via systemd: service name='org.freedesktop.PolicyKit1' unit='polkit.service' requested by ':1.22' (uid=0 pid=2006 comm="/usr/lib/rtkit/rtkit-daemon ") Oct 22 12:21:08 volumio systemd[1]: Starting Authorization Manager... Oct 22 12:21:08 volumio volumio[1169]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 22 12:21:08 volumio polkitd[2009]: started daemon version 0.105 using authority implementation `local' version `0.105' Oct 22 12:21:08 volumio dbus-daemon[790]: [system] Successfully activated service 'org.freedesktop.PolicyKit1' Oct 22 12:21:08 volumio systemd[1]: Started Authorization Manager. Oct 22 12:21:08 volumio pulseaudio[2005]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 22 12:21:08 volumio pulseaudio[2005]: Unable to contact D-Bus: org.freedesktop.DBus.Error.NotSupported: Unable to autolaunch a dbus-daemon without a $DISPLAY for X11 Oct 22 12:21:08 volumio bluetoothd[799]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSource Oct 22 12:21:08 volumio bluetoothd[799]: Endpoint registered: sender=:1.42 path=/MediaEndpoint/A2DPSink Oct 22 12:21:08 volumio pulseaudio[2005]: 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 Oct 22 12:21:08 volumio volumiobt[1955]: Applying permissions Oct 22 12:21:08 volumio volumiobt[1955]: Setting BT discoverable and pairable Oct 22 12:21:08 volumio kernel: Bluetooth: RFCOMM TTY layer initialized Oct 22 12:21:08 volumio kernel: Bluetooth: RFCOMM socket layer initialized Oct 22 12:21:08 volumio kernel: Bluetooth: RFCOMM ver 1.11 Oct 22 12:21:08 volumio volumiobt[1955]: Agent registered Oct 22 12:21:08 volumio volumiobt[1955]: [bluetooth]# -e power on Oct 22 12:21:08 volumio volumiobt[1955]: Invalid command in menu main: -e Oct 22 12:21:08 volumio volumiobt[1955]: Oct 22 12:21:08 volumio volumiobt[1955]: Use "help" for a list of available commands in a menu. Oct 22 12:21:08 volumio volumiobt[1955]: Use "menu " if you want to enter any submenu. Oct 22 12:21:08 volumio volumiobt[1955]: Use "back" if you want to return to menu main. Oct 22 12:21:08 volumio volumiobt[1955]: [bluetooth]# agent on Oct 22 12:21:08 volumio volumiobt[1955]: Agent is already registered Oct 22 12:21:08 volumio volumiobt[1955]: [bluetooth]# discoverable on Oct 22 12:21:08 volumio volumiobt[1955]: [bluetooth]# pairable on Oct 22 12:21:08 volumio volumiobt[1955]: [bluetooth]# agent NoInputNoOutput Oct 22 12:21:08 volumio volumiobt[1955]: Agent is already registered Oct 22 12:21:08 volumio volumiobt[1955]: [bluetooth]# default-agent Oct 22 12:21:08 volumio volumiobt[1955]: [bluetooth]# quit Oct 22 12:21:08 volumio volumiobt[1955]: [59B blob data] Oct 22 12:21:09 volumio volumio[1169]: info: MRS: Getting audio outputs on start Oct 22 12:21:09 volumio volumio[1169]: info: MRS: Requesting all other devices output Oct 22 12:21:10 volumio volumio[1169]: info: TidalConnect service started! Oct 22 12:21:10 volumio volumio[1169]: info: BOOT COMPLETED Oct 22 12:21:10 volumio volumio[1169]: [Metrics] CommandRouter: 24s 82.80ms Oct 22 12:21:10 volumio volumio[1169]: info: CoreCommandRouter::volumiosetStartupVolume Oct 22 12:21:10 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:10 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 12:21:10 volumio volumio[1169]: info: CoreCommandRouter::Close All Modals sent Oct 22 12:21:10 volumio volumio[1169]: info: CoreCommandRouter::Close All Modals sent Oct 22 12:21:10 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 22 12:21:10 volumio volumio[1169]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 22 12:21:10 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 22 12:21:10 volumio volumio[1169]: info: Received Get System Version Oct 22 12:21:10 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 12:21:10 volumio volumio[1169]: info: Received Get System Info Oct 22 12:21:10 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 12:21:10 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 12:21:10 volumio volumio[1169]: info: Discovery: Getting this device information Oct 22 12:21:10 volumio volumio[1169]: info: CoreCommandRouter::volumioGetState Oct 22 12:21:10 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 12:21:11 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Oct 22 12:21:11 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 22 12:21:11 volumio volumio[1169]: No protocol specified Oct 22 12:21:11 volumio volumio[1169]: xcb_connection_has_error() returned true Oct 22 12:21:11 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Oct 22 12:21:15 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 22 12:21:15 volumio volumio[1169]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Oct 22 12:21:15 volumio volumio[1169]: info: CoreCommandRouter::volumioGetState Oct 22 12:21:20 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:20 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 22 12:21:20 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Oct 22 12:21:20 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 22 12:21:20 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 22 12:21:20 volumio volumio[1169]: info: CoreCommandRouter::volumioGetBrowseSources Oct 22 12:21:20 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 12:21:20 volumio volumio[1169]: info: VolumeController::SetAlsaVolume53 Oct 22 12:21:20 volumio volumio[1169]: info: CoreStateMachine::pushState Oct 22 12:21:20 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:21:20 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Oct 22 12:21:20 volumio volumio[1169]: info: MRS: Pushing multiroomSync output update for this device Oct 22 12:21:20 volumio volumio[1169]: info: MRS: Pushing multiroomSync output Oct 22 12:21:20 volumio volumio[1169]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 22 12:21:20 volumio volumio[1169]: xcb_connection_has_error() returned true Oct 22 12:21:20 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 22 12:21:20 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 22 12:21:20 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 22 12:21:20 volumio volumio-remote-updater[783]: No test mode Oct 22 12:21:20 volumio volumio-remote-updater[783]: No alpha test mode Oct 22 12:21:20 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Oct 22 12:21:20 volumio volumio[1169]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Oct 22 12:21:20 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Oct 22 12:21:23 volumio volumio[1169]: info: VolumeController::SetAlsaVolume52 Oct 22 12:21:23 volumio volumio[1169]: info: CoreStateMachine::pushState Oct 22 12:21:23 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:21:23 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Oct 22 12:21:23 volumio volumio[1169]: info: MRS: Pushing multiroomSync output update for this device Oct 22 12:21:23 volumio volumio[1169]: info: MRS: Pushing multiroomSync output Oct 22 12:21:23 volumio volumio[1169]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 22 12:21:23 volumio volumio[1169]: xcb_connection_has_error() returned true Oct 22 12:21:25 volumio volumio[1169]: info: VolumeController::SetAlsaVolume51 Oct 22 12:21:25 volumio volumio[1169]: info: CoreStateMachine::pushState Oct 22 12:21:25 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:21:25 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Oct 22 12:21:25 volumio volumio[1169]: info: MRS: Pushing multiroomSync output update for this device Oct 22 12:21:25 volumio volumio[1169]: info: MRS: Pushing multiroomSync output Oct 22 12:21:25 volumio volumio[1169]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 22 12:21:25 volumio volumio[1169]: xcb_connection_has_error() returned true Oct 22 12:21:26 volumio volumio[1169]: info: VolumeController::SetAlsaVolume50 Oct 22 12:21:26 volumio volumio[1169]: info: CoreStateMachine::pushState Oct 22 12:21:26 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 22 12:21:26 volumio volumio[1169]: info: CoreCommandRouter::volumioPushState Oct 22 12:21:26 volumio volumio[1169]: info: MRS: Pushing multiroomSync output update for this device Oct 22 12:21:26 volumio volumio[1169]: info: MRS: Pushing multiroomSync output Oct 22 12:21:26 volumio volumio[1169]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: No protocol specified Oct 22 12:21:26 volumio volumio[1169]: xcb_connection_has_error() returned true Oct 22 12:21:26 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 12:21:26 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 12:21:26 volumio volumio[1169]: info: Discovery: Getting this device information Oct 22 12:21:26 volumio volumio[1169]: info: CoreCommandRouter::volumioGetState Oct 22 12:21:26 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 12:21:37 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Oct 22 12:21:38 volumio volumio[1169]: info: browseTIDALUri took 122 milliseconds Oct 22 12:21:38 volumio volumio[1169]: error: TIDAL Browse Error: No response received Oct 22 12:21:38 volumio volumio[1169]: error: Failed to execute browseSource: No response received Oct 22 12:21:47 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Oct 22 12:21:47 volumio volumio[1169]: info: browseTIDALUri took 148 milliseconds Oct 22 12:21:47 volumio volumio[1169]: error: TIDAL Browse Error: No response received Oct 22 12:21:47 volumio volumio[1169]: error: Failed to execute browseSource: No response received Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 12:21:56 volumio volumio[1169]: info: Retrieving Cloud Streaming UI Oct 22 12:21:56 volumio volumio[1169]: info: Getting Tidal Cloud Configuration Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 12:21:56 volumio volumio[1169]: info: Getting Qobuz Cloud Configuration Oct 22 12:21:56 volumio volumio[1169]: info: Asking plugin for UI Config Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 12:21:56 volumio volumio[1169]: info: Getting Spotify Cloud Configuration Oct 22 12:21:56 volumio volumio[1169]: info: Asking plugin for UI Config Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 12:21:56 volumio volumio[1169]: info: Saving Spotify Acccount Oct 22 12:21:56 volumio volumio[1169]: info: Got it Oct 22 12:21:56 volumio volumio[1169]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 22 12:21:56 volumio volumio[1169]: info: Got Tidal Cloud Configuration Oct 22 12:21:56 volumio volumio[1169]: info: Got it Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::volumioGetBrowseSources Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::volumioGetBrowseSources Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::volumioGetBrowseSources Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 12:21:56 volumio volumio[1169]: info: Discovery: Getting this device information Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::volumioGetState Oct 22 12:21:56 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 12:22:00 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 12:22:04 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 12:22:08 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 12:22:12 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 12:22:16 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 12:22:17 volumio volumio[1169]: info: CALLMETHOD: music_service tidal saveConfig [object Object] Oct 22 12:22:17 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: tidal , saveConfig Oct 22 12:22:17 volumio volumio[1169]: info: Saving configuration Oct 22 12:22:17 volumio volumio[1169]: info: Done Oct 22 12:22:20 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 22 12:22:26 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 22 12:22:26 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 22 12:22:26 volumio volumio[1169]: info: Discovery: Getting this device information Oct 22 12:22:26 volumio volumio[1169]: info: CoreCommandRouter::volumioGetState Oct 22 12:22:26 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 22 12:22:30 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 22 12:22:30 volumio volumio[1169]: info: Preload queue cleared Oct 22 12:22:33 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 22 12:22:33 volumio volumio[1169]: info: Preload queue cleared Oct 22 12:22:35 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 22 12:22:35 volumio volumio[1169]: info: Preload queue cleared Oct 22 12:22:41 volumio volumio[1169]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Oct 22 12:22:41 volumio volumio[1169]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 12:22:41 volumio volumio[1169]: TypeError: Cannot read property 'length' of undefined Oct 22 12:22:41 volumio volumio[1169]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Oct 22 12:22:41 volumio volumio[1169]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Oct 22 12:22:41 volumio volumio[1169]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Oct 22 12:22:41 volumio volumio[1169]: at Parser.emit (events.js:400:28) Oct 22 12:22:41 volumio volumio[1169]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Oct 22 12:22:41 volumio volumio[1169]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Oct 22 12:22:41 volumio volumio[1169]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Oct 22 12:22:41 volumio volumio[1169]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Oct 22 12:22:41 volumio volumio[1169]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Oct 22 12:22:41 volumio volumio[1169]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Oct 22 12:22:41 volumio volumio[1169]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Oct 22 12:22:41 volumio volumio[1169]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Oct 22 12:22:41 volumio volumio[1169]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Oct 22 12:22:41 volumio volumio[1169]: at IncomingMessage.emit (events.js:412:35) Oct 22 12:22:41 volumio volumio[1169]: at endReadableNT (internal/streams/readable.js:1333:12) Oct 22 12:22:41 volumio volumio[1169]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 22 12:22:41 volumio volumio[1169]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 22 12:22:41 volumio sudo[2123]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-22 12:21 Oct 22 12:22:41 volumio sudo[2123]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"