-- Logs begin at Mon 2025-04-21 22:19:28 WEST, end at Mon 2025-04-21 22:21:36 WEST. -- Apr 21 22:20:00 myvol-vm0 volumio[1068]: info: MyVolumio token set successfully Apr 21 22:20:00 myvol-vm0 volumio[1068]: info: MYVOLUMIO: Adding device Apr 21 22:20:00 myvol-vm0 volumio[1068]: info: MYVOLUMIO: Evaluating Server Apr 21 22:20:00 myvol-vm0 volumio[1068]: info: MyVolumio Plan changed: superstar Apr 21 22:20:00 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Subscribed plan changed to superstar Apr 21 22:20:00 myvol-vm0 volumio[1068]: info: Adding audio output: Apr 21 22:20:00 myvol-vm0 volumio[1068]: info: MYVOLUMIO: Adding device Apr 21 22:20:00 myvol-vm0 volumio[1068]: info: MYVOLUMIO: Evaluating Server Apr 21 22:20:00 myvol-vm0 volumio[1068]: info: Remote config written successfully Apr 21 22:20:00 myvol-vm0 volumio[1068]: info: Starting Tunnel 1 Apr 21 22:20:00 myvol-vm0 volumio[1068]: info: Starting Tunnel Connection Checker Apr 21 22:20:00 myvol-vm0 volumio[1068]: info: Completed starting MyVolumio Plugin Apr 21 22:20:01 myvol-vm0 systemd[1]: systemd-fsckd.service: Succeeded. Apr 21 22:20:01 myvol-vm0 volumio[1068]: info: MYVolumio Device enabled Apr 21 22:20:01 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Device activated, enabling myvolumio plugins... Apr 21 22:20:01 myvol-vm0 volumio[1068]: info: MyVolumio status changed Apr 21 22:20:01 myvol-vm0 volumio[1068]: info: Streaming services startup Apr 21 22:20:01 myvol-vm0 volumio[1068]: info: Starting Streaming Daemon Apr 21 22:20:01 myvol-vm0 volumio[1068]: info: Setting Geolocation for MyVolumio to eu2 Apr 21 22:20:01 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:20:01 myvol-vm0 sudo[1762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Apr 21 22:20:01 myvol-vm0 sudo[1762]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 22:20:01 myvol-vm0 sudo[1762]: pam_unix(sudo:session): session closed for user root Apr 21 22:20:01 myvol-vm0 volumio[1068]: error: Cannot start Volumio Streaming Daemon Apr 21 22:20:01 myvol-vm0 volumio[1068]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Apr 21 22:20:01 myvol-vm0 volumio[1068]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Apr 21 22:20:01 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 21 22:20:01 myvol-vm0 volumio[1068]: error: [MyVolumio PluginManager] Cache data is invalid! Apr 21 22:20:01 myvol-vm0 volumio[1068]: info: Setting Geolocation for MyVolumio to eu8 Apr 21 22:20:01 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:20:01 myvol-vm0 volumio[1068]: info: Successfully Added MyVolumio device Apr 21 22:20:01 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Loading plugin "bluetooth"... Apr 21 22:20:02 myvol-vm0 volumio[1068]: info: [1745270402116] Starting BluetoothController Apr 21 22:20:02 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Loading plugin "multiroom"... Apr 21 22:20:03 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Apr 21 22:20:03 myvol-vm0 sudo[1765]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Apr 21 22:20:03 myvol-vm0 sudo[1765]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 22:20:03 myvol-vm0 sudo[1765]: pam_unix(sudo:session): session closed for user root Apr 21 22:20:03 myvol-vm0 volumio[1068]: info: MRS: MultiRoom plugin initialized Apr 21 22:20:03 myvol-vm0 volumio[1068]: info: MRS: STOPPING SNAPCLIENT Apr 21 22:20:03 myvol-vm0 volumio[1068]: info: MRS: Snap server stop Apr 21 22:20:03 myvol-vm0 sudo[1781]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Apr 21 22:20:03 myvol-vm0 sudo[1781]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 22:20:03 myvol-vm0 volumio[1068]: info: MRS: STOPPING volumioStreaming Apr 21 22:20:03 myvol-vm0 sudo[1784]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Apr 21 22:20:03 myvol-vm0 sudo[1784]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 22:20:03 myvol-vm0 sudo[1781]: pam_unix(sudo:session): session closed for user root Apr 21 22:20:03 myvol-vm0 sudo[1787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Apr 21 22:20:03 myvol-vm0 sudo[1787]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 22:20:03 myvol-vm0 sudo[1784]: pam_unix(sudo:session): session closed for user root Apr 21 22:20:03 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Apr 21 22:20:03 myvol-vm0 sudo[1787]: pam_unix(sudo:session): session closed for user root Apr 21 22:20:03 myvol-vm0 sudo[1790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Apr 21 22:20:03 myvol-vm0 sudo[1790]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 22:20:03 myvol-vm0 sudo[1790]: pam_unix(sudo:session): session closed for user root Apr 21 22:20:03 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Apr 21 22:20:03 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Apr 21 22:20:03 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Apr 21 22:20:03 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Apr 21 22:20:03 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Apr 21 22:20:03 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Apr 21 22:20:03 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Preparing to generate the ALSA configuration file Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getHwuuid Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Reading ALSA contributions from plugins. Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Updating MyVolumio device info Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Updating MyVolumio device info Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Successfully Added MyVolumio device Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: MRS: Removed streaming files Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: MRS: volumioStreaming STOPPED Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: MRS: SNAPSERVER STOPPED Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: MRS: SNAPCLIENT STOPPED Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Asound.conf file written Apr 21 22:20:04 myvol-vm0 sudo[1793]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 21 22:20:04 myvol-vm0 sudo[1793]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 22:20:04 myvol-vm0 sudo[1793]: pam_unix(sudo:session): session closed for user root Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Output device has changed, restarting MPD Apr 21 22:20:04 myvol-vm0 sudo[1798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 21 22:20:04 myvol-vm0 sudo[1798]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 21 22:20:04 myvol-vm0 sudo[1798]: pam_unix(sudo:session): session closed for user root Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:20:04 myvol-vm0 sudo[1801]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 21 22:20:04 myvol-vm0 sudo[1801]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 22:20:04 myvol-vm0 systemd[1]: musicservicesshield.service: Succeeded. Apr 21 22:20:04 myvol-vm0 systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Apr 21 22:20:04 myvol-vm0 systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Apr 21 22:20:04 myvol-vm0 systemd[1]: Stopping Music Player Daemon... Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , pushMultiRoomStatus Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: MRS: Pushing multiroomSync output for this device Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: MRS: Pushing multiroomSync output Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Adding audio output: Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Adding audio output: Apr 21 22:20:04 myvol-vm0 systemd[1]: mpd.service: Succeeded. Apr 21 22:20:04 myvol-vm0 systemd[1]: Stopped Music Player Daemon. Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Adding METAVOLUMIO REST API Endpoints Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Plugin cd_controller disabled by user. Not starting Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Plugin smart_inputs disabled by user. Not starting Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Apr 21 22:20:04 myvol-vm0 systemd[1]: Starting Music Player Daemon... Apr 21 22:20:04 myvol-vm0 volumio[1068]: error: Hi Res Audio Failed Login: Missing Login Data Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Adding HIGHRESAUDIO REST API Endpoints Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Refreshing QOBUZ token Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Stopping AccessToken refresher cron Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: AccessToken refresher cron started Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Adding TIDAL REST API Endpoints Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: MPD Permissions set Apr 21 22:20:04 myvol-vm0 sudo[1806]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 21 22:20:04 myvol-vm0 sudo[1806]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:20:04 myvol-vm0 sudo[1806]: pam_unix(sudo:session): session closed for user root Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: MRS: Found cast device: VODAFONE-TV-ec2336244a899a108a22fb5927602c9d Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Adding audio output: Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Access Token successfully retrieved Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: [1745270404736] CoreMusicLibrary::Adding element QOBUZ Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 21 22:20:04 myvol-vm0 volumio[1068]: Cannot find translation for source SoundCloud Apr 21 22:20:04 myvol-vm0 volumio[1068]: Cannot find translation for source Mother Earth Radio Apr 21 22:20:04 myvol-vm0 volumio[1068]: Cannot find translation for source Podcast Apr 21 22:20:04 myvol-vm0 volumio[1068]: Cannot find translation for source Radio Paradise Apr 21 22:20:04 myvol-vm0 volumio[1068]: Cannot find translation for source QOBUZ Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Stopping AccessToken refresher cron for QOBUZ Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: AccessToken refresher cron started for QOBUZ Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Adding QOBUZ REST API Endpoints Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Apr 21 22:20:04 myvol-vm0 volumio[1068]: [Metrics] CommandRouter: 28s 182.38ms Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumiosetStartupVolume Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: VolumeController:: Setting startup Volume 60 Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: VolumeController::SetAlsaVolume60 Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::Close All Modals sent Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::Close All Modals sent Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreStateMachine::pushState Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrack 0 Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioPushState Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: MRS: Pushing multiroomSync output update for this device Apr 21 22:20:04 myvol-vm0 volumio[1068]: info: MRS: Pushing multiroomSync output Apr 21 22:20:05 myvol-vm0 volumio[1068]: info: Successfully Updated MyVolumio device Apr 21 22:20:05 myvol-vm0 volumio[1068]: info: Successfully Updated MyVolumio device Apr 21 22:20:05 myvol-vm0 mpd[1811]: Apr 21 22:20 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 21 22:20:05 myvol-vm0 systemd[1]: Started Music Player Daemon. Apr 21 22:20:05 myvol-vm0 sudo[1801]: pam_unix(sudo:session): session closed for user root Apr 21 22:20:05 myvol-vm0 volumio[1068]: error: updateQueue error: null Apr 21 22:20:05 myvol-vm0 systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Apr 21 22:20:05 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Apr 21 22:20:05 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 21 22:20:05 myvol-vm0 volumio[1822]: cset: --> shielding system active with Apr 21 22:20:05 myvol-vm0 volumio[1822]: cset: "system" cpuset of CPUSPEC(2-3) with 120 tasks running Apr 21 22:20:05 myvol-vm0 volumio[1822]: cset: "user" cpuset of CPUSPEC(0-1) with 3 tasks running Apr 21 22:20:05 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Apr 21 22:20:05 myvol-vm0 systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Apr 21 22:20:05 myvol-vm0 sudo[1850]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Apr 21 22:20:05 myvol-vm0 sudo[1850]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 21 22:20:06 myvol-vm0 systemd[1]: Started MyVolumio SSH Tunnel. Apr 21 22:20:06 myvol-vm0 sudo[1850]: pam_unix(sudo:session): session closed for user root Apr 21 22:20:06 myvol-vm0 volumio[1068]: info: Remote SSH Started Apr 21 22:20:06 myvol-vm0 autossh[1853]: port set to 0, monitoring disabled Apr 21 22:20:06 myvol-vm0 autossh[1853]: starting ssh (count 1) Apr 21 22:20:06 myvol-vm0 autossh[1853]: ssh child pid is 1856 Apr 21 22:20:06 myvol-vm0 volumio[1068]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Apr 21 22:20:06 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioGetState Apr 21 22:20:06 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrack 0 Apr 21 22:20:06 myvol-vm0 volumiossh-tunnel[1852]: Warning: Permanently added '[eu8.myvolumio.org]:2222,[178.62.246.67]:2222' (RSA) to the list of known hosts. Apr 21 22:20:08 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioGetState Apr 21 22:20:08 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrack 0 Apr 21 22:20:08 myvol-vm0 volumio[1068]: info: Listing playlists Apr 21 22:20:08 myvol-vm0 volumio[1068]: info: Listing playlists Apr 21 22:20:09 myvol-vm0 volumio[1068]: info: MRS: Getting audio outputs on start Apr 21 22:20:09 myvol-vm0 volumio[1068]: info: MRS: Requesting all other devices output Apr 21 22:20:11 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 21 22:20:11 myvol-vm0 volumio[1068]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Apr 21 22:20:11 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioGetState Apr 21 22:20:11 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrack 0 Apr 21 22:20:11 myvol-vm0 volumio[1068]: info: BOOT COMPLETED Apr 21 22:20:15 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 22:20:15 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 21 22:20:15 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 21 22:20:15 myvol-vm0 volumio-remote-updater[785]: No test mode Apr 21 22:20:15 myvol-vm0 volumio-remote-updater[785]: No alpha test mode Apr 21 22:20:15 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Apr 21 22:20:15 myvol-vm0 volumio[1068]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Apr 21 22:20:15 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Apr 21 22:20:16 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:20:16 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 21 22:20:16 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Apr 21 22:20:16 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Apr 21 22:20:16 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 21 22:20:16 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioGetBrowseSources Apr 21 22:20:16 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 21 22:20:17 myvol-vm0 systemd[1]: systemd-timedated.service: Succeeded. Apr 21 22:20:18 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioGetState Apr 21 22:20:18 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrack 0 Apr 21 22:20:28 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioGetState Apr 21 22:20:28 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrack 0 Apr 21 22:20:28 myvol-vm0 volumio[1068]: info: Listing playlists Apr 21 22:20:28 myvol-vm0 volumio[1068]: info: Listing playlists Apr 21 22:20:38 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioGetState Apr 21 22:20:38 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrack 0 Apr 21 22:20:48 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioGetState Apr 21 22:20:48 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrack 0 Apr 21 22:20:48 myvol-vm0 volumio[1068]: info: Listing playlists Apr 21 22:20:58 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioGetState Apr 21 22:20:58 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrack 0 Apr 21 22:21:08 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioGetState Apr 21 22:21:08 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrack 0 Apr 21 22:21:08 myvol-vm0 volumio[1068]: info: Listing playlists Apr 21 22:21:08 myvol-vm0 volumio[1068]: info: Listing playlists Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: Preload queue cleared Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreStateMachine::ClearQueue Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreStateMachine::stop Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CorePlayQueue::clearPlayQueue Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CorePlayQueue::saveQueue Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioPushQueue Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreStateMachine::addQueueItems Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CorePlayQueue::addQueueItems Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: Preload queue cleared Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: Adding Item to queue: upnp/folder/http://192.168.1.69:2869/upnphost/udhisapi.dll?control=uuid:a5312352-6ed6-46e1-9343-4d44937e2443+urn:upnp-org:serviceId:ContentDirectory@14_d86b6c9e_ceb8a8b8_a54da302 Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: Exploding uri upnp/folder/http://192.168.1.69:2869/upnphost/udhisapi.dll?control=uuid:a5312352-6ed6-46e1-9343-4d44937e2443+urn:upnp-org:serviceId:ContentDirectory@14_d86b6c9e_ceb8a8b8_a54da302 in service upnp_browser Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioPushQueue Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CorePlayQueue::saveQueue Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreStateMachine::updateTrackBlock Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrackBlock Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioPlay Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreStateMachine::play index 0 Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreStateMachine::stop Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreStateMachine::play index undefined Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 22:21:17 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrack 0 Apr 21 22:21:18 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioGetState Apr 21 22:21:18 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrack 0 Apr 21 22:21:25 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 21 22:21:25 myvol-vm0 volumio[1068]: info: Preload queue cleared Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: Preload queue cleared Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreStateMachine::ClearQueue Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreStateMachine::stop Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CorePlayQueue::clearPlayQueue Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CorePlayQueue::saveQueue Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioPushQueue Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreStateMachine::addQueueItems Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CorePlayQueue::addQueueItems Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: Preload queue cleared Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: Adding Item to queue: upnp/folder/http://192.168.1.69:2869/upnphost/udhisapi.dll?control=uuid:a5312352-6ed6-46e1-9343-4d44937e2443+urn:upnp-org:serviceId:ContentDirectory@14_d86b6c9e_ceb8a8b8_a54da302_56d25508 Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: Exploding uri upnp/folder/http://192.168.1.69:2869/upnphost/udhisapi.dll?control=uuid:a5312352-6ed6-46e1-9343-4d44937e2443+urn:upnp-org:serviceId:ContentDirectory@14_d86b6c9e_ceb8a8b8_a54da302_56d25508 in service upnp_browser Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioPushQueue Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CorePlayQueue::saveQueue Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreStateMachine::updateTrackBlock Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrackBlock Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioPlay Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreStateMachine::play index 0 Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreStateMachine::stop Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreStateMachine::play index undefined Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 21 22:21:27 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrack 0 Apr 21 22:21:28 myvol-vm0 volumio[1068]: info: CoreCommandRouter::volumioGetState Apr 21 22:21:28 myvol-vm0 volumio[1068]: info: CorePlayQueue::getTrack 0 Apr 21 22:21:28 myvol-vm0 volumio[1068]: info: Listing playlists Apr 21 22:21:28 myvol-vm0 volumio[1068]: info: Listing playlists Apr 21 22:21:31 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 21 22:21:31 myvol-vm0 volumio[1068]: info: Preload queue cleared Apr 21 22:21:35 myvol-vm0 volumio[1068]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Apr 21 22:21:35 myvol-vm0 volumio[1068]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 22:21:35 myvol-vm0 volumio[1068]: TypeError: Cannot read property 'length' of undefined Apr 21 22:21:35 myvol-vm0 volumio[1068]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Apr 21 22:21:35 myvol-vm0 volumio[1068]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Apr 21 22:21:35 myvol-vm0 volumio[1068]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Apr 21 22:21:35 myvol-vm0 volumio[1068]: at Parser.emit (events.js:400:28) Apr 21 22:21:35 myvol-vm0 volumio[1068]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Apr 21 22:21:35 myvol-vm0 volumio[1068]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Apr 21 22:21:35 myvol-vm0 volumio[1068]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Apr 21 22:21:35 myvol-vm0 volumio[1068]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Apr 21 22:21:35 myvol-vm0 volumio[1068]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Apr 21 22:21:35 myvol-vm0 volumio[1068]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Apr 21 22:21:35 myvol-vm0 volumio[1068]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Apr 21 22:21:35 myvol-vm0 volumio[1068]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Apr 21 22:21:35 myvol-vm0 volumio[1068]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Apr 21 22:21:35 myvol-vm0 volumio[1068]: at IncomingMessage.emit (events.js:412:35) Apr 21 22:21:35 myvol-vm0 volumio[1068]: at endReadableNT (internal/streams/readable.js:1333:12) Apr 21 22:21:35 myvol-vm0 volumio[1068]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Apr 21 22:21:35 myvol-vm0 volumio[1068]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 21 22:21:36 myvol-vm0 sudo[2088]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-21 22:20 Apr 21 22:21:36 myvol-vm0 sudo[2088]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 07:27:48 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="4bc1ecb08f34ad14b420d6678a2aef1b"