Apr 14 23:39:00 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:00 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:00 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:00 volumio-r2r volumio[1139]: info:
Apr 14 23:39:00 volumio-r2r volumio[1139]: ---------------------------- Client requests Volumio play
Apr 14 23:39:00 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:00 volumio-r2r volumio[1139]: info: CoreStateMachine::play index undefined
Apr 14 23:39:00 volumio-r2r volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:00 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:00 volumio-r2r volumio[1139]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:00 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:00 volumio-r2r volumio[1139]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:00 volumio-r2r volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:00 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:00 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:01 volumio-r2r systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 5.
Apr 14 23:39:01 volumio-r2r systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:39:01 volumio-r2r systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:39:01 volumio-r2r upmpdcli[3403]: Could not open config: /tmp/upmpdcli.conf
Apr 14 23:39:01 volumio-r2r systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Apr 14 23:39:01 volumio-r2r systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Apr 14 23:39:02 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:02 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:02 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:02 volumio-r2r volumio[1139]: info:
Apr 14 23:39:02 volumio-r2r volumio[1139]: ---------------------------- Client requests Volumio play
Apr 14 23:39:02 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:02 volumio-r2r volumio[1139]: info: CoreStateMachine::play index undefined
Apr 14 23:39:02 volumio-r2r volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:02 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:02 volumio-r2r volumio[1139]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:02 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:02 volumio-r2r volumio[1139]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:02 volumio-r2r volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:02 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:02 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:04 volumio-r2r systemd[1]: volumio-autoplay.service: Start request repeated too quickly.
Apr 14 23:39:04 volumio-r2r systemd[1]: volumio-autoplay.service: Failed with result 'start-limit-hit'.
Apr 14 23:39:04 volumio-r2r systemd[1]: Failed to start volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:05 volumio-r2r sshd[3375]: Accepted password for volumio from 192.168.1.16 port 51473 ssh2
Apr 14 23:39:05 volumio-r2r sshd[3375]: pam_unix(sshd:session): session opened for user volumio(uid=1000) by (uid=0)
Apr 14 23:39:05 volumio-r2r systemd[1]: Created slice user-1000.slice - User Slice of UID 1000.
Apr 14 23:39:05 volumio-r2r systemd[1]: Starting user-runtime-dir@1000.service - User Runtime Directory /run/user/1000...
Apr 14 23:39:05 volumio-r2r systemd-logind[1103]: New session 1 of user volumio.
Apr 14 23:39:05 volumio-r2r systemd[1]: Finished user-runtime-dir@1000.service - User Runtime Directory /run/user/1000.
Apr 14 23:39:05 volumio-r2r systemd[1]: Starting user@1000.service - User Manager for UID 1000...
Apr 14 23:39:05 volumio-r2r (systemd)[3467]: pam_unix(systemd-user:session): session opened for user volumio(uid=1000) by (uid=0)
Apr 14 23:39:05 volumio-r2r systemd[3467]: Queued start job for default target default.target.
Apr 14 23:39:05 volumio-r2r systemd[3467]: Created slice app.slice - User Application Slice.
Apr 14 23:39:05 volumio-r2r systemd[3467]: Reached target paths.target - Paths.
Apr 14 23:39:05 volumio-r2r systemd[3467]: Reached target timers.target - Timers.
Apr 14 23:39:05 volumio-r2r systemd[3467]: Starting dbus.socket - D-Bus User Message Bus Socket...
Apr 14 23:39:05 volumio-r2r systemd[3467]: Listening on dirmngr.socket - GnuPG network certificate management daemon.
Apr 14 23:39:05 volumio-r2r systemd[3467]: Listening on gpg-agent-browser.socket - GnuPG cryptographic agent and passphrase cache (access for web browsers).
Apr 14 23:39:05 volumio-r2r systemd[3467]: Listening on gpg-agent-extra.socket - GnuPG cryptographic agent and passphrase cache (restricted).
Apr 14 23:39:05 volumio-r2r systemd[3467]: Listening on gpg-agent-ssh.socket - GnuPG cryptographic agent (ssh-agent emulation).
Apr 14 23:39:05 volumio-r2r systemd[3467]: Listening on gpg-agent.socket - GnuPG cryptographic agent and passphrase cache.
Apr 14 23:39:05 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:05 volumio-r2r systemd[3467]: Listening on dbus.socket - D-Bus User Message Bus Socket.
Apr 14 23:39:05 volumio-r2r systemd[3467]: Reached target sockets.target - Sockets.
Apr 14 23:39:05 volumio-r2r systemd[3467]: Reached target basic.target - Basic System.
Apr 14 23:39:05 volumio-r2r systemd[1]: Started user@1000.service - User Manager for UID 1000.
Apr 14 23:39:05 volumio-r2r systemd[1]: Started session-1.scope - Session 1 of User volumio.
Apr 14 23:39:05 volumio-r2r systemd[3467]: Started mpris-proxy.service - Bluetooth mpris proxy.
Apr 14 23:39:05 volumio-r2r systemd[3467]: Reached target default.target - Main User Target.
Apr 14 23:39:05 volumio-r2r systemd[3467]: Startup finished in 287ms.
Apr 14 23:39:05 volumio-r2r systemd[3467]: Created slice session.slice - User Core Session Slice.
Apr 14 23:39:05 volumio-r2r systemd[3467]: Starting dbus.service - D-Bus User Message Bus...
Apr 14 23:39:05 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:05 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:05 volumio-r2r systemd[3467]: Started dbus.service - D-Bus User Message Bus.
Apr 14 23:39:06 volumio-r2r sshd[3375]: pam_env(sshd:session): deprecated reading of user environment enabled
Apr 14 23:39:06 volumio-r2r volumio[1139]: info:
Apr 14 23:39:06 volumio-r2r volumio[1139]: ---------------------------- Client requests Volumio play
Apr 14 23:39:06 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:06 volumio-r2r volumio[1139]: info: CoreStateMachine::play index undefined
Apr 14 23:39:06 volumio-r2r volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:06 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:06 volumio-r2r volumio[1139]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:06 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:06 volumio-r2r volumio[1139]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:06 volumio-r2r volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:06 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:06 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:07 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:07 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:07 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:07 volumio-r2r volumio[1139]: info:
Apr 14 23:39:07 volumio-r2r volumio[1139]: ---------------------------- Client requests Volumio play
Apr 14 23:39:07 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:07 volumio-r2r volumio[1139]: info: CoreStateMachine::play index undefined
Apr 14 23:39:07 volumio-r2r volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:07 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:07 volumio-r2r volumio[1139]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:07 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:07 volumio-r2r volumio[1139]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:07 volumio-r2r volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:07 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:07 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:09 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:09 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:09 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:09 volumio-r2r volumio[1139]: info:
Apr 14 23:39:09 volumio-r2r volumio[1139]: ---------------------------- Client requests Volumio play
Apr 14 23:39:09 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:09 volumio-r2r volumio[1139]: info: CoreStateMachine::play index undefined
Apr 14 23:39:09 volumio-r2r volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:09 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:09 volumio-r2r volumio[1139]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:09 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:09 volumio-r2r volumio[1139]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:09 volumio-r2r volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:09 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:09 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:09 volumio-r2r sudo[3590]:  volumio : TTY=pts/1 ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/cp /volumio/app/plugins/music_service/mpd/lib/mpd.js.bak /volumio/app/plugins/music_service/mpd/lib/mpd.js
Apr 14 23:39:09 volumio-r2r sudo[3590]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000)
Apr 14 23:39:09 volumio-r2r sudo[3590]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:11 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:11 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:11 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:11 volumio-r2r volumio[1139]: info:
Apr 14 23:39:11 volumio-r2r volumio[1139]: ---------------------------- Client requests Volumio play
Apr 14 23:39:11 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:11 volumio-r2r volumio[1139]: info: CoreStateMachine::play index undefined
Apr 14 23:39:11 volumio-r2r volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:11 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:11 volumio-r2r volumio[1139]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:11 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:11 volumio-r2r volumio[1139]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:11 volumio-r2r volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:11 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:11 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:13 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:13 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:13 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:13 volumio-r2r volumio[1139]: info:
Apr 14 23:39:13 volumio-r2r volumio[1139]: ---------------------------- Client requests Volumio play
Apr 14 23:39:13 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:13 volumio-r2r volumio[1139]: info: CoreStateMachine::play index undefined
Apr 14 23:39:13 volumio-r2r volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:13 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:13 volumio-r2r volumio[1139]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:13 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:13 volumio-r2r volumio[1139]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:13 volumio-r2r volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:13 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:13 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:14 volumio-r2r systemd[1]: volumio-autoplay.service: Start request repeated too quickly.
Apr 14 23:39:14 volumio-r2r systemd[1]: volumio-autoplay.service: Failed with result 'start-limit-hit'.
Apr 14 23:39:14 volumio-r2r systemd[1]: Failed to start volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:16 volumio-r2r volumio[1139]: 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: 12
Apr 14 23:39:16 volumio-r2r volumio[1139]: 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: 12
Apr 14 23:39:16 volumio-r2r systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 6.
Apr 14 23:39:16 volumio-r2r systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:39:16 volumio-r2r systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:39:16 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:16 volumio-r2r upmpdcli[3693]: Could not open config: /tmp/upmpdcli.conf
Apr 14 23:39:16 volumio-r2r systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Apr 14 23:39:16 volumio-r2r systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Apr 14 23:39:16 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:16 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:16 volumio-r2r volumio[1139]: info:
Apr 14 23:39:16 volumio-r2r volumio[1139]: ---------------------------- Client requests Volumio play
Apr 14 23:39:16 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:16 volumio-r2r volumio[1139]: info: CoreStateMachine::play index undefined
Apr 14 23:39:16 volumio-r2r volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:16 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:16 volumio-r2r volumio[1139]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:16 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:16 volumio-r2r volumio[1139]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:16 volumio-r2r volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:16 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:16 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:17 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:17 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:17 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:17 volumio-r2r volumio[1139]: info:
Apr 14 23:39:17 volumio-r2r volumio[1139]: ---------------------------- Client requests Volumio play
Apr 14 23:39:17 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:17 volumio-r2r volumio[1139]: info: CoreStateMachine::play index undefined
Apr 14 23:39:17 volumio-r2r volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:17 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:17 volumio-r2r volumio[1139]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:17 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:17 volumio-r2r volumio[1139]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:17 volumio-r2r volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:17 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:17 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:18 volumio-r2r sudo[3751]:  volumio : TTY=pts/1 ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/cp /volumio/app/plugins/audio_interface/alsa_controller/index.js.bak /volumio/app/plugins/audio_interface/alsa_controller/index.js
Apr 14 23:39:18 volumio-r2r sudo[3751]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000)
Apr 14 23:39:18 volumio-r2r sudo[3751]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:19 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:19 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:19 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:19 volumio-r2r volumio[1139]: info:
Apr 14 23:39:19 volumio-r2r volumio[1139]: ---------------------------- Client requests Volumio play
Apr 14 23:39:19 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:19 volumio-r2r volumio[1139]: info: CoreStateMachine::play index undefined
Apr 14 23:39:19 volumio-r2r volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:19 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:19 volumio-r2r volumio[1139]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:19 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:19 volumio-r2r volumio[1139]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:19 volumio-r2r volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:19 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:19 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:21 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:21 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:21 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:21 volumio-r2r volumio[1139]: info:
Apr 14 23:39:21 volumio-r2r volumio[1139]: ---------------------------- Client requests Volumio play
Apr 14 23:39:21 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:21 volumio-r2r volumio[1139]: info: CoreStateMachine::play index undefined
Apr 14 23:39:21 volumio-r2r volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:21 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:21 volumio-r2r volumio[1139]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:21 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:21 volumio-r2r volumio[1139]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:21 volumio-r2r volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:21 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:21 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:23 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:23 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:23 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:23 volumio-r2r volumio[1139]: info:
Apr 14 23:39:23 volumio-r2r volumio[1139]: ---------------------------- Client requests Volumio play
Apr 14 23:39:23 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:23 volumio-r2r volumio[1139]: info: CoreStateMachine::play index undefined
Apr 14 23:39:23 volumio-r2r volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:23 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:23 volumio-r2r volumio[1139]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:23 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:23 volumio-r2r volumio[1139]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:23 volumio-r2r volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:23 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:23 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:24 volumio-r2r systemd[1]: volumio-autoplay.service: Start request repeated too quickly.
Apr 14 23:39:24 volumio-r2r systemd[1]: volumio-autoplay.service: Failed with result 'start-limit-hit'.
Apr 14 23:39:24 volumio-r2r systemd[1]: Failed to start volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:26 volumio-r2r go-librespot[2010]: time="2026-04-14T23:39:26+03:00" level=trace msg="sent dealer ping"
Apr 14 23:39:26 volumio-r2r go-librespot[2010]: time="2026-04-14T23:39:26+03:00" level=trace msg="received dealer pong"
Apr 14 23:39:26 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:26 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:26 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:26 volumio-r2r volumio[1139]: info:
Apr 14 23:39:26 volumio-r2r volumio[1139]: ---------------------------- Client requests Volumio play
Apr 14 23:39:26 volumio-r2r volumio[1139]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:26 volumio-r2r volumio[1139]: info: CoreStateMachine::play index undefined
Apr 14 23:39:26 volumio-r2r volumio[1139]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:26 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:26 volumio-r2r volumio[1139]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:26 volumio-r2r volumio[1139]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:26 volumio-r2r volumio[1139]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:26 volumio-r2r volumio[1139]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:26 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:26 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:28 volumio-r2r sudo[3917]:  volumio : TTY=pts/1 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/systemctl restart volumio
Apr 14 23:39:28 volumio-r2r sudo[3917]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000)
Apr 14 23:39:28 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:28 volumio-r2r systemd[1]: Started dynamicswap.service - dynamicswap service.
Apr 14 23:39:28 volumio-r2r systemd[1]: Stopping volumio.service - Volumio Backend Module...
Apr 14 23:39:28 volumio-r2r systemd[1]: dynamicswap.service: Deactivated successfully.
Apr 14 23:39:28 volumio-r2r go-librespot[2010]: time="2026-04-14T23:39:28+03:00" level=error msg="websocket connection errored" error="failed to get reader: failed to read frame header: EOF"
Apr 14 23:39:28 volumio-r2r volumio-remote-updater[1111]: [2026-04-14 23:39:28] [error] handle_read_frame error: websocketpp.transport:7 (End of File)
Apr 14 23:39:28 volumio-r2r volumio-remote-updater[1111]: [2026-04-14 23:39:28] [disconnect] Disconnect close local:[1006,End of File] remote:[1006]
Apr 14 23:39:28 volumio-r2r systemd[1]: volumio.service: Deactivated successfully.
Apr 14 23:39:28 volumio-r2r systemd[1]: Stopped volumio.service - Volumio Backend Module.
Apr 14 23:39:28 volumio-r2r systemd[1]: volumio.service: Consumed 11.567s CPU time.
Apr 14 23:39:28 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:28 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:28 volumio-r2r systemd[1]: Started volumio.service - Volumio Backend Module.
Apr 14 23:39:28 volumio-r2r sudo[3917]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: -------------------------------------------
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: -----            Volumio3              ----
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: -------------------------------------------
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: -----          System startup          ----
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: -------------------------------------------
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: MYVOLUMIO Environment detected
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Plugin folders cleanup
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Scanning into folder /volumio/app/plugins/
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Scanning category audio_interface
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Scanning category miscellanea
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Scanning category music_service
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Scanning category plugins.json
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Scanning category system_controller
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Scanning category user_interface
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Scanning into folder /data/plugins/
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Scanning category music_service
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Scanning category system_controller
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Scanning category user_interface
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Plugin folders cleanup completed
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: -------------------------------------------
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: -----      Core plugins startup        ----
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: -------------------------------------------
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Loading plugins from folder /volumio/app/plugins/
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Adding plugin upnp to MyMusic Plugins
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Adding plugin airplay_emulation to MyMusic Plugins
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Adding plugin upnp_browser to MyMusic Plugins
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Loading plugins from folder /data/plugins/
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Loading plugin "system"...
Apr 14 23:39:28 volumio-r2r volumio[3949]: info: Loading plugin "appearance"...
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Loading plugin "network"...
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Refreshing Cached IP Addresses
Apr 14 23:39:29 volumio-r2r sudo[3985]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0
Apr 14 23:39:29 volumio-r2r sudo[3985]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:29 volumio-r2r sudo[3985]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Loading plugin "services"...
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Loading plugin "alsa_controller"...
Apr 14 23:39:29 volumio-r2r sudo[3987]:  volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0
Apr 14 23:39:29 volumio-r2r sudo[3987]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:29 volumio-r2r sudo[3987]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Loading plugin "wizard"...
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Loading plugin "networkfs"...
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Starting Udev Watcher for removable devices
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Ignoring mount for partition: boot
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Ignoring mount for partition: volumio
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Ignoring mount for partition: volumio_data
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Loading plugin "volumio_command_line_client"...
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Plugin upnp is not enabled
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Loading plugin "my_music"...
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Loading plugin "mpd"...
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Plugin upnp_browser is not enabled
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Loading plugin "alarm-clock"...
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Plugin airplay_emulation is not enabled
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Loading plugin "last_100"...
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Loading plugin "webradio"...
Apr 14 23:39:29 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Loading plugin "i2s_dacs"...
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: I2S DAC not set, start Auto-detection
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Loading plugin "volumiodiscovery"...
Apr 14 23:39:29 volumio-r2r volumio[3949]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 14 23:39:29 volumio-r2r volumio[3949]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 14 23:39:29 volumio-r2r volumio[3949]: *** WARNING *** For more information see 
Apr 14 23:39:29 volumio-r2r volumio[3949]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 14 23:39:29 volumio-r2r volumio[3949]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 14 23:39:29 volumio-r2r volumio[3949]: *** WARNING *** For more information see 
Apr 14 23:39:29 volumio-r2r node[3949]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi.
Apr 14 23:39:29 volumio-r2r node[3949]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 14 23:39:29 volumio-r2r node[3949]: *** WARNING *** For more information see 
Apr 14 23:39:29 volumio-r2r node[3949]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi.
Apr 14 23:39:29 volumio-r2r node[3949]: *** WARNING *** Please fix your application to use the native API of Avahi!
Apr 14 23:39:29 volumio-r2r node[3949]: *** WARNING *** For more information see 
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Applying required configuration parameters for plugin volumiodiscovery
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Discovery: Started advertising with name: Volumio_R2R
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback
Apr 14 23:39:29 volumio-r2r volumio[3949]: info: Loading plugin "rp2"...
Apr 14 23:39:30 volumio-r2r volumio[3949]: info: Loading plugin "spop"...
Apr 14 23:39:30 volumio-r2r volumio[3949]: info: Loading plugin "now_playing"...
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Loading plugin "outputs"...
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Loading plugin "albumart"...
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Plugin example_plugin is not enabled
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Loading plugin "inputs"...
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Loading plugin "updater_comm"...
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Plugin mpdemulation is not enabled
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Loading plugin "rest_api"...
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Loading plugin "websocket"...
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Starting Socket.io Server version 1.7.4
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Loading plugin "80s80s"...
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Applying required configuration parameters for plugin 80s80s
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: [1776199171145] [80s80s] API delay: 5
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Loading plugin "motherearthradio"...
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Loading plugin "radio_paradise"...
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Applying required configuration parameters for plugin radio_paradise
Apr 14 23:39:31 volumio-r2r volumio[4041]: Forking 3 albumart workers
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: [1776199171199] [RadioParadise] API delay: 5
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Loading plugin "rpi_eeprom_config"...
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Applying required configuration parameters for plugin rpi_eeprom_config
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: [RpiEepromConfig] Hardware capabilities loaded
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Loading plugin "touch_display"...
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Applying required configuration parameters for plugin touch_display
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Loading i18n strings for locale tr
Apr 14 23:39:31 volumio-r2r volumio[3949]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found.
Apr 14 23:39:31 volumio-r2r volumio[3949]: Updating browse sources language
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::initPlayerControls
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 14 23:39:31 volumio-r2r volumio[3949]: Express server listening on port 3000
Apr 14 23:39:31 volumio-r2r volumio[3949]:  [Metrics]  WebUI:  2s 862.71ms 
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreStateMachine::resetVolumioState
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreStateMachine::getcurrentVolume
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioRetrievevolume
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreStateMachine::pushState
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioPushState
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Volumio Network Manager: Network status updated: 1
Apr 14 23:39:31 volumio-r2r volumio[3949]: 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: 1
Apr 14 23:39:31 volumio-r2r volumio[3949]: 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: 1
Apr 14 23:39:31 volumio-r2r volumio[3949]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS x86_64 14541.0.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/144.0.0.0 Safari/537.36 Engine version: 3 Transport: websocket Total Clients: 2
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Reloading queue from file
Apr 14 23:39:31 volumio-r2r volumio[3949]: 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: 3
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreStateMachine::setRepeat null single undefined
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreStateMachine::pushState
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioPushState
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreStateMachine::setRandom null
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreStateMachine::pushState
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioPushState
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Setting Device type: Raspberry PI
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Completed loading Core Plugins
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Preparing to generate the ALSA configuration file
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: USB Boot Capable - System SBC Revision found in cpuinfo:  c04170
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:31 volumio-r2r volumio[3949]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4
Apr 14 23:39:31 volumio-r2r volumio[4052]: Starting albumart workers
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Asound.conf file written
Apr 14 23:39:31 volumio-r2r volumio[4053]: Starting albumart workers
Apr 14 23:39:31 volumio-r2r sudo[4105]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf
Apr 14 23:39:31 volumio-r2r sudo[4105]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:31 volumio-r2r sudo[4105]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:31 volumio-r2r volumio[3949]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Output device has changed, restarting MPD
Apr 14 23:39:31 volumio-r2r volumio[4051]: Starting albumart workers
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: ___________ START PLUGINS ___________
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: ControllerMpd::onStart: Initializing MPD
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Creating MPD Configuration file
Apr 14 23:39:31 volumio-r2r sudo[4111]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 14 23:39:31 volumio-r2r systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 7.
Apr 14 23:39:31 volumio-r2r sudo[4111]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: [1776199171800] CoreMusicLibrary::Adding element Last_100
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 14 23:39:31 volumio-r2r systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:39:31 volumio-r2r sudo[4113]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 14 23:39:31 volumio-r2r sudo[4113]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:31 volumio-r2r sudo[4116]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 14 23:39:31 volumio-r2r sudo[4116]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: [1776199171807] CoreMusicLibrary::Adding element Webradio
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 14 23:39:31 volumio-r2r sudo[4111]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:31 volumio-r2r sudo[4116]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:31 volumio-r2r sudo[4119]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 14 23:39:31 volumio-r2r sudo[4119]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Initializing BBC Radios
Apr 14 23:39:31 volumio-r2r systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:39:31 volumio-r2r systemd[1]: volumio-autoplay.service: start operation timed out. Terminating.
Apr 14 23:39:31 volumio-r2r systemd[1]: volumio-autoplay.service: Main process exited, code=killed, status=15/TERM
Apr 14 23:39:31 volumio-r2r systemd[1]: volumio-autoplay.service: Failed with result 'timeout'.
Apr 14 23:39:31 volumio-r2r systemd[1]: Failed to start volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:31 volumio-r2r systemd[1]: Stopping mpd.service - Music Player Daemon...
Apr 14 23:39:31 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:31 volumio-r2r upmpdcli[4118]: Could not open config: /tmp/upmpdcli.conf
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 14 23:39:31 volumio-r2r systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Apr 14 23:39:31 volumio-r2r systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: [1776199171896] CoreMusicLibrary::Adding element Radio Paradise (RP2)
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 14 23:39:31 volumio-r2r volumio[3949]: Cannot find translation for source Radio Paradise (RP2)
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: Creating Spotify config file
Apr 14 23:39:31 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r systemd[1]: mpd.service: Deactivated successfully.
Apr 14 23:39:32 volumio-r2r systemd[1]: Stopped mpd.service - Music Player Daemon.
Apr 14 23:39:32 volumio-r2r systemd[1]: mpd.service: Consumed 1.220s CPU time.
Apr 14 23:39:32 volumio-r2r systemd[1]: mpd.socket: Deactivated successfully.
Apr 14 23:39:32 volumio-r2r systemd[1]: Closed mpd.socket - Music Player Daemon Socket.
Apr 14 23:39:32 volumio-r2r systemd[1]: Stopping mpd.socket - Music Player Daemon Socket...
Apr 14 23:39:32 volumio-r2r systemd[1]: Listening on mpd.socket - Music Player Daemon Socket.
Apr 14 23:39:32 volumio-r2r systemd[1]: Starting mpd.service - Music Player Daemon...
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [now-playing] ConfigUpdater: config is up to date.
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [1776199172077] CoreMusicLibrary::Adding element 80s80s Radio
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 14 23:39:32 volumio-r2r volumio[3949]: Cannot find translation for source Radio Paradise (RP2)
Apr 14 23:39:32 volumio-r2r volumio[3949]: Cannot find translation for source 80s80s Radio
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [1776199172079] CoreMusicLibrary::Adding element Mother Earth Radio
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 14 23:39:32 volumio-r2r volumio[3949]: Cannot find translation for source Radio Paradise (RP2)
Apr 14 23:39:32 volumio-r2r volumio[3949]: Cannot find translation for source 80s80s Radio
Apr 14 23:39:32 volumio-r2r volumio[3949]: Cannot find translation for source Mother Earth Radio
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [MER] Plugin started (SSE mode)
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [1776199172081] CoreMusicLibrary::Adding element Radio Paradise
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 14 23:39:32 volumio-r2r volumio[3949]: Cannot find translation for source Radio Paradise (RP2)
Apr 14 23:39:32 volumio-r2r volumio[3949]: Cannot find translation for source 80s80s Radio
Apr 14 23:39:32 volumio-r2r volumio[3949]: Cannot find translation for source Mother Earth Radio
Apr 14 23:39:32 volumio-r2r volumio[3949]: Cannot find translation for source Radio Paradise
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [RpiEepromConfig] Starting Raspberry Pi EEPROM Configuration Manager
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [RpiEepromConfig] Detected full model string: Raspberry Pi 5 Model B Rev 1.0
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [RpiEepromConfig] Matched to capability profile: Raspberry Pi 5
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [RpiEepromConfig] Detected model: Raspberry Pi 5
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [RpiEepromConfig] Boot modes: sd, usb, nvme
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [RpiEepromConfig] Using rpi-eeprom-config at: /usr/bin/rpi-eeprom-config
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [RpiEepromConfig] Backup directory ensured: /data/configuration/system_controller/rpi_eeprom_config/backup
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [RpiEepromConfig] Plugin started successfully
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: Loading i18n strings for locale tr
Apr 14 23:39:32 volumio-r2r volumio[3949]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found.
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: Volumio Calling Home
Apr 14 23:39:32 volumio-r2r sudo[4164]:  volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge
Apr 14 23:39:32 volumio-r2r sudo[4164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:32 volumio-r2r sudo[4164]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:32 volumio-r2r sudo[4155]:     root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log
Apr 14 23:39:32 volumio-r2r sudo[4155]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0)
Apr 14 23:39:32 volumio-r2r sudo[4155]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:32 volumio-r2r sudo[4172]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service
Apr 14 23:39:32 volumio-r2r sudo[4172]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:32 volumio-r2r sudo[4174]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service
Apr 14 23:39:32 volumio-r2r sudo[4174]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:32 volumio-r2r sudo[4177]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload
Apr 14 23:39:32 volumio-r2r sudo[4177]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [now-playing] App is listening on port 4004.
Apr 14 23:39:32 volumio-r2r volumio[3949]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: Discovery: adding 2bff6875-b240-455b-9d78-3357f59a9e23
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: Discovery: Found device Volumio_R2R
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: Discovery: this is already registered,  2bff6875-b240-455b-9d78-3357f59a9e23
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: Discovery: Found device Volumio_R2R
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: touch_display: No backlight interface detected.
Apr 14 23:39:32 volumio-r2r volumio[3949]: 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: 5
Apr 14 23:39:32 volumio-r2r systemd[1]: Reloading.
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: MPD Permissions set
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: MPD Permissions set
Apr 14 23:39:32 volumio-r2r volumio[3949]: info:
Apr 14 23:39:32 volumio-r2r volumio[3949]: ---------------------------- Client requests Volumio play
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreStateMachine::play index undefined
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:32 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:32 volumio-r2r volumio[3949]: error: API:pushError: TypeError: Cannot read properties of undefined (reading 'then')
Apr 14 23:39:32 volumio-r2r sudo[4185]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/
Apr 14 23:39:32 volumio-r2r sudo[4185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: Spotify config file written
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:32 volumio-r2r volumio[3949]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6
Apr 14 23:39:32 volumio-r2r sudo[4188]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service
Apr 14 23:39:32 volumio-r2r sudo[4185]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:32 volumio-r2r sudo[4188]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: No need to fix Spotify hosts
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetVisibleSources
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetQueue
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreStateMachine::getQueue
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CorePlayQueue::getQueue
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: Listing playlists
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: touch_display: IframeKeyboardBridge extension installed successfully
Apr 14 23:39:32 volumio-r2r systemd[1]: /etc/systemd/system/volumio.service.d/override.conf:6: Invalid environment assignment, ignoring: --trace-warnings
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: Volumio called home
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: touch_display: No Raspberry Pi Foundation touch screen detected.
Apr 14 23:39:32 volumio-r2r sudo[4226]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf
Apr 14 23:39:32 volumio-r2r sudo[4226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:32 volumio-r2r sudo[4224]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf
Apr 14 23:39:32 volumio-r2r sudo[4224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:32 volumio-r2r sudo[4224]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:32 volumio-r2r sudo[4226]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set.
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set.
Apr 14 23:39:32 volumio-r2r volumio[3949]: info:
Apr 14 23:39:32 volumio-r2r volumio[3949]: ---------------------------- Client requests Volumio play
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreStateMachine::play index undefined
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:32 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:32 volumio-r2r volumio[3949]: error: API:pushError: TypeError: Cannot read properties of undefined (reading 'then')
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: New Spotify access tokenBQAITJzQ7N...
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: Spotify credentials grant success - running version from March 24, 2019
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:32 volumio-r2r systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 14 23:39:32 volumio-r2r systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 14 23:39:32 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:32 volumio-r2r sudo[4177]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:32 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:32 volumio-r2r systemd[1]: Reloading.
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: touch_display: systemctl daemon-reload succeeded.
Apr 14 23:39:32 volumio-r2r sudo[4235]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service
Apr 14 23:39:32 volumio-r2r sudo[4235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:32 volumio-r2r volumio[3949]: SPOTIFY: User informations: {"country":"TR","display_name":"mp3dragon","email":"erdalturkekul@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/mp3dragon"},"followers":{"href":null,"total":4},"href":"https://api.spotify.com/v1/users/mp3dragon","id":"mp3dragon","images":[],"product":"premium","type":"user","uri":"spotify:user:mp3dragon"}
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: Spotify Successfully logged in
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object]
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: [1776199172932] CoreMusicLibrary::Adding element Spotify
Apr 14 23:39:32 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Apr 14 23:39:32 volumio-r2r volumio[3949]: Cannot find translation for source Radio Paradise (RP2)
Apr 14 23:39:32 volumio-r2r volumio[3949]: Cannot find translation for source 80s80s Radio
Apr 14 23:39:32 volumio-r2r volumio[3949]: Cannot find translation for source Mother Earth Radio
Apr 14 23:39:32 volumio-r2r volumio[3949]: Cannot find translation for source Radio Paradise
Apr 14 23:39:32 volumio-r2r volumio[3949]: Cannot find translation for source Spotify
Apr 14 23:39:33 volumio-r2r systemd[1]: /etc/systemd/system/volumio.service.d/override.conf:6: Invalid environment assignment, ignoring: --trace-warnings
Apr 14 23:39:33 volumio-r2r volumio-remote-updater[1111]: [2026-04-14 23:39:33] [connect] Successful connection
Apr 14 23:39:33 volumio-r2r volumio-remote-updater[1111]: [2026-04-14 23:39:33] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1776199173 101
Apr 14 23:39:33 volumio-r2r volumio[3949]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 7
Apr 14 23:39:33 volumio-r2r mpd[4170]: 2026-04-14T23:39:33 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg
Apr 14 23:39:33 volumio-r2r systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 14 23:39:33 volumio-r2r systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 14 23:39:33 volumio-r2r systemd[1]: Started mpd.service - Music Player Daemon.
Apr 14 23:39:33 volumio-r2r sudo[4119]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:33 volumio-r2r sudo[4113]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:33 volumio-r2r sudo[4174]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: touch_display: systemctl disable getty@tty1.service succeeded.
Apr 14 23:39:33 volumio-r2r systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon...
Apr 14 23:39:33 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:33 volumio-r2r volumio[3949]: error: MPD error: The expression evaluated to a falsy value:
Apr 14 23:39:33 volumio-r2r volumio[3949]:   assert.ok(self.idling)
Apr 14 23:39:33 volumio-r2r sudo[4235]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:33 volumio-r2r volumio[3949]: error: The expression evaluated to a falsy value:
Apr 14 23:39:33 volumio-r2r volumio[3949]:   assert.ok(self.idling)
Apr 14 23:39:33 volumio-r2r systemd[1]: go-librespot-daemon.service: Deactivated successfully.
Apr 14 23:39:33 volumio-r2r systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon.
Apr 14 23:39:33 volumio-r2r volumio[3949]: error: updateQueue error: null
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: touch_display: systemctl start volumio-kiosk.service succeeded.
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: touch_display: Volumio Kiosk started.
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: Completed starting Core Plugins
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: -------------------------------------------
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: -----    MyVolumio plugins startup     ----
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: -------------------------------------------
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Fetching plans data....
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: MPD running with PID4170
Apr 14 23:39:33 volumio-r2r volumio[3949]:  ,establishing connection
Apr 14 23:39:33 volumio-r2r sudo[4172]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:33 volumio-r2r systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon.
Apr 14 23:39:33 volumio-r2r sudo[4188]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:33 volumio-r2r go-librespot[4271]: go-librespot daemon starting...
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: touch_display: systemctl stop getty@tty1.service succeeded.
Apr 14 23:39:33 volumio-r2r volumio[3949]: error: updateQueue error: null
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:33 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:33+03:00" level=info msg="running go-librespot 0.7.1"
Apr 14 23:39:33 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:33+03:00" level=debug msg="app state loaded"
Apr 14 23:39:33 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:33+03:00" level=info msg="api server listening on 127.0.0.1:9879"
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: touch_display: X display number found: 0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info:
Apr 14 23:39:33 volumio-r2r volumio[3949]: ---------------------------- Client requests Volumio play
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioPlay
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreStateMachine::play index undefined
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0
Apr 14 23:39:33 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: sendMpdCommand stop took 3 milliseconds
Apr 14 23:39:33 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand clear
Apr 14 23:39:33 volumio-r2r volumio[3949]: info:
Apr 14 23:39:33 volumio-r2r volumio[3949]: ---------------------------- MPD announces system playlist update
Apr 14 23:39:33 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:33 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: Ignoring MPD Status Update
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: sendMpdCommand clear took 3 milliseconds
Apr 14 23:39:33 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand consume 1
Apr 14 23:39:33 volumio-r2r volumio[3949]: info:
Apr 14 23:39:33 volumio-r2r volumio[3949]: ---------------------------- MPD announces system playlist update
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: Ignoring MPD Status Update
Apr 14 23:39:33 volumio-r2r volumio[3949]: info:
Apr 14 23:39:33 volumio-r2r volumio[3949]: ---------------------------- MPD announces system playlist update
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: Ignoring MPD Status Update
Apr 14 23:39:33 volumio-r2r volumio[3949]: error: updateQueue error: null
Apr 14 23:39:33 volumio-r2r volumio[3949]: info:
Apr 14 23:39:33 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: options
Apr 14 23:39:33 volumio-r2r volumio[3949]: error: updateQueue error: null
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ------------------------------ 3ms
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: sendMpdCommand consume 1 took 1 milliseconds
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:33 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ------------------------------ 2ms
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: [1776199173564] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flacm
Apr 14 23:39:33 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flacm"
Apr 14 23:39:33 volumio-r2r volumio[3949]: info:
Apr 14 23:39:33 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: options
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:33 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:33 volumio-r2r volumio[3949]: error: updateQueue error: null
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ------------------------------ 3ms
Apr 14 23:39:33 volumio-r2r volumio[3949]: info:
Apr 14 23:39:33 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: options
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:33 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: sendMpdCommand status took 9 milliseconds
Apr 14 23:39:33 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreStateMachine::pushState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioPushState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ------------------------------ 18ms
Apr 14 23:39:33 volumio-r2r volumio[3949]: info:
Apr 14 23:39:33 volumio-r2r volumio[3949]: ---------------------------- MPD announces system playlist update
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: Ignoring MPD Status Update
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: sendMpdCommand add "https://stream.radioparadise.com/flacm" took 18 milliseconds
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: sendMpdCommand status took 18 milliseconds
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: sendMpdCommand status took 17 milliseconds
Apr 14 23:39:33 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand play
Apr 14 23:39:33 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:33 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreStateMachine::pushState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioPushState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreStateMachine::pushState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioPushState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ------------------------------ 34ms
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ------------------------------ 33ms
Apr 14 23:39:33 volumio-r2r volumio[3949]: info:
Apr 14 23:39:33 volumio-r2r volumio[3949]: ---------------------------- MPD announces system playlist update
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: Ignoring MPD Status Update
Apr 14 23:39:33 volumio-r2r volumio[3949]: info:
Apr 14 23:39:33 volumio-r2r volumio[3949]: ---------------------------- MPD announces system playlist update
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: Ignoring MPD Status Update
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ------------------------------ 23ms
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: sendMpdCommand play took 18 milliseconds
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ------------------------------ 6ms
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: ------------------------------ 5ms
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: [1776199173605] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: touch_display: X display number found: 0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:33 volumio-r2r volumio[3949]: info: touch_display: Setting screensaver timeout to 0 seconds.
Apr 14 23:39:33 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:33+03:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]"
Apr 14 23:39:33 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:33+03:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]"
Apr 14 23:39:33 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:33+03:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]"
Apr 14 23:39:33 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:33+03:00" level=info msg="zeroconf server listening on port 42821"
Apr 14 23:39:33 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:33+03:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration"
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=debug msg="obtained new client token: AACDizwQwfxqKSAOQqXHfEQWtzR4DkvAzvSRIXY5Gpv1xPO53Eg3qCCKjNcfCnCOlr0WB9SO3Llxm7EPjC5+Hi3xm/34/37XjPHthe4Cif1DFdR8vB/bXxVsM6mxsLDidRqvUqwRRD7xHHlMSBFiwfVD7xtAXZ3j6jbqmmOKVy98wjE2ThZeoF4eII+DLSCbbNXW+ecc3EvVsInl44TdQRUjpfx2dlu07JsbAAy7BrlTmdBWed05oDD29g=="
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=debug msg="connected to ap-gew4.spotify.com:4070"
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=debug msg="completed keyexchange"
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=debug msg="completed challenge"
Apr 14 23:39:34 volumio-r2r volumio[3949]: info:
Apr 14 23:39:34 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: player
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:34 volumio-r2r volumio[3949]: info:
Apr 14 23:39:34 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: player
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:34 volumio-r2r volumio[3949]: info:
Apr 14 23:39:34 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: player
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:34 volumio-r2r volumio[3949]: info:
Apr 14 23:39:34 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: player
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand status took 12 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: info:
Apr 14 23:39:34 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: player
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand status took 13 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand status took 12 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand status took 2 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand playlistinfo took 2 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseTrackInfo
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=info msg="authenticated AP" username="mp*****on"
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ------------------------------ 17ms
Apr 14 23:39:34 volumio-r2r volumio[3949]: info:
Apr 14 23:39:34 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: player
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand status took 3 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand playlistinfo took 3 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand status took 1 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseTrackInfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseTrackInfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseTrackInfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ------------------------------ 18ms
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ------------------------------ 18ms
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ------------------------------ 7ms
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand playlistinfo took 11 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand playlistinfo took 11 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseTrackInfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseTrackInfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ------------------------------ 16ms
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ------------------------------ 13ms
Apr 14 23:39:34 volumio-r2r volumio[3949]: info:
Apr 14 23:39:34 volumio-r2r volumio[3949]: ---------------------------- MPD announces system playlist update
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: Ignoring MPD Status Update
Apr 14 23:39:34 volumio-r2r volumio[3949]: info:
Apr 14 23:39:34 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: player
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:34 volumio-r2r volumio[3949]: info:
Apr 14 23:39:34 volumio-r2r volumio[3949]: ---------------------------- MPD announces system playlist update
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: Ignoring MPD Status Update
Apr 14 23:39:34 volumio-r2r volumio[3949]: info:
Apr 14 23:39:34 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: player
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:34 volumio-r2r volumio[3949]: info:
Apr 14 23:39:34 volumio-r2r volumio[3949]: ---------------------------- MPD announces system playlist update
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: Ignoring MPD Status Update
Apr 14 23:39:34 volumio-r2r volumio[3949]: info:
Apr 14 23:39:34 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: player
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ------------------------------ 2ms
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand status took 1 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ------------------------------ 1ms
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand status took 1 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ------------------------------ 1ms
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand status took 2 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand playlistinfo took 1 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand playlistinfo took 1 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseTrackInfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseTrackInfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ------------------------------ 4ms
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ------------------------------ 4ms
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: sendMpdCommand playlistinfo took 1 milliseconds
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseTrackInfo
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: ------------------------------ 4ms
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=info msg="authenticated Login5" username="mp*****on"
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=debug msg="initializing zeroconf session" username="mp*****on"
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: [1776199174575] [RadioParadise] received new metadata: {"time":112,"artist":"Mazzy Star","title":"Into Dust","album":"So Tonight That I Might See","year":"1993","cover":"https://img.radioparadise.com/covers/l/7714.jpg","cover_med":"https://img.radioparadise.com/covers/m/7714.jpg","cover_small":"https://img.radioparadise.com/covers/s/7714.jpg"}
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/7714.jpg","uri":"https://stream.radioparadise.com/flacm","name":"Into Dust","title":"Into Dust","artist":"Mazzy Star","album":"So Tonight That I Might See","streaming":true,"disableUiControls":true,"duration":117,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2}
Apr 14 23:39:34 volumio-r2r volumio[3949]: verbose: CURRENT POSITION 0
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CoreStateMachine::syncState   stateService play
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CoreStateMachine::syncState   currentStatus stop
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: [1776199174576] [RadioParadise] setting new timer with duration of 117000 seconds.
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 1
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=debug msg="dealer connection opened"
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=trace msg="starting accesspoint recv loop"
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=trace msg="starting dealer recv loop"
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=trace msg="received accesspoint ping"
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=debug msg="received connection id: YzJiNjA1ZWQtNzA4...MjQxMzY3RkI3Ng=="
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=trace msg="received accesspoint pong ack"
Apr 14 23:39:34 volumio-r2r systemd[1]: Starting volumio-autoplay.service - Volumio autoplay tick...
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:34 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:34 volumio-r2r systemd[1]: volumio-autoplay.timer: Deactivated successfully.
Apr 14 23:39:34 volumio-r2r systemd[1]: Stopped volumio-autoplay.timer - Volumio autoplay timer.
Apr 14 23:39:34 volumio-r2r systemd[1]: volumio-autoplay.service: Deactivated successfully.
Apr 14 23:39:34 volumio-r2r systemd[1]: Finished volumio-autoplay.service - Volumio autoplay tick.
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=debug msg="put connect state because NEW_DEVICE"
Apr 14 23:39:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:34+03:00" level=debug msg="update volume requested to 64879/65535"
Apr 14 23:39:35 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:35+03:00" level=debug msg="put connect state because VOLUME_CHANGED"
Apr 14 23:39:35 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:35+03:00" level=trace msg="emitting websocket event: volume"
Apr 14 23:39:36 volumio-r2r volumio[3949]: info: go-librespot daemon successfully initialized
Apr 14 23:39:38 volumio-r2r volumio[3949]: error: Failed LSINFO: Error:  [50@0] {lsinfo} No such directory
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: Initializing connection to go-librespot Websocket
Apr 14 23:39:39 volumio-r2r go-librespot[4272]: time="2026-04-14T23:39:39+03:00" level=debug msg="new websocket client"
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: Connection to go-librespot Websocket established
Apr 14 23:39:39 volumio-r2r volumio[3949]: info:
Apr 14 23:39:39 volumio-r2r volumio[3949]: ---------------------------- MPD announces system playlist update
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: Ignoring MPD Status Update
Apr 14 23:39:39 volumio-r2r volumio[3949]: info:
Apr 14 23:39:39 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: player
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:39 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:39 volumio-r2r volumio[3949]: info:
Apr 14 23:39:39 volumio-r2r volumio[3949]: ---------------------------- MPD announces system playlist update
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: Ignoring MPD Status Update
Apr 14 23:39:39 volumio-r2r volumio[3949]: info:
Apr 14 23:39:39 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: player
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:39 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:39 volumio-r2r volumio[3949]: info:
Apr 14 23:39:39 volumio-r2r volumio[3949]: ---------------------------- MPD announces system playlist update
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: Ignoring MPD Status Update
Apr 14 23:39:39 volumio-r2r volumio[3949]: info:
Apr 14 23:39:39 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: player
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:39:39 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: ------------------------------ 2ms
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: sendMpdCommand status took 1 milliseconds
Apr 14 23:39:39 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:39 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: ------------------------------ 1ms
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: sendMpdCommand status took 1 milliseconds
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: ------------------------------ 1ms
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: sendMpdCommand status took 1 milliseconds
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: sendMpdCommand playlistinfo took 1 milliseconds
Apr 14 23:39:39 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:39 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 23:39:39 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseState
Apr 14 23:39:39 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Apr 14 23:39:39 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseTrackInfo
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: ------------------------------ 2ms
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: sendMpdCommand playlistinfo took 0 milliseconds
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: sendMpdCommand playlistinfo took 0 milliseconds
Apr 14 23:39:39 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseTrackInfo
Apr 14 23:39:39 volumio-r2r volumio[3949]: verbose: ControllerMpd::parseTrackInfo
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: ControllerMpd::pushState
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: ------------------------------ 3ms
Apr 14 23:39:39 volumio-r2r volumio[3949]: info: ------------------------------ 3ms
Apr 14 23:39:40 volumio-r2r volumio[3949]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 14 23:39:40 volumio-r2r volumio[3949]: info: CoreCommandRouter::Close All Modals sent
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: Adding plugin bluetooth to MyMusic Plugins
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: Adding plugin multiroom to MyMusic Plugins
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: Adding plugin metavolumio to MyMusic Plugins
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: Adding plugin cd_controller to MyMusic Plugins
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: Adding plugin qobuzconnect to MyMusic Plugins
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: Adding plugin smart_inputs to MyMusic Plugins
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: Adding plugin tidalconnect to MyMusic Plugins
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"...
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: Starting MyVolumio Remote Streaming Endpoints
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: MyVolumio login type: Token
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started
Apr 14 23:39:42 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"...
Apr 14 23:39:43 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded
Apr 14 23:39:43 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services
Apr 14 23:39:43 volumio-r2r volumio[3949]: info: Streaming services startup
Apr 14 23:39:43 volumio-r2r volumio[3949]: info: Starting Streaming Daemon
Apr 14 23:39:43 volumio-r2r sudo[4440]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 14 23:39:43 volumio-r2r sudo[4440]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:43 volumio-r2r volumio[3949]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started
Apr 14 23:39:43 volumio-r2r sudo[4440]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:43 volumio-r2r volumio[3949]: info: Getting Spotify volume
Apr 14 23:39:43 volumio-r2r volumio[3949]: error: Cannot start Volumio Streaming Daemon
Apr 14 23:39:43 volumio-r2r volumio[3949]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 14 23:39:43 volumio-r2r volumio[3949]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 14 23:39:43 volumio-r2r volumio[3949]: info: Spotify volume: 99
Apr 14 23:39:43 volumio-r2r volumio[3949]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8
Apr 14 23:39:43 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:43 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:39:43 volumio-r2r volumio[3949]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN
Apr 14 23:39:44 volumio-r2r volumio[3949]: info: MyVolumio token set successfully
Apr 14 23:39:44 volumio-r2r volumio[3949]: info: MYVOLUMIO: Adding device
Apr 14 23:39:44 volumio-r2r volumio[3949]: info: MYVOLUMIO: Evaluating Server
Apr 14 23:39:44 volumio-r2r volumio[3949]: info: MyVolumio status changed
Apr 14 23:39:44 volumio-r2r volumio[3949]: info: Streaming services startup
Apr 14 23:39:44 volumio-r2r volumio[3949]: info: Starting Streaming Daemon
Apr 14 23:39:44 volumio-r2r volumio[3949]: info: Removing browser output: myVolumio user plan is not superstar
Apr 14 23:39:44 volumio-r2r volumio[3949]: info: Removing audio output:
Apr 14 23:39:44 volumio-r2r volumio[3949]: info: Stoppping Tunnel 1
Apr 14 23:39:44 volumio-r2r sudo[4485]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service
Apr 14 23:39:44 volumio-r2r sudo[4485]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:44 volumio-r2r sudo[4487]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service
Apr 14 23:39:44 volumio-r2r sudo[4487]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000)
Apr 14 23:39:44 volumio-r2r sudo[4485]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:44 volumio-r2r volumio[3949]: error: Cannot start Volumio Streaming Daemon
Apr 14 23:39:44 volumio-r2r volumio[3949]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service
Apr 14 23:39:44 volumio-r2r volumio[3949]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found.
Apr 14 23:39:44 volumio-r2r systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 14 23:39:44 volumio-r2r systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 14 23:39:44 volumio-r2r systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 14 23:39:44 volumio-r2r systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 14 23:39:44 volumio-r2r systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 14 23:39:44 volumio-r2r systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 14 23:39:44 volumio-r2r systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 14 23:39:44 volumio-r2r systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether.
Apr 14 23:39:44 volumio-r2r sudo[4487]: pam_unix(sudo:session): session closed for user root
Apr 14 23:39:44 volumio-r2r volumio[3949]: info: Remote SSH Stopped
Apr 14 23:39:44 volumio-r2r volumio[3949]: info: Setting Geolocation for MyVolumio to eu4
Apr 14 23:39:44 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 14 23:39:44 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 14 23:39:44 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 14 23:39:45 volumio-r2r volumio[3949]: info: Successfully Added MyVolumio device
Apr 14 23:39:45 volumio-r2r volumio[3949]: info: Updating MyVolumio device info
Apr 14 23:39:45 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 14 23:39:45 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 14 23:39:45 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 14 23:39:46 volumio-r2r volumio[3949]: info: Successfully Updated MyVolumio device
Apr 14 23:39:47 volumio-r2r systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 8.
Apr 14 23:39:47 volumio-r2r systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:39:47 volumio-r2r systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:39:47 volumio-r2r upmpdcli[4523]: Could not open config: /tmp/upmpdcli.conf
Apr 14 23:39:47 volumio-r2r systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Apr 14 23:39:47 volumio-r2r systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Apr 14 23:39:57 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings
Apr 14 23:39:57 volumio-r2r volumio[3949]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9
Apr 14 23:39:57 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetState
Apr 14 23:39:57 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:40:02 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 14 23:40:02 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 14 23:40:02 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Apr 14 23:40:02 volumio-r2r volumio-remote-updater[1111]: Test mode disabled
Apr 14 23:40:02 volumio-r2r volumio-remote-updater[1111]: Alpha mode disabled
Apr 14 23:40:02 volumio-r2r volumio-remote-updater[1111]: Alpha legacy test mode disabled
Apr 14 23:40:02 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled
Apr 14 23:40:02 volumio-r2r systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 9.
Apr 14 23:40:02 volumio-r2r systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:40:02 volumio-r2r systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:40:02 volumio-r2r upmpdcli[4750]: Could not open config: /tmp/upmpdcli.conf
Apr 14 23:40:02 volumio-r2r systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Apr 14 23:40:02 volumio-r2r systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Apr 14 23:40:02 volumio-r2r volumio[3949]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"FIXES
Fix for missing PWM backlight module
Fix for crash loop on Onboarding plugin
NEW ADDITIONS
Bump RPi/CM4 kernel to 6.12.74
","title":"Update v4.119","updateavailable":true}
Apr 14 23:40:02 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache
Apr 14 23:40:02 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:40:02 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:40:02 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam
Apr 14 23:40:02 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam
Apr 14 23:40:02 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject
Apr 14 23:40:02 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 14 23:40:02 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion
Apr 14 23:40:03 volumio-r2r volumio[3949]: error: MyVolumio Plugin failed to authenticate in a timely fashion
Apr 14 23:40:03 volumio-r2r volumio[3949]: info: Completed starting MyVolumio Plugin
Apr 14 23:40:03 volumio-r2r volumio[3949]:  [Metrics]  CommandRouter:  34s 317.52ms 
Apr 14 23:40:03 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumiosetStartupVolume
Apr 14 23:40:03 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 14 23:40:03 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 14 23:40:03 volumio-r2r volumio[3949]: info: CoreCommandRouter::Close All Modals sent
Apr 14 23:40:03 volumio-r2r volumio[3949]: info: CoreCommandRouter::Close All Modals sent
Apr 14 23:40:04 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable
Apr 14 23:40:04 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 14 23:40:04 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect
Apr 14 23:40:04 volumio-r2r go-librespot[4272]: time="2026-04-14T23:40:04+03:00" level=trace msg="sent dealer ping"
Apr 14 23:40:04 volumio-r2r go-librespot[4272]: time="2026-04-14T23:40:04+03:00" level=trace msg="received dealer pong"
Apr 14 23:40:10 volumio-r2r volumio[3949]: info: BOOT COMPLETED
Apr 14 23:40:17 volumio-r2r systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 10.
Apr 14 23:40:17 volumio-r2r systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:40:17 volumio-r2r systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:40:17 volumio-r2r upmpdcli[4965]: Could not open config: /tmp/upmpdcli.conf
Apr 14 23:40:17 volumio-r2r systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Apr 14 23:40:17 volumio-r2r systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Apr 14 23:40:32 volumio-r2r systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 11.
Apr 14 23:40:32 volumio-r2r systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:40:32 volumio-r2r systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:40:32 volumio-r2r upmpdcli[5186]: Could not open config: /tmp/upmpdcli.conf
Apr 14 23:40:32 volumio-r2r systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Apr 14 23:40:32 volumio-r2r systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Apr 14 23:40:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:40:34+03:00" level=trace msg="sent dealer ping"
Apr 14 23:40:34 volumio-r2r go-librespot[4272]: time="2026-04-14T23:40:34+03:00" level=trace msg="received dealer pong"
Apr 14 23:40:44 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 14 23:40:44 volumio-r2r volumio[3949]: info: CURURI: music-library
Apr 14 23:40:44 volumio-r2r volumio[3949]: info: Preload queue cleared
Apr 14 23:40:45 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 14 23:40:45 volumio-r2r volumio[3949]: info: CURURI: music-library/INTERNAL
Apr 14 23:40:45 volumio-r2r volumio[3949]: info: Preload queue cleared
Apr 14 23:40:46 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 14 23:40:46 volumio-r2r volumio[3949]: info: CURURI: music-library/INTERNAL/Erdal
Apr 14 23:40:46 volumio-r2r volumio[3949]: info: Preload queue cleared
Apr 14 23:40:47 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 14 23:40:47 volumio-r2r volumio[3949]: info: CURURI: music-library/INTERNAL/Erdal/DSDTest
Apr 14 23:40:47 volumio-r2r volumio[3949]: info: Preload queue cleared
Apr 14 23:40:47 volumio-r2r volumio[3949]: info: Preloading song: music-library/INTERNAL/Erdal/DSDTest/01 - David Elias - The Window - Vision of Her (DSD64).dsf
Apr 14 23:40:47 volumio-r2r volumio[3949]: info: Preloading song: music-library/INTERNAL/Erdal/DSDTest/1kHz-0dBFS.dsf
Apr 14 23:40:47 volumio-r2r volumio[3949]: info: Preloading song: music-library/INTERNAL/Erdal/DSDTest/Scorpions - Acoustica [88985406981] D4.dsf
Apr 14 23:40:47 volumio-r2r volumio[3949]: info: Preloading song: music-library/INTERNAL/Erdal/DSDTest/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf
Apr 14 23:40:47 volumio-r2r volumio[3949]: info: Preloading song: music-library/INTERNAL/Erdal/DSDTest/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf
Apr 14 23:40:48 volumio-r2r volumio[3949]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/01 - David Elias - The Window - Vision of Her (DSD64).dsf in service mpd
Apr 14 23:40:48 volumio-r2r volumio[3949]: 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 14 23:40:48 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/01 - David Elias - The Window - Vision of Her (DSD64).dsf
Apr 14 23:40:48 volumio-r2r systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 12.
Apr 14 23:40:48 volumio-r2r systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:40:48 volumio-r2r systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD.
Apr 14 23:40:48 volumio-r2r volumio[3949]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/1kHz-0dBFS.dsf in service mpd
Apr 14 23:40:48 volumio-r2r volumio[3949]: info: ALBUMART /albumart?cacheid=671&web=//medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2F1kHz-0dBFS.dsf&metadata=false
Apr 14 23:40:48 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/1kHz-0dBFS.dsf
Apr 14 23:40:48 volumio-r2r upmpdcli[5393]: Could not open config: /tmp/upmpdcli.conf
Apr 14 23:40:48 volumio-r2r systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE
Apr 14 23:40:48 volumio-r2r systemd[1]: upmpdcli.service: Failed with result 'exit-code'.
Apr 14 23:40:48 volumio-r2r volumio[3949]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/Scorpions - Acoustica [88985406981] D4.dsf in service mpd
Apr 14 23:40:48 volumio-r2r volumio[3949]: info: ALBUMART /albumart?cacheid=671&web=//medium&path=%2Fmnt%2FINTERNAL%2FErdal%2FDSDTest%2FScorpions%20-%20Acoustica%20%5B88985406981%5D%20D4.dsf&metadata=false
Apr 14 23:40:48 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Scorpions - Acoustica [88985406981] D4.dsf
Apr 14 23:40:48 volumio-r2r volumio[3949]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf in service mpd
Apr 14 23:40:48 volumio-r2r volumio[3949]: 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 14 23:40:48 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf
Apr 14 23:40:48 volumio-r2r volumio[3949]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf in service mpd
Apr 14 23:40:48 volumio-r2r volumio[3949]: 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 14 23:40:48 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf
Apr 14 23:40:51 volumio-r2r sudo[5453]:  volumio : TTY=pts/1 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -u volumio -f
Apr 14 23:40:51 volumio-r2r sudo[5453]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000)
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Preload queue cleared
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioReplaceandPlayItems
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::ClearQueue
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::stop
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::stPlaybackTimer
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::updateTrackBlock
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrackBlock
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::pushState
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioPushState
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::serviceStop
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreCommandRouter::serviceStop
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: ControllerMpd::stop
Apr 14 23:40:54 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CorePlayQueue::clearPlayQueue
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CorePlayQueue::saveQueue
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioPushQueue
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::addQueueItems
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CorePlayQueue::addQueueItems
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Preload queue cleared
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/01 - David Elias - The Window - Vision of Her (DSD64).dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Using cached record of: music-library/INTERNAL/Erdal/DSDTest/01 - David Elias - The Window - Vision of Her (DSD64).dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioPushQueue
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CorePlayQueue::saveQueue
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::updateTrackBlock
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrackBlock
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioPlay
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::play index 0
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::addQueueItems
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CorePlayQueue::addQueueItems
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Preload queue cleared
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/1kHz-0dBFS.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Using cached record of: music-library/INTERNAL/Erdal/DSDTest/1kHz-0dBFS.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/Scorpions - Acoustica [88985406981] D4.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Using cached record of: music-library/INTERNAL/Erdal/DSDTest/Scorpions - Acoustica [88985406981] D4.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Using cached record of: music-library/INTERNAL/Erdal/DSDTest/07 - David Elias - Acoustic Trio - Rodeo On A Ridge (DSD64).dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Using cached record of: music-library/INTERNAL/Erdal/DSDTest/08 - David Elias - Crossing - Morning Light Western Town (DSD64 2.0).dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979) in service mpd
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/5 Tracks in DSD512-st
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/5 Tracks in DSD512-st in service mpd
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK) in service mpd
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK in service mpd
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/Pink Floyd - The Dark Side of the Moon (2016)
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/Pink Floyd - The Dark Side of the Moon (2016) in service mpd
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/Pink Floyd - The Wall (2016)
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/Pink Floyd - The Wall (2016) in service mpd
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: Adding Item to queue: music-library/INTERNAL/Erdal/DSDTest/The Alan Parsons Project - I Robot (1977- Japan)
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Exploding uri music-library/INTERNAL/Erdal/DSDTest/The Alan Parsons Project - I Robot (1977- Japan) in service mpd
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::stop
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::play index undefined
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::startPlaybackTimer
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:40:54 volumio-r2r volumio[3949]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Erdal/DSDTest/01 - David Elias - The Window - Vision of Her (DSD64).dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand stop
Apr 14 23:40:54 volumio-r2r volumio[3949]: info:
Apr 14 23:40:54 volumio-r2r volumio[3949]: ---------------------------- MPD announces state update: player
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: sendMpdCommand stop took 43 milliseconds
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: ControllerMpd::getState
Apr 14 23:40:54 volumio-r2r volumio[3949]: verbose: ControllerMpd::sendMpdCommand status
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreCommandRouter::servicePushState
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreStateMachine::pushState
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CoreCommandRouter::volumioPushState
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: CorePlayQueue::getTrack 0
Apr 14 23:40:54 volumio-r2r volumio[3949]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received radio_paradise
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/01 - Good Times Bad Times.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/02 - Babe I'm Gonna Leave You.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/03 - You Shook Me.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/04 - Dazed And Confused.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/05 - Your Time Is Gonna Come - Black Mountain Side.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/06 - Communication Breakdown.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/07 - I Can't Quit You Baby.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/(uaoa) Led Zeppelin - Led Zeppelin - 1969 (1979)/08 - How Many More Times.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/5 Tracks in DSD512-st/1. Les Chats Noirs-La Chanson-DSD512.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/5 Tracks in DSD512-st/2. Miles Davis-Milestones, Yuko Mabuchi-DSD512.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/5 Tracks in DSD512-st/4. Barrios-La Catedral, Ricardo Gallén-3.Allegro solemne-DSD512.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/5 Tracks in DSD512-st/5. Dave Brubeck-Take Five Reprise (Desmond), Feenbrothers-DSD512.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)/01 - Highway Star.dff
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)/02 - Maybe I'm A Leo.dff
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)/03 - Pictures Of Home.dff
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)/04 - Never Before.dff
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)/05 - Smoke On The Water.dff
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)/06 - Lazy.dff
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Deep Purple - Machine Head (1972 UK)/07 - Space Truckin'.dff
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/01 - So Far Away.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/02 - Money For Nothing.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/03 - Walk Of Life.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/04 - Your Latest Trick.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/05 - Why Worry.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/06 - Ride Across The River.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/07 - The Man's Too Strong.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/08 - One World.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: info: URI /mnt/INTERNAL/Erdal/DSDTest/Dire Straits - Brothers In Arms 1985 UK/09 - Brothers In Arms.dsf
Apr 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: 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 14 23:40:54 volumio-r2r volumio[3949]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 14 23:40:54 volumio-r2r volumio[3949]: Error: Unable to resolve or reject the same promise twice
Apr 14 23:40:54 volumio-r2r volumio[3949]:     at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43)
Apr 14 23:40:54 volumio-r2r volumio[3949]:     at /volumio/app/plugins/music_service/mpd/index.js:2569:21
Apr 14 23:40:54 volumio-r2r volumio[3949]:     at MpdClient.handleMessage (/volumio/app/plugins/music_service/mpd/lib/mpd.js:77:3)
Apr 14 23:40:54 volumio-r2r volumio[3949]:     at MpdClient.receive (/volumio/app/plugins/music_service/mpd/lib/mpd.js:68:12)
Apr 14 23:40:54 volumio-r2r volumio[3949]:     at Socket. (/volumio/app/plugins/music_service/mpd/lib/mpd.js:43:12)
Apr 14 23:40:54 volumio-r2r volumio[3949]:     at Socket.emit (node:events:514:28)
Apr 14 23:40:54 volumio-r2r volumio[3949]:     at addChunk (node:internal/streams/readable:343:12)
Apr 14 23:40:54 volumio-r2r volumio[3949]:     at readableAddChunk (node:internal/streams/readable:312:11)
Apr 14 23:40:54 volumio-r2r volumio[3949]:     at Readable.push (node:internal/streams/readable:253:10)
Apr 14 23:40:54 volumio-r2r volumio[3949]:     at Pipe.onStreamRead (node:internal/stream_base_commons:190:23)
Apr 14 23:40:54 volumio-r2r volumio[3949]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Apr 14 23:40:54 volumio-r2r sudo[5510]:  volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-14 23:39'
Apr 14 23:40:54 volumio-r2r sudo[5510]: 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"