-- Logs begin at Mon 2025-12-22 16:56:30 UTC, end at Tue 2025-12-23 20:08:55 UTC. -- Dec 23 20:07:16 volumio2 volumio[1105]: verbose: New Socket.io Connection to 192.168.68.104:3000 from 192.168.68.113 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 5 Dec 23 20:07:16 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 20:07:16 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 20:07:16 volumio2 volumio[1105]: info: Discovery: Getting this device information Dec 23 20:07:16 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetState Dec 23 20:07:16 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 20:07:25 volumio2 volumio[1105]: verbose: New Socket.io Connection to 192.168.68.104 from 192.168.68.113 UA: Mozilla/5.0 (Linux; Android 11; KFTUWI Build/RS8338.3339N; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/140.0.7339.251 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 23 20:07:25 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 23 20:07:25 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 23 20:07:26 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetVisibleSources Dec 23 20:07:26 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 20:07:26 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetState Dec 23 20:07:26 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 23 20:07:26 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:07:26 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:07:26 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:07:26 volumio2 volumio[1105]: info: Listing playlists Dec 23 20:07:26 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 23 20:07:26 volumio2 volumio[1105]: info: Received Get System Info Dec 23 20:07:26 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 20:07:26 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 20:07:26 volumio2 volumio[1105]: info: Discovery: Getting this device information Dec 23 20:07:26 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetState Dec 23 20:07:26 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 20:07:26 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetState Dec 23 20:07:30 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 20:07:30 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 20:07:30 volumio2 volumio[1105]: info: Discovery: Getting this device information Dec 23 20:07:30 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetState Dec 23 20:07:30 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 20:07:30 volumio2 volumio[1105]: verbose: New Socket.io Connection to 192.168.68.104:3000 from 192.168.68.113 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 23 20:07:30 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 23 20:07:30 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioNext Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::next Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::next Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand next Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand next took 9 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:07:31 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:07:31 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:07:31 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:07:31 volumio2 volumio[1105]: error: updateQueue error: null Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 2ms Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand status took 3 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 2ms Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand status took 2 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 2ms Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand status took 1 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 1 Dec 23 20:07:31 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 23 20:07:31 volumio2 volumio[1105]: verbose: CURRENT POSITION 1 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService stop Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::play index undefined Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 23 20:07:31 volumio2 volumio[1105]: verbose: CURRENT POSITION 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService stop Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: No code Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 23 20:07:31 volumio2 volumio[1105]: verbose: CURRENT POSITION 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService stop Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: No code Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::startPlaybackTimer Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: [1766520451386] ControllerUPNPBrowser::clearAddPlayTrack Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 33ms Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 32ms Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 33ms Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand stop took 7 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand clear Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:07:31 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand clear took 12 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac" Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:07:31 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:07:31 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:07:31 volumio2 volumio[1105]: error: updateQueue error: null Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 6ms Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac" Dec 23 20:07:31 volumio2 volumio[1105]: error: updateQueue error: null Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:07:31 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 303ms Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 302ms Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand add "http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac" took 1 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand play Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:07:31 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:07:31 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 2ms Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand play took 2 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 3ms Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 2ms Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand status took 1 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand status took 2 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand status took 1 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand status took 1 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseTrackInfo Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":908,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","artist":null,"album":null,"uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","trackType":"flac"} Dec 23 20:07:31 volumio2 volumio[1105]: verbose: CURRENT POSITION 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 5ms Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand status took 1 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseTrackInfo Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseTrackInfo Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":908,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","artist":null,"album":null,"uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","trackType":"flac"} Dec 23 20:07:31 volumio2 volumio[1105]: verbose: CURRENT POSITION 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:07:31 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":908,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","artist":null,"album":null,"uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","trackType":"flac"} Dec 23 20:07:31 volumio2 volumio[1105]: verbose: CURRENT POSITION 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:07:31 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":908,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","artist":null,"album":null,"uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","trackType":"flac"} Dec 23 20:07:31 volumio2 volumio[1105]: verbose: CURRENT POSITION 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:07:31 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 23ms Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 22ms Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 22ms Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 25 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseTrackInfo Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseTrackInfo Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":908,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","artist":null,"album":null,"uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","trackType":"flac"} Dec 23 20:07:31 volumio2 volumio[1105]: verbose: CURRENT POSITION 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:07:31 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":908,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","artist":null,"album":null,"uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","trackType":"flac"} Dec 23 20:07:31 volumio2 volumio[1105]: verbose: CURRENT POSITION 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:07:31 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 39ms Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 40ms Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:07:31 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:07:31 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:07:31 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:07:31 volumio2 volumio[1105]: info: Dec 23 20:07:31 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 5ms Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand status took 4 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 3ms Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand status took 2 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 2ms Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand status took 2 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseTrackInfo Dec 23 20:07:31 volumio2 volumio[1105]: verbose: ControllerMpd::parseTrackInfo Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":908,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2096 Kbps","isStreaming":false,"title":"Symphony No. 5 in C Sharp Minor: II. Stüermisch bewegt Mit gröesster Vehemenz","artist":"Gustav Mahler","album":"25","uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","trackType":"flac"} Dec 23 20:07:31 volumio2 volumio[1105]: verbose: CURRENT POSITION 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:07:31 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":908,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2096 Kbps","isStreaming":false,"title":"Symphony No. 5 in C Sharp Minor: II. Stüermisch bewegt Mit gröesster Vehemenz","artist":"Gustav Mahler","album":"25","uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","trackType":"flac"} Dec 23 20:07:31 volumio2 volumio[1105]: verbose: CURRENT POSITION 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:07:31 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:07:31 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":992,"duration":908,"samplerate":"96 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"2204 Kbps","isStreaming":false,"title":"Symphony No. 5 in C Sharp Minor: II. Stüermisch bewegt Mit gröesster Vehemenz","artist":"Gustav Mahler","album":"25","uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/03*20-*20Symphony*20No.*205*20in*20C*20Sharp*20Minor*20II.*20St*c3*bcermisch*20bewegt*20Mit*20gr*c3*b6esster*20Vehemenz.flac","trackType":"flac"} Dec 23 20:07:31 volumio2 volumio[1105]: verbose: CURRENT POSITION 2 Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:07:31 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 23ms Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 22ms Dec 23 20:07:31 volumio2 volumio[1105]: info: ------------------------------ 21ms Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:07:31 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioNext Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::next Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::next Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand next Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand next took 4 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:08:23 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:08:23 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:08:23 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:08:23 volumio2 volumio[1105]: error: updateQueue error: null Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 6ms Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand status took 3 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 2 Dec 23 20:08:23 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 23 20:08:23 volumio2 volumio[1105]: verbose: CURRENT POSITION 2 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService stop Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::play index undefined Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::startPlaybackTimer Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: [1766520503021] ControllerUPNPBrowser::clearAddPlayTrack Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand stop Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 16ms Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: error: updateQueue error: null Dec 23 20:08:23 volumio2 volumio[1105]: error: updateQueue error: null Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 16ms Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand status took 15 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 15ms Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand status took 15 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand stop took 4 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand clear Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 23 20:08:23 volumio2 volumio[1105]: verbose: CURRENT POSITION 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService stop Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: No code Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Dec 23 20:08:23 volumio2 volumio[1105]: verbose: CURRENT POSITION 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService stop Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: No code Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 34ms Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 34ms Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:08:23 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand clear took 28 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac" Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:08:23 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:08:23 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:08:23 volumio2 volumio[1105]: error: updateQueue error: null Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 3ms Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac" Dec 23 20:08:23 volumio2 volumio[1105]: error: updateQueue error: null Dec 23 20:08:23 volumio2 volumio[1105]: error: updateQueue error: null Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:08:23 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 507ms Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 506ms Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand add "http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac" took 1 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::setConsumeUpdateService mpd Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand play Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:08:23 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:08:23 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 3ms Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand play took 2 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 1ms Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand status took 4 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand status took 2 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand status took 2 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand status took 5 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 3 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand status took 3 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand status took 2 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseTrackInfo Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseTrackInfo Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","artist":null,"album":null,"uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","trackType":"flac"} Dec 23 20:08:23 volumio2 volumio[1105]: verbose: CURRENT POSITION 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus stop Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","artist":null,"album":null,"uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","trackType":"flac"} Dec 23 20:08:23 volumio2 volumio[1105]: verbose: CURRENT POSITION 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:08:23 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","artist":null,"album":null,"uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","trackType":"flac"} Dec 23 20:08:23 volumio2 volumio[1105]: verbose: CURRENT POSITION 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:08:23 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 17ms Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 20ms Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 19ms Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 15 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 14 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseTrackInfo Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseTrackInfo Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","artist":null,"album":null,"uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","trackType":"flac"} Dec 23 20:08:23 volumio2 volumio[1105]: verbose: CURRENT POSITION 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:08:23 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","artist":null,"album":null,"uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","trackType":"flac"} Dec 23 20:08:23 volumio2 volumio[1105]: verbose: CURRENT POSITION 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:08:23 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","artist":null,"album":null,"uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","trackType":"flac"} Dec 23 20:08:23 volumio2 volumio[1105]: verbose: CURRENT POSITION 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:08:23 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 33ms Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 31ms Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 30ms Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:08:23 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:08:23 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces system playlist update Dec 23 20:08:23 volumio2 volumio[1105]: info: Ignoring MPD Status Update Dec 23 20:08:23 volumio2 volumio[1105]: info: Dec 23 20:08:23 volumio2 volumio[1105]: ---------------------------- MPD announces state update: player Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::getState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand status Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 5ms Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand status took 4 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 3ms Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand status took 3 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 2ms Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand status took 2 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseState Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 1 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseTrackInfo Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseTrackInfo Dec 23 20:08:23 volumio2 volumio[1105]: verbose: ControllerMpd::parseTrackInfo Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"637 Kbps","isStreaming":false,"title":"Tchaikovsky / Hopak from Mazeppa","artist":"Minnesota Orchestra","album":"25","uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","trackType":"flac"} Dec 23 20:08:23 volumio2 volumio[1105]: verbose: CURRENT POSITION 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:08:23 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"614 Kbps","isStreaming":false,"title":"Tchaikovsky / Hopak from Mazeppa","artist":"Minnesota Orchestra","album":"25","uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","trackType":"flac"} Dec 23 20:08:23 volumio2 volumio[1105]: verbose: CURRENT POSITION 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:08:23 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: ControllerMpd::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::servicePushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getTrack 3 Dec 23 20:08:23 volumio2 volumio[1105]: verbose: STATE SERVICE {"status":"play","position":0,"seek":869,"duration":252,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"611 Kbps","isStreaming":false,"title":"Tchaikovsky / Hopak from Mazeppa","artist":"Minnesota Orchestra","album":"25","uri":"http://192.168.68.109:9790/minimserver/*/music/USB1/usbshare/FLAC/SMOOTH*20MIX/25/04*20-*20Tchaikovsky*20*20Hopak*20from*20Mazeppa.flac","trackType":"flac"} Dec 23 20:08:23 volumio2 volumio[1105]: verbose: CURRENT POSITION 3 Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState stateService play Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::syncState currentStatus play Dec 23 20:08:23 volumio2 volumio[1105]: info: Received an update from plugin. extracting info from payload Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::pushState Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioPushState Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 26ms Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 25ms Dec 23 20:08:23 volumio2 volumio[1105]: info: ------------------------------ 23ms Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreCommandRouter::volumioGetQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CoreStateMachine::getQueue Dec 23 20:08:23 volumio2 volumio[1105]: info: CorePlayQueue::getQueue Dec 23 20:08:55 volumio2 volumio[1105]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Dec 23 20:08:55 volumio2 volumio[1105]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 20:08:55 volumio2 volumio[1105]: TypeError: Cannot read property 'length' of undefined Dec 23 20:08:55 volumio2 volumio[1105]: at /volumio/app/plugins/music_service/upnp_browser/index.js:341:25 Dec 23 20:08:55 volumio2 volumio[1105]: at /volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:131:13 Dec 23 20:08:55 volumio2 volumio[1105]: at Parser. (/volumio/node_modules/xml2js/lib/parser.js:304:18) Dec 23 20:08:55 volumio2 volumio[1105]: at Parser.emit (events.js:400:28) Dec 23 20:08:55 volumio2 volumio[1105]: at SAXParser.onclosetag (/volumio/node_modules/xml2js/lib/parser.js:262:26) Dec 23 20:08:55 volumio2 volumio[1105]: at emit (/volumio/node_modules/sax/lib/sax.js:624:35) Dec 23 20:08:55 volumio2 volumio[1105]: at emitNode (/volumio/node_modules/sax/lib/sax.js:629:5) Dec 23 20:08:55 volumio2 volumio[1105]: at closeTag (/volumio/node_modules/sax/lib/sax.js:889:7) Dec 23 20:08:55 volumio2 volumio[1105]: at SAXParser.write (/volumio/node_modules/sax/lib/sax.js:1436:13) Dec 23 20:08:55 volumio2 volumio[1105]: at Parser.exports.Parser.Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:323:31) Dec 23 20:08:55 volumio2 volumio[1105]: at Parser.parseString (/volumio/node_modules/xml2js/lib/parser.js:5:59) Dec 23 20:08:55 volumio2 volumio[1105]: at Object.exports.parseString (/volumio/node_modules/xml2js/lib/parser.js:369:19) Dec 23 20:08:55 volumio2 volumio[1105]: at IncomingMessage. (/volumio/app/plugins/music_service/upnp_browser/dlna-browser.js:92:15) Dec 23 20:08:55 volumio2 volumio[1105]: at IncomingMessage.emit (events.js:412:35) Dec 23 20:08:55 volumio2 volumio[1105]: at endReadableNT (internal/streams/readable.js:1333:12) Dec 23 20:08:55 volumio2 volumio[1105]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Dec 23 20:08:55 volumio2 volumio[1105]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 20:08:55 volumio2 sudo[16262]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-23 20:07 Dec 23 20:08:55 volumio2 sudo[16262]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="669bb6cc406bb77b11c5184fedb8a4dfd19ba4ec" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:55:15 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="tinkerboard" VOLUMIO_DEVICENAME="Asus Tinkerboard" VOLUMIO_HASH="2f3686ade78abf3b92c180a0c5b20c6b"