-- Logs begin at Thu 2026-01-08 04:55:33 UTC, end at Thu 2026-05-28 12:17:15 UTC. -- May 28 12:16:08 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:08 opera volumio[11375]: info: May 28 12:16:08 opera volumio[11375]: ---------------------------- MPD announces state update: update May 28 12:16:08 opera volumio[11375]: info: ControllerMpd::getState May 28 12:16:08 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:08 opera volumio[11375]: info: sendMpdCommand status took 2 milliseconds May 28 12:16:08 opera volumio[11375]: info: sendMpdCommand status took 1 milliseconds May 28 12:16:08 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:10 opera volumio[11375]: info: Command Router : Notfying DB Updatetrue May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::Close All Modals sent May 28 12:16:10 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:10 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 28 12:16:10 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::syncState stateService stop May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus stop May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: No code May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: ------------------------------ 1749ms May 28 12:16:10 opera volumio[11375]: info: MPD Database updated - AlbumList cache refreshed May 28 12:16:10 opera volumio[11375]: info: May 28 12:16:10 opera volumio[11375]: ---------------------------- MPD announces state update: database May 28 12:16:10 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:10 opera volumio[11375]: info: May 28 12:16:10 opera volumio[11375]: ---------------------------- MPD announces state update: update May 28 12:16:10 opera volumio[11375]: info: ControllerMpd::getState May 28 12:16:10 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:10 opera volumio[11375]: info: ControllerMpd::getState May 28 12:16:10 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:10 opera volumio[11375]: info: MPD Database updated - AlbumList cache refreshed May 28 12:16:10 opera volumio[11375]: info: May 28 12:16:10 opera volumio[11375]: ---------------------------- MPD announces state update: database May 28 12:16:10 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:10 opera volumio[11375]: info: May 28 12:16:10 opera volumio[11375]: ---------------------------- MPD announces state update: update May 28 12:16:10 opera volumio[11375]: info: ControllerMpd::getState May 28 12:16:10 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:10 opera volumio[11375]: info: ControllerMpd::getState May 28 12:16:10 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:10 opera volumio[11375]: info: sendMpdCommand status took 48 milliseconds May 28 12:16:10 opera volumio[11375]: info: sendMpdCommand status took 47 milliseconds May 28 12:16:10 opera volumio[11375]: info: sendMpdCommand status took 44 milliseconds May 28 12:16:10 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:10 opera volumio[11375]: info: Command Router : Notfying DB Updatefalse May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::Close All Modals sent May 28 12:16:10 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:10 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:10 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 28 12:16:10 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::syncState stateService stop May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus stop May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: No code May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 28 12:16:10 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::syncState stateService stop May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus stop May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: No code May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: ------------------------------ 124ms May 28 12:16:10 opera volumio[11375]: info: ------------------------------ 124ms May 28 12:16:10 opera volumio[11375]: info: sendMpdCommand status took 134 milliseconds May 28 12:16:10 opera volumio[11375]: info: sendMpdCommand status took 134 milliseconds May 28 12:16:10 opera volumio[11375]: info: sendMpdCommand status took 135 milliseconds May 28 12:16:10 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:10 opera volumio[11375]: info: Command Router : Notfying DB Updatefalse May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::Close All Modals sent May 28 12:16:10 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:10 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:10 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 28 12:16:10 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::syncState stateService stop May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus stop May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: No code May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} May 28 12:16:10 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::syncState stateService stop May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus stop May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: No code May 28 12:16:10 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:10 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:10 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:10 opera volumio[11375]: info: ------------------------------ 230ms May 28 12:16:10 opera volumio[11375]: info: ------------------------------ 230ms May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:10 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:13 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 28 12:16:13 opera volumio[11375]: info: CURURI: albums:// May 28 12:16:13 opera volumio[11375]: listAlbums - loading Albums from cache May 28 12:16:23 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri May 28 12:16:23 opera volumio[11375]: info: CURURI: albums://Count%20Basie/Fancy%20Pants May 28 12:16:23 opera volumio[11375]: info: Executing endpoint metavolumio May 28 12:16:23 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 28 12:16:23 opera volumio[11375]: info: Executing endpoint metavolumio May 28 12:16:23 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio May 28 12:16:24 opera volumio[1612]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.21:5353): query type 65 is invalid May 28 12:16:24 opera volumio[1612]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.21:5353): query type 65 is invalid May 28 12:16:25 opera volumio[11375]: info: CoreCommandRouter::volumioReplaceandPlayItems May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::ClearQueue May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::stop May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 12:16:25 opera volumio[11375]: verbose: UNSET VOLATILE May 28 12:16:25 opera volumio[11375]: info: CorePlayQueue::clearPlayQueue May 28 12:16:25 opera volumio[11375]: info: CorePlayQueue::saveQueue May 28 12:16:25 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:25 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:25 opera volumio[11375]: info: CoreCommandRouter::volumioPushQueue May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::addQueueItems May 28 12:16:25 opera volumio[11375]: info: CorePlayQueue::addQueueItems May 28 12:16:25 opera volumio[11375]: info: Adding Item to queue: albums://Count%20Basie/Fancy%20Pants May 28 12:16:25 opera volumio[11375]: info: Exploding uri albums://Count%20Basie/Fancy%20Pants in service mpd May 28 12:16:25 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:25 opera volumio[11375]: info: CorePlayQueue::saveQueue May 28 12:16:25 opera volumio[11375]: info: CoreCommandRouter::volumioPushQueue May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::updateTrackBlock May 28 12:16:25 opera volumio[11375]: info: CorePlayQueue::getTrackBlock May 28 12:16:25 opera volumio[11375]: info: CoreCommandRouter::volumioPlay May 28 12:16:25 opera volumio[11375]: verbose: UNSET VOLATILE May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::play index 0 May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::stop May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 12:16:25 opera volumio[11375]: verbose: UNSET VOLATILE May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::play index undefined May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::setConsumeUpdateService undefined May 28 12:16:25 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::startPlaybackTimer May 28 12:16:25 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:25 opera volumio[11375]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Count Basie-Fancy Pants/Put It Right Here.flac May 28 12:16:25 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand stop May 28 12:16:25 opera volumio[11375]: info: sendMpdCommand stop took 15 milliseconds May 28 12:16:25 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand clear May 28 12:16:25 opera volumio[11375]: info: May 28 12:16:25 opera volumio[11375]: ---------------------------- MPD announces system playlist update May 28 12:16:25 opera volumio[11375]: info: Ignoring MPD Status Update May 28 12:16:25 opera volumio[11375]: info: sendMpdCommand clear took 2 milliseconds May 28 12:16:25 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Count Basie-Fancy Pants/Put It Right Here.flac" May 28 12:16:25 opera volumio[11375]: error: updateQueue error: null May 28 12:16:25 opera volumio[11375]: info: May 28 12:16:25 opera volumio[11375]: ---------------------------- MPD announces system playlist update May 28 12:16:25 opera volumio[11375]: info: Ignoring MPD Status Update May 28 12:16:25 opera volumio[11375]: info: ------------------------------ 6ms May 28 12:16:25 opera volumio[11375]: info: sendMpdCommand add "INTERNAL/Count Basie-Fancy Pants/Put It Right Here.flac" took 4 milliseconds May 28 12:16:25 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand play May 28 12:16:25 opera volumio[11375]: info: ------------------------------ 2ms May 28 12:16:25 opera volumio[11375]: info: sendMpdCommand play took 2 milliseconds May 28 12:16:25 opera volumio[1612]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.21:5353): query type 65 is invalid May 28 12:16:25 opera volumio[1612]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.21:5353): query type 65 is invalid May 28 12:16:25 opera volumio[11375]: info: May 28 12:16:25 opera volumio[11375]: ---------------------------- MPD announces state update: player May 28 12:16:25 opera volumio[11375]: info: ControllerMpd::getState May 28 12:16:25 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:25 opera volumio[11375]: info: May 28 12:16:25 opera volumio[11375]: ---------------------------- MPD announces state update: player May 28 12:16:25 opera volumio[11375]: info: sendMpdCommand status took 4 milliseconds May 28 12:16:25 opera volumio[11375]: info: ControllerMpd::getState May 28 12:16:25 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:25 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:25 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 12:16:25 opera volumio[11375]: info: sendMpdCommand status took 4 milliseconds May 28 12:16:25 opera volumio[11375]: info: sendMpdCommand playlistinfo took 2 milliseconds May 28 12:16:25 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:25 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 12:16:25 opera volumio[11375]: verbose: ControllerMpd::parseTrackInfo May 28 12:16:25 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:16:25 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:16:25 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:25 opera volumio[11375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"258 Kbps","isStreaming":false,"title":"Put It Right Here","artist":"Count Basie","album":"Fancy Pants","uri":"INTERNAL/Count Basie-Fancy Pants/Put It Right Here.flac","trackType":"flac"} May 28 12:16:25 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::syncState stateService play May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus stop May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:25 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:25 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:25 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:25 opera volumio[11375]: info: ------------------------------ 42ms May 28 12:16:25 opera volumio[11375]: info: sendMpdCommand playlistinfo took 34 milliseconds May 28 12:16:25 opera volumio[11375]: verbose: ControllerMpd::parseTrackInfo May 28 12:16:25 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:16:25 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:16:25 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:25 opera volumio[11375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"564 Kbps","isStreaming":false,"title":"Put It Right Here","artist":"Count Basie","album":"Fancy Pants","uri":"INTERNAL/Count Basie-Fancy Pants/Put It Right Here.flac","trackType":"flac"} May 28 12:16:25 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::syncState stateService play May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus play May 28 12:16:25 opera volumio[11375]: info: Received an update from plugin. extracting info from payload May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:25 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:25 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:25 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:25 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:25 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:25 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:25 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:25 opera volumio[11375]: info: ------------------------------ 63ms May 28 12:16:25 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:25 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:25 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:54 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:54 opera volumio[11375]: info: May 28 12:16:54 opera volumio[11375]: ---------------------------- MPD announces state update: update May 28 12:16:54 opera volumio[11375]: info: ControllerMpd::getState May 28 12:16:54 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:54 opera volumio[11375]: info: sendMpdCommand status took 2 milliseconds May 28 12:16:54 opera volumio[11375]: info: sendMpdCommand status took 1 milliseconds May 28 12:16:54 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:55 opera volumio[11375]: info: Command Router : Notfying DB Updatetrue May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::Close All Modals sent May 28 12:16:55 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:55 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 12:16:55 opera volumio[11375]: info: MPD Database updated - AlbumList cache refreshed May 28 12:16:55 opera volumio[11375]: info: May 28 12:16:55 opera volumio[11375]: ---------------------------- MPD announces state update: database May 28 12:16:55 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:55 opera volumio[11375]: info: May 28 12:16:55 opera volumio[11375]: ---------------------------- MPD announces state update: update May 28 12:16:55 opera volumio[11375]: info: ControllerMpd::getState May 28 12:16:55 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:55 opera volumio[11375]: info: ControllerMpd::getState May 28 12:16:55 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:16:55 opera volumio[11375]: info: sendMpdCommand playlistinfo took 6 milliseconds May 28 12:16:55 opera volumio[11375]: verbose: ControllerMpd::parseTrackInfo May 28 12:16:55 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:16:55 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:55 opera volumio[11375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29350,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"784 Kbps","isStreaming":false,"title":"Put It Right Here","artist":"Count Basie","album":"Fancy Pants","uri":"INTERNAL/Count Basie-Fancy Pants/Put It Right Here.flac","trackType":"flac"} May 28 12:16:55 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:16:55 opera volumio[11375]: info: CoreStateMachine::syncState stateService play May 28 12:16:55 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus play May 28 12:16:55 opera volumio[11375]: info: Received an update from plugin. extracting info from payload May 28 12:16:55 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:55 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:55 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:55 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:55 opera volumio[11375]: info: ------------------------------ 1047ms May 28 12:16:55 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:55 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:55 opera volumio[11375]: info: sendMpdCommand status took 55 milliseconds May 28 12:16:55 opera volumio[11375]: info: sendMpdCommand status took 55 milliseconds May 28 12:16:55 opera volumio[11375]: info: sendMpdCommand status took 54 milliseconds May 28 12:16:55 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:55 opera volumio[11375]: info: Command Router : Notfying DB Updatefalse May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::Close All Modals sent May 28 12:16:55 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:55 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 12:16:55 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:16:55 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 12:16:55 opera volumio[11375]: info: sendMpdCommand playlistinfo took 5 milliseconds May 28 12:16:55 opera volumio[11375]: info: sendMpdCommand playlistinfo took 5 milliseconds May 28 12:16:55 opera volumio[11375]: verbose: ControllerMpd::parseTrackInfo May 28 12:16:55 opera volumio[11375]: verbose: ControllerMpd::parseTrackInfo May 28 12:16:55 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:16:55 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:55 opera volumio[11375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30464,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"Put It Right Here","artist":"Count Basie","album":"Fancy Pants","uri":"INTERNAL/Count Basie-Fancy Pants/Put It Right Here.flac","trackType":"flac"} May 28 12:16:55 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:16:55 opera volumio[11375]: info: CoreStateMachine::syncState stateService play May 28 12:16:55 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus play May 28 12:16:55 opera volumio[11375]: info: Received an update from plugin. extracting info from payload May 28 12:16:55 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:55 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:55 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:55 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:55 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:16:55 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:55 opera volumio[11375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":30464,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"723 Kbps","isStreaming":false,"title":"Put It Right Here","artist":"Count Basie","album":"Fancy Pants","uri":"INTERNAL/Count Basie-Fancy Pants/Put It Right Here.flac","trackType":"flac"} May 28 12:16:55 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:16:55 opera volumio[11375]: info: CoreStateMachine::syncState stateService play May 28 12:16:55 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus play May 28 12:16:55 opera volumio[11375]: info: Received an update from plugin. extracting info from payload May 28 12:16:55 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:55 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:55 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:16:55 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:16:55 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:16:55 opera volumio[11375]: info: ------------------------------ 230ms May 28 12:16:55 opera volumio[11375]: info: ------------------------------ 231ms May 28 12:16:55 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:55 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:55 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:16:55 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:17:06 opera volumio[11375]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Claudio%20Arrau/Complete%20Philips%20Recordings/18a0096e-ba4b-478c-850c-9f2a37527387.jpg' May 28 12:17:12 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:17:12 opera volumio[11375]: info: May 28 12:17:12 opera volumio[11375]: ---------------------------- MPD announces state update: update May 28 12:17:12 opera volumio[11375]: info: ControllerMpd::getState May 28 12:17:12 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:17:12 opera volumio[11375]: info: sendMpdCommand status took 5 milliseconds May 28 12:17:12 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:17:13 opera volumio[11375]: info: Command Router : Notfying DB Updatetrue May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::Close All Modals sent May 28 12:17:13 opera volumio[11375]: info: MPD Database updated - AlbumList cache refreshed May 28 12:17:13 opera volumio[11375]: info: May 28 12:17:13 opera volumio[11375]: ---------------------------- MPD announces state update: database May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:17:13 opera volumio[11375]: info: May 28 12:17:13 opera volumio[11375]: ---------------------------- MPD announces state update: update May 28 12:17:13 opera volumio[11375]: info: sendMpdCommand status took 682 milliseconds May 28 12:17:13 opera volumio[11375]: info: ControllerMpd::getState May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:17:13 opera volumio[11375]: info: ControllerMpd::getState May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 12:17:13 opera volumio[11375]: info: MPD Database updated - AlbumList cache refreshed May 28 12:17:13 opera volumio[11375]: info: May 28 12:17:13 opera volumio[11375]: ---------------------------- MPD announces state update: database May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:17:13 opera volumio[11375]: info: May 28 12:17:13 opera volumio[11375]: ---------------------------- MPD announces state update: update May 28 12:17:13 opera volumio[11375]: info: ControllerMpd::getState May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:17:13 opera volumio[11375]: info: ControllerMpd::getState May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand status May 28 12:17:13 opera volumio[11375]: info: sendMpdCommand status took 36 milliseconds May 28 12:17:13 opera volumio[11375]: info: sendMpdCommand status took 37 milliseconds May 28 12:17:13 opera volumio[11375]: info: sendMpdCommand status took 37 milliseconds May 28 12:17:13 opera volumio[11375]: info: sendMpdCommand playlistinfo took 36 milliseconds May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:17:13 opera volumio[11375]: info: Command Router : Notfying DB Updatefalse May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::Close All Modals sent May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::parseTrackInfo May 28 12:17:13 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":47717,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"1010 Kbps","isStreaming":false,"title":"Put It Right Here","artist":"Count Basie","album":"Fancy Pants","uri":"INTERNAL/Count Basie-Fancy Pants/Put It Right Here.flac","trackType":"flac"} May 28 12:17:13 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::syncState stateService play May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus play May 28 12:17:13 opera volumio[11375]: info: Received an update from plugin. extracting info from payload May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:17:13 opera volumio[11375]: info: ------------------------------ 763ms May 28 12:17:13 opera volumio[11375]: info: sendMpdCommand status took 87 milliseconds May 28 12:17:13 opera volumio[11375]: info: sendMpdCommand status took 87 milliseconds May 28 12:17:13 opera volumio[11375]: info: sendMpdCommand status took 87 milliseconds May 28 12:17:13 opera volumio[11375]: info: sendMpdCommand playlistinfo took 33 milliseconds May 28 12:17:13 opera volumio[11375]: info: sendMpdCommand playlistinfo took 32 milliseconds May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:17:13 opera volumio[11375]: info: Command Router : Notfying DB Updatefalse May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::Close All Modals sent May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::parseState May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::sendMpdCommand playlistinfo May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::parseTrackInfo May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::parseTrackInfo May 28 12:17:13 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":48460,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"924 Kbps","isStreaming":false,"title":"Put It Right Here","artist":"Count Basie","album":"Fancy Pants","uri":"INTERNAL/Count Basie-Fancy Pants/Put It Right Here.flac","trackType":"flac"} May 28 12:17:13 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::syncState stateService play May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus play May 28 12:17:13 opera volumio[11375]: info: Received an update from plugin. extracting info from payload May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:17:13 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":48460,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"924 Kbps","isStreaming":false,"title":"Put It Right Here","artist":"Count Basie","album":"Fancy Pants","uri":"INTERNAL/Count Basie-Fancy Pants/Put It Right Here.flac","trackType":"flac"} May 28 12:17:13 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::syncState stateService play May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus play May 28 12:17:13 opera volumio[11375]: info: Received an update from plugin. extracting info from payload May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:17:13 opera volumio[11375]: info: ------------------------------ 150ms May 28 12:17:13 opera volumio[11375]: info: ------------------------------ 156ms May 28 12:17:13 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:17:13 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:17:13 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:17:13 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:17:13 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:17:13 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:17:13 opera volumio[11375]: info: sendMpdCommand playlistinfo took 87 milliseconds May 28 12:17:13 opera volumio[11375]: info: sendMpdCommand playlistinfo took 86 milliseconds May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::parseTrackInfo May 28 12:17:13 opera volumio[11375]: verbose: ControllerMpd::parseTrackInfo May 28 12:17:13 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":48460,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"924 Kbps","isStreaming":false,"title":"Put It Right Here","artist":"Count Basie","album":"Fancy Pants","uri":"INTERNAL/Count Basie-Fancy Pants/Put It Right Here.flac","trackType":"flac"} May 28 12:17:13 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::syncState stateService play May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus play May 28 12:17:13 opera volumio[11375]: info: Received an update from plugin. extracting info from payload May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:17:13 opera volumio[11375]: info: ControllerMpd::pushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::servicePushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: verbose: STATE SERVICE {"status":"play","position":0,"seek":48460,"duration":313,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"924 Kbps","isStreaming":false,"title":"Put It Right Here","artist":"Count Basie","album":"Fancy Pants","uri":"INTERNAL/Count Basie-Fancy Pants/Put It Right Here.flac","trackType":"flac"} May 28 12:17:13 opera volumio[11375]: verbose: CURRENT POSITION 0 May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::syncState stateService play May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::syncState currentStatus play May 28 12:17:13 opera volumio[11375]: info: Received an update from plugin. extracting info from payload May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:17:13 opera volumio[11375]: info: CoreStateMachine::pushState May 28 12:17:13 opera volumio[11375]: info: CorePlayQueue::getTrack 0 May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::volumioPushState May 28 12:17:13 opera volumio[11375]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 28 12:17:13 opera volumio[11375]: info: ------------------------------ 220ms May 28 12:17:13 opera volumio[11375]: info: ------------------------------ 220ms May 28 12:17:13 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:17:13 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:17:13 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:17:13 opera volumio[11375]: info: Updating RAAT Signal Path May 28 12:17:15 opera volumio[11375]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 12:17:15 opera volumio[11375]: { Error: connect ETIMEDOUT 157.240.2.14:443 May 28 12:17:15 opera volumio[11375]: at Object._errnoException (util.js:1022:11) May 28 12:17:15 opera volumio[11375]: at _exceptionWithHostPort (util.js:1044:20) May 28 12:17:15 opera volumio[11375]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) May 28 12:17:15 opera volumio[11375]: code: 'ETIMEDOUT', May 28 12:17:15 opera volumio[11375]: errno: 'ETIMEDOUT', May 28 12:17:15 opera volumio[11375]: syscall: 'connect', May 28 12:17:15 opera volumio[11375]: address: '157.240.2.14', May 28 12:17:15 opera volumio[11375]: port: 443 } May 28 12:17:15 opera volumio[11375]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 28 12:17:15 opera sudo[9109]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-28 12:16 May 28 12:17:15 opera sudo[9109]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 8 (jessie)" NAME="Debian GNU/Linux" VERSION_ID="8" VERSION="8 (jessie)" ID=debian HOME_URL="http://www.debian.org/" SUPPORT_URL="http://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="dd2a4339faaeb892c7f467e7dc8d424aefb0bd03" VOLUMIO_FE_VERSION="047a38c6e11e7058b10d3038ac9dcbff94f92828" VOLUMIO_BE_VERSION="3e944f35d34b575025d16b976b4cb4d9aed53b66" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="opera" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Apr 8 17:29:57 CEST 2022" VOLUMIO_VERSION="1.033" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_VENDOR="Opera Consonance" VOLUMIO_MODEL="X5" VOLUMIO_VENDOR_MODEL="Opera X5" VOLUMIO_HASH="77fa3b0bf86939c934741dd9bb62adcc"