-- Logs begin at Thu 2026-01-08 04:55:33 UTC, end at Tue 2026-06-02 08:59:40 UTC. -- Jun 02 08:58:01 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:01 opera volumio[19120]: info: Jun 02 08:58:01 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:58:01 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:01 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:01 opera volumio[19120]: info: sendMpdCommand status took 1 milliseconds Jun 02 08:58:01 opera volumio[19120]: info: sendMpdCommand status took 7 milliseconds Jun 02 08:58:01 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:02 opera volumio[19120]: info: Command Router : Notfying DB Updatetrue Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:02 opera volumio[19120]: info: MPD Database updated - AlbumList cache refreshed Jun 02 08:58:02 opera volumio[19120]: info: Jun 02 08:58:02 opera volumio[19120]: ---------------------------- MPD announces state update: database Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:02 opera volumio[19120]: info: Jun 02 08:58:02 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:58:02 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:02 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:02 opera volumio[19120]: info: MPD Database updated - AlbumList cache refreshed Jun 02 08:58:02 opera volumio[19120]: info: Jun 02 08:58:02 opera volumio[19120]: ---------------------------- MPD announces state update: database Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:02 opera volumio[19120]: info: Jun 02 08:58:02 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:58:02 opera volumio[19120]: info: sendMpdCommand playlistinfo took 7 milliseconds Jun 02 08:58:02 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:02 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:02 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53150,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"587 Kbps","isStreaming":false,"title":"採真遊","artist":"汪鐸","album":"白霫","uri":"INTERNAL/汪鐸-白霫/採真遊.flac","trackType":"flac"} Jun 02 08:58:02 opera volumio[19120]: verbose: CURRENT POSITION 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:58:02 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:02 opera volumio[19120]: info: ------------------------------ 263ms Jun 02 08:58:02 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:02 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:02 opera volumio[19120]: info: sendMpdCommand status took 55 milliseconds Jun 02 08:58:02 opera volumio[19120]: info: sendMpdCommand status took 54 milliseconds Jun 02 08:58:02 opera volumio[19120]: info: sendMpdCommand status took 55 milliseconds Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:02 opera volumio[19120]: info: Command Router : Notfying DB Updatefalse Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:02 opera volumio[19120]: info: sendMpdCommand status took 104 milliseconds Jun 02 08:58:02 opera volumio[19120]: info: sendMpdCommand status took 104 milliseconds Jun 02 08:58:02 opera volumio[19120]: info: sendMpdCommand status took 104 milliseconds Jun 02 08:58:02 opera volumio[19120]: info: sendMpdCommand playlistinfo took 36 milliseconds Jun 02 08:58:02 opera volumio[19120]: info: sendMpdCommand playlistinfo took 36 milliseconds Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:02 opera volumio[19120]: info: Command Router : Notfying DB Updatefalse Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:02 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53521,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"採真遊","artist":"汪鐸","album":"白霫","uri":"INTERNAL/汪鐸-白霫/採真遊.flac","trackType":"flac"} Jun 02 08:58:02 opera volumio[19120]: verbose: CURRENT POSITION 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:58:02 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:02 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53521,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"採真遊","artist":"汪鐸","album":"白霫","uri":"INTERNAL/汪鐸-白霫/採真遊.flac","trackType":"flac"} Jun 02 08:58:02 opera volumio[19120]: verbose: CURRENT POSITION 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:58:02 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:02 opera volumio[19120]: info: ------------------------------ 156ms Jun 02 08:58:02 opera volumio[19120]: info: ------------------------------ 157ms Jun 02 08:58:02 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:02 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:02 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:02 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:02 opera volumio[19120]: info: sendMpdCommand playlistinfo took 43 milliseconds Jun 02 08:58:02 opera volumio[19120]: info: sendMpdCommand playlistinfo took 42 milliseconds Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:02 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:02 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53521,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"採真遊","artist":"汪鐸","album":"白霫","uri":"INTERNAL/汪鐸-白霫/採真遊.flac","trackType":"flac"} Jun 02 08:58:02 opera volumio[19120]: verbose: CURRENT POSITION 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:58:02 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:02 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":53521,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"639 Kbps","isStreaming":false,"title":"採真遊","artist":"汪鐸","album":"白霫","uri":"INTERNAL/汪鐸-白霫/採真遊.flac","trackType":"flac"} Jun 02 08:58:02 opera volumio[19120]: verbose: CURRENT POSITION 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:58:02 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:02 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:02 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:02 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:02 opera volumio[19120]: info: ------------------------------ 205ms Jun 02 08:58:02 opera volumio[19120]: info: ------------------------------ 207ms Jun 02 08:58:02 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:02 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:02 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:02 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:03 opera volumio[1635]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.21:5353): query type 65 is invalid Jun 02 08:58:04 opera volumio[1635]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.21:5353): query type 65 is invalid Jun 02 08:58:04 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 02 08:58:04 opera volumio[19120]: info: CURURI: music-library Jun 02 08:58:05 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 02 08:58:05 opera volumio[19120]: info: CURURI: music-library/INTERNAL Jun 02 08:58:06 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 02 08:58:06 opera volumio[19120]: info: CURURI: music-library/INTERNAL/abcde.64103518 Jun 02 08:58:09 opera volumio[1635]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.21:5353): query type 65 is invalid Jun 02 08:58:10 opera volumio[1635]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.21:5353): query type 65 is invalid Jun 02 08:58:10 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 02 08:58:10 opera volumio[19120]: info: CURURI: albums:// Jun 02 08:58:10 opera volumio[19120]: listAlbums - loading Albums from cache Jun 02 08:58:32 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 02 08:58:32 opera volumio[19120]: info: CURURI: albums://Bach%20J.S./Cello%20Suites%20-%20Starker%20J. Jun 02 08:58:32 opera volumio[19120]: info: Executing endpoint metavolumio Jun 02 08:58:32 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 02 08:58:32 opera volumio[19120]: info: Executing endpoint metavolumio Jun 02 08:58:32 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 02 08:58:32 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:32 opera volumio[19120]: info: Jun 02 08:58:32 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:58:32 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:32 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:32 opera volumio[19120]: info: sendMpdCommand status took 3 milliseconds Jun 02 08:58:32 opera volumio[19120]: info: sendMpdCommand status took 2 milliseconds Jun 02 08:58:32 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:33 opera volumio[19120]: info: Command Router : Notfying DB Updatetrue Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:33 opera volumio[19120]: info: MPD Database updated - AlbumList cache refreshed Jun 02 08:58:33 opera volumio[19120]: info: Jun 02 08:58:33 opera volumio[19120]: ---------------------------- MPD announces state update: database Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:33 opera volumio[19120]: info: Jun 02 08:58:33 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:58:33 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:33 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:33 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:33 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84033,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"757 Kbps","isStreaming":false,"title":"採真遊","artist":"汪鐸","album":"白霫","uri":"INTERNAL/汪鐸-白霫/採真遊.flac","trackType":"flac"} Jun 02 08:58:33 opera volumio[19120]: verbose: CURRENT POSITION 8 Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:58:33 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:33 opera volumio[19120]: info: ------------------------------ 458ms Jun 02 08:58:33 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:33 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand status took 69 milliseconds Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand status took 58 milliseconds Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand status took 58 milliseconds Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:33 opera volumio[19120]: info: Command Router : Notfying DB Updatefalse Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand playlistinfo took 5 milliseconds Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:33 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:33 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84520,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"598 Kbps","isStreaming":false,"title":"採真遊","artist":"汪鐸","album":"白霫","uri":"INTERNAL/汪鐸-白霫/採真遊.flac","trackType":"flac"} Jun 02 08:58:33 opera volumio[19120]: verbose: CURRENT POSITION 8 Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:58:33 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:33 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:33 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":84520,"duration":270,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"598 Kbps","isStreaming":false,"title":"採真遊","artist":"汪鐸","album":"白霫","uri":"INTERNAL/汪鐸-白霫/採真遊.flac","trackType":"flac"} Jun 02 08:58:33 opera volumio[19120]: verbose: CURRENT POSITION 8 Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:58:33 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:33 opera volumio[19120]: info: ------------------------------ 146ms Jun 02 08:58:33 opera volumio[19120]: info: ------------------------------ 147ms Jun 02 08:58:33 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:33 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:33 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:33 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::ClearQueue Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::stop Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 08:58:33 opera volumio[19120]: verbose: UNSET VOLATILE Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::stPlaybackTimer Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::updateTrackBlock Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrackBlock Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::serviceStop Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 8 Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::serviceStop Jun 02 08:58:33 opera volumio[19120]: info: ControllerMpd::stop Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand stop Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::clearPlayQueue Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::saveQueue Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioPushQueue Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::addQueueItems Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::addQueueItems Jun 02 08:58:33 opera volumio[19120]: info: Adding Item to queue: albums://Bach%20J.S./Cello%20Suites%20-%20Starker%20J. Jun 02 08:58:33 opera volumio[19120]: info: Exploding uri albums://Bach%20J.S./Cello%20Suites%20-%20Starker%20J. in service mpd Jun 02 08:58:33 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:33 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::saveQueue Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioPushQueue Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::updateTrackBlock Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrackBlock Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioPlay Jun 02 08:58:33 opera volumio[19120]: verbose: UNSET VOLATILE Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::play index 0 Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::stop Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 08:58:33 opera volumio[19120]: verbose: UNSET VOLATILE Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::play index undefined Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::startPlaybackTimer Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Bach J.S.-Cello Suites - Starker J./No. 1 in G Major. Praeludium.flac Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand stop Jun 02 08:58:33 opera volumio[19120]: info: Jun 02 08:58:33 opera volumio[19120]: ---------------------------- MPD announces state update: player Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand stop took 50 milliseconds Jun 02 08:58:33 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand stop took 20 milliseconds Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand clear Jun 02 08:58:33 opera volumio[19120]: info: Jun 02 08:58:33 opera volumio[19120]: ---------------------------- MPD announces system playlist update Jun 02 08:58:33 opera volumio[19120]: info: Ignoring MPD Status Update Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand status took 5 milliseconds Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand clear took 3 milliseconds Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Bach J.S.-Cello Suites - Starker J./No. 1 in G Major. Praeludium.flac" Jun 02 08:58:33 opera volumio[19120]: error: updateQueue error: null Jun 02 08:58:33 opera volumio[19120]: info: Jun 02 08:58:33 opera volumio[19120]: ---------------------------- MPD announces system playlist update Jun 02 08:58:33 opera volumio[19120]: info: Ignoring MPD Status Update Jun 02 08:58:33 opera volumio[19120]: info: ------------------------------ 6ms Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand add "INTERNAL/Bach J.S.-Cello Suites - Starker J./No. 1 in G Major. Praeludium.flac" took 4 milliseconds Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand play Jun 02 08:58:33 opera volumio[19120]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 02 08:58:33 opera volumio[19120]: info: ------------------------------ 14ms Jun 02 08:58:33 opera volumio[19120]: info: ------------------------------ 5ms Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand play took 3 milliseconds Jun 02 08:58:33 opera volumio[19120]: info: Jun 02 08:58:33 opera volumio[19120]: ---------------------------- MPD announces state update: player Jun 02 08:58:33 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:33 opera volumio[19120]: info: Jun 02 08:58:33 opera volumio[19120]: ---------------------------- MPD announces state update: player Jun 02 08:58:33 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand status took 3 milliseconds Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand status took 4 milliseconds Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:33 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:33 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"354 Kbps","isStreaming":false,"title":"No. 1 in G Major. Praeludium","artist":"Bach J.S.","album":"Cello Suites - Starker J.","uri":"INTERNAL/Bach J.S.-Cello Suites - Starker J./No. 1 in G Major. Praeludium.flac","trackType":"flac"} Jun 02 08:58:33 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus stop Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:33 opera volumio[19120]: info: ------------------------------ 30ms Jun 02 08:58:33 opera volumio[19120]: info: sendMpdCommand playlistinfo took 22 milliseconds Jun 02 08:58:33 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:33 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:33 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"354 Kbps","isStreaming":false,"title":"No. 1 in G Major. Praeludium","artist":"Bach J.S.","album":"Cello Suites - Starker J.","uri":"INTERNAL/Bach J.S.-Cello Suites - Starker J./No. 1 in G Major. Praeludium.flac","trackType":"flac"} Jun 02 08:58:33 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:58:33 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:33 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:33 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:33 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:33 opera volumio[19120]: info: ------------------------------ 64ms Jun 02 08:58:33 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:33 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:33 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:39 opera volumio[19120]: error: Failed request for metavolumio API Jun 02 08:58:39 opera volumio[19120]: error: Failed request for metavolumio API Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:47 opera volumio[19120]: info: Jun 02 08:58:47 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:58:47 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:47 opera volumio[19120]: info: sendMpdCommand status took 7 milliseconds Jun 02 08:58:47 opera volumio[19120]: info: sendMpdCommand status took 5 milliseconds Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:47 opera volumio[19120]: info: Command Router : Notfying DB Updatetrue Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:47 opera volumio[19120]: info: MPD Database updated - AlbumList cache refreshed Jun 02 08:58:47 opera volumio[19120]: info: Jun 02 08:58:47 opera volumio[19120]: ---------------------------- MPD announces state update: database Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:47 opera volumio[19120]: info: Jun 02 08:58:47 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:58:47 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:47 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:47 opera volumio[19120]: info: MPD Database updated - AlbumList cache refreshed Jun 02 08:58:47 opera volumio[19120]: info: Jun 02 08:58:47 opera volumio[19120]: ---------------------------- MPD announces state update: database Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:47 opera volumio[19120]: info: Jun 02 08:58:47 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:58:47 opera volumio[19120]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 02 08:58:47 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:47 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:47 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:47 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:47 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14349,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"618 Kbps","isStreaming":false,"title":"No. 1 in G Major. Praeludium","artist":"Bach J.S.","album":"Cello Suites - Starker J.","uri":"INTERNAL/Bach J.S.-Cello Suites - Starker J./No. 1 in G Major. Praeludium.flac","trackType":"flac"} Jun 02 08:58:47 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:58:47 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:47 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:58:47 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:58:47 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:47 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:47 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:47 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:47 opera volumio[19120]: info: ------------------------------ 419ms Jun 02 08:58:47 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:47 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:47 opera volumio[19120]: info: sendMpdCommand status took 50 milliseconds Jun 02 08:58:47 opera volumio[19120]: info: sendMpdCommand status took 50 milliseconds Jun 02 08:58:47 opera volumio[19120]: info: sendMpdCommand status took 50 milliseconds Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:47 opera volumio[19120]: info: Command Router : Notfying DB Updatefalse Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:47 opera volumio[19120]: info: sendMpdCommand status took 102 milliseconds Jun 02 08:58:47 opera volumio[19120]: info: sendMpdCommand status took 101 milliseconds Jun 02 08:58:47 opera volumio[19120]: info: sendMpdCommand status took 100 milliseconds Jun 02 08:58:47 opera volumio[19120]: info: sendMpdCommand playlistinfo took 34 milliseconds Jun 02 08:58:47 opera volumio[19120]: info: sendMpdCommand playlistinfo took 35 milliseconds Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:47 opera volumio[19120]: info: Command Router : Notfying DB Updatefalse Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:47 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:47 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:47 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:47 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14837,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"603 Kbps","isStreaming":false,"title":"No. 1 in G Major. Praeludium","artist":"Bach J.S.","album":"Cello Suites - Starker J.","uri":"INTERNAL/Bach J.S.-Cello Suites - Starker J./No. 1 in G Major. Praeludium.flac","trackType":"flac"} Jun 02 08:58:47 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:58:47 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:47 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:58:47 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:58:47 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:47 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:47 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:47 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:47 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:47 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:47 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14837,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"603 Kbps","isStreaming":false,"title":"No. 1 in G Major. Praeludium","artist":"Bach J.S.","album":"Cello Suites - Starker J.","uri":"INTERNAL/Bach J.S.-Cello Suites - Starker J./No. 1 in G Major. Praeludium.flac","trackType":"flac"} Jun 02 08:58:47 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:58:47 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:47 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:58:47 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:58:47 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:47 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:47 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:47 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:47 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:48 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:48 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:48 opera volumio[19120]: info: ------------------------------ 160ms Jun 02 08:58:48 opera volumio[19120]: info: ------------------------------ 161ms Jun 02 08:58:48 opera volumio[19120]: info: sendMpdCommand playlistinfo took 35 milliseconds Jun 02 08:58:48 opera volumio[19120]: info: sendMpdCommand playlistinfo took 35 milliseconds Jun 02 08:58:48 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:48 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:58:48 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:48 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:48 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:48 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14837,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"603 Kbps","isStreaming":false,"title":"No. 1 in G Major. Praeludium","artist":"Bach J.S.","album":"Cello Suites - Starker J.","uri":"INTERNAL/Bach J.S.-Cello Suites - Starker J./No. 1 in G Major. Praeludium.flac","trackType":"flac"} Jun 02 08:58:48 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:58:48 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:48 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:58:48 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:58:48 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:48 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:48 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:48 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:48 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:48 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:48 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:48 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:48 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:58:48 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:58:48 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:48 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":14837,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"603 Kbps","isStreaming":false,"title":"No. 1 in G Major. Praeludium","artist":"Bach J.S.","album":"Cello Suites - Starker J.","uri":"INTERNAL/Bach J.S.-Cello Suites - Starker J./No. 1 in G Major. Praeludium.flac","trackType":"flac"} Jun 02 08:58:48 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:58:48 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:58:48 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:58:48 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:58:48 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:48 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:48 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:48 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:48 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:58:48 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:58:48 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:58:48 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:58:48 opera volumio[19120]: info: ------------------------------ 179ms Jun 02 08:58:48 opera volumio[19120]: info: ------------------------------ 184ms Jun 02 08:58:48 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:48 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:48 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:48 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:48 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:48 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:48 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:48 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:58:59 opera volumio[1635]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.21:5353): query type 65 is invalid Jun 02 08:58:59 opera volumio[1635]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.21:5353): query type 65 is invalid Jun 02 08:59:00 opera volumio[1635]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.21:5353): query type 65 is invalid Jun 02 08:59:00 opera volumio[1635]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.21:5353): query type 65 is invalid Jun 02 08:59:01 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 02 08:59:01 opera volumio[19120]: info: CURURI: albums:// Jun 02 08:59:01 opera volumio[19120]: listAlbums - loading Albums from cache Jun 02 08:59:05 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:05 opera volumio[19120]: info: Jun 02 08:59:05 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:59:05 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:59:05 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:05 opera volumio[19120]: info: sendMpdCommand status took 2 milliseconds Jun 02 08:59:05 opera volumio[19120]: info: sendMpdCommand status took 1 milliseconds Jun 02 08:59:05 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:07 opera volumio[19120]: info: Command Router : Notfying DB Updatetrue Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:59:07 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:07 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:59:07 opera volumio[19120]: info: MPD Database updated - AlbumList cache refreshed Jun 02 08:59:07 opera volumio[19120]: info: Jun 02 08:59:07 opera volumio[19120]: ---------------------------- MPD announces state update: database Jun 02 08:59:07 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:07 opera volumio[19120]: info: Jun 02 08:59:07 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:59:07 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:59:07 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:07 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:59:07 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:07 opera volumio[19120]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 02 08:59:07 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:59:07 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:59:07 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:07 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":32833,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"637 Kbps","isStreaming":false,"title":"No. 1 in G Major. Praeludium","artist":"Bach J.S.","album":"Cello Suites - Starker J.","uri":"INTERNAL/Bach J.S.-Cello Suites - Starker J./No. 1 in G Major. Praeludium.flac","trackType":"flac"} Jun 02 08:59:07 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:59:07 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:59:07 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:59:07 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:59:07 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:07 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:07 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:07 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:07 opera volumio[19120]: info: ------------------------------ 1530ms Jun 02 08:59:07 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:07 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:07 opera volumio[19120]: info: sendMpdCommand status took 96 milliseconds Jun 02 08:59:07 opera volumio[19120]: info: sendMpdCommand status took 97 milliseconds Jun 02 08:59:07 opera volumio[19120]: info: sendMpdCommand status took 98 milliseconds Jun 02 08:59:07 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:07 opera volumio[19120]: info: Command Router : Notfying DB Updatefalse Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:59:07 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:07 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:59:07 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:07 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 02 08:59:07 opera volumio[19120]: info: CURURI: albums://Pierre%20Fournier/J.S.%20Bach%20Suiten%20fur%20Violoncello%20allein%20Nr.%201%2C5%2C%20and%203 Jun 02 08:59:07 opera volumio[19120]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 02 08:59:07 opera volumio[19120]: info: sendMpdCommand playlistinfo took 8 milliseconds Jun 02 08:59:07 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:59:07 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:59:07 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:59:07 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:07 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34342,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"624 Kbps","isStreaming":false,"title":"No. 1 in G Major. Praeludium","artist":"Bach J.S.","album":"Cello Suites - Starker J.","uri":"INTERNAL/Bach J.S.-Cello Suites - Starker J./No. 1 in G Major. Praeludium.flac","trackType":"flac"} Jun 02 08:59:07 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:59:07 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:59:07 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:59:07 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:59:07 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:07 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:07 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:07 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:07 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:59:07 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:07 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":34342,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"624 Kbps","isStreaming":false,"title":"No. 1 in G Major. Praeludium","artist":"Bach J.S.","album":"Cello Suites - Starker J.","uri":"INTERNAL/Bach J.S.-Cello Suites - Starker J./No. 1 in G Major. Praeludium.flac","trackType":"flac"} Jun 02 08:59:07 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:59:07 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:59:07 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:59:07 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:59:07 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:07 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:07 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:07 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:07 opera volumio[19120]: info: ------------------------------ 352ms Jun 02 08:59:07 opera volumio[19120]: info: ------------------------------ 353ms Jun 02 08:59:07 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:07 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:07 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:07 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:07 opera volumio[19120]: info: Executing endpoint metavolumio Jun 02 08:59:07 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 02 08:59:08 opera volumio[19120]: info: Executing endpoint metavolumio Jun 02 08:59:08 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::ClearQueue Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::stop Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 08:59:11 opera volumio[19120]: verbose: UNSET VOLATILE Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::stPlaybackTimer Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::updateTrackBlock Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::getTrackBlock Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::serviceStop Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::serviceStop Jun 02 08:59:11 opera volumio[19120]: info: ControllerMpd::stop Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand stop Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::clearPlayQueue Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::saveQueue Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::volumioPushQueue Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::addQueueItems Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::addQueueItems Jun 02 08:59:11 opera volumio[19120]: info: Adding Item to queue: albums://Pierre%20Fournier/J.S.%20Bach%20Suiten%20fur%20Violoncello%20allein%20Nr.%201%2C5%2C%20and%203 Jun 02 08:59:11 opera volumio[19120]: info: Exploding uri albums://Pierre%20Fournier/J.S.%20Bach%20Suiten%20fur%20Violoncello%20allein%20Nr.%201%2C5%2C%20and%203 in service mpd Jun 02 08:59:11 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:11 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::saveQueue Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::volumioPushQueue Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::updateTrackBlock Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::getTrackBlock Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::volumioPlay Jun 02 08:59:11 opera volumio[19120]: verbose: UNSET VOLATILE Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::play index 0 Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::stop Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 08:59:11 opera volumio[19120]: verbose: UNSET VOLATILE Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::play index undefined Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::startPlaybackTimer Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/Pierre Fournier-J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3/Suite No. 1 in G Prelude.flac Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand stop Jun 02 08:59:11 opera volumio[19120]: info: Jun 02 08:59:11 opera volumio[19120]: ---------------------------- MPD announces state update: player Jun 02 08:59:11 opera volumio[19120]: info: sendMpdCommand stop took 55 milliseconds Jun 02 08:59:11 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:11 opera volumio[19120]: info: sendMpdCommand stop took 22 milliseconds Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand clear Jun 02 08:59:11 opera volumio[19120]: info: Jun 02 08:59:11 opera volumio[19120]: ---------------------------- MPD announces system playlist update Jun 02 08:59:11 opera volumio[19120]: info: Ignoring MPD Status Update Jun 02 08:59:11 opera volumio[19120]: info: sendMpdCommand status took 23 milliseconds Jun 02 08:59:11 opera volumio[19120]: info: sendMpdCommand clear took 23 milliseconds Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/Pierre Fournier-J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3/Suite No. 1 in G Prelude.flac" Jun 02 08:59:11 opera volumio[19120]: error: updateQueue error: null Jun 02 08:59:11 opera volumio[19120]: info: Jun 02 08:59:11 opera volumio[19120]: ---------------------------- MPD announces system playlist update Jun 02 08:59:11 opera volumio[19120]: info: Ignoring MPD Status Update Jun 02 08:59:11 opera volumio[19120]: info: ------------------------------ 15ms Jun 02 08:59:11 opera volumio[19120]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 02 08:59:11 opera volumio[19120]: info: sendMpdCommand add "INTERNAL/Pierre Fournier-J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3/Suite No. 1 in G Prelude.flac" took 10 milliseconds Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand play Jun 02 08:59:11 opera volumio[19120]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Jun 02 08:59:11 opera volumio[19120]: info: ------------------------------ 41ms Jun 02 08:59:11 opera volumio[19120]: info: ------------------------------ 12ms Jun 02 08:59:11 opera volumio[19120]: info: sendMpdCommand play took 11 milliseconds Jun 02 08:59:11 opera volumio[19120]: info: Jun 02 08:59:11 opera volumio[19120]: ---------------------------- MPD announces state update: player Jun 02 08:59:11 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:11 opera volumio[19120]: info: Jun 02 08:59:11 opera volumio[19120]: ---------------------------- MPD announces state update: player Jun 02 08:59:11 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:11 opera volumio[19120]: info: sendMpdCommand status took 3 milliseconds Jun 02 08:59:11 opera volumio[19120]: info: sendMpdCommand status took 3 milliseconds Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:59:11 opera volumio[19120]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 02 08:59:11 opera volumio[19120]: info: sendMpdCommand playlistinfo took 1 milliseconds Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:59:11 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:59:11 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:11 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Suite No. 1 in G Prelude","artist":"Pierre Fournier","album":"J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3","uri":"INTERNAL/Pierre Fournier-J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3/Suite No. 1 in G Prelude.flac","trackType":"flac"} Jun 02 08:59:11 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus stop Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:11 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:11 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":46,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Suite No. 1 in G Prelude","artist":"Pierre Fournier","album":"J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3","uri":"INTERNAL/Pierre Fournier-J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3/Suite No. 1 in G Prelude.flac","trackType":"flac"} Jun 02 08:59:11 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:59:11 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:11 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:11 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:11 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:11 opera volumio[19120]: info: ------------------------------ 36ms Jun 02 08:59:11 opera volumio[19120]: info: ------------------------------ 35ms Jun 02 08:59:11 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:11 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:11 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:13 opera volumio[19120]: info: Jun 02 08:59:13 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:59:13 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:13 opera volumio[19120]: info: sendMpdCommand status took 2 milliseconds Jun 02 08:59:13 opera volumio[19120]: info: sendMpdCommand status took 1 milliseconds Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:13 opera volumio[19120]: info: Command Router : Notfying DB Updatetrue Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:59:13 opera volumio[19120]: info: MPD Database updated - AlbumList cache refreshed Jun 02 08:59:13 opera volumio[19120]: info: Jun 02 08:59:13 opera volumio[19120]: ---------------------------- MPD announces state update: database Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:13 opera volumio[19120]: info: Jun 02 08:59:13 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:59:13 opera volumio[19120]: info: MPD Database updated - AlbumList cache refreshed Jun 02 08:59:13 opera volumio[19120]: info: Jun 02 08:59:13 opera volumio[19120]: ---------------------------- MPD announces state update: database Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:13 opera volumio[19120]: info: Jun 02 08:59:13 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:59:13 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:13 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:13 opera volumio[19120]: info: sendMpdCommand playlistinfo took 6 milliseconds Jun 02 08:59:13 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:13 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:59:13 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2461,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"637 Kbps","isStreaming":false,"title":"Suite No. 1 in G Prelude","artist":"Pierre Fournier","album":"J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3","uri":"INTERNAL/Pierre Fournier-J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3/Suite No. 1 in G Prelude.flac","trackType":"flac"} Jun 02 08:59:13 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:59:13 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:13 opera volumio[19120]: info: ------------------------------ 195ms Jun 02 08:59:13 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:13 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:13 opera volumio[19120]: info: sendMpdCommand status took 60 milliseconds Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:13 opera volumio[19120]: info: Command Router : Notfying DB Updatefalse Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:59:13 opera volumio[19120]: info: sendMpdCommand status took 117 milliseconds Jun 02 08:59:13 opera volumio[19120]: info: sendMpdCommand status took 116 milliseconds Jun 02 08:59:13 opera volumio[19120]: info: sendMpdCommand status took 115 milliseconds Jun 02 08:59:13 opera volumio[19120]: info: sendMpdCommand status took 116 milliseconds Jun 02 08:59:13 opera volumio[19120]: info: sendMpdCommand status took 115 milliseconds Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:13 opera volumio[19120]: info: Command Router : Notfying DB Updatefalse Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:59:13 opera volumio[19120]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 02 08:59:13 opera volumio[19120]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 02 08:59:13 opera volumio[19120]: info: sendMpdCommand playlistinfo took 11 milliseconds Jun 02 08:59:13 opera volumio[19120]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:59:13 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:59:13 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2600,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"625 Kbps","isStreaming":false,"title":"Suite No. 1 in G Prelude","artist":"Pierre Fournier","album":"J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3","uri":"INTERNAL/Pierre Fournier-J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3/Suite No. 1 in G Prelude.flac","trackType":"flac"} Jun 02 08:59:13 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:59:13 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:13 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2600,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"625 Kbps","isStreaming":false,"title":"Suite No. 1 in G Prelude","artist":"Pierre Fournier","album":"J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3","uri":"INTERNAL/Pierre Fournier-J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3/Suite No. 1 in G Prelude.flac","trackType":"flac"} Jun 02 08:59:13 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:59:13 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:13 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2600,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"625 Kbps","isStreaming":false,"title":"Suite No. 1 in G Prelude","artist":"Pierre Fournier","album":"J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3","uri":"INTERNAL/Pierre Fournier-J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3/Suite No. 1 in G Prelude.flac","trackType":"flac"} Jun 02 08:59:13 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:59:13 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:13 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":2600,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"625 Kbps","isStreaming":false,"title":"Suite No. 1 in G Prelude","artist":"Pierre Fournier","album":"J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3","uri":"INTERNAL/Pierre Fournier-J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3/Suite No. 1 in G Prelude.flac","trackType":"flac"} Jun 02 08:59:13 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:59:13 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:13 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:13 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:13 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:13 opera volumio[19120]: info: ------------------------------ 233ms Jun 02 08:59:13 opera volumio[19120]: info: ------------------------------ 234ms Jun 02 08:59:13 opera volumio[19120]: info: ------------------------------ 234ms Jun 02 08:59:13 opera volumio[19120]: info: ------------------------------ 235ms Jun 02 08:59:13 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:13 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:13 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:13 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:13 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:13 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:13 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:13 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:14 opera volumio[19120]: error: Failed request for metavolumio API Jun 02 08:59:15 opera volumio[19120]: error: Failed request for metavolumio API Jun 02 08:59:32 opera volumio[19120]: 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/33342dbd-1929-4fd7-b65c-738f25bcd7a9.jpg' Jun 02 08:59:32 opera volumio[19120]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/GARY%20KARR/ADAGIO%20D%27ALBINONI/3c880f74-39bb-40eb-b739-a676768badc1.jpg' Jun 02 08:59:32 opera volumio[19120]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Glenn%20Gould/Goldberg%20Variations/8dbcef9b-ec72-42b6-a893-a13b35f03541.png' Jun 02 08:59:35 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:35 opera volumio[19120]: info: Jun 02 08:59:35 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:59:35 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:59:35 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:35 opera volumio[19120]: info: sendMpdCommand status took 4 milliseconds Jun 02 08:59:35 opera volumio[19120]: info: sendMpdCommand status took 3 milliseconds Jun 02 08:59:35 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:36 opera volumio[19120]: info: Command Router : Notfying DB Updatetrue Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:59:36 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:36 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:59:36 opera volumio[19120]: info: MPD Database updated - AlbumList cache refreshed Jun 02 08:59:36 opera volumio[19120]: info: Jun 02 08:59:36 opera volumio[19120]: ---------------------------- MPD announces state update: database Jun 02 08:59:36 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:36 opera volumio[19120]: info: Jun 02 08:59:36 opera volumio[19120]: ---------------------------- MPD announces state update: update Jun 02 08:59:36 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:59:36 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:36 opera volumio[19120]: info: ControllerMpd::getState Jun 02 08:59:36 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand status Jun 02 08:59:36 opera volumio[19120]: info: sendMpdCommand playlistinfo took 9 milliseconds Jun 02 08:59:36 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:59:36 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:59:36 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:36 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":23962,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":true,"repeat":false,"bitrate":"693 Kbps","isStreaming":false,"title":"Suite No. 1 in G Prelude","artist":"Pierre Fournier","album":"J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3","uri":"INTERNAL/Pierre Fournier-J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3/Suite No. 1 in G Prelude.flac","trackType":"flac"} Jun 02 08:59:36 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:59:36 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:59:36 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:59:36 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:59:36 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:36 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:36 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:36 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:36 opera volumio[19120]: info: ------------------------------ 1097ms Jun 02 08:59:36 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:36 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:36 opera volumio[19120]: info: sendMpdCommand status took 66 milliseconds Jun 02 08:59:36 opera volumio[19120]: info: sendMpdCommand status took 65 milliseconds Jun 02 08:59:36 opera volumio[19120]: info: sendMpdCommand status took 65 milliseconds Jun 02 08:59:36 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:36 opera volumio[19120]: info: Command Router : Notfying DB Updatefalse Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::Close All Modals sent Jun 02 08:59:36 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:36 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:59:36 opera volumio[19120]: verbose: ControllerMpd::parseState Jun 02 08:59:36 opera volumio[19120]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 02 08:59:36 opera volumio[19120]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 02 08:59:36 opera volumio[19120]: info: sendMpdCommand playlistinfo took 4 milliseconds Jun 02 08:59:36 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:59:36 opera volumio[19120]: verbose: ControllerMpd::parseTrackInfo Jun 02 08:59:36 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:59:36 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:36 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25100,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"650 Kbps","isStreaming":false,"title":"Suite No. 1 in G Prelude","artist":"Pierre Fournier","album":"J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3","uri":"INTERNAL/Pierre Fournier-J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3/Suite No. 1 in G Prelude.flac","trackType":"flac"} Jun 02 08:59:36 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:59:36 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:59:36 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:59:36 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:59:36 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:36 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:36 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:36 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:36 opera volumio[19120]: info: ControllerMpd::pushState Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::servicePushState Jun 02 08:59:36 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:36 opera volumio[19120]: verbose: STATE SERVICE {"status":"play","position":0,"seek":25100,"duration":153,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"650 Kbps","isStreaming":false,"title":"Suite No. 1 in G Prelude","artist":"Pierre Fournier","album":"J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3","uri":"INTERNAL/Pierre Fournier-J.S. Bach Suiten fur Violoncello allein Nr. 1,5, and 3/Suite No. 1 in G Prelude.flac","trackType":"flac"} Jun 02 08:59:36 opera volumio[19120]: verbose: CURRENT POSITION 0 Jun 02 08:59:36 opera volumio[19120]: info: CoreStateMachine::syncState stateService play Jun 02 08:59:36 opera volumio[19120]: info: CoreStateMachine::syncState currentStatus play Jun 02 08:59:36 opera volumio[19120]: info: Received an update from plugin. extracting info from payload Jun 02 08:59:36 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:36 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:36 opera volumio[19120]: info: CoreStateMachine::pushState Jun 02 08:59:36 opera volumio[19120]: info: CorePlayQueue::getTrack 0 Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::volumioPushState Jun 02 08:59:36 opera volumio[19120]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 02 08:59:36 opera volumio[19120]: info: ------------------------------ 220ms Jun 02 08:59:36 opera volumio[19120]: info: ------------------------------ 221ms Jun 02 08:59:36 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:36 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:36 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:36 opera volumio[19120]: info: Updating RAAT Signal Path Jun 02 08:59:39 opera volumio[1635]: [Vollibrespot] : couldn't parse packet from V4(192.168.3.21:5353): query type 65 is invalid Jun 02 08:59:39 opera volumio[19120]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 08:59:39 opera volumio[19120]: { Error: connect ETIMEDOUT 173.252.88.67:443 Jun 02 08:59:39 opera volumio[19120]: at Object._errnoException (util.js:1022:11) Jun 02 08:59:39 opera volumio[19120]: at _exceptionWithHostPort (util.js:1044:20) Jun 02 08:59:39 opera volumio[19120]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1198:14) Jun 02 08:59:39 opera volumio[19120]: code: 'ETIMEDOUT', Jun 02 08:59:39 opera volumio[19120]: errno: 'ETIMEDOUT', Jun 02 08:59:39 opera volumio[19120]: syscall: 'connect', Jun 02 08:59:39 opera volumio[19120]: address: '173.252.88.67', Jun 02 08:59:39 opera volumio[19120]: port: 443 } Jun 02 08:59:39 opera volumio[19120]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 08:59:40 opera sudo[2718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-06-02 08:58 Jun 02 08:59:40 opera sudo[2718]: 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"