-- Logs begin at Wed 2025-06-11 10:33:43 MSK, end at Wed 2025-06-11 12:50:42 MSK. -- Jun 11 12:49:01 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 11 12:49:01 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:49:03 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 11 12:49:03 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:49:10 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 11 12:49:10 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:49:12 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4913 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4914 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4915 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4916 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4917 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4918 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4919 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4920 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4921 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4922 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4923 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4924 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4925 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4926 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4927 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F4928 Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4913 in service upnp_browser Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4914 in service upnp_browser Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4915 in service upnp_browser Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4916 in service upnp_browser Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4917 in service upnp_browser Jun 11 12:49:12 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4918 in service upnp_browser Jun 11 12:49:13 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4919 in service upnp_browser Jun 11 12:49:13 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4920 in service upnp_browser Jun 11 12:49:13 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4921 in service upnp_browser Jun 11 12:49:13 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4922 in service upnp_browser Jun 11 12:49:13 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4923 in service upnp_browser Jun 11 12:49:13 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4924 in service upnp_browser Jun 11 12:49:13 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4925 in service upnp_browser Jun 11 12:49:13 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4926 in service upnp_browser Jun 11 12:49:13 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4927 in service upnp_browser Jun 11 12:49:13 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F4928 in service upnp_browser Jun 11 12:49:13 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:49:13 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 11 12:49:13 smsl-n100 volumio[1623]: info: CoreStateMachine::ClearQueue Jun 11 12:49:13 smsl-n100 volumio[1623]: info: CoreStateMachine::stop Jun 11 12:49:13 smsl-n100 volumio[1623]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 11 12:49:13 smsl-n100 volumio[1623]: info: CorePlayQueue::clearPlayQueue Jun 11 12:49:13 smsl-n100 volumio[1623]: info: CorePlayQueue::saveQueue Jun 11 12:49:13 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushQueue Jun 11 12:49:13 smsl-n100 volumio[1623]: info: CoreStateMachine::addQueueItems Jun 11 12:49:13 smsl-n100 volumio[1623]: info: CorePlayQueue::addQueueItems Jun 11 12:49:13 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:49:13 smsl-n100 volumio[1623]: info: Adding Item to queue: upnp/folder/http://192.168.1.247:52100/ContentDirectory/control@5860 Jun 11 12:49:13 smsl-n100 volumio[1623]: info: Exploding uri upnp/folder/http://192.168.1.247:52100/ContentDirectory/control@5860 in service upnp_browser Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushQueue Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CorePlayQueue::saveQueue Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::updateTrackBlock Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrackBlock Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPlay Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::play index 0 Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::stop Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::play index undefined Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::startPlaybackTimer Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:49:14 smsl-n100 volumio[1623]: info: [1749635354040] ControllerUPNPBrowser::clearAddPlayTrack Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand stop Jun 11 12:49:14 smsl-n100 volumio[1623]: info: sendMpdCommand stop took 22 milliseconds Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand clear Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Jun 11 12:49:14 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:49:14 smsl-n100 volumio[1623]: info: sendMpdCommand clear took 20 milliseconds Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.247:52100/Music/F4913.wav" Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Jun 11 12:49:14 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Jun 11 12:49:14 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:49:14 smsl-n100 volumio[1623]: error: updateQueue error: null Jun 11 12:49:14 smsl-n100 volumio[1623]: error: updateQueue error: null Jun 11 12:49:14 smsl-n100 volumio[1623]: error: updateQueue error: null Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ------------------------------ 29ms Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.247:52100/Music/F4913.wav" Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ------------------------------ 23ms Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ------------------------------ 21ms Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Jun 11 12:49:14 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:49:14 smsl-n100 volumio[1623]: info: sendMpdCommand add "http://192.168.1.247:52100/Music/F4913.wav" took 16 milliseconds Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand play Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Jun 11 12:49:14 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Jun 11 12:49:14 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ------------------------------ 24ms Jun 11 12:49:14 smsl-n100 volumio[1623]: info: sendMpdCommand play took 19 milliseconds Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ------------------------------ 18ms Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ------------------------------ 14ms Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Jun 11 12:49:14 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Jun 11 12:49:14 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:49:14 smsl-n100 volumio[1623]: info: Jun 11 12:49:14 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 11 12:49:14 smsl-n100 volumio[1623]: info: sendMpdCommand status took 25 milliseconds Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 11 12:49:14 smsl-n100 volumio[1623]: info: sendMpdCommand status took 22 milliseconds Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 11 12:49:14 smsl-n100 volumio[1623]: info: sendMpdCommand status took 20 milliseconds Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:49:14 smsl-n100 volumio[1623]: info: sendMpdCommand clearerror took 35 milliseconds Jun 11 12:49:14 smsl-n100 volumio[1623]: info: sendMpdCommand clearerror took 32 milliseconds Jun 11 12:49:14 smsl-n100 volumio[1623]: info: sendMpdCommand clearerror took 29 milliseconds Jun 11 12:49:14 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 14 milliseconds Jun 11 12:49:14 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 11 12:49:14 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseTrackInfo Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseTrackInfo Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseTrackInfo Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F4913.wav","artist":null,"album":null,"uri":"http://192.168.1.247:52100/Music/F4913.wav","trackType":"wav"} Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService stop Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus stop Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:14 smsl-n100 volumio[1623]: info: No code Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F4913.wav","artist":null,"album":null,"uri":"http://192.168.1.247:52100/Music/F4913.wav","trackType":"wav"} Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService stop Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus stop Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:14 smsl-n100 volumio[1623]: info: No code Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F4913.wav","artist":null,"album":null,"uri":"http://192.168.1.247:52100/Music/F4913.wav","trackType":"wav"} Jun 11 12:49:14 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService stop Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus stop Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:14 smsl-n100 volumio[1623]: info: No code Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:14 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ------------------------------ 298ms Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ------------------------------ 295ms Jun 11 12:49:14 smsl-n100 volumio[1623]: info: ------------------------------ 293ms Jun 11 12:49:14 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:14 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:14 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:14 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:14 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:14 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:14 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:14 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPlay Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::play index undefined Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::startPlaybackTimer Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:49:20 smsl-n100 volumio[1623]: info: [1749635360374] ControllerUPNPBrowser::clearAddPlayTrack Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand stop Jun 11 12:49:20 smsl-n100 volumio[1623]: info: sendMpdCommand stop took 5 milliseconds Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand clear Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Jun 11 12:49:20 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:49:20 smsl-n100 volumio[1623]: info: sendMpdCommand clear took 4 milliseconds Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.247:52100/Music/F4913.wav" Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Jun 11 12:49:20 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Jun 11 12:49:20 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:49:20 smsl-n100 volumio[1623]: error: updateQueue error: null Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ------------------------------ 18ms Jun 11 12:49:20 smsl-n100 volumio[1623]: error: updateQueue error: null Jun 11 12:49:20 smsl-n100 volumio[1623]: error: updateQueue error: null Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.247:52100/Music/F4913.wav" Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ------------------------------ 18ms Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ------------------------------ 15ms Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Jun 11 12:49:20 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:49:20 smsl-n100 volumio[1623]: info: sendMpdCommand add "http://192.168.1.247:52100/Music/F4913.wav" took 11 milliseconds Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand play Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Jun 11 12:49:20 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Jun 11 12:49:20 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Jun 11 12:49:20 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ------------------------------ 27ms Jun 11 12:49:20 smsl-n100 volumio[1623]: info: sendMpdCommand play took 21 milliseconds Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ------------------------------ 14ms Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ------------------------------ 12ms Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Jun 11 12:49:20 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:49:20 smsl-n100 volumio[1623]: info: Jun 11 12:49:20 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 11 12:49:20 smsl-n100 volumio[1623]: info: sendMpdCommand status took 25 milliseconds Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 11 12:49:20 smsl-n100 volumio[1623]: info: sendMpdCommand status took 24 milliseconds Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand clearerror Jun 11 12:49:20 smsl-n100 volumio[1623]: info: sendMpdCommand status took 21 milliseconds Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:49:20 smsl-n100 volumio[1623]: info: sendMpdCommand clearerror took 34 milliseconds Jun 11 12:49:20 smsl-n100 volumio[1623]: info: sendMpdCommand clearerror took 31 milliseconds Jun 11 12:49:20 smsl-n100 volumio[1623]: info: sendMpdCommand clearerror took 28 milliseconds Jun 11 12:49:20 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 13 milliseconds Jun 11 12:49:20 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 12 milliseconds Jun 11 12:49:20 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 10 milliseconds Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseTrackInfo Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseTrackInfo Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseTrackInfo Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F4913.wav","artist":null,"album":null,"uri":"http://192.168.1.247:52100/Music/F4913.wav","trackType":"wav"} Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService stop Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus stop Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:20 smsl-n100 volumio[1623]: info: No code Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F4913.wav","artist":null,"album":null,"uri":"http://192.168.1.247:52100/Music/F4913.wav","trackType":"wav"} Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService stop Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus stop Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:20 smsl-n100 volumio[1623]: info: No code Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"F4913.wav","artist":null,"album":null,"uri":"http://192.168.1.247:52100/Music/F4913.wav","trackType":"wav"} Jun 11 12:49:20 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService stop Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus stop Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:20 smsl-n100 volumio[1623]: info: No code Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:49:20 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ------------------------------ 306ms Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ------------------------------ 299ms Jun 11 12:49:20 smsl-n100 volumio[1623]: info: ------------------------------ 297ms Jun 11 12:49:20 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:20 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:20 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:20 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:20 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:20 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:20 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:20 smsl-n100 volumio[1623]: error: Failed to parse state for serial connection: TypeError: Cannot read property 'toUpperCase' of undefined Jun 11 12:49:31 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 11 12:49:31 smsl-n100 volumio[1623]: info: browseTIDALUri took 764 milliseconds Jun 11 12:49:31 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:49:31 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:49:34 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 11 12:49:35 smsl-n100 volumio[1623]: info: browseTIDALUri took 1183 milliseconds Jun 11 12:49:35 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:49:35 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:49:40 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: tidal , handleBrowseUri Jun 11 12:49:40 smsl-n100 volumio[1623]: info: browseTIDALUri took 731 milliseconds Jun 11 12:49:40 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:49:40 smsl-n100 volumio[1623]: info: Preloading song: tidal://song/419815688 Jun 11 12:49:41 smsl-n100 volumio[1623]: info: Exploding uri tidal://song/419815688 in service tidal Jun 11 12:49:41 smsl-n100 volumio[1623]: info: Executing endpoint metavolumio Jun 11 12:49:41 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 11 12:49:41 smsl-n100 volumio[1623]: info: Executing endpoint metavolumio Jun 11 12:49:41 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Jun 11 12:49:41 smsl-n100 volumio[1623]: info: explodeTIDALUri took 580 milliseconds Jun 11 12:49:48 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 11 12:49:48 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:49:49 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 11 12:49:49 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:49:51 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 11 12:49:52 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:49:54 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 11 12:49:59 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:50:08 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: upnp_browser , handleBrowseUri Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F12411 Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F12412 Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F12413 Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F12414 Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F12415 Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F12416 Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F12417 Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F12418 Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F12419 Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F12420 Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F12421 Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F12422 Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Preloading song: upnp/http://192.168.1.247:52100/ContentDirectory/control@F12423 Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F12411 in service upnp_browser Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F12412 in service upnp_browser Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F12413 in service upnp_browser Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F12414 in service upnp_browser Jun 11 12:50:08 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F12415 in service upnp_browser Jun 11 12:50:09 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F12416 in service upnp_browser Jun 11 12:50:09 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F12417 in service upnp_browser Jun 11 12:50:09 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F12418 in service upnp_browser Jun 11 12:50:09 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F12419 in service upnp_browser Jun 11 12:50:09 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F12420 in service upnp_browser Jun 11 12:50:09 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F12421 in service upnp_browser Jun 11 12:50:09 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F12422 in service upnp_browser Jun 11 12:50:09 smsl-n100 volumio[1623]: info: Exploding uri upnp/http://192.168.1.247:52100/ContentDirectory/control@F12423 in service upnp_browser Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreStateMachine::ClearQueue Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreStateMachine::stop Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CorePlayQueue::clearPlayQueue Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CorePlayQueue::saveQueue Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushQueue Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreStateMachine::addQueueItems Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CorePlayQueue::addQueueItems Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Preload queue cleared Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Adding Item to queue: upnp/folder/http://192.168.1.247:52100/ContentDirectory/control@1240 Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Exploding uri upnp/folder/http://192.168.1.247:52100/ContentDirectory/control@1240 in service upnp_browser Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushQueue Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CorePlayQueue::saveQueue Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreStateMachine::updateTrackBlock Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrackBlock Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPlay Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreStateMachine::play index 0 Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreStateMachine::stop Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreStateMachine::play index undefined Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreStateMachine::startPlaybackTimer Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:50:10 smsl-n100 volumio[1623]: info: [1749635410787] ControllerUPNPBrowser::clearAddPlayTrack Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand stop Jun 11 12:50:10 smsl-n100 volumio[1623]: info: sendMpdCommand stop took 26 milliseconds Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand clear Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Jun 11 12:50:10 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:50:10 smsl-n100 volumio[1623]: info: sendMpdCommand clear took 7 milliseconds Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand load "http://192.168.1.247:52100/Music/F12411.flac" Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Jun 11 12:50:10 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Jun 11 12:50:10 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:50:10 smsl-n100 volumio[1623]: error: updateQueue error: null Jun 11 12:50:10 smsl-n100 volumio[1623]: info: ------------------------------ 23ms Jun 11 12:50:10 smsl-n100 volumio[1623]: error: updateQueue error: null Jun 11 12:50:10 smsl-n100 volumio[1623]: error: updateQueue error: null Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand add "http://192.168.1.247:52100/Music/F12411.flac" Jun 11 12:50:10 smsl-n100 volumio[1623]: info: ------------------------------ 38ms Jun 11 12:50:10 smsl-n100 volumio[1623]: info: ------------------------------ 35ms Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Jun 11 12:50:10 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:50:10 smsl-n100 volumio[1623]: info: sendMpdCommand add "http://192.168.1.247:52100/Music/F12411.flac" took 7 milliseconds Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand play Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Jun 11 12:50:10 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Jun 11 12:50:10 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:50:10 smsl-n100 volumio[1623]: info: ------------------------------ 20ms Jun 11 12:50:10 smsl-n100 volumio[1623]: info: sendMpdCommand play took 16 milliseconds Jun 11 12:50:10 smsl-n100 volumio[1623]: info: ------------------------------ 14ms Jun 11 12:50:10 smsl-n100 volumio[1623]: info: ------------------------------ 11ms Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Jun 11 12:50:10 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:50:10 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Jun 11 12:50:10 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:50:10 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Jun 11 12:50:10 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:50:10 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:50:10 smsl-n100 volumio[1623]: info: sendMpdCommand status took 29 milliseconds Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Jun 11 12:50:10 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Jun 11 12:50:10 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:50:10 smsl-n100 volumio[1623]: info: Jun 11 12:50:10 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:50:10 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:50:10 smsl-n100 volumio[1623]: info: sendMpdCommand status took 45 milliseconds Jun 11 12:50:10 smsl-n100 volumio[1623]: info: sendMpdCommand status took 40 milliseconds Jun 11 12:50:10 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:50:10 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 27 milliseconds Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:50:10 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseTrackInfo Jun 11 12:50:10 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:50:10 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2995,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Alone","artist":"KHYMERA","album":"'A New Promise'","uri":"http://192.168.1.247:52100/Music/F12411.flac","trackType":"flac"} Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus stop Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ------------------------------ 104ms Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Jun 11 12:50:11 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Jun 11 12:50:11 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:50:11 smsl-n100 volumio[1623]: info: sendMpdCommand status took 75 milliseconds Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ------------------------------ 87ms Jun 11 12:50:11 smsl-n100 volumio[1623]: info: sendMpdCommand status took 69 milliseconds Jun 11 12:50:11 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 62 milliseconds Jun 11 12:50:11 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 61 milliseconds Jun 11 12:50:11 smsl-n100 volumio[1623]: info: sendMpdCommand status took 28 milliseconds Jun 11 12:50:11 smsl-n100 volumio[1623]: info: sendMpdCommand status took 20 milliseconds Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseTrackInfo Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2995,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Alone","artist":"KHYMERA","album":"'A New Promise'","uri":"http://192.168.1.247:52100/Music/F12411.flac","trackType":"flac"} Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Received an update from plugin. extracting info from payload Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":2995,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Alone","artist":"KHYMERA","album":"'A New Promise'","uri":"http://192.168.1.247:52100/Music/F12411.flac","trackType":"flac"} Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Received an update from plugin. extracting info from payload Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ------------------------------ 301ms Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ------------------------------ 296ms Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Jun 11 12:50:11 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Jun 11 12:50:11 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Jun 11 12:50:11 smsl-n100 volumio[1623]: ---------------------------- MPD announces system playlist update Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Ignoring MPD Status Update Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Jun 11 12:50:11 smsl-n100 volumio[1623]: ---------------------------- MPD announces state update: player Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ControllerMpd::getState Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand status Jun 11 12:50:11 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 185 milliseconds Jun 11 12:50:11 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 184 milliseconds Jun 11 12:50:11 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 178 milliseconds Jun 11 12:50:11 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 176 milliseconds Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ------------------------------ 28ms Jun 11 12:50:11 smsl-n100 volumio[1623]: info: sendMpdCommand status took 24 milliseconds Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ------------------------------ 23ms Jun 11 12:50:11 smsl-n100 volumio[1623]: info: sendMpdCommand status took 19 milliseconds Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseTrackInfo Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseTrackInfo Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseTrackInfo Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseTrackInfo Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseState Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":2995,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"954 Kbps","isStreaming":false,"title":"Alone","artist":"KHYMERA","album":"'A New Promise'","uri":"http://192.168.1.247:52100/Music/F12411.flac","trackType":"flac"} Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Received an update from plugin. extracting info from payload Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"play","position":0,"seek":983,"duration":2995,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"954 Kbps","isStreaming":false,"title":"Alone","artist":"KHYMERA","album":"'A New Promise'","uri":"http://192.168.1.247:52100/Music/F12411.flac","trackType":"flac"} Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Received an update from plugin. extracting info from payload Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":2995,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"951 Kbps","isStreaming":false,"title":"Alone","artist":"KHYMERA","album":"'A New Promise'","uri":"http://192.168.1.247:52100/Music/F12411.flac","trackType":"flac"} Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Received an update from plugin. extracting info from payload Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":2995,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"951 Kbps","isStreaming":false,"title":"Alone","artist":"KHYMERA","album":"'A New Promise'","uri":"http://192.168.1.247:52100/Music/F12411.flac","trackType":"flac"} Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Received an update from plugin. extracting info from payload Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ------------------------------ 541ms Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ------------------------------ 537ms Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ------------------------------ 483ms Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ------------------------------ 475ms Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 481 milliseconds Jun 11 12:50:11 smsl-n100 volumio[1623]: info: sendMpdCommand playlistinfo took 480 milliseconds Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseTrackInfo Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: ControllerMpd::parseTrackInfo Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":2995,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"950 Kbps","isStreaming":false,"title":"Alone","artist":"KHYMERA","album":"'A New Promise'","uri":"http://192.168.1.247:52100/Music/F12411.flac","trackType":"flac"} Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Received an update from plugin. extracting info from payload Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ControllerMpd::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::servicePushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getTrack 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1601,"duration":2995,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"948 Kbps","isStreaming":false,"title":"Alone","artist":"KHYMERA","album":"'A New Promise'","uri":"http://192.168.1.247:52100/Music/F12411.flac","trackType":"flac"} Jun 11 12:50:11 smsl-n100 volumio[1623]: verbose: CURRENT POSITION 0 Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState stateService play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::syncState currentStatus play Jun 11 12:50:11 smsl-n100 volumio[1623]: info: Received an update from plugin. extracting info from payload Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ------------------------------ 649ms Jun 11 12:50:11 smsl-n100 volumio[1623]: info: ------------------------------ 644ms Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreStateMachine::pushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioPushState Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output update for this device Jun 11 12:50:11 smsl-n100 volumio[1623]: info: MRS: Pushing multiroomSync output Jun 11 12:50:12 smsl-n100 volumio[1623]: error: Serial API: Failed to decode command: 100, message: undefined Jun 11 12:50:12 smsl-n100 volumio[1623]: error: Serial API: Failed to decode command: MAXVOL, message: Jun 11 12:50:12 smsl-n100 volumio[1623]: error: Serial API: Failed to decode command: 100, message: undefined Jun 11 12:50:12 smsl-n100 volumio[1623]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 11 12:50:12 smsl-n100 volumio[1623]: info: CoreCommandRouter::volumioGetQueue Jun 11 12:50:12 smsl-n100 volumio[1623]: info: CoreStateMachine::getQueue Jun 11 12:50:12 smsl-n100 volumio[1623]: info: CorePlayQueue::getQueue Jun 11 12:50:19 smsl-n100 volumio[1623]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/Amun%20Re/Magic%20Kingdom/ab41df1b-536b-473d-ba61-76fdc788d31d.jpg' Jun 11 12:50:25 smsl-n100 volumio[1623]: An internal error occurred while serving an albumart. Details: Error: ENOENT: no such file or directory, stat '/data/albumart/web/The%20Duskfall/Source/81f97171-4c83-47ea-91f7-300e221097dd.jpg' Jun 11 12:50:41 smsl-n100 volumio[1623]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 11 12:50:41 smsl-n100 volumio[1623]: Error: write EPROTO 4155994656:error:1409442E:SSL routines:ssl3_read_bytes:tlsv1 alert protocol version:../deps/openssl/openssl/ssl/record/rec_layer_s3.c:1563:SSL alert number 70 Jun 11 12:50:41 smsl-n100 volumio[1623]: at WriteWrap.onWriteComplete [as oncomplete] (internal/stream_base_commons.js:94:16) { Jun 11 12:50:41 smsl-n100 volumio[1623]: errno: -71, Jun 11 12:50:41 smsl-n100 volumio[1623]: code: 'EPROTO', Jun 11 12:50:41 smsl-n100 volumio[1623]: syscall: 'write' Jun 11 12:50:41 smsl-n100 volumio[1623]: } Jun 11 12:50:41 smsl-n100 volumio[1623]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 11 12:50:42 smsl-n100 sudo[2500]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-11 12:49 Jun 11 12:50:42 smsl-n100 sudo[2500]: 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"