Jan 28 11:07:19 rpimusic volumio[18195]: info: Tunnel connection is inactive, restarting it Jan 28 11:07:19 rpimusic volumio[18195]: info: Starting Tunnel 1 Jan 28 11:07:19 rpimusic volumio[18195]: info: Starting Tunnel Connection Checker Jan 28 11:07:19 rpimusic sudo[17773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Jan 28 11:07:19 rpimusic sudo[17773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 28 11:07:19 rpimusic autossh[17122]: received signal to exit (15) Jan 28 11:07:19 rpimusic systemd[1]: Stopping sshtunnel.service - MyVolumio SSH Tunnel... Jan 28 11:07:19 rpimusic systemd[1]: sshtunnel.service: Deactivated successfully. Jan 28 11:07:19 rpimusic systemd[1]: Stopped sshtunnel.service - MyVolumio SSH Tunnel. Jan 28 11:07:19 rpimusic systemd[1]: Started sshtunnel.service - MyVolumio SSH Tunnel. Jan 28 11:07:19 rpimusic sudo[17773]: pam_unix(sudo:session): session closed for user root Jan 28 11:07:19 rpimusic volumio[18195]: info: Remote SSH Started Jan 28 11:07:19 rpimusic autossh[17776]: port set to 0, monitoring disabled Jan 28 11:07:19 rpimusic autossh[17776]: starting ssh (count 1) Jan 28 11:07:19 rpimusic autossh[17776]: ssh child pid is 17779 Jan 28 11:07:19 rpimusic volumio[18195]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Jan 28 11:07:19 rpimusic volumio[18195]: info: CoreCommandRouter::volumioGetState Jan 28 11:07:19 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 6 Jan 28 11:07:19 rpimusic volumiossh-tunnel[17779]: Warning: Permanently added '[eu4.myvolumio.org]:2222' (RSA) to the list of known hosts. Jan 28 11:07:20 rpimusic volumiossh-tunnel[17779]: Warning: remote port forwarding failed for listen port 8091 Jan 28 11:07:25 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 28 11:07:25 rpimusic volumio[18195]: info: Received Get System Info Jan 28 11:07:25 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 28 11:07:25 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 28 11:07:25 rpimusic volumio[18195]: info: Discovery: Getting this device information Jan 28 11:07:25 rpimusic volumio[18195]: info: CoreCommandRouter::volumioGetState Jan 28 11:07:25 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 6 Jan 28 11:07:25 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 28 11:07:38 rpimusic volumio[18195]: info: Preload queue cleared Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::volumioReplaceandPlayItems Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::ClearQueue Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::stop Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::stPlaybackTimer Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::updateTrackBlock Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::getTrackBlock Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::pushState Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 6 Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::volumioPushState Jan 28 11:07:38 rpimusic volumio[18195]: info: MRS: Pushing multiroomSync output update for this device Jan 28 11:07:38 rpimusic volumio[18195]: info: MRS: Pushing multiroomSync output Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::volumioGetState Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 6 Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::serviceStop Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 6 Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::serviceStop Jan 28 11:07:38 rpimusic volumio[18195]: info: [1769587658508] ControllerQobuz::stop Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 11:07:38 rpimusic volumio[18195]: info: ControllerMpd::stop Jan 28 11:07:38 rpimusic volumio[18195]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::clearPlayQueue Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::saveQueue Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::volumioPushQueue Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::addQueueItems Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::addQueueItems Jan 28 11:07:38 rpimusic volumio[18195]: info: Preload queue cleared Jan 28 11:07:38 rpimusic volumio[18195]: info: Adding Item to queue: qobuz://song/96137477 Jan 28 11:07:38 rpimusic volumio[18195]: info: Using cached record of: qobuz://song/96137477 Jan 28 11:07:38 rpimusic volumio[18195]: info: Adding Item to queue: qobuz://song/96137478 Jan 28 11:07:38 rpimusic volumio[18195]: info: Using cached record of: qobuz://song/96137478 Jan 28 11:07:38 rpimusic volumio[18195]: info: Adding Item to queue: qobuz://song/96137479 Jan 28 11:07:38 rpimusic volumio[18195]: info: Using cached record of: qobuz://song/96137479 Jan 28 11:07:38 rpimusic volumio[18195]: info: Adding Item to queue: qobuz://song/96137480 Jan 28 11:07:38 rpimusic volumio[18195]: info: Using cached record of: qobuz://song/96137480 Jan 28 11:07:38 rpimusic volumio[18195]: info: Adding Item to queue: qobuz://song/96137481 Jan 28 11:07:38 rpimusic volumio[18195]: info: Using cached record of: qobuz://song/96137481 Jan 28 11:07:38 rpimusic volumio[18195]: info: Adding Item to queue: qobuz://song/96137482 Jan 28 11:07:38 rpimusic volumio[18195]: info: Using cached record of: qobuz://song/96137482 Jan 28 11:07:38 rpimusic volumio[18195]: info: Adding Item to queue: qobuz://song/96137483 Jan 28 11:07:38 rpimusic volumio[18195]: info: Using cached record of: qobuz://song/96137483 Jan 28 11:07:38 rpimusic volumio[18195]: info: Adding Item to queue: qobuz://song/96137484 Jan 28 11:07:38 rpimusic volumio[18195]: info: Using cached record of: qobuz://song/96137484 Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::volumioPushQueue Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::saveQueue Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::updateTrackBlock Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::getTrackBlock Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::volumioGetState Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::volumioPlay Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::play index 7 Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::addQueueItems Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::addQueueItems Jan 28 11:07:38 rpimusic volumio[18195]: info: Preload queue cleared Jan 28 11:07:38 rpimusic volumio[18195]: info: Adding Item to queue: qobuz://song/96137485 Jan 28 11:07:38 rpimusic volumio[18195]: info: Using cached record of: qobuz://song/96137485 Jan 28 11:07:38 rpimusic volumio[18195]: info: Adding Item to queue: qobuz://song/96137486 Jan 28 11:07:38 rpimusic volumio[18195]: info: Using cached record of: qobuz://song/96137486 Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::stop Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::volumioPushQueue Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::saveQueue Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::play index undefined Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::setConsumeUpdateService undefined Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::updateTrackBlock Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::getTrackBlock Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 7 Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::startPlaybackTimer Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 7 Jan 28 11:07:38 rpimusic volumio[18195]: info: [1769587658519] ControllerQobuz::clearAddPlayTrack Jan 28 11:07:38 rpimusic volumio[18195]: info: Jan 28 11:07:38 rpimusic volumio[18195]: ---------------------------- MPD announces state update: player Jan 28 11:07:38 rpimusic volumio[18195]: info: sendMpdCommand stop took 24 milliseconds Jan 28 11:07:38 rpimusic volumio[18195]: info: ControllerMpd::getState Jan 28 11:07:38 rpimusic volumio[18195]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:07:38 rpimusic volumio[18195]: info: sendMpdCommand status took 0 milliseconds Jan 28 11:07:38 rpimusic volumio[18195]: verbose: ControllerMpd::parseState Jan 28 11:07:38 rpimusic volumio[18195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 11:07:38 rpimusic volumio[18195]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 28 11:07:38 rpimusic volumio[18195]: verbose: ControllerMpd::parseTrackInfo Jan 28 11:07:38 rpimusic volumio[18195]: info: ControllerMpd::pushState Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::servicePushState Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreStateMachine::pushState Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 7 Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::volumioPushState Jan 28 11:07:38 rpimusic volumio[18195]: info: MRS: Pushing multiroomSync output update for this device Jan 28 11:07:38 rpimusic volumio[18195]: info: MRS: Pushing multiroomSync output Jan 28 11:07:38 rpimusic volumio[18195]: info: CoreCommandRouter::volumioGetState Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 7 Jan 28 11:07:38 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 7 Jan 28 11:07:38 rpimusic volumio[18195]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jan 28 11:07:38 rpimusic volumio[18195]: info: ------------------------------ 6ms Jan 28 11:07:38 rpimusic volumio[18195]: info: touch_display: Setting screensaver timeout to 120 seconds. Jan 28 11:07:38 rpimusic volumio[18195]: info: getStreamUrl took 245 milliseconds Jan 28 11:07:38 rpimusic volumio[18195]: verbose: ControllerMpd::sendMpdCommand stop Jan 28 11:07:38 rpimusic volumio[18195]: info: sendMpdCommand stop took 0 milliseconds Jan 28 11:07:38 rpimusic volumio[18195]: verbose: ControllerMpd::sendMpdCommand clear Jan 28 11:07:38 rpimusic volumio[18195]: info: Jan 28 11:07:38 rpimusic volumio[18195]: ---------------------------- MPD announces system playlist update Jan 28 11:07:38 rpimusic volumio[18195]: info: Ignoring MPD Status Update Jan 28 11:07:38 rpimusic volumio[18195]: info: sendMpdCommand clear took 0 milliseconds Jan 28 11:07:38 rpimusic volumio[18195]: verbose: ControllerMpd::sendMpdCommand load "https://streaming-qobuz-std.akamaized.net/file?uid=8030277&eid=96137484&fmt=7&profile=raw&app_id=539451548&cid=3648025&etsp=1769591258&hmac=o5gWKC-EiCby4sOU6aOb0Csvzos" Jan 28 11:07:38 rpimusic volumio[18195]: error: updateQueue error: null Jan 28 11:07:38 rpimusic volumio[18195]: info: ------------------------------ 1ms Jan 28 11:07:39 rpimusic volumio[18195]: verbose: ControllerMpd::sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=8030277&eid=96137484&fmt=7&profile=raw&app_id=539451548&cid=3648025&etsp=1769591258&hmac=o5gWKC-EiCby4sOU6aOb0Csvzos" Jan 28 11:07:39 rpimusic volumio[18195]: info: Jan 28 11:07:39 rpimusic volumio[18195]: ---------------------------- MPD announces system playlist update Jan 28 11:07:39 rpimusic volumio[18195]: info: Ignoring MPD Status Update Jan 28 11:07:39 rpimusic volumio[18195]: info: sendMpdCommand add "https://streaming-qobuz-std.akamaized.net/file?uid=8030277&eid=96137484&fmt=7&profile=raw&app_id=539451548&cid=3648025&etsp=1769591258&hmac=o5gWKC-EiCby4sOU6aOb0Csvzos" took 0 milliseconds Jan 28 11:07:39 rpimusic volumio[18195]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 11:07:39 rpimusic volumio[18195]: verbose: ControllerMpd::sendMpdCommand play Jan 28 11:07:39 rpimusic volumio[18195]: info: ------------------------------ 1ms Jan 28 11:07:39 rpimusic volumio[18195]: info: sendMpdCommand play took 1 milliseconds Jan 28 11:07:39 rpimusic volumio[18195]: info: Jan 28 11:07:39 rpimusic volumio[18195]: ---------------------------- MPD announces state update: player Jan 28 11:07:39 rpimusic volumio[18195]: info: ControllerMpd::getState Jan 28 11:07:39 rpimusic volumio[18195]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:07:39 rpimusic volumio[18195]: info: Jan 28 11:07:39 rpimusic volumio[18195]: ---------------------------- MPD announces state update: player Jan 28 11:07:39 rpimusic volumio[18195]: info: sendMpdCommand status took 3 milliseconds Jan 28 11:07:39 rpimusic volumio[18195]: info: ControllerMpd::getState Jan 28 11:07:39 rpimusic volumio[18195]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:07:39 rpimusic volumio[18195]: verbose: ControllerMpd::parseState Jan 28 11:07:39 rpimusic volumio[18195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 11:07:39 rpimusic volumio[18195]: info: sendMpdCommand status took 1 milliseconds Jan 28 11:07:39 rpimusic volumio[18195]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 28 11:07:39 rpimusic volumio[18195]: verbose: ControllerMpd::parseState Jan 28 11:07:39 rpimusic volumio[18195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 11:07:39 rpimusic volumio[18195]: verbose: ControllerMpd::parseTrackInfo Jan 28 11:07:39 rpimusic volumio[18195]: info: ControllerMpd::pushState Jan 28 11:07:39 rpimusic volumio[18195]: info: CoreCommandRouter::servicePushState Jan 28 11:07:39 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 7 Jan 28 11:07:39 rpimusic volumio[18195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":335,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=8030277&eid=96137484&fmt=7&profile=raw&app_id=539451548&cid=3648025&etsp=1769591258&hmac=o5gWKC-EiCby4sOU6aOb0Csvzos","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8030277&eid=96137484&fmt=7&profile=raw&app_id=539451548&cid=3648025&etsp=1769591258&hmac=o5gWKC-EiCby4sOU6aOb0Csvzos","trackType":"qobuz"} Jan 28 11:07:39 rpimusic volumio[18195]: verbose: CURRENT POSITION 7 Jan 28 11:07:39 rpimusic volumio[18195]: info: CoreStateMachine::syncState stateService play Jan 28 11:07:39 rpimusic volumio[18195]: info: CoreStateMachine::syncState currentStatus stop Jan 28 11:07:39 rpimusic volumio[18195]: info: ------------------------------ 6ms Jan 28 11:07:39 rpimusic volumio[18195]: info: sendMpdCommand playlistinfo took 0 milliseconds Jan 28 11:07:39 rpimusic volumio[18195]: verbose: ControllerMpd::parseTrackInfo Jan 28 11:07:39 rpimusic volumio[18195]: info: ControllerMpd::pushState Jan 28 11:07:39 rpimusic volumio[18195]: info: CoreCommandRouter::servicePushState Jan 28 11:07:39 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 7 Jan 28 11:07:39 rpimusic volumio[18195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":335,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=8030277&eid=96137484&fmt=7&profile=raw&app_id=539451548&cid=3648025&etsp=1769591258&hmac=o5gWKC-EiCby4sOU6aOb0Csvzos","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8030277&eid=96137484&fmt=7&profile=raw&app_id=539451548&cid=3648025&etsp=1769591258&hmac=o5gWKC-EiCby4sOU6aOb0Csvzos","trackType":"qobuz"} Jan 28 11:07:39 rpimusic volumio[18195]: verbose: CURRENT POSITION 7 Jan 28 11:07:39 rpimusic volumio[18195]: info: CoreStateMachine::syncState stateService play Jan 28 11:07:39 rpimusic volumio[18195]: info: CoreStateMachine::syncState currentStatus play Jan 28 11:07:39 rpimusic volumio[18195]: info: Received an update from plugin. extracting info from payload Jan 28 11:07:39 rpimusic volumio[18195]: info: CoreStateMachine::pushState Jan 28 11:07:39 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 11:07:39 rpimusic volumio[18195]: info: CoreCommandRouter::volumioPushState Jan 28 11:07:39 rpimusic volumio[18195]: info: MRS: Pushing multiroomSync output update for this device Jan 28 11:07:39 rpimusic volumio[18195]: info: MRS: Pushing multiroomSync output Jan 28 11:07:39 rpimusic volumio[18195]: info: CoreCommandRouter::volumioGetState Jan 28 11:07:39 rpimusic volumio[18195]: info: CoreStateMachine::pushState Jan 28 11:07:39 rpimusic volumio[18195]: info: CoreCommandRouter::volumioPushState Jan 28 11:07:39 rpimusic volumio[18195]: info: MRS: Pushing multiroomSync output update for this device Jan 28 11:07:39 rpimusic volumio[18195]: info: MRS: Pushing multiroomSync output Jan 28 11:07:39 rpimusic volumio[18195]: info: CoreCommandRouter::volumioGetState Jan 28 11:07:39 rpimusic volumio[18195]: info: ------------------------------ 7ms Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreCommandRouter::volumioSeek Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreStateMachine::seek Jan 28 11:07:42 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 7 Jan 28 11:07:42 rpimusic volumio[18195]: info: TRACKBLOCK {"album":"Sticky Fingers","albumUri":"qobuz://album/hk73zapvzymtc","albumart":"https://static.qobuz.com/images/covers/tc/ym/hk73zapvzymtc_600.jpg","artist":"The Rolling Stones","artistUri":"qobuz://artist/38323","audioQuality":"HI_RES","bitdepth":"24 bit","duration":334,"explicit":false,"name":"Sister Morphine","samplerate":"44 KHz","service":"qobuz","title":"Sister Morphine","trackType":"qobuz","tracknumber":8,"type":"track","uri":"qobuz://song/96137484","volumeNumber":1,"tagImage":"/albumart?sectionimage=music_service/qobuz/icons/qobuzhires.png"} Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreStateMachine::startPlaybackTimer Jan 28 11:07:42 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 7 Jan 28 11:07:42 rpimusic volumio[18195]: info: [1769587662724] ControllerQobuz::seek Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreStateMachine::setConsumeUpdateService mpd Jan 28 11:07:42 rpimusic volumio[18195]: info: ControllerMpd::seek Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreStateMachine::pushState Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreCommandRouter::volumioPushState Jan 28 11:07:42 rpimusic volumio[18195]: info: MRS: Pushing multiroomSync output update for this device Jan 28 11:07:42 rpimusic volumio[18195]: info: MRS: Pushing multiroomSync output Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreCommandRouter::volumioGetState Jan 28 11:07:42 rpimusic volumio[18195]: error: null Jan 28 11:07:42 rpimusic volumio[18195]: info: Jan 28 11:07:42 rpimusic volumio[18195]: ---------------------------- MPD announces state update: player Jan 28 11:07:42 rpimusic volumio[18195]: info: ControllerMpd::getState Jan 28 11:07:42 rpimusic volumio[18195]: verbose: ControllerMpd::sendMpdCommand status Jan 28 11:07:42 rpimusic volumio[18195]: info: sendMpdCommand status took 3 milliseconds Jan 28 11:07:42 rpimusic volumio[18195]: verbose: ControllerMpd::parseState Jan 28 11:07:42 rpimusic volumio[18195]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 28 11:07:42 rpimusic volumio[18195]: info: sendMpdCommand playlistinfo took 2 milliseconds Jan 28 11:07:42 rpimusic volumio[18195]: verbose: ControllerMpd::parseTrackInfo Jan 28 11:07:42 rpimusic volumio[18195]: info: ControllerMpd::pushState Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreCommandRouter::servicePushState Jan 28 11:07:42 rpimusic volumio[18195]: info: CorePlayQueue::getTrack 7 Jan 28 11:07:42 rpimusic volumio[18195]: verbose: STATE SERVICE {"status":"play","position":0,"seek":45000,"duration":335,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"file?uid=8030277&eid=96137484&fmt=7&profile=raw&app_id=539451548&cid=3648025&etsp=1769591258&hmac=o5gWKC-EiCby4sOU6aOb0Csvzos","artist":null,"album":null,"uri":"https://streaming-qobuz-std.akamaized.net/file?uid=8030277&eid=96137484&fmt=7&profile=raw&app_id=539451548&cid=3648025&etsp=1769591258&hmac=o5gWKC-EiCby4sOU6aOb0Csvzos","trackType":"qobuz"} Jan 28 11:07:42 rpimusic volumio[18195]: verbose: CURRENT POSITION 7 Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreStateMachine::syncState stateService play Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreStateMachine::syncState currentStatus play Jan 28 11:07:42 rpimusic volumio[18195]: info: Received an update from plugin. extracting info from payload Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreStateMachine::pushState Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreCommandRouter::volumioPushState Jan 28 11:07:42 rpimusic volumio[18195]: info: MRS: Pushing multiroomSync output update for this device Jan 28 11:07:42 rpimusic volumio[18195]: info: MRS: Pushing multiroomSync output Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreCommandRouter::volumioGetState Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreStateMachine::pushState Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreCommandRouter::volumioPushState Jan 28 11:07:42 rpimusic volumio[18195]: info: MRS: Pushing multiroomSync output update for this device Jan 28 11:07:42 rpimusic volumio[18195]: info: MRS: Pushing multiroomSync output Jan 28 11:07:42 rpimusic volumio[18195]: info: CoreCommandRouter::volumioGetState Jan 28 11:07:42 rpimusic volumio[18195]: info: ------------------------------ 13ms Jan 28 11:07:57 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: mpd , search Jan 28 11:07:57 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: qobuz , search Jan 28 11:07:58 rpimusic volumio[18195]: info: searchQOBUZUri took 1097 milliseconds Jan 28 11:07:58 rpimusic volumio[18195]: info: All search sources collected, pushing search results Jan 28 11:07:58 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: qobuz , handleBrowseUri Jan 28 11:07:58 rpimusic volumio[18195]: info: handleBrowseUri took 642 milliseconds Jan 28 11:07:58 rpimusic volumio[18195]: info: Preload queue cleared Jan 28 11:07:58 rpimusic volumio[18195]: info: Executing endpoint getSimilarArtists Jan 28 11:07:58 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getSimilarArtists Jan 28 11:07:59 rpimusic volumio[18195]: info: Executing endpoint metavolumio Jan 28 11:07:59 rpimusic volumio[18195]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jan 28 11:08:00 rpimusic volumio[18195]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 11:08:00 rpimusic volumio[18195]: AggregateError Jan 28 11:08:00 rpimusic volumio[18195]: at internalConnectMultiple (node:net:1114:18) Jan 28 11:08:00 rpimusic volumio[18195]: at afterConnectMultiple (node:net:1667:5) { Jan 28 11:08:00 rpimusic volumio[18195]: code: 'ECONNREFUSED', Jan 28 11:08:00 rpimusic volumio[18195]: [errors]: [ Jan 28 11:08:00 rpimusic volumio[18195]: Error: connect ECONNREFUSED 158.69.209.125:443 Jan 28 11:08:00 rpimusic volumio[18195]: at createConnectionError (node:net:1634:14) Jan 28 11:08:00 rpimusic volumio[18195]: at afterConnectMultiple (node:net:1664:40) { Jan 28 11:08:00 rpimusic volumio[18195]: errno: -111, Jan 28 11:08:00 rpimusic volumio[18195]: code: 'ECONNREFUSED', Jan 28 11:08:00 rpimusic volumio[18195]: syscall: 'connect', Jan 28 11:08:00 rpimusic volumio[18195]: address: '158.69.209.125', Jan 28 11:08:00 rpimusic volumio[18195]: port: 443 Jan 28 11:08:00 rpimusic volumio[18195]: }, Jan 28 11:08:00 rpimusic volumio[18195]: Error: connect ECONNREFUSED 158.69.210.98:443 Jan 28 11:08:00 rpimusic volumio[18195]: at createConnectionError (node:net:1634:14) Jan 28 11:08:00 rpimusic volumio[18195]: at afterConnectMultiple (node:net:1664:40) { Jan 28 11:08:00 rpimusic volumio[18195]: errno: -111, Jan 28 11:08:00 rpimusic volumio[18195]: code: 'ECONNREFUSED', Jan 28 11:08:00 rpimusic volumio[18195]: syscall: 'connect', Jan 28 11:08:00 rpimusic volumio[18195]: address: '158.69.210.98', Jan 28 11:08:00 rpimusic volumio[18195]: port: 443 Jan 28 11:08:00 rpimusic volumio[18195]: } Jan 28 11:08:00 rpimusic volumio[18195]: ] Jan 28 11:08:00 rpimusic volumio[18195]: } Jan 28 11:08:00 rpimusic volumio[18195]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 28 11:08:01 rpimusic sudo[17945]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-28 11:07' Jan 28 11:08:01 rpimusic sudo[17945]: 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"