Apr 11 17:30:21 volumio-pi4 volumio[1258]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:30:21 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 11 17:30:21 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:30:21 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:30:21 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:30:21 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:30:21 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:30:21 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:30:21 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:30:21 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:30:21 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:30:21 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 11 17:30:21 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: Preparing to save Alsa Options, stopping services first Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioGetState Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPause Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreStateMachine::pause Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"HIFI DSD"},"i2s":false,"i2sid":{"value":"adafruit-max98357","label":"Adafruit MAX98357"}} Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: Setting mixer HIFI DSD for card HIFI DSD Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioUpdateVolumeSettings Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: Updating Volume Controller Parameters: Device: 5 Name: HIFI DSD Mixer: HIFI DSD Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: Disabling external Volume Control Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: Preparing to generate the ALSA configuration file Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: VolumeController:: Volume=100 Mute =false Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioGetState Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreStateMachine::pushState Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushState Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: Asound.conf file written Apr 11 17:30:24 volumio-pi4 sudo[4436]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 11 17:30:24 volumio-pi4 sudo[4436]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:30:24 volumio-pi4 sudo[4436]: pam_unix(sudo:session): session closed for user root Apr 11 17:30:24 volumio-pi4 volumio[1258]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Apr 11 17:30:24 volumio-pi4 volumio[1258]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Apr 11 17:30:24 volumio-pi4 volumio[1258]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Apr 11 17:30:24 volumio-pi4 volumio[1258]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: Output device has changed, restarting MPD Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: Output device has changed, restarting Shairport Sync Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:30:24 volumio-pi4 sudo[4442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 17:30:24 volumio-pi4 sudo[4442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:30:24 volumio-pi4 sudo[4442]: pam_unix(sudo:session): session closed for user root Apr 11 17:30:24 volumio-pi4 sudo[4444]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 17:30:24 volumio-pi4 sudo[4444]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: Output device has changed, restarting MPD Apr 11 17:30:24 volumio-pi4 systemd[1]: Stopping mpd.service - Music Player Daemon... Apr 11 17:30:24 volumio-pi4 sudo[4452]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 11 17:30:24 volumio-pi4 sudo[4452]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: Output device has changed, restarting Shairport Sync Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:30:24 volumio-pi4 sudo[4452]: pam_unix(sudo:session): session closed for user root Apr 11 17:30:24 volumio-pi4 sudo[4455]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 11 17:30:24 volumio-pi4 sudo[4455]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:30:24 volumio-pi4 volumio[1258]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 11 17:30:25 volumio-pi4 systemd[1]: mpd.service: Deactivated successfully. Apr 11 17:30:25 volumio-pi4 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 11 17:30:25 volumio-pi4 systemd[1]: mpd.service: Consumed 1.558s CPU time. Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: MPD Permissions set Apr 11 17:30:25 volumio-pi4 systemd[1]: mpd.socket: Deactivated successfully. Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: MPD Permissions set Apr 11 17:30:25 volumio-pi4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 11 17:30:25 volumio-pi4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioGetState Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: Starting Shairport Sync Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: Starting Shairport Sync Apr 11 17:30:25 volumio-pi4 sudo[4476]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 17:30:25 volumio-pi4 sudo[4476]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:30:25 volumio-pi4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 17:30:25 volumio-pi4 sudo[4478]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 11 17:30:25 volumio-pi4 sudo[4478]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Apr 11 17:30:25 volumio-pi4 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 17:30:25 volumio-pi4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 17:30:25 volumio-pi4 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 17:30:25 volumio-pi4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:30:25 volumio-pi4 systemd[1]: shairport-sync.service: Consumed 1.975s CPU time. Apr 11 17:30:25 volumio-pi4 systemd[1]: mpd.service: Deactivated successfully. Apr 11 17:30:25 volumio-pi4 systemd[1]: Stopped mpd.service - Music Player Daemon. Apr 11 17:30:25 volumio-pi4 systemd[1]: mpd.socket: Deactivated successfully. Apr 11 17:30:25 volumio-pi4 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Apr 11 17:30:25 volumio-pi4 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Apr 11 17:30:25 volumio-pi4 volumio[1258]: error: [ytcr] Error connecting MPD: Apr 11 17:30:25 volumio-pi4 volumio[1258]: (Error) read ECONNRESET Apr 11 17:30:25 volumio-pi4 volumio[1258]: Error: read ECONNRESET Apr 11 17:30:25 volumio-pi4 volumio[1258]: at Pipe.onStreamRead (node:internal/stream_base_commons:217:20) Apr 11 17:30:25 volumio-pi4 volumio[1258]: Retrying in 5 seconds... Apr 11 17:30:25 volumio-pi4 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Apr 11 17:30:25 volumio-pi4 systemd[1]: Starting mpd.service - Music Player Daemon... Apr 11 17:30:25 volumio-pi4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:30:25 volumio-pi4 sudo[4476]: pam_unix(sudo:session): session closed for user root Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: Shairport-Sync Started Apr 11 17:30:25 volumio-pi4 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Apr 11 17:30:25 volumio-pi4 systemd[1]: shairport-sync.service: Deactivated successfully. Apr 11 17:30:25 volumio-pi4 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:30:25 volumio-pi4 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Apr 11 17:30:25 volumio-pi4 sudo[4478]: pam_unix(sudo:session): session closed for user root Apr 11 17:30:25 volumio-pi4 volumio[1258]: info: Shairport-Sync Started Apr 11 17:30:25 volumio-pi4 sudo[4483]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 11 17:30:25 volumio-pi4 sudo[4483]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Apr 11 17:30:25 volumio-pi4 sudo[4483]: pam_unix(sudo:session): session closed for user root Apr 11 17:30:26 volumio-pi4 mpd[4501]: 2026-04-11T17:30:26 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 11 17:30:26 volumio-pi4 systemd[1]: Started mpd.service - Music Player Daemon. Apr 11 17:30:26 volumio-pi4 sudo[4455]: pam_unix(sudo:session): session closed for user root Apr 11 17:30:26 volumio-pi4 sudo[4444]: pam_unix(sudo:session): session closed for user root Apr 11 17:30:26 volumio-pi4 volumio[1258]: error: MPD error: The expression evaluated to a falsy value: Apr 11 17:30:26 volumio-pi4 volumio[1258]: assert.ok(self.idling) Apr 11 17:30:26 volumio-pi4 volumio[1258]: error: The expression evaluated to a falsy value: Apr 11 17:30:26 volumio-pi4 volumio[1258]: assert.ok(self.idling) Apr 11 17:30:26 volumio-pi4 volumio[1258]: error: updateQueue error: null Apr 11 17:30:28 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Apr 11 17:30:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::getcurrentVolume Apr 11 17:30:28 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 17:30:28 volumio-pi4 volumio[1258]: info: VolumeController:: Volume=100 Mute =false Apr 11 17:30:28 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioGetState Apr 11 17:30:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::pushState Apr 11 17:30:28 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushState Apr 11 17:30:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::updateTrackBlock Apr 11 17:30:28 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrackBlock Apr 11 17:30:28 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioRetrievevolume Apr 11 17:30:28 volumio-pi4 volumio[1258]: info: VolumeController:: Volume=100 Mute =false Apr 11 17:30:28 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioGetState Apr 11 17:30:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::pushState Apr 11 17:30:28 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushState Apr 11 17:30:29 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 11 17:30:32 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioGetState Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPlay Apr 11 17:30:34 volumio-pi4 volumio[1258]: verbose: UNSET VOLATILE: Service: soundcloud Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) Volatile state unset, stopping playback (if any)... Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) Push Volumio state: {"status":"stop","albumart":"/albumart","uri":"","seek":19000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":false} Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreCommandRouter::servicePushState Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreStateMachine::pushState Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushState Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrack 5 Apr 11 17:30:34 volumio-pi4 volumio[1258]: verbose: STATE SERVICE {"status":"stop","albumart":"/albumart","uri":"","seek":19000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":false} Apr 11 17:30:34 volumio-pi4 volumio[1258]: verbose: CURRENT POSITION 5 Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreStateMachine::syncState stateService stop Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreStateMachine::syncState currentStatus pause Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreStateMachine::pushState Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushState Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreStateMachine::stPlaybackTimer Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreStateMachine::updateTrackBlock Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrackBlock Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreStateMachine::play index 5 Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreStateMachine::stop Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreStateMachine::play index undefined Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrack 5 Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CoreStateMachine::startPlaybackTimer Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrack 5 Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: [soundcloud] clearAddPlayTrack: soundcloud/track@trackId=1883537676 Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: [soundcloud-testing] Available transcodings: [{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1883537676/e8b30a5a-9ad4-489b-a226-f3e4209f05a1/stream/hls","preset":"aac_160k","protocol":"hls","mimeType":"audio/mp4; codecs=\"mp4a.40.2\"","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1883537676/d6c2f161-de47-41cc-91bb-900053a3ec5d/stream/hls","preset":"abr_sq","protocol":"hls","mimeType":"audio/mpegurl","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1883537676/6fcc9c73-601e-4d11-b370-e2c32e3e4e85/stream/hls","preset":"mp3_1_0","protocol":"hls","mimeType":"audio/mpeg","quality":"sq"},{"url":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1883537676/6fcc9c73-601e-4d11-b370-e2c32e3e4e85/stream/progressive","preset":"mp3_1_0","protocol":"progressive","mimeType":"audio/mpeg","quality":"sq"}] Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: [soundcloud-testing] Chosen transcoding: {"format":"aac_160k+hls","codec":"aac","protocol":"hls","bitrate":"160 kbps","transcodingUrl":"https://api-v2.soundcloud.com/media/soundcloud:tracks:1883537676/e8b30a5a-9ad4-489b-a226-f3e4209f05a1/stream/hls"} Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) [b1e19228] http demux error: local stream 13 error: Cancellation (0x8) Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) [b1e19228] http demux error: local stream 1 error: Cancellation (0x8) Apr 11 17:30:34 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) Player status "stopped" - unsetting ourselves as current service... Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioGetState Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrack 5 Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) Stopping playback by current service... Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioStop Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreStateMachine::stop Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) Setting ourselves as the current service... Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) Push Volumio state: {"status":"pause","albumart":"https://i1.sndcdn.com/artworks-pZT1bS1givMfDMJX-51hxJg-t500x500.jpg","uri":"soundcloud/track@trackId=1883537676","seek":0,"duration":0,"service":"soundcloud","artist":"Fred again..","album":"SoundCloud Track","name":"Rooftop Live (Arun’s Roof, London)","title":"Rooftop Live (Arun’s Roof, London)","trackType":"aac","samplerate":"160 kbps","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":false} Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreCommandRouter::servicePushState Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreStateMachine::pushState Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushState Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioGetState Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=1883537676","title":"Rooftop Live (Arun’s Roof, London)","artist":"Fred again..","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-pZT1bS1givMfDMJX-51hxJg-t500x500.jpg","trackType":"aac","duration":-1,"samplerate":"160 kbps","service":"soundcloud","seek":0,"isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":false} Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreCommandRouter::servicePushState Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreStateMachine::pushState Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushState Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: touch_display: Setting screensaver timeout to 0 seconds. Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioGetState Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) Push Volumio state: {"status":"play","uri":"soundcloud/track@trackId=1883537676","title":"Rooftop Live (Arun’s Roof, London)","artist":"Fred again..","album":"SoundCloud Track","albumart":"https://i1.sndcdn.com/artworks-pZT1bS1givMfDMJX-51hxJg-t500x500.jpg","trackType":"aac","duration":6192,"samplerate":"160 kbps","service":"soundcloud","seek":0,"isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":false} Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreCommandRouter::servicePushState Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreStateMachine::pushState Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushState Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) [b229d7e8] adaptive demux: Changing stream format Unknown -> MP4 Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) [b0fc5df0] mp4 demux: Fragment sequence discontinuity detected 1 != 0 Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) [b229d7e8] http demux error: local stream 1 error: Cancellation (0x8) Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) [b229d7e8] http demux error: local stream 3 error: Cancellation (0x8) Apr 11 17:30:35 volumio-pi4 volumio[1258]: info: Added "Rooftop Live (Arun’s Roof, London)" to play history Apr 11 17:30:36 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) [b229d7e8] http demux error: local stream 5 error: Cancellation (0x8) Apr 11 17:30:36 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) [b229d7e8] http demux error: local stream 7 error: Cancellation (0x8) Apr 11 17:30:44 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) [b229d7e8] http demux error: local stream 9 error: Cancellation (0x8) Apr 11 17:30:54 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) [b229d7e8] http demux error: local stream 11 error: Cancellation (0x8) Apr 11 17:30:59 volumio-pi4 ntpd[1052]: PROTO: 119.28.230.190 unlink local addr 192.168.11.15 -> Apr 11 17:31:04 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) [b229d7e8] http demux error: local stream 13 error: Cancellation (0x8) Apr 11 17:31:12 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Apr 11 17:31:12 volumio-pi4 volumio[1258]: info: [jellyfin-browse] browseUri: jellyfin Apr 11 17:31:12 volumio-pi4 volumio[1258]: info: Preload queue cleared Apr 11 17:31:13 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Apr 11 17:31:13 volumio-pi4 volumio[1258]: info: [jellyfin-browse] browseUri: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497 Apr 11 17:31:13 volumio-pi4 volumio[1258]: info: [jellyfin-conn] Using previously assigned device Id for vvv@avtm1: 9187b784-301e-4456-8aa6-8132c0fff599 Apr 11 17:31:14 volumio-pi4 volumio[1258]: info: [jellyfin-conn] Login successful: vvv@avtm1 Apr 11 17:31:14 volumio-pi4 volumio[1258]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"f8b3085dfb5e40719b8f85f3c9bf4138","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"startIndex":0,"limit":47,"sortBy":["SortName"],"sortOrder":["Ascending"]} Apr 11 17:31:14 volumio-pi4 volumio[1258]: verbose: [jellyfin] getItemsFromAPI(): http://avtm1.lan:8096/UserViews?userId=f8b3085dfb5e40719b8f85f3c9bf4138 Apr 11 17:31:14 volumio-pi4 volumio[1258]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"f8b3085dfb5e40719b8f85f3c9bf4138","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"cb2cddcf70ad59d435f6cba80d3c19e6","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Apr 11 17:31:14 volumio-pi4 volumio[1258]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"f8b3085dfb5e40719b8f85f3c9bf4138","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"e1e3ec17ee4defbd3a633407f833adb2","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Apr 11 17:31:14 volumio-pi4 volumio[1258]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"f8b3085dfb5e40719b8f85f3c9bf4138","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"25eb8e7e53809155e5285f1e5061ded1","startIndex":0,"limit":11,"sortBy":["DateCreated","SortName"],"sortOrder":["Descending","Ascending"],"includeItemTypes":["MusicAlbum"]} Apr 11 17:31:14 volumio-pi4 volumio[1258]: verbose: [jellyfin] getItemsFromAPI(): http://avtm1.lan:8096/Items?userId=f8b3085dfb5e40719b8f85f3c9bf4138&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=e1e3ec17ee4defbd3a633407f833adb2&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Apr 11 17:31:14 volumio-pi4 volumio[1258]: verbose: [jellyfin] getItemsFromAPI(): http://avtm1.lan:8096/Items?userId=f8b3085dfb5e40719b8f85f3c9bf4138&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=cb2cddcf70ad59d435f6cba80d3c19e6&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Apr 11 17:31:14 volumio-pi4 volumio[1258]: verbose: [jellyfin] getItemsFromAPI(): http://avtm1.lan:8096/Items?userId=f8b3085dfb5e40719b8f85f3c9bf4138&startIndex=0&limit=11&recursive=true&sortOrder=Descending&sortOrder=Ascending&parentId=25eb8e7e53809155e5285f1e5061ded1&includeItemTypes=MusicAlbum&sortBy=DateCreated&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Apr 11 17:31:14 volumio-pi4 volumio[1258]: info: Preload queue cleared Apr 11 17:31:14 volumio-pi4 volumio[1258]: info: Preload queue cleared Apr 11 17:31:14 volumio-pi4 volumio[1258]: info: Preload queue cleared Apr 11 17:31:14 volumio-pi4 volumio[1258]: info: Preload queue cleared Apr 11 17:31:14 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) [b229d7e8] http demux error: local stream 15 error: Cancellation (0x8) Apr 11 17:31:15 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: jellyfin , handleBrowseUri Apr 11 17:31:15 volumio-pi4 volumio[1258]: info: [jellyfin-browse] browseUri: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d Apr 11 17:31:15 volumio-pi4 volumio[1258]: verbose: [jellyfin] getItemsFromAPI(): {"userId":"f8b3085dfb5e40719b8f85f3c9bf4138","enableImageTypes":["Primary"],"imageTypeLimit":1,"recursive":true,"parentId":"c5079d03a62696fac8d594f9bb0d845d","startIndex":0,"sortBy":["SortName"],"sortOrder":["Ascending"],"fields":["MediaSources"],"includeItemTypes":["Audio"]} Apr 11 17:31:17 volumio-pi4 volumio[1258]: verbose: [jellyfin] getItemsFromAPI(): http://avtm1.lan:8096/Items?userId=f8b3085dfb5e40719b8f85f3c9bf4138&startIndex=0&recursive=true&sortOrder=Ascending&parentId=c5079d03a62696fac8d594f9bb0d845d&fields=MediaSources&includeItemTypes=Audio&sortBy=SortName&imageTypeLimit=1&enableImageTypes=Primary Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Preload queue cleared Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Preloading song: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=95a79f5e8c319f94550118757b444a71 Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Preloading song: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=5bd48c331e21b4334f868bd40d1a50b3 Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Preloading song: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=95759808fb2f783184f807a9152e236f Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Preload queue cleared Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: No valid Plugin REST Endpoint Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Preload queue cleared Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::ClearQueue Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::stop Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::serviceStop Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreCommandRouter::serviceStop Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioGetState Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::stPlaybackTimer Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::updateTrackBlock Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrackBlock Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CorePlayQueue::clearPlayQueue Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CorePlayQueue::saveQueue Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::addQueueItems Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CorePlayQueue::addQueueItems Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Preload queue cleared Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=95a79f5e8c319f94550118757b444a71 Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Exploding uri jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=95a79f5e8c319f94550118757b444a71 in service jellyfin Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: [jellyfin-browse] explodeUri: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=95a79f5e8c319f94550118757b444a71 Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) [b229d7e8] http demux error: local stream 17 error: Cancellation (0x8) Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) [b229d7e8] http demux error: local stream 1 error: Cancellation (0x8) Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CorePlayQueue::saveQueue Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::updateTrackBlock Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrackBlock Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPlay Apr 11 17:31:18 volumio-pi4 volumio[1258]: verbose: UNSET VOLATILE: Service: soundcloud Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) Volatile state unset, stopping playback (if any)... Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) Push Volumio state: {"status":"stop","albumart":"/albumart","uri":"","seek":42000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":false} Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreCommandRouter::servicePushState Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::pushState Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushState Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrack 5 Apr 11 17:31:18 volumio-pi4 volumio[1258]: verbose: STATE SERVICE {"status":"stop","albumart":"/albumart","uri":"","seek":42000,"duration":0,"service":"soundcloud","isStreaming":false,"repeat":false,"repeatSingle":false,"random":false,"volume":100,"mute":false,"disableVolumeControl":false} Apr 11 17:31:18 volumio-pi4 volumio[1258]: verbose: CURRENT POSITION 5 Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::syncState stateService stop Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::syncState currentStatus stop Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::pushState Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushState Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: No code Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::pushState Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushState Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::play index 0 Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::addQueueItems Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CorePlayQueue::addQueueItems Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Preload queue cleared Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=5bd48c331e21b4334f868bd40d1a50b3 Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Exploding uri jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=5bd48c331e21b4334f868bd40d1a50b3 in service jellyfin Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: [jellyfin-browse] explodeUri: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=5bd48c331e21b4334f868bd40d1a50b3 Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=95759808fb2f783184f807a9152e236f Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Exploding uri jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=95759808fb2f783184f807a9152e236f in service jellyfin Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: [jellyfin-browse] explodeUri: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=95759808fb2f783184f807a9152e236f Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::stop Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::play index undefined Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrack 0 Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::startPlaybackTimer Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrack 0 Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/song@songId=95a79f5e8c319f94550118757b444a71 Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: [jellyfin-play] Stream URL for Varuna Title Screen: http://avtm1.lan:8096/Audio/95a79f5e8c319f94550118757b444a71/stream.flac?static=true&mediaSourceId=95a79f5e8c319f94550118757b444a71&tag=e01d017f328c38d40b645c524abca8fa Apr 11 17:31:18 volumio-pi4 volumio[1258]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: sendMpdCommand stop took 2 milliseconds Apr 11 17:31:18 volumio-pi4 volumio[1258]: verbose: ControllerMpd::sendMpdCommand clear Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Apr 11 17:31:18 volumio-pi4 volumio[1258]: ---------------------------- MPD announces system playlist update Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Ignoring MPD Status Update Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: sendMpdCommand clear took 3 milliseconds Apr 11 17:31:18 volumio-pi4 volumio[1258]: verbose: ControllerMpd::sendMpdCommand load "http://avtm1.lan:8096/Audio/95a79f5e8c319f94550118757b444a71/stream.flac?static=true&mediaSourceId=95a79f5e8c319f94550118757b444a71&tag=e01d017f328c38d40b645c524abca8fa&t.flac" Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Apr 11 17:31:18 volumio-pi4 volumio[1258]: ---------------------------- MPD announces system playlist update Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: Ignoring MPD Status Update Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CorePlayQueue::saveQueue Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CoreStateMachine::updateTrackBlock Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrackBlock Apr 11 17:31:18 volumio-pi4 volumio[1258]: error: updateQueue error: null Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: ------------------------------ 15ms Apr 11 17:31:18 volumio-pi4 volumio[1258]: info: [soundcloud] [vlc] (PID: 3460) Player status "stopped" - unsetting ourselves as current service... Apr 11 17:31:27 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPause Apr 11 17:31:27 volumio-pi4 volumio[1258]: info: CoreStateMachine::pause Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: Preload queue cleared Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::ClearQueue Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::stop Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CorePlayQueue::clearPlayQueue Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CorePlayQueue::saveQueue Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::addQueueItems Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CorePlayQueue::addQueueItems Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: Preload queue cleared Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=95a79f5e8c319f94550118757b444a71 Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=95a79f5e8c319f94550118757b444a71 Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CorePlayQueue::saveQueue Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::updateTrackBlock Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrackBlock Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPlay Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::play index 0 Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::addQueueItems Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CorePlayQueue::addQueueItems Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: Preload queue cleared Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=5bd48c331e21b4334f868bd40d1a50b3 Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=5bd48c331e21b4334f868bd40d1a50b3 Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: Adding Item to queue: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=95759808fb2f783184f807a9152e236f Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: Using cached record of: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/songs@albumId=c5079d03a62696fac8d594f9bb0d845d/song@songId=95759808fb2f783184f807a9152e236f Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::stop Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreCommandRouter::volumioPushQueue Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CorePlayQueue::saveQueue Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::play index undefined Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::updateTrackBlock Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrackBlock Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrack 0 Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CoreStateMachine::startPlaybackTimer Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: CorePlayQueue::getTrack 0 Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: [jellyfin-play] clearAddPlayTrack: jellyfin/vvv@54189ce4319a483c9a68c49cb3002497/song@songId=95a79f5e8c319f94550118757b444a71 Apr 11 17:31:28 volumio-pi4 volumio[1258]: info: [jellyfin-play] Stream URL for Varuna Title Screen: http://avtm1.lan:8096/Audio/95a79f5e8c319f94550118757b444a71/stream.flac?static=true&mediaSourceId=95a79f5e8c319f94550118757b444a71&tag=e01d017f328c38d40b645c524abca8fa Apr 11 17:31:28 volumio-pi4 volumio[1258]: verbose: ControllerMpd::sendMpdCommand stop Apr 11 17:31:31 volumio-pi4 volumio[1258]: error: updateQueue error: null Apr 11 17:31:31 volumio-pi4 volumio[1258]: verbose: ControllerMpd::sendMpdCommand addid "http://avtm1.lan:8096/Audio/95a79f5e8c319f94550118757b444a71/stream.flac?static=true&mediaSourceId=95a79f5e8c319f94550118757b444a71&tag=e01d017f328c38d40b645c524abca8fa&t.flac" Apr 11 17:31:31 volumio-pi4 volumio[1258]: info: ------------------------------ 12944ms Apr 11 17:31:31 volumio-pi4 volumio[1258]: info: sendMpdCommand stop took 3127 milliseconds Apr 11 17:31:31 volumio-pi4 volumio[1258]: verbose: ControllerMpd::sendMpdCommand clear Apr 11 17:31:31 volumio-pi4 volumio[1258]: info: Apr 11 17:31:31 volumio-pi4 volumio[1258]: ---------------------------- MPD announces system playlist update Apr 11 17:31:31 volumio-pi4 volumio[1258]: info: Ignoring MPD Status Update Apr 11 17:31:31 volumio-pi4 volumio[1258]: info: sendMpdCommand addid "http://avtm1.lan:8096/Audio/95a79f5e8c319f94550118757b444a71/stream.flac?static=true&mediaSourceId=95a79f5e8c319f94550118757b444a71&tag=e01d017f328c38d40b645c524abca8fa&t.flac" took 2 milliseconds Apr 11 17:31:31 volumio-pi4 volumio[1258]: info: sendMpdCommand clear took 2 milliseconds Apr 11 17:31:31 volumio-pi4 volumio[1258]: verbose: ControllerMpd::sendMpdCommand load "http://avtm1.lan:8096/Audio/95a79f5e8c319f94550118757b444a71/stream.flac?static=true&mediaSourceId=95a79f5e8c319f94550118757b444a71&tag=e01d017f328c38d40b645c524abca8fa&t.flac" Apr 11 17:31:31 volumio-pi4 volumio[1258]: verbose: MPD COMMAND [object Object] Apr 11 17:31:31 volumio-pi4 volumio[1258]: verbose: MPD COMMAND [object Object] Apr 11 17:31:31 volumio-pi4 volumio[1258]: verbose: MPD COMMAND [object Object] Apr 11 17:31:31 volumio-pi4 volumio[1258]: info: Apr 11 17:31:31 volumio-pi4 volumio[1258]: ---------------------------- MPD announces system playlist update Apr 11 17:31:31 volumio-pi4 volumio[1258]: info: Ignoring MPD Status Update Apr 11 17:31:31 volumio-pi4 volumio[1258]: error: updateQueue error: null Apr 11 17:31:31 volumio-pi4 volumio[1258]: info: ------------------------------ 5ms Apr 11 17:31:31 volumio-pi4 volumio[1258]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 17:31:31 volumio-pi4 volumio[1258]: Error: [50@0] {addtagid} No such song Apr 11 17:31:31 volumio-pi4 volumio[1258]: at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:63:17) Apr 11 17:31:31 volumio-pi4 volumio[1258]: at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12) Apr 11 17:31:31 volumio-pi4 volumio[1258]: at Socket.emit (node:events:514:28) Apr 11 17:31:31 volumio-pi4 volumio[1258]: at addChunk (node:internal/streams/readable:343:12) Apr 11 17:31:31 volumio-pi4 volumio[1258]: at readableAddChunk (node:internal/streams/readable:312:11) Apr 11 17:31:31 volumio-pi4 volumio[1258]: at Readable.push (node:internal/streams/readable:253:10) Apr 11 17:31:31 volumio-pi4 volumio[1258]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Apr 11 17:31:31 volumio-pi4 volumio[1258]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 11 17:31:32 volumio-pi4 sudo[4695]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-11 17:30' Apr 11 17:31:32 volumio-pi4 sudo[4695]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"