Apr 15 02:07:02 volumio-r2r sudo[2787]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 15 02:07:02 volumio-r2r sudo[2787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 02:07:02 volumio-r2r sudo[2789]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 15 02:07:02 volumio-r2r sudo[2787]: pam_unix(sudo:session): session closed for user root Apr 15 02:07:02 volumio-r2r sudo[2789]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 02:07:02 volumio-r2r sudo[2789]: pam_unix(sudo:session): session closed for user root Apr 15 02:07:02 volumio-r2r volumio[1145]: verbose: New Socket.io Connection to 192.168.1.21 from 192.168.1.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/147.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 15 02:07:02 volumio-r2r sudo[2793]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 15 02:07:02 volumio-r2r sudo[2793]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 02:07:02 volumio-r2r sudo[2795]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 15 02:07:02 volumio-r2r sudo[2795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 02:07:02 volumio-r2r sudo[2793]: pam_unix(sudo:session): session closed for user root Apr 15 02:07:02 volumio-r2r sudo[2795]: pam_unix(sudo:session): session closed for user root Apr 15 02:07:02 volumio-r2r volumio[1145]: verbose: New Socket.io Connection to 192.168.1.21 from 192.168.1.16 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/147.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Apr 15 02:07:02 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioGetState Apr 15 02:07:02 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:02 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 15 02:07:02 volumio-r2r volumio[1145]: info: Listing playlists Apr 15 02:07:02 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 15 02:07:02 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Apr 15 02:07:02 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Apr 15 02:07:02 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 15 02:07:04 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 15 02:07:04 volumio-r2r volumio[1145]: info: Received Get System Info Apr 15 02:07:04 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 02:07:04 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 02:07:04 volumio-r2r volumio[1145]: info: Discovery: Getting this device information Apr 15 02:07:04 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioGetState Apr 15 02:07:04 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:04 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 15 02:07:06 volumio-r2r volumio[1145]: info: Received Get System Info Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 02:07:06 volumio-r2r volumio[1145]: info: Discovery: Getting this device information Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioGetState Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:06 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 02:07:07 volumio-r2r systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Apr 15 02:07:07 volumio-r2r systemd[1]: setdatetime-helper.service: Deactivated successfully. Apr 15 02:07:07 volumio-r2r systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Preparing to save Alsa Options, stopping services first Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioGetState Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPause Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreStateMachine::pause Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreStateMachine::stPlaybackTimer Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreStateMachine::servicePause Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePause Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand pause Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Saving Audio Output to: {"output_device":{"value":"1","label":"ZeyBack_R2RDAC"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Setting mixer PCM for card ZeyBack_R2RDAC Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Updating Volume Controller Parameters: Device: 1 Name: ZeyBack_R2RDAC Mixer: PCM Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Disabling external Volume Control Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Preparing to generate the ALSA configuration file Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Apr 15 02:07:09 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:09 volumio-r2r volumio[1145]: info: sendMpdCommand pause took 66 milliseconds Apr 15 02:07:09 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: STATE SERVICE {"status":"pause","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26051.jpg","uri":"https://stream.radioparadise.com/flacm","name":"Tear it Down","title":"Tear it Down","artist":"Airbag","album":"The Century of the Self","streaming":true,"disableUiControls":true,"duration":846,"seek":38558,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: CURRENT POSITION 0 Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState stateService pause Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState currentStatus pause Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreStateMachine::stPlaybackTimer Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Apr 15 02:07:09 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:09 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Apr 15 02:07:09 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:09 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:09 volumio-r2r volumio[1145]: info: sendMpdCommand status took 23 milliseconds Apr 15 02:07:09 volumio-r2r volumio[1145]: info: sendMpdCommand status took 10 milliseconds Apr 15 02:07:09 volumio-r2r volumio[1145]: info: sendMpdCommand status took 8 milliseconds Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:09 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 15 02:07:09 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 15 02:07:09 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:09 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:09 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:09 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:09 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:09 volumio-r2r volumio[1145]: info: ------------------------------ 35ms Apr 15 02:07:09 volumio-r2r volumio[1145]: info: ------------------------------ 22ms Apr 15 02:07:09 volumio-r2r volumio[1145]: info: ------------------------------ 21ms Apr 15 02:07:09 volumio-r2r volumio[1145]: info: VolumeController:: Volume=0 Mute =false Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 15 02:07:09 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 15 02:07:09 volumio-r2r volumio[1145]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Asound.conf file written Apr 15 02:07:09 volumio-r2r sudo[2965]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 15 02:07:09 volumio-r2r sudo[2965]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 02:07:09 volumio-r2r sudo[2965]: pam_unix(sudo:session): session closed for user root Apr 15 02:07:09 volumio-r2r volumio[1145]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 15 02:07:09 volumio-r2r volumio[1145]: No state is present for card H20 Apr 15 02:07:09 volumio-r2r volumio[1145]: Found hardware: "USB-Audio" "USB Mixer" "USB20b1:0008" "" "" Apr 15 02:07:09 volumio-r2r volumio[1145]: Hardware is initialized using a generic method Apr 15 02:07:09 volumio-r2r volumio[1145]: No state is present for card H20 Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Output device has changed, restarting MPD Apr 15 02:07:09 volumio-r2r volumio[1145]: info: Output device has changed, restarting MPD Apr 15 02:07:09 volumio-r2r sudo[2971]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 02:07:10 volumio-r2r sudo[2971]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 02:07:10 volumio-r2r sudo[2975]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 15 02:07:10 volumio-r2r sudo[2974]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 02:07:10 volumio-r2r sudo[2975]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 02:07:10 volumio-r2r sudo[2974]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 02:07:10 volumio-r2r sudo[2971]: pam_unix(sudo:session): session closed for user root Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r sudo[2975]: pam_unix(sudo:session): session closed for user root Apr 15 02:07:10 volumio-r2r sudo[2978]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 15 02:07:10 volumio-r2r sudo[2978]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:10 volumio-r2r volumio[1145]: info: MPD Permissions set Apr 15 02:07:10 volumio-r2r volumio[1145]: info: MPD Permissions set Apr 15 02:07:10 volumio-r2r systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 15 02:07:10 volumio-r2r systemd[1]: mpd.service: Deactivated successfully. Apr 15 02:07:10 volumio-r2r systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 15 02:07:10 volumio-r2r systemd[1]: mpd.service: Consumed 1.800s CPU time. Apr 15 02:07:10 volumio-r2r systemd[1]: mpd.socket: Deactivated successfully. Apr 15 02:07:10 volumio-r2r systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 15 02:07:10 volumio-r2r systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 15 02:07:10 volumio-r2r systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 15 02:07:10 volumio-r2r systemd[1]: Starting mpd.service - Music Player Daemon... Apr 15 02:07:10 volumio-r2r sudo[2996]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 15 02:07:10 volumio-r2r sudo[2996]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 15 02:07:10 volumio-r2r sudo[2996]: pam_unix(sudo:session): session closed for user root Apr 15 02:07:10 volumio-r2r mpd[2998]: 2026-04-15T02:07:10 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 15 02:07:10 volumio-r2r systemd[1]: Started mpd.service - Music Player Daemon. Apr 15 02:07:10 volumio-r2r sudo[2978]: pam_unix(sudo:session): session closed for user root Apr 15 02:07:10 volumio-r2r sudo[2974]: pam_unix(sudo:session): session closed for user root Apr 15 02:07:10 volumio-r2r volumio[1145]: error: updateQueue error: null Apr 15 02:07:12 volumio-r2r systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 4. Apr 15 02:07:12 volumio-r2r systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 15 02:07:12 volumio-r2r systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 15 02:07:12 volumio-r2r upmpdcli[3031]: Could not open config: /tmp/upmpdcli.conf Apr 15 02:07:12 volumio-r2r systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 15 02:07:12 volumio-r2r systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 15 02:07:13 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 15 02:07:13 volumio-r2r volumio[1145]: info: CoreStateMachine::getcurrentVolume Apr 15 02:07:13 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Apr 15 02:07:13 volumio-r2r volumio[1145]: info: VolumeController:: Volume=0 Mute =false Apr 15 02:07:13 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:13 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:13 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:13 volumio-r2r volumio[1145]: info: CoreStateMachine::updateTrackBlock Apr 15 02:07:13 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrackBlock Apr 15 02:07:13 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioRetrievevolume Apr 15 02:07:13 volumio-r2r volumio[1145]: info: VolumeController:: Volume=0 Mute =false Apr 15 02:07:13 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:13 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:13 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:14 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 15 02:07:19 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioGetVisibleSources Apr 15 02:07:19 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 02:07:19 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioGetQueue Apr 15 02:07:19 volumio-r2r volumio[1145]: info: CoreStateMachine::getQueue Apr 15 02:07:19 volumio-r2r volumio[1145]: info: CorePlayQueue::getQueue Apr 15 02:07:21 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 15 02:07:21 volumio-r2r volumio[1145]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 15 02:07:21 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 15 02:07:21 volumio-r2r volumio[1145]: info: Received Get System Version Apr 15 02:07:21 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 15 02:07:21 volumio-r2r volumio[1145]: info: Received Get System Info Apr 15 02:07:21 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 02:07:21 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 02:07:21 volumio-r2r volumio[1145]: info: Discovery: Getting this device information Apr 15 02:07:21 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioGetState Apr 15 02:07:21 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:21 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 02:07:21 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Apr 15 02:07:21 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:07:22 volumio-r2r go-librespot[1999]: time="2026-04-15T02:07:22+03:00" level=trace msg="sent dealer ping" Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::ClearQueue Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::stop Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::updateTrackBlock Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrackBlock Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::stPlaybackTimer Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::serviceStop Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::serviceStop Apr 15 02:07:22 volumio-r2r volumio[1145]: info: ControllerMpd::stop Apr 15 02:07:22 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::clearPlayQueue Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::saveQueue Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushQueue Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::addQueueItems Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::addQueueItems Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Adding Item to queue: webrp/0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Exploding uri webrp/0 in service radio_paradise Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushQueue Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::saveQueue Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::updateTrackBlock Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrackBlock Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPlay Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::play index 0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::stop Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::play index undefined Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::startPlaybackTimer Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0 Apr 15 02:07:22 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 02:07:22 volumio-r2r go-librespot[1999]: time="2026-04-15T02:07:22+03:00" level=trace msg="received dealer pong" Apr 15 02:07:22 volumio-r2r volumio[1145]: info: sendMpdCommand stop took 30 milliseconds Apr 15 02:07:22 volumio-r2r volumio[1145]: info: sendMpdCommand stop took 5 milliseconds Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:22 volumio-r2r volumio[1145]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26051.jpg","uri":"https://stream.radioparadise.com/flacm","name":"Tear it Down","title":"Tear it Down","artist":"Airbag","album":"The Century of the Self","streaming":true,"disableUiControls":true,"duration":846,"seek":38558,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 15 02:07:22 volumio-r2r volumio[1145]: verbose: CURRENT POSITION 0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState stateService stop Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: No code Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:22 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Apr 15 02:07:22 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:22 volumio-r2r volumio[1145]: info: sendMpdCommand clear took 25 milliseconds Apr 15 02:07:22 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Apr 15 02:07:22 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:22 volumio-r2r volumio[1145]: error: updateQueue error: null Apr 15 02:07:22 volumio-r2r volumio[1145]: error: updateQueue error: null Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Apr 15 02:07:22 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: options Apr 15 02:07:22 volumio-r2r volumio[1145]: info: ------------------------------ 3ms Apr 15 02:07:22 volumio-r2r volumio[1145]: info: sendMpdCommand consume 1 took 2 milliseconds Apr 15 02:07:22 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:07:22 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:22 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:22 volumio-r2r volumio[1145]: info: [1776208042829] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flacm Apr 15 02:07:22 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flacm" Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Apr 15 02:07:22 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: options Apr 15 02:07:22 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:22 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Apr 15 02:07:22 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:22 volumio-r2r volumio[1145]: info: sendMpdCommand status took 12 milliseconds Apr 15 02:07:22 volumio-r2r volumio[1145]: info: sendMpdCommand add "https://stream.radioparadise.com/flacm" took 11 milliseconds Apr 15 02:07:22 volumio-r2r volumio[1145]: info: sendMpdCommand status took 11 milliseconds Apr 15 02:07:22 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:22 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand play Apr 15 02:07:22 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:22 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:22 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:22 volumio-r2r volumio[1145]: info: ------------------------------ 32ms Apr 15 02:07:22 volumio-r2r volumio[1145]: info: ------------------------------ 39ms Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Apr 15 02:07:22 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:22 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:22 volumio-r2r volumio[1145]: info: ------------------------------ 30ms Apr 15 02:07:22 volumio-r2r volumio[1145]: info: sendMpdCommand play took 22 milliseconds Apr 15 02:07:22 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:07:22 volumio-r2r volumio[1145]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=0 Apr 15 02:07:22 volumio-r2r volumio[1145]: info: [1776208042871] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Apr 15 02:07:23 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Apr 15 02:07:23 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:23 volumio-r2r volumio[1145]: info: sendMpdCommand status took 19 milliseconds Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Apr 15 02:07:23 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Apr 15 02:07:23 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:23 volumio-r2r volumio[1145]: info: sendMpdCommand status took 19 milliseconds Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:23 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:23 volumio-r2r volumio[1145]: info: sendMpdCommand status took 0 milliseconds Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ------------------------------ 21ms Apr 15 02:07:23 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:07:23 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:23 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ------------------------------ 21ms Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ------------------------------ 3ms Apr 15 02:07:23 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ------------------------------ 7ms Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Apr 15 02:07:23 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Apr 15 02:07:23 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Apr 15 02:07:23 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Apr 15 02:07:23 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ------------------------------ 118ms Apr 15 02:07:23 volumio-r2r volumio[1145]: info: sendMpdCommand status took 118 milliseconds Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ------------------------------ 117ms Apr 15 02:07:23 volumio-r2r volumio[1145]: info: sendMpdCommand status took 117 milliseconds Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:23 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:23 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:23 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ------------------------------ 120ms Apr 15 02:07:23 volumio-r2r volumio[1145]: info: ------------------------------ 119ms Apr 15 02:07:23 volumio-r2r volumio[1145]: info: [1776208043568] [RadioParadise] received new metadata: {"time":788,"artist":"Airbag","title":"Tear it Down","album":"The Century of the Self","year":"2024","cover":"https://img.radioparadise.com/covers/l/26051.jpg","cover_med":"https://img.radioparadise.com/covers/m/26051.jpg","cover_small":"https://img.radioparadise.com/covers/s/26051.jpg"} Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26051.jpg","uri":"https://stream.radioparadise.com/flacm","name":"Tear it Down","title":"Tear it Down","artist":"Airbag","album":"The Century of the Self","streaming":true,"disableUiControls":true,"duration":793,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 15 02:07:23 volumio-r2r volumio[1145]: verbose: CURRENT POSITION 0 Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState stateService play Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Apr 15 02:07:23 volumio-r2r volumio[1145]: info: [1776208043568] [RadioParadise] setting new timer with duration of 793000 seconds. Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:23 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 1 Apr 15 02:07:27 volumio-r2r systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5. Apr 15 02:07:27 volumio-r2r systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 15 02:07:27 volumio-r2r systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 15 02:07:27 volumio-r2r upmpdcli[3296]: Could not open config: /tmp/upmpdcli.conf Apr 15 02:07:27 volumio-r2r systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 15 02:07:27 volumio-r2r systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 15 02:07:28 volumio-r2r volumio[1145]: info: Apr 15 02:07:28 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:28 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:28 volumio-r2r volumio[1145]: info: Apr 15 02:07:28 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:28 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:28 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:28 volumio-r2r volumio[1145]: info: Apr 15 02:07:28 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:28 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:28 volumio-r2r volumio[1145]: info: Apr 15 02:07:28 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:28 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:28 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:28 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:07:28 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:07:28 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:28 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:28 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:07:28 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:07:28 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:28 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:28 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:28 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:28 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:28 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:28 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:28 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:28 volumio-r2r volumio[1145]: info: ------------------------------ 3ms Apr 15 02:07:28 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:28 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:28 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:28 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:28 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:28 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:28 volumio-r2r volumio[1145]: info: ------------------------------ 3ms Apr 15 02:07:29 volumio-r2r volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 02:07:29 volumio-r2r volumio[1145]: info: CoreCommandRouter::Close All Modals sent Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::ClearQueue Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::stop Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::stPlaybackTimer Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::updateTrackBlock Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrackBlock Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::serviceStop Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreCommandRouter::serviceStop Apr 15 02:07:38 volumio-r2r volumio[1145]: info: ControllerMpd::stop Apr 15 02:07:38 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::clearPlayQueue Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::saveQueue Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushQueue Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::addQueueItems Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::addQueueItems Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Adding Item to queue: webrp/0 Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Using cached record of: webrp/0 Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushQueue Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::saveQueue Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::updateTrackBlock Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrackBlock Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPlay Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::play index 0 Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::stop Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::play index undefined Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::startPlaybackTimer Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:38 volumio-r2r volumio[1145]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0 Apr 15 02:07:38 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Apr 15 02:07:38 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:38 volumio-r2r volumio[1145]: info: sendMpdCommand stop took 42 milliseconds Apr 15 02:07:38 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:38 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:38 volumio-r2r volumio[1145]: info: sendMpdCommand stop took 25 milliseconds Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:38 volumio-r2r volumio[1145]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26051.jpg","uri":"https://stream.radioparadise.com/flacm","name":"Tear it Down","title":"Tear it Down","artist":"Airbag","album":"The Century of the Self","streaming":true,"disableUiControls":true,"duration":793,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 15 02:07:38 volumio-r2r volumio[1145]: verbose: CURRENT POSITION 0 Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState stateService stop Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:38 volumio-r2r volumio[1145]: info: No code Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:38 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 02:07:38 volumio-r2r volumio[1145]: info: sendMpdCommand status took 23 milliseconds Apr 15 02:07:38 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:38 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Apr 15 02:07:38 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:38 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:38 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Apr 15 02:07:38 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:38 volumio-r2r volumio[1145]: info: sendMpdCommand clear took 23 milliseconds Apr 15 02:07:38 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 7 milliseconds Apr 15 02:07:38 volumio-r2r volumio[1145]: info: sendMpdCommand status took 7 milliseconds Apr 15 02:07:38 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 15 02:07:38 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:38 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:38 volumio-r2r volumio[1145]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Apr 15 02:07:38 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:38 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:38 volumio-r2r volumio[1145]: info: ------------------------------ 37ms Apr 15 02:07:38 volumio-r2r volumio[1145]: info: ------------------------------ 16ms Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Apr 15 02:07:38 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:38 volumio-r2r volumio[1145]: error: updateQueue error: null Apr 15 02:07:38 volumio-r2r volumio[1145]: error: updateQueue error: null Apr 15 02:07:38 volumio-r2r volumio[1145]: info: ------------------------------ 11ms Apr 15 02:07:38 volumio-r2r volumio[1145]: info: sendMpdCommand consume 1 took 10 milliseconds Apr 15 02:07:38 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:07:38 volumio-r2r volumio[1145]: info: [1776208058729] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flacm Apr 15 02:07:38 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flacm" Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Apr 15 02:07:38 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:38 volumio-r2r volumio[1145]: info: sendMpdCommand add "https://stream.radioparadise.com/flacm" took 3 milliseconds Apr 15 02:07:38 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand play Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Apr 15 02:07:38 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:38 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:38 volumio-r2r volumio[1145]: info: ------------------------------ 7ms Apr 15 02:07:38 volumio-r2r volumio[1145]: info: sendMpdCommand play took 2 milliseconds Apr 15 02:07:38 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:07:38 volumio-r2r volumio[1145]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=0 Apr 15 02:07:38 volumio-r2r volumio[1145]: info: [1776208058739] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Apr 15 02:07:39 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Apr 15 02:07:39 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:39 volumio-r2r volumio[1145]: info: sendMpdCommand status took 24 milliseconds Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Apr 15 02:07:39 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Apr 15 02:07:39 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:39 volumio-r2r volumio[1145]: info: sendMpdCommand status took 24 milliseconds Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:39 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:39 volumio-r2r volumio[1145]: info: sendMpdCommand status took 0 milliseconds Apr 15 02:07:39 volumio-r2r volumio[1145]: info: sendMpdCommand status took 0 milliseconds Apr 15 02:07:39 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ------------------------------ 26ms Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ------------------------------ 26ms Apr 15 02:07:39 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:39 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ------------------------------ 3ms Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Apr 15 02:07:39 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Apr 15 02:07:39 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Apr 15 02:07:39 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Apr 15 02:07:39 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:07:39 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:07:39 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:39 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:39 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:39 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:07:39 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:07:39 volumio-r2r volumio[1145]: info: [1776208059441] [RadioParadise] received new metadata: {"time":772,"artist":"Airbag","title":"Tear it Down","album":"The Century of the Self","year":"2024","cover":"https://img.radioparadise.com/covers/l/26051.jpg","cover_med":"https://img.radioparadise.com/covers/m/26051.jpg","cover_small":"https://img.radioparadise.com/covers/s/26051.jpg"} Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26051.jpg","uri":"https://stream.radioparadise.com/flacm","name":"Tear it Down","title":"Tear it Down","artist":"Airbag","album":"The Century of the Self","streaming":true,"disableUiControls":true,"duration":777,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 15 02:07:39 volumio-r2r volumio[1145]: verbose: CURRENT POSITION 0 Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState stateService play Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Apr 15 02:07:39 volumio-r2r volumio[1145]: info: [1776208059442] [RadioParadise] setting new timer with duration of 777000 seconds. Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:39 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 1 Apr 15 02:07:42 volumio-r2r volumio[1145]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 9 Apr 15 02:07:42 volumio-r2r volumio[1145]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: unknown Engine version: 4 Transport: websocket Total Clients: 9 Apr 15 02:07:43 volumio-r2r systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6. Apr 15 02:07:43 volumio-r2r systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 15 02:07:43 volumio-r2r systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 15 02:07:43 volumio-r2r upmpdcli[3504]: Could not open config: /tmp/upmpdcli.conf Apr 15 02:07:43 volumio-r2r systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 15 02:07:43 volumio-r2r systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 15 02:07:44 volumio-r2r volumio[1145]: info: Apr 15 02:07:44 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:44 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:44 volumio-r2r volumio[1145]: info: Apr 15 02:07:44 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:44 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:44 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:44 volumio-r2r volumio[1145]: info: Apr 15 02:07:44 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:44 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:44 volumio-r2r volumio[1145]: info: Apr 15 02:07:44 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:44 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:44 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:44 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:07:44 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:07:44 volumio-r2r volumio[1145]: info: ------------------------------ 0ms Apr 15 02:07:44 volumio-r2r volumio[1145]: info: sendMpdCommand status took 0 milliseconds Apr 15 02:07:44 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:44 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:44 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:44 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:44 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:44 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 15 02:07:44 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:44 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:44 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:44 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:44 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:44 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:44 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:44 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:44 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:44 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:44 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:07:44 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:07:44 volumio-r2r volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 02:07:44 volumio-r2r volumio[1145]: info: CoreCommandRouter::Close All Modals sent Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::ClearQueue Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::stop Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::stPlaybackTimer Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::updateTrackBlock Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrackBlock Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::serviceStop Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::serviceStop Apr 15 02:07:49 volumio-r2r volumio[1145]: info: ControllerMpd::stop Apr 15 02:07:49 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::clearPlayQueue Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::saveQueue Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushQueue Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::addQueueItems Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::addQueueItems Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Adding Item to queue: webrp/2 Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Exploding uri webrp/2 in service radio_paradise Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushQueue Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::saveQueue Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::updateTrackBlock Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrackBlock Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPlay Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::play index 0 Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::stop Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::play index undefined Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::startPlaybackTimer Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:49 volumio-r2r volumio[1145]: info: [RadioParadise] Matched station: Radio Paradise Rock Mix (FLAC), chan=2 Apr 15 02:07:49 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Apr 15 02:07:49 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:49 volumio-r2r volumio[1145]: info: sendMpdCommand stop took 39 milliseconds Apr 15 02:07:49 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:49 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:49 volumio-r2r volumio[1145]: info: sendMpdCommand stop took 18 milliseconds Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:49 volumio-r2r volumio[1145]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26051.jpg","uri":"https://stream.radioparadise.com/flacm","name":"Tear it Down","title":"Tear it Down","artist":"Airbag","album":"The Century of the Self","streaming":true,"disableUiControls":true,"duration":777,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 15 02:07:49 volumio-r2r volumio[1145]: verbose: CURRENT POSITION 0 Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState stateService stop Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:49 volumio-r2r volumio[1145]: info: No code Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:49 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Apr 15 02:07:49 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:49 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:49 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Apr 15 02:07:49 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:49 volumio-r2r volumio[1145]: info: sendMpdCommand status took 20 milliseconds Apr 15 02:07:49 volumio-r2r volumio[1145]: info: sendMpdCommand clear took 14 milliseconds Apr 15 02:07:49 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:07:49 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:49 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:49 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 15 02:07:49 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:49 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:07:49 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:49 volumio-r2r volumio[1145]: info: ------------------------------ 11ms Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Apr 15 02:07:49 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:49 volumio-r2r volumio[1145]: error: updateQueue error: null Apr 15 02:07:49 volumio-r2r volumio[1145]: error: updateQueue error: null Apr 15 02:07:49 volumio-r2r volumio[1145]: info: ------------------------------ 17ms Apr 15 02:07:49 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 17 milliseconds Apr 15 02:07:49 volumio-r2r volumio[1145]: info: sendMpdCommand consume 1 took 17 milliseconds Apr 15 02:07:49 volumio-r2r volumio[1145]: info: ------------------------------ 7ms Apr 15 02:07:49 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:49 volumio-r2r volumio[1145]: info: [1776208069703] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/rock-flacm Apr 15 02:07:49 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/rock-flacm" Apr 15 02:07:49 volumio-r2r volumio[1145]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Apr 15 02:07:49 volumio-r2r volumio[1145]: info: ------------------------------ 38ms Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Apr 15 02:07:49 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:49 volumio-r2r volumio[1145]: info: sendMpdCommand add "https://stream.radioparadise.com/rock-flacm" took 1 milliseconds Apr 15 02:07:49 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand play Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Apr 15 02:07:49 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:49 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:49 volumio-r2r volumio[1145]: info: ------------------------------ 5ms Apr 15 02:07:49 volumio-r2r volumio[1145]: info: sendMpdCommand play took 2 milliseconds Apr 15 02:07:49 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:07:49 volumio-r2r volumio[1145]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=2 Apr 15 02:07:49 volumio-r2r volumio[1145]: info: [1776208069710] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Apr 15 02:07:50 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Apr 15 02:07:50 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:50 volumio-r2r volumio[1145]: info: sendMpdCommand status took 7 milliseconds Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Apr 15 02:07:50 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Apr 15 02:07:50 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:50 volumio-r2r volumio[1145]: info: sendMpdCommand status took 8 milliseconds Apr 15 02:07:50 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 15 02:07:50 volumio-r2r volumio[1145]: info: sendMpdCommand status took 0 milliseconds Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ------------------------------ 9ms Apr 15 02:07:50 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:07:50 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 15 02:07:50 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ------------------------------ 10ms Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:07:50 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ------------------------------ 3ms Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Apr 15 02:07:50 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Apr 15 02:07:50 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Apr 15 02:07:50 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Apr 15 02:07:50 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:07:50 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:07:50 volumio-r2r volumio[1145]: info: sendMpdCommand status took 0 milliseconds Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:50 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:50 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:50 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:07:50 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:07:50 volumio-r2r volumio[1145]: info: [1776208070396] [RadioParadise] received new metadata: {"time":96,"artist":"AC/DC","title":"Hells Bells","album":"Back In Black","year":"1980","cover":"https://img.radioparadise.com/covers/l/11942.jpg","cover_med":"https://img.radioparadise.com/covers/m/11942.jpg","cover_small":"https://img.radioparadise.com/covers/s/11942.jpg"} Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/11942.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"Hells Bells","title":"Hells Bells","artist":"AC/DC","album":"Back In Black","streaming":true,"disableUiControls":true,"duration":101,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 15 02:07:50 volumio-r2r volumio[1145]: verbose: CURRENT POSITION 0 Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState stateService play Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Apr 15 02:07:50 volumio-r2r volumio[1145]: info: [1776208070397] [RadioParadise] setting new timer with duration of 101000 seconds. Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:50 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 1 Apr 15 02:07:52 volumio-r2r volumio[1145]: info: Apr 15 02:07:52 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:52 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:52 volumio-r2r volumio[1145]: info: Apr 15 02:07:52 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:52 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:52 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:52 volumio-r2r volumio[1145]: info: Apr 15 02:07:52 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:07:52 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:07:52 volumio-r2r volumio[1145]: info: Apr 15 02:07:52 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:07:52 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:07:52 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:07:52 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:07:52 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:07:52 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:07:52 volumio-r2r volumio[1145]: info: sendMpdCommand status took 0 milliseconds Apr 15 02:07:52 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:52 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:52 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:07:52 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:07:52 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:07:52 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 15 02:07:52 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:52 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:07:52 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:52 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:52 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:52 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:52 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:07:52 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:07:52 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:07:52 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:07:52 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:07:52 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:07:52 volumio-r2r go-librespot[1999]: time="2026-04-15T02:07:52+03:00" level=trace msg="sent dealer ping" Apr 15 02:07:52 volumio-r2r go-librespot[1999]: time="2026-04-15T02:07:52+03:00" level=trace msg="received dealer pong" Apr 15 02:07:55 volumio-r2r volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 02:07:55 volumio-r2r volumio[1145]: info: CoreCommandRouter::Close All Modals sent Apr 15 02:07:58 volumio-r2r systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7. Apr 15 02:07:58 volumio-r2r systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 15 02:07:58 volumio-r2r systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 15 02:07:58 volumio-r2r upmpdcli[3726]: Could not open config: /tmp/upmpdcli.conf Apr 15 02:07:58 volumio-r2r systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 15 02:07:58 volumio-r2r systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 15 02:08:10 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::ClearQueue Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::stop Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::stPlaybackTimer Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::updateTrackBlock Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrackBlock Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::serviceStop Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::serviceStop Apr 15 02:08:10 volumio-r2r volumio[1145]: info: ControllerMpd::stop Apr 15 02:08:10 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::clearPlayQueue Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::saveQueue Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushQueue Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::addQueueItems Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::addQueueItems Apr 15 02:08:10 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:08:10 volumio-r2r volumio[1145]: info: Adding Item to queue: webrp/0 Apr 15 02:08:10 volumio-r2r volumio[1145]: info: Using cached record of: webrp/0 Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushQueue Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::saveQueue Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::updateTrackBlock Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrackBlock Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPlay Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::play index 0 Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::stop Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::play index undefined Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::startPlaybackTimer Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:10 volumio-r2r volumio[1145]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0 Apr 15 02:08:10 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 02:08:10 volumio-r2r volumio[1145]: info: Apr 15 02:08:10 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:08:10 volumio-r2r volumio[1145]: info: sendMpdCommand stop took 42 milliseconds Apr 15 02:08:10 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:08:10 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:08:10 volumio-r2r volumio[1145]: info: sendMpdCommand stop took 22 milliseconds Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:10 volumio-r2r volumio[1145]: verbose: STATE SERVICE {"status":"stop","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/11942.jpg","uri":"https://stream.radioparadise.com/rock-flacm","name":"Hells Bells","title":"Hells Bells","artist":"AC/DC","album":"Back In Black","streaming":true,"disableUiControls":true,"duration":101,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 15 02:08:10 volumio-r2r volumio[1145]: verbose: CURRENT POSITION 0 Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState stateService stop Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:08:10 volumio-r2r volumio[1145]: info: No code Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:08:10 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand clear Apr 15 02:08:10 volumio-r2r volumio[1145]: info: Apr 15 02:08:10 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:08:10 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:08:10 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:08:10 volumio-r2r volumio[1145]: info: Apr 15 02:08:10 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:08:10 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:08:10 volumio-r2r volumio[1145]: info: sendMpdCommand status took 24 milliseconds Apr 15 02:08:10 volumio-r2r volumio[1145]: info: sendMpdCommand clear took 17 milliseconds Apr 15 02:08:10 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:08:10 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:08:10 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:08:10 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand consume 1 Apr 15 02:08:10 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:08:10 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:08:10 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:10 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:08:10 volumio-r2r volumio[1145]: info: ------------------------------ 15ms Apr 15 02:08:10 volumio-r2r volumio[1145]: info: Apr 15 02:08:10 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:08:10 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:08:10 volumio-r2r volumio[1145]: error: updateQueue error: null Apr 15 02:08:10 volumio-r2r volumio[1145]: error: updateQueue error: null Apr 15 02:08:10 volumio-r2r volumio[1145]: info: ------------------------------ 24ms Apr 15 02:08:10 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 24 milliseconds Apr 15 02:08:10 volumio-r2r volumio[1145]: info: sendMpdCommand consume 1 took 24 milliseconds Apr 15 02:08:10 volumio-r2r volumio[1145]: info: ------------------------------ 9ms Apr 15 02:08:10 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:08:10 volumio-r2r volumio[1145]: info: [1776208090995] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flacm Apr 15 02:08:10 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flacm" Apr 15 02:08:10 volumio-r2r volumio[1145]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Apr 15 02:08:10 volumio-r2r volumio[1145]: info: ------------------------------ 50ms Apr 15 02:08:10 volumio-r2r volumio[1145]: info: Apr 15 02:08:10 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:08:10 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:08:10 volumio-r2r volumio[1145]: info: sendMpdCommand add "https://stream.radioparadise.com/flacm" took 2 milliseconds Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand play Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Apr 15 02:08:11 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ------------------------------ 11ms Apr 15 02:08:11 volumio-r2r volumio[1145]: info: sendMpdCommand play took 4 milliseconds Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ------------------------------ 3ms Apr 15 02:08:11 volumio-r2r volumio[1145]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=0 Apr 15 02:08:11 volumio-r2r volumio[1145]: info: [1776208091009] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Apr 15 02:08:11 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Apr 15 02:08:11 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Apr 15 02:08:11 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:08:11 volumio-r2r volumio[1145]: info: sendMpdCommand status took 27 milliseconds Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Apr 15 02:08:11 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:08:11 volumio-r2r volumio[1145]: info: sendMpdCommand status took 27 milliseconds Apr 15 02:08:11 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:08:11 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:08:11 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ------------------------------ 30ms Apr 15 02:08:11 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 3 milliseconds Apr 15 02:08:11 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 15 02:08:11 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 2 milliseconds Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ------------------------------ 30ms Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ------------------------------ 5ms Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ------------------------------ 4ms Apr 15 02:08:11 volumio-r2r volumio[1145]: info: [1776208091698] [RadioParadise] received new metadata: {"time":741,"artist":"Airbag","title":"Tear it Down","album":"The Century of the Self","year":"2024","cover":"https://img.radioparadise.com/covers/l/26051.jpg","cover_med":"https://img.radioparadise.com/covers/m/26051.jpg","cover_small":"https://img.radioparadise.com/covers/s/26051.jpg"} Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/26051.jpg","uri":"https://stream.radioparadise.com/flacm","name":"Tear it Down","title":"Tear it Down","artist":"Airbag","album":"The Century of the Self","streaming":true,"disableUiControls":true,"duration":746,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: CURRENT POSITION 0 Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState stateService play Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CoreStateMachine::syncState currentStatus stop Apr 15 02:08:11 volumio-r2r volumio[1145]: info: [1776208091698] [RadioParadise] setting new timer with duration of 746000 seconds. Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 1 Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Apr 15 02:08:11 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Apr 15 02:08:11 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Apr 15 02:08:11 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Apr 15 02:08:11 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ------------------------------ 0ms Apr 15 02:08:11 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:08:11 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:08:11 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:08:11 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:08:11 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:08:11 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:11 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:08:11 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:08:13 volumio-r2r systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8. Apr 15 02:08:13 volumio-r2r systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Apr 15 02:08:13 volumio-r2r systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Apr 15 02:08:13 volumio-r2r upmpdcli[3933]: Could not open config: /tmp/upmpdcli.conf Apr 15 02:08:13 volumio-r2r systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Apr 15 02:08:13 volumio-r2r systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Apr 15 02:08:17 volumio-r2r volumio[1145]: info: Apr 15 02:08:17 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:08:17 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:08:17 volumio-r2r volumio[1145]: info: Apr 15 02:08:17 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:08:17 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:08:17 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:08:17 volumio-r2r volumio[1145]: info: Apr 15 02:08:17 volumio-r2r volumio[1145]: ---------------------------- MPD announces system playlist update Apr 15 02:08:17 volumio-r2r volumio[1145]: info: Ignoring MPD Status Update Apr 15 02:08:17 volumio-r2r volumio[1145]: info: Apr 15 02:08:17 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:08:17 volumio-r2r volumio[1145]: info: ControllerMpd::getState Apr 15 02:08:17 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand status Apr 15 02:08:17 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:08:17 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:08:17 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:08:17 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:08:17 volumio-r2r volumio[1145]: info: ------------------------------ 1ms Apr 15 02:08:17 volumio-r2r volumio[1145]: info: sendMpdCommand status took 1 milliseconds Apr 15 02:08:17 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:08:17 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseState Apr 15 02:08:17 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 15 02:08:17 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:08:17 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:08:17 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:08:17 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:17 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:08:17 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:08:17 volumio-r2r volumio[1145]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 15 02:08:17 volumio-r2r volumio[1145]: verbose: ControllerMpd::parseTrackInfo Apr 15 02:08:17 volumio-r2r volumio[1145]: info: ControllerMpd::pushState Apr 15 02:08:17 volumio-r2r volumio[1145]: info: CoreCommandRouter::servicePushState Apr 15 02:08:17 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:17 volumio-r2r volumio[1145]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Apr 15 02:08:17 volumio-r2r volumio[1145]: info: ------------------------------ 2ms Apr 15 02:08:17 volumio-r2r volumio[1145]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 02:08:17 volumio-r2r volumio[1145]: info: CoreCommandRouter::Close All Modals sent Apr 15 02:08:22 volumio-r2r go-librespot[1999]: time="2026-04-15T02:08:22+03:00" level=trace msg="received accesspoint ping" Apr 15 02:08:22 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 15 02:08:22 volumio-r2r volumio[1145]: info: CURURI: music-library Apr 15 02:08:22 volumio-r2r go-librespot[1999]: time="2026-04-15T02:08:22+03:00" level=trace msg="received accesspoint pong ack" Apr 15 02:08:22 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:08:22 volumio-r2r go-librespot[1999]: time="2026-04-15T02:08:22+03:00" level=trace msg="sent dealer ping" Apr 15 02:08:22 volumio-r2r go-librespot[1999]: time="2026-04-15T02:08:22+03:00" level=trace msg="received dealer pong" Apr 15 02:08:23 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 15 02:08:23 volumio-r2r volumio[1145]: info: CURURI: music-library/INTERNAL Apr 15 02:08:23 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:08:23 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 15 02:08:23 volumio-r2r volumio[1145]: info: CURURI: music-library/INTERNAL/Erdal Apr 15 02:08:23 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:08:24 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 15 02:08:24 volumio-r2r volumio[1145]: info: CURURI: music-library/INTERNAL/Erdal/DSDTest Apr 15 02:08:25 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:08:25 volumio-r2r volumio[1145]: info: Preloading song: music-library/INTERNAL/Erdal/DSDTest/01 - David Elias - The Window - Vision of Her (DSD64).dsf Apr 15 02:08:25 volumio-r2r volumio[1145]: info: Preloading song: music-library/INTERNAL/Erdal/DSDTest/1kHz-0dBFS.dsf Apr 15 02:08:25 volumio-r2r volumio[1145]: info: Preloading song: music-library/INTERNAL/Erdal/DSDTest/Scorpions - Acoustica [88985406981] D4.dsf Apr 15 02:08:25 volumio-r2r volumio[1145]: info: Preloading song: music-library/INTERNAL/Erdal/DSDTest/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf Apr 15 02:08:25 volumio-r2r volumio[1145]: info: Preloading song: music-library/INTERNAL/Erdal/DSDTest/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf Apr 15 02:08:25 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/01 - David Elias - The Window - Vision of Her (DSD64).dsf in service mpd Apr 15 02:08:25 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=David%20Elias//medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F01%20-%20David%20Elias%20-%20The%20Window%20-%20Vision%20of%20Her%20(DSD64).dsf&metadata=false Apr 15 02:08:25 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/01 - David Elias - The Window - Vision of Her (DSD64).dsf Apr 15 02:08:25 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/1kHz-0dBFS.dsf in service mpd Apr 15 02:08:25 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=//medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F1kHz-0dBFS.dsf&metadata=false Apr 15 02:08:25 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/1kHz-0dBFS.dsf Apr 15 02:08:25 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/Scorpions - Acoustica [88985406981] D4.dsf in service mpd Apr 15 02:08:25 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=//medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FScorpions%20-%20Acoustica%20%5B88985406981%5D%20D4.dsf&metadata=false Apr 15 02:08:25 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Scorpions - Acoustica [88985406981] D4.dsf Apr 15 02:08:25 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf in service mpd Apr 15 02:08:25 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=David%20Elias/Acoustic%20Trio%20-%20DSD%20Sessions/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F07%20-%20David%20Elias%20-%20Acoustic%20Trio%20-%20Rodeo%20On%20A%20Ridge%20(DSD64).dsf&metadata=false Apr 15 02:08:25 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf Apr 15 02:08:25 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf in service mpd Apr 15 02:08:25 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=David%20Elias/Crossing/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F08%20-%20David%20Elias%20-%20Crossing%20-%20Morning%20Light%20Western%20Town%20(DSD64%202.0).dsf&metadata=false Apr 15 02:08:25 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::ClearQueue Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::stop Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::stPlaybackTimer Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::updateTrackBlock Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrackBlock Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::pushState Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushState Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::serviceStop Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreCommandRouter::serviceStop Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ControllerMpd::stop Apr 15 02:08:26 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CorePlayQueue::clearPlayQueue Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CorePlayQueue::saveQueue Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushQueue Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::addQueueItems Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CorePlayQueue::addQueueItems Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/01 - David Elias - The Window - Vision of Her (DSD64).dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Using cached record of: music-library/INTERNAL/Erdal/DSDTest/01 - David Elias - The Window - Vision of Her (DSD64).dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPushQueue Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CorePlayQueue::saveQueue Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::updateTrackBlock Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrackBlock Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreCommandRouter::volumioPlay Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::play index 0 Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::addQueueItems Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CorePlayQueue::addQueueItems Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Preload queue cleared Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/1kHz-0dBFS.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Using cached record of: music-library/INTERNAL/Erdal/DSDTest/1kHz-0dBFS.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/Scorpions - Acoustica [88985406981] D4.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Using cached record of: music-library/INTERNAL/Erdal/DSDTest/Scorpions - Acoustica [88985406981] D4.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Using cached record of: music-library/INTERNAL/Erdal/DSDTest/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Using cached record of: music-library/INTERNAL/Erdal/DSDTest/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979) Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979) in service mpd Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/5 Tracks in DSD512-st Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/5 Tracks in DSD512-st in service mpd Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK) Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK) in service mpd Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK in service mpd Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/Lipps, Inc. - Mouth To Mouth - 1980,(Holland),DSF(tracks),(CA2ML+NG-6+308+TT-81) Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/Lipps, Inc. - Mouth To Mouth - 1980,(Holland),DSF(tracks),(CA2ML+NG-6+308+TT-81) in service mpd Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/Pink Floyd - The Dark Side of the Moon (2016) Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/Pink Floyd - The Dark Side of the Moon (2016) in service mpd Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/Pink Floyd - The Wall (2016) Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/Pink Floyd - The Wall (2016) in service mpd Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/The Alan Parsons Project - Eye In The Sky - 1982,(Germany),DSF(tracks),(ART-9XA+KIV+SAEC-308+TT-81+K-10R) Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/The Alan Parsons Project - Eye In The Sky - 1982,(Germany),DSF(tracks),(ART-9XA+KIV+SAEC-308+TT-81+K-10R) in service mpd Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/The Alan Parsons Project - I Robot (1977- Japan) Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/The Alan Parsons Project - I Robot (1977- Japan) in service mpd Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/The Alan Parsons Project - The Turn Of A Friendly Card - 1980,(Germany),DSF(tracks),(ART-9XA+KIV+SAEC-308+TT-81+K-10R) Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/The Alan Parsons Project - The Turn Of A Friendly Card - 1980,(Germany),DSF(tracks),(ART-9XA+KIV+SAEC-308+TT-81+K-10R) in service mpd Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::stop Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::play index undefined Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CoreStateMachine::startPlaybackTimer Apr 15 02:08:26 volumio-r2r volumio[1145]: info: CorePlayQueue::getTrack 0 Apr 15 02:08:26 volumio-r2r volumio[1145]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Erdal/DSDTest/01 - David Elias - The Window - Vision of Her (DSD64).dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: verbose: ControllerMpd::sendMpdCommand stop Apr 15 02:08:26 volumio-r2r volumio[1145]: info: Apr 15 02:08:26 volumio-r2r volumio[1145]: ---------------------------- MPD announces state update: player Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Led%20Zeppelin/Led%20Zeppelin/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F(uaoa)%20Led%20Zeppelin%20-%20Led%20Zeppelin%20-%201969%20(1979)%2F01%20-%20Good%20Times%20Bad%20Times.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/01 - Good Times Bad Times.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Led%20Zeppelin/Led%20Zeppelin/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F(uaoa)%20Led%20Zeppelin%20-%20Led%20Zeppelin%20-%201969%20(1979)%2F02%20-%20Babe%20I'm%20Gonna%20Leave%20You.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/02 - Babe I'm Gonna Leave You.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Led%20Zeppelin/Led%20Zeppelin/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F(uaoa)%20Led%20Zeppelin%20-%20Led%20Zeppelin%20-%201969%20(1979)%2F03%20-%20You%20Shook%20Me.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/03 - You Shook Me.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Led%20Zeppelin/Led%20Zeppelin/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F(uaoa)%20Led%20Zeppelin%20-%20Led%20Zeppelin%20-%201969%20(1979)%2F04%20-%20Dazed%20And%20Confused.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/04 - Dazed And Confused.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Led%20Zeppelin/Led%20Zeppelin/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F(uaoa)%20Led%20Zeppelin%20-%20Led%20Zeppelin%20-%201969%20(1979)%2F05%20-%20Your%20Time%20Is%20Gonna%20Come%20-%20Black%20Mountain%20Side.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/05 - Your Time Is Gonna Come - Black Mountain Side.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Led%20Zeppelin/Led%20Zeppelin/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F(uaoa)%20Led%20Zeppelin%20-%20Led%20Zeppelin%20-%201969%20(1979)%2F06%20-%20Communication%20Breakdown.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/06 - Communication Breakdown.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Led%20Zeppelin/Led%20Zeppelin/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F(uaoa)%20Led%20Zeppelin%20-%20Led%20Zeppelin%20-%201969%20(1979)%2F07%20-%20I%20Can't%20Quit%20You%20Baby.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/07 - I Can't Quit You Baby.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Led%20Zeppelin/Led%20Zeppelin/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F(uaoa)%20Led%20Zeppelin%20-%20Led%20Zeppelin%20-%201969%20(1979)%2F08%20-%20How%20Many%20More%20Times.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/08 - How Many More Times.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Les%20Chats%20Noirs/Les%20Chats%20Noirs/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F5%20Tracks%20in%20DSD512-st%2F1.%20Les%20Chats%20Noirs-La%20Chanson-DSD512.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/5 Tracks in DSD512-st/1. Les Chats Noirs-La Chanson-DSD512.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Yuko%20Mabuchi%20(piano)%2C%20JJ%20Kirkpatrick%20(trumpet)%2C%20Del%20Atkins%20(bass)%2C%20Bobby%20Breton%20(drums)/Yuko%20Mabuchi%20plays%20Miles%20Davis/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F5%20Tracks%20in%20DSD512-st%2F2.%20Miles%20Davis-Milestones%2C%20Yuko%20Mabuchi-DSD512.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/5 Tracks in DSD512-st/2. Miles Davis-Milestones, Yuko Mabuchi-DSD512.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Rachel%20Podger%2C%20Brecon%20Baroque/Le%20Quattro%20Stagioni/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F5%20Tracks%20in%20DSD512-st%2F3.%20Vivaldi-The%20Four%20Seasons-Winter%2C%20Rachel%20Podger%2C%20Brecon%20Baroque-1.Largo-DSD512.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/5 Tracks in DSD512-st/3. Vivaldi-The Four Seasons-Winter, Rachel Podger, Brecon Baroque-1.Largo-DSD512.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Ricardo%20Gall%C3%A9n/En%20Silencio/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F5%20Tracks%20in%20DSD512-st%2F4.%20Barrios-La%20Catedral%2C%20Ricardo%20Gall%C3%A9n-3.Allegro%20solemne-DSD512.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/5 Tracks in DSD512-st/4. Barrios-La Catedral, Ricardo Gallén-3.Allegro solemne-DSD512.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Feenbrothers/Play%20Dave%20Brubeck/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F5%20Tracks%20in%20DSD512-st%2F5.%20Dave%20Brubeck-Take%20Five%20Reprise%20(Desmond)%2C%20Feenbrothers-DSD512.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/5 Tracks in DSD512-st/5. Dave Brubeck-Take Five Reprise (Desmond), Feenbrothers-DSD512.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=//medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDeep%20Purple%20-%20Machine%20Head%20(1972%20UK)%2F01%20-%20Highway%20Star.dff&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)/01 - Highway Star.dff Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=//medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDeep%20Purple%20-%20Machine%20Head%20(1972%20UK)%2F02%20-%20Maybe%20I'm%20A%20Leo.dff&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)/02 - Maybe I'm A Leo.dff Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=//medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDeep%20Purple%20-%20Machine%20Head%20(1972%20UK)%2F03%20-%20Pictures%20Of%20Home.dff&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)/03 - Pictures Of Home.dff Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=//medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDeep%20Purple%20-%20Machine%20Head%20(1972%20UK)%2F04%20-%20Never%20Before.dff&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)/04 - Never Before.dff Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=//medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDeep%20Purple%20-%20Machine%20Head%20(1972%20UK)%2F05%20-%20Smoke%20On%20The%20Water.dff&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)/05 - Smoke On The Water.dff Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=//medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDeep%20Purple%20-%20Machine%20Head%20(1972%20UK)%2F06%20-%20Lazy.dff&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)/06 - Lazy.dff Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=//medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDeep%20Purple%20-%20Machine%20Head%20(1972%20UK)%2F07%20-%20Space%20Truckin'.dff&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)/07 - Space Truckin'.dff Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Dire%20Straits/Brothers%20In%20Arms/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDire%20Straits%20-%20Brothers%20In%20Arms%201985%20UK%2F01%20-%20So%20Far%20Away.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/01 - So Far Away.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Dire%20Straits/Brothers%20In%20Arms/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDire%20Straits%20-%20Brothers%20In%20Arms%201985%20UK%2F02%20-%20Money%20For%20Nothing.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/02 - Money For Nothing.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Dire%20Straits/Brothers%20In%20Arms/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDire%20Straits%20-%20Brothers%20In%20Arms%201985%20UK%2F03%20-%20Walk%20Of%20Life.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/03 - Walk Of Life.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Dire%20Straits/Brothers%20In%20Arms/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDire%20Straits%20-%20Brothers%20In%20Arms%201985%20UK%2F04%20-%20Your%20Latest%20Trick.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/04 - Your Latest Trick.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Dire%20Straits/Brothers%20In%20Arms/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDire%20Straits%20-%20Brothers%20In%20Arms%201985%20UK%2F05%20-%20Why%20Worry.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/05 - Why Worry.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Dire%20Straits/Brothers%20In%20Arms/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDire%20Straits%20-%20Brothers%20In%20Arms%201985%20UK%2F06%20-%20Ride%20Across%20The%20River.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/06 - Ride Across The River.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Dire%20Straits/Brothers%20In%20Arms/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDire%20Straits%20-%20Brothers%20In%20Arms%201985%20UK%2F07%20-%20The%20Man's%20Too%20Strong.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/07 - The Man's Too Strong.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Dire%20Straits/Brothers%20In%20Arms/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDire%20Straits%20-%20Brothers%20In%20Arms%201985%20UK%2F08%20-%20One%20World.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/08 - One World.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Dire%20Straits/Brothers%20In%20Arms/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FDire%20Straits%20-%20Brothers%20In%20Arms%201985%20UK%2F09%20-%20Brothers%20In%20Arms.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/09 - Brothers In Arms.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Lipps%2C%20Inc./Mouth%20To%20Mouth%2C(CA2ML%2BNG-6%2B308%2BTT-81)/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FLipps%2C%20Inc.%20-%20Mouth%20To%20Mouth%20-%201980%2C(Holland)%2CDSF(tracks)%2C(CA2ML%2BNG-6%2B308%2BTT-81)%2FA1%20Funkytown.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Lipps, Inc. - Mouth To Mouth - 1980,(Holland),DSF(tracks),(CA2ML+NG-6+308+TT-81)/A1 Funkytown.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Lipps%2C%20Inc./Mouth%20To%20Mouth%2C(CA2ML%2BNG-6%2B308%2BTT-81)/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FLipps%2C%20Inc.%20-%20Mouth%20To%20Mouth%20-%201980%2C(Holland)%2CDSF(tracks)%2C(CA2ML%2BNG-6%2B308%2BTT-81)%2FA2%20All%20Night%20Dancing.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Lipps, Inc. - Mouth To Mouth - 1980,(Holland),DSF(tracks),(CA2ML+NG-6+308+TT-81)/A2 All Night Dancing.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Lipps%2C%20Inc./Mouth%20To%20Mouth%2C(CA2ML%2BNG-6%2B308%2BTT-81)/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FLipps%2C%20Inc.%20-%20Mouth%20To%20Mouth%20-%201980%2C(Holland)%2CDSF(tracks)%2C(CA2ML%2BNG-6%2B308%2BTT-81)%2FB1%20Rock%20It.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Lipps, Inc. - Mouth To Mouth - 1980,(Holland),DSF(tracks),(CA2ML+NG-6+308+TT-81)/B1 Rock It.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=Lipps%2C%20Inc./Mouth%20To%20Mouth%2C(CA2ML%2BNG-6%2B308%2BTT-81)/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FLipps%2C%20Inc.%20-%20Mouth%20To%20Mouth%20-%201980%2C(Holland)%2CDSF(tracks)%2C(CA2ML%2BNG-6%2B308%2BTT-81)%2FB2%20Power.dsf&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Lipps, Inc. - Mouth To Mouth - 1980,(Holland),DSF(tracks),(CA2ML+NG-6+308+TT-81)/B2 Power.dsf Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=/The%20Dark%20Side%20Of%20The%20Moon/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FPink%20Floyd%20-%20The%20Dark%20Side%20of%20the%20Moon%20(2016)%2FPink%20Floyd%20-%20The%20Dark%20Side%20of%20The%20Moon.cue&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Pink Floyd - The Dark Side of the Moon (2016)/Pink Floyd - The Dark Side of The Moon.cue Apr 15 02:08:26 volumio-r2r volumio[1145]: info: ALBUMART /albumart?cacheid=671&web=/The%20Dark%20Side%20Of%20The%20Moon/medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FPink%20Floyd%20-%20The%20Dark%20Side%20of%20the%20Moon%20(2016)%2FPink%20Floyd%20-%20The%20Dark%20Side%20of%20The%20Moon.cue&metadata=false Apr 15 02:08:26 volumio-r2r volumio[1145]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Pink Floyd - The Dark Side of the Moon (2016)/Pink Floyd - The Dark Side of The Moon.cue Apr 15 02:08:26 volumio-r2r volumio[1145]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 02:08:26 volumio-r2r volumio[1145]: Error: Unable to resolve or reject the same promise twice Apr 15 02:08:26 volumio-r2r volumio[1145]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Apr 15 02:08:26 volumio-r2r volumio[1145]: at /volumio/app/plugins/music_service/mpd/index.js:2569:21 Apr 15 02:08:26 volumio-r2r volumio[1145]: at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3) Apr 15 02:08:26 volumio-r2r volumio[1145]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12) Apr 15 02:08:26 volumio-r2r volumio[1145]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 15 02:08:26 volumio-r2r volumio[1145]: at Socket.emit (node:events:514:28) Apr 15 02:08:26 volumio-r2r volumio[1145]: at addChunk (node:internal/streams/readable:343:12) Apr 15 02:08:26 volumio-r2r volumio[1145]: at readableAddChunk (node:internal/streams/readable:312:11) Apr 15 02:08:26 volumio-r2r volumio[1145]: at Readable.push (node:internal/streams/readable:253:10) Apr 15 02:08:26 volumio-r2r volumio[1145]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Apr 15 02:08:26 volumio-r2r volumio[1145]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 02:08:26 volumio-r2r sudo[4138]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-15 02:07' Apr 15 02:08:26 volumio-r2r sudo[4138]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"