-- Logs begin at Thu 2026-08-27 10:27:46 EEST, end at Fri 2026-08-28 09:07:54 EEST. -- Aug 28 09:06:12 playroomnew volumio[959]: info: CorePlayQueue::getTrack 0 Aug 28 09:06:12 playroomnew volumio[959]: info: CorePlayQueue::getTrack 1 Aug 28 09:06:12 playroomnew volumio[959]: info: Prefetching next song Aug 28 09:06:12 playroomnew volumio[959]: info: [1787897172467] ControllerTidal::prefetch Aug 28 09:06:12 playroomnew volumio[959]: info: Getting stream with soundQuality LOSSLESS Aug 28 09:06:12 playroomnew volumio[959]: verbose: ControllerMpd::sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/448430916&soundQuality=LOSSLESS" Aug 28 09:06:12 playroomnew volumio[959]: info: Aug 28 09:06:12 playroomnew volumio[959]: ---------------------------- MPD announces system playlist update Aug 28 09:06:12 playroomnew volumio[959]: info: Ignoring MPD Status Update Aug 28 09:06:12 playroomnew volumio[959]: info: sendMpdCommand add "http://127.0.0.1:3245?data=tidal://song/448430916&soundQuality=LOSSLESS" took 3 milliseconds Aug 28 09:06:12 playroomnew volumio[959]: info: CoreStateMachine::setConsumeUpdateService mpd Aug 28 09:06:12 playroomnew volumio[959]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 28 09:06:12 playroomnew volumio[959]: STREAMING PROXY: Handling url /?data=tidal://song/448430916&soundQuality=LOSSLESS Aug 28 09:06:12 playroomnew volumio[959]: info: Aug 28 09:06:12 playroomnew volumio[959]: ---------------------------- MPD announces state update: options Aug 28 09:06:12 playroomnew volumio[959]: info: ------------------------------ 5ms Aug 28 09:06:12 playroomnew volumio[959]: info: sendMpdCommand consume 1 took 2 milliseconds Aug 28 09:06:12 playroomnew volumio[959]: info: ControllerMpd::getState Aug 28 09:06:12 playroomnew volumio[959]: verbose: ControllerMpd::sendMpdCommand status Aug 28 09:06:12 playroomnew volumio[959]: info: sendMpdCommand status took 2 milliseconds Aug 28 09:06:12 playroomnew volumio[959]: verbose: ControllerMpd::parseState Aug 28 09:06:12 playroomnew volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 09:06:12 playroomnew volumio[959]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 28 09:06:12 playroomnew volumio[959]: verbose: ControllerMpd::parseTrackInfo Aug 28 09:06:12 playroomnew volumio[959]: info: ControllerMpd::pushState Aug 28 09:06:12 playroomnew volumio[959]: info: CoreCommandRouter::servicePushState Aug 28 09:06:12 playroomnew volumio[959]: info: CorePlayQueue::getTrack 0 Aug 28 09:06:12 playroomnew volumio[959]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32329,"duration":36,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"872 Kbps","isStreaming":false,"title":"448430915&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/448430915&soundQuality=LOSSLESS","trackType":"tidal"} Aug 28 09:06:12 playroomnew volumio[959]: verbose: CURRENT POSITION 0 Aug 28 09:06:12 playroomnew volumio[959]: info: CoreStateMachine::syncState stateService play Aug 28 09:06:12 playroomnew volumio[959]: info: CoreStateMachine::syncState currentStatus play Aug 28 09:06:12 playroomnew volumio[959]: info: Received an update from plugin. extracting info from payload Aug 28 09:06:12 playroomnew volumio[959]: info: CoreStateMachine::pushState Aug 28 09:06:12 playroomnew volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:06:12 playroomnew volumio[959]: info: CoreCommandRouter::volumioPushState Aug 28 09:06:12 playroomnew volumio[959]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:06:12 playroomnew volumio[959]: info: MRS: Pushing multiroomSync output Aug 28 09:06:12 playroomnew volumio[959]: info: CoreCommandRouter::volumioGetState Aug 28 09:06:12 playroomnew volumio[959]: info: CoreStateMachine::pushState Aug 28 09:06:12 playroomnew volumio[959]: info: CoreCommandRouter::volumioPushState Aug 28 09:06:12 playroomnew volumio[959]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:06:12 playroomnew volumio[959]: info: MRS: Pushing multiroomSync output Aug 28 09:06:12 playroomnew volumio[959]: info: CoreCommandRouter::volumioGetState Aug 28 09:06:12 playroomnew volumio[959]: info: ------------------------------ 41ms Aug 28 09:06:12 playroomnew volumio[959]: info: Executing endpoint getStreamUrltidal Aug 28 09:06:12 playroomnew volumio[959]: info: CoreCommandRouter::executeOnPlugin: tidal , getStreamUrlAPI Aug 28 09:06:13 playroomnew volumio[959]: info: getStreamUrl took 559 milliseconds Aug 28 09:06:13 playroomnew volumio[959]: STREAMING PROXY: Got real url: http://lgf.audio.tidal.com/mediatracks/CAEaKAgDEiQ0NzEwMDMxOGUwM2QyZWViYmY2MGZiNTdjOTI4YTMxMC5tcDQ/0.flac?token=1787900772~ZDdjNmZiM2EwOGEzMmE1ZWNlZGIzYjE3ZTkzODAyNjgyYjkwMTI5OQ== Aug 28 09:06:13 playroomnew volumio[959]: STREAMING PROXY: Requesting upstream with range: none Aug 28 09:06:13 playroomnew volumio[959]: STREAMING PROXY: Upstream status code: 200 Aug 28 09:06:15 playroomnew volumio[959]: info: Aug 28 09:06:15 playroomnew volumio[959]: ---------------------------- MPD announces system playlist update Aug 28 09:06:15 playroomnew volumio[959]: info: Ignoring MPD Status Update Aug 28 09:06:15 playroomnew volumio[959]: info: Aug 28 09:06:15 playroomnew volumio[959]: ---------------------------- MPD announces state update: player Aug 28 09:06:15 playroomnew volumio[959]: info: ControllerMpd::getState Aug 28 09:06:15 playroomnew volumio[959]: verbose: ControllerMpd::sendMpdCommand status Aug 28 09:06:15 playroomnew volumio[959]: info: ------------------------------ 8ms Aug 28 09:06:15 playroomnew volumio[959]: info: sendMpdCommand status took 8 milliseconds Aug 28 09:06:15 playroomnew volumio[959]: verbose: ControllerMpd::parseState Aug 28 09:06:15 playroomnew volumio[959]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 28 09:06:15 playroomnew volumio[959]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 28 09:06:15 playroomnew volumio[959]: verbose: ControllerMpd::parseTrackInfo Aug 28 09:06:15 playroomnew volumio[959]: info: ControllerMpd::pushState Aug 28 09:06:15 playroomnew volumio[959]: info: CoreCommandRouter::servicePushState Aug 28 09:06:15 playroomnew volumio[959]: info: CorePlayQueue::getTrack 0 Aug 28 09:06:15 playroomnew volumio[959]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":245,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"822 Kbps","isStreaming":false,"title":"448430916&soundQuality=LOSSLESS","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/448430916&soundQuality=LOSSLESS","trackType":"tidal"} Aug 28 09:06:15 playroomnew volumio[959]: verbose: CURRENT POSITION 0 Aug 28 09:06:15 playroomnew volumio[959]: info: CoreStateMachine::syncState stateService play Aug 28 09:06:15 playroomnew volumio[959]: info: CoreStateMachine::syncState currentStatus play Aug 28 09:06:15 playroomnew volumio[959]: info: Received an update from plugin. extracting info from payload Aug 28 09:06:15 playroomnew volumio[959]: info: CoreStateMachine::pushState Aug 28 09:06:15 playroomnew volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:06:15 playroomnew volumio[959]: info: CoreCommandRouter::volumioPushState Aug 28 09:06:15 playroomnew volumio[959]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:06:15 playroomnew volumio[959]: info: MRS: Pushing multiroomSync output Aug 28 09:06:15 playroomnew volumio[959]: info: CoreCommandRouter::volumioGetState Aug 28 09:06:15 playroomnew volumio[959]: info: CoreStateMachine::pushState Aug 28 09:06:15 playroomnew volumio[959]: info: CoreCommandRouter::volumioPushState Aug 28 09:06:15 playroomnew volumio[959]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:06:15 playroomnew volumio[959]: info: MRS: Pushing multiroomSync output Aug 28 09:06:15 playroomnew volumio[959]: info: CoreCommandRouter::volumioGetState Aug 28 09:06:15 playroomnew volumio[959]: info: ------------------------------ 39ms Aug 28 09:06:16 playroomnew volumio[959]: info: CoreStateMachine::startPlaybackTimer Aug 28 09:06:17 playroomnew volumio[959]: info: CorePlayQueue::getTrack 1 Aug 28 09:06:17 playroomnew volumio[959]: STREAMING PROXY: Upstream closed. Received 25357558 bytes Aug 28 09:06:17 playroomnew volumio[959]: STREAMING PROXY: Client closed connection Aug 28 09:06:17 playroomnew volumio[959]: info: CoreStateMachine::pushState Aug 28 09:06:17 playroomnew volumio[959]: info: CorePlayQueue::getTrack 1 Aug 28 09:06:17 playroomnew volumio[959]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 28 09:06:17 playroomnew volumio[959]: info: CoreCommandRouter::volumioPushState Aug 28 09:06:17 playroomnew volumio[959]: info: MRS: Pushing multiroomSync output update for this device Aug 28 09:06:17 playroomnew volumio[959]: info: MRS: Pushing multiroomSync output Aug 28 09:06:17 playroomnew volumio[959]: info: CoreCommandRouter::volumioGetState Aug 28 09:06:17 playroomnew volumio[959]: info: CorePlayQueue::getTrack 1 Aug 28 09:06:26 playroomnew volumiossh-tunnel[24765]: ssh: connect to host eu6.myvolumio.org port 2222: Connection timed out Aug 28 09:06:26 playroomnew autossh[24766]: ssh exited with error status 255; restarting ssh Aug 28 09:06:26 playroomnew autossh[24766]: starting ssh (count 2) Aug 28 09:06:26 playroomnew autossh[24766]: ssh child pid is 25077 Aug 28 09:06:31 playroomnew volumiossh-tunnel[24765]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. Aug 28 09:06:49 playroomnew volumio[959]: info: Tunnel connection is inactive, restarting it Aug 28 09:06:49 playroomnew volumio[959]: info: Starting Tunnel 1 Aug 28 09:06:49 playroomnew volumio[959]: info: Starting Tunnel Connection Checker Aug 28 09:06:49 playroomnew sudo[25115]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 28 09:06:49 playroomnew sudo[25115]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 28 09:06:49 playroomnew systemd[1]: Stopping MyVolumio SSH Tunnel... Aug 28 09:06:49 playroomnew systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Aug 28 09:06:49 playroomnew autossh[24766]: received signal to exit (15) Aug 28 09:06:49 playroomnew systemd[1]: sshtunnel.service: Succeeded. Aug 28 09:06:49 playroomnew systemd[1]: Stopped MyVolumio SSH Tunnel. Aug 28 09:06:49 playroomnew volumio[959]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 28 09:06:49 playroomnew volumio[959]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 28 09:06:49 playroomnew systemd[1]: Started MyVolumio SSH Tunnel. Aug 28 09:06:49 playroomnew sudo[25115]: pam_unix(sudo:session): session closed for user root Aug 28 09:06:49 playroomnew volumio[959]: info: Remote SSH Started Aug 28 09:06:49 playroomnew autossh[25132]: port set to 0, monitoring disabled Aug 28 09:06:49 playroomnew autossh[25132]: starting ssh (count 1) Aug 28 09:06:49 playroomnew autossh[25132]: ssh child pid is 25135 Aug 28 09:06:49 playroomnew volumio[959]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Aug 28 09:06:49 playroomnew volumio[959]: info: CoreCommandRouter::volumioGetState Aug 28 09:06:49 playroomnew volumio[959]: info: CorePlayQueue::getTrack 1 Aug 28 09:06:50 playroomnew volumiossh-tunnel[25117]: Warning: Permanently added '[eu6.myvolumio.org]:2222,[207.154.233.230]:2222' (RSA) to the list of known hosts. Aug 28 09:07:26 playroomnew volumiossh-tunnel[25117]: Connection to eu6.myvolumio.org closed by remote host. Aug 28 09:07:26 playroomnew autossh[25132]: ssh exited with error status 255; restarting ssh Aug 28 09:07:26 playroomnew autossh[25132]: starting ssh (count 2) Aug 28 09:07:26 playroomnew autossh[25132]: ssh child pid is 25218 Aug 28 09:07:31 playroomnew volumiossh-tunnel[25117]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 28 09:07:31 playroomnew autossh[25132]: ssh exited with error status 255; restarting ssh Aug 28 09:07:31 playroomnew autossh[25132]: starting ssh (count 3) Aug 28 09:07:31 playroomnew autossh[25132]: ssh child pid is 25234 Aug 28 09:07:31 playroomnew volumiossh-tunnel[25117]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 28 09:07:31 playroomnew autossh[25132]: ssh exited with error status 255; restarting ssh Aug 28 09:07:31 playroomnew autossh[25132]: starting ssh (count 4) Aug 28 09:07:31 playroomnew autossh[25132]: ssh child pid is 25235 Aug 28 09:07:31 playroomnew volumiossh-tunnel[25117]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 28 09:07:31 playroomnew autossh[25132]: ssh exited with error status 255; restarting ssh Aug 28 09:07:31 playroomnew autossh[25132]: starting ssh (count 5) Aug 28 09:07:31 playroomnew autossh[25132]: ssh child pid is 25236 Aug 28 09:07:31 playroomnew volumiossh-tunnel[25117]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 28 09:07:31 playroomnew autossh[25132]: ssh exited with error status 255; restarting ssh Aug 28 09:07:31 playroomnew autossh[25132]: starting ssh (count 6) Aug 28 09:07:31 playroomnew autossh[25132]: ssh child pid is 25237 Aug 28 09:07:32 playroomnew volumiossh-tunnel[25117]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 28 09:07:32 playroomnew autossh[25132]: ssh exited with error status 255; restarting ssh Aug 28 09:07:34 playroomnew autossh[25132]: starting ssh (count 7) Aug 28 09:07:34 playroomnew autossh[25132]: ssh child pid is 25238 Aug 28 09:07:42 playroomnew volumiossh-tunnel[25117]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 28 09:07:42 playroomnew autossh[25132]: ssh exited with error status 255; restarting ssh Aug 28 09:07:43 playroomnew volumio[959]: info: browseTIDALUri took 134812 milliseconds Aug 28 09:07:43 playroomnew volumio[959]: error: TIDAL Browse Error: No response received Aug 28 09:07:43 playroomnew volumio[959]: error: Failed to execute browseSource: No response received Aug 28 09:07:50 playroomnew autossh[25132]: starting ssh (count 8) Aug 28 09:07:50 playroomnew autossh[25132]: ssh child pid is 25267 Aug 28 09:07:50 playroomnew volumiossh-tunnel[25117]: ssh: connect to host eu6.myvolumio.org port 2222: Connection refused Aug 28 09:07:50 playroomnew autossh[25132]: ssh exited with error status 255; restarting ssh Aug 28 09:07:53 playroomnew volumio[959]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 09:07:53 playroomnew volumio[959]: Error: connect ETIMEDOUT 151.101.2.79:443 Aug 28 09:07:53 playroomnew volumio[959]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1146:16) { Aug 28 09:07:53 playroomnew volumio[959]: errno: -110, Aug 28 09:07:53 playroomnew volumio[959]: code: 'ETIMEDOUT', Aug 28 09:07:53 playroomnew volumio[959]: syscall: 'connect', Aug 28 09:07:53 playroomnew volumio[959]: address: '151.101.2.79', Aug 28 09:07:53 playroomnew volumio[959]: port: 443 Aug 28 09:07:53 playroomnew volumio[959]: } Aug 28 09:07:53 playroomnew volumio[959]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 28 09:07:54 playroomnew sudo[25277]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-08-28 09:06 Aug 28 09:07:54 playroomnew sudo[25277]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"