-- Logs begin at Sat 2024-10-19 08:13:18 CST, end at Sat 2024-10-19 08:34:54 CST. -- Oct 19 08:33:27 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 19 08:33:27 volumio volumio[855]: info: CURURI: music-library/NAS/music/SENNHEISER Oct 19 08:33:27 volumio volumio[855]: info: Preload queue cleared Oct 19 08:33:27 volumio volumio[855]: info: Preloading song: music-library/NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff Oct 19 08:33:27 volumio volumio[855]: info: Preloading song: music-library/NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff Oct 19 08:33:27 volumio volumio[855]: info: Preloading song: music-library/NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff Oct 19 08:33:27 volumio volumio[855]: info: Preloading song: music-library/NAS/music/SENNHEISER/02 - Sara K. - Stars.dff Oct 19 08:33:27 volumio volumio[855]: info: Preloading song: music-library/NAS/music/SENNHEISER/03 - Sara K. - Turned My Upside Down.dff Oct 19 08:33:27 volumio volumio[855]: info: Preloading song: music-library/NAS/music/SENNHEISER/03 - Sara K. - When I Didn´t Care.dff Oct 19 08:33:27 volumio volumio[855]: info: Preloading song: music-library/NAS/music/SENNHEISER/04 - Sara K. & Chris Jones - Stop Those Bells.dff Oct 19 08:33:27 volumio volumio[855]: info: Preloading song: music-library/NAS/music/SENNHEISER/04 - Steve Strauss - Closer.dff Oct 19 08:33:27 volumio volumio[855]: info: Preloading song: music-library/NAS/music/SENNHEISER/05 - bassface swing trio - I Love Paris.dff Oct 19 08:33:27 volumio volumio[855]: info: Preloading song: music-library/NAS/music/SENNHEISER/05 - Christian Willisohn - Caruso.dff Oct 19 08:33:27 volumio volumio[855]: info: Exploding uri music-library/NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff in service mpd Oct 19 08:33:27 volumio volumio[855]: info: ALBUMART /albumart?cacheid=518&web=//extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FSENNHEISER%2F01%20-%20Beo%20Brockhausen%20-%20Wolkenmeer.dff&metadata=false Oct 19 08:33:27 volumio volumio[855]: info: URI /mnt/NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff Oct 19 08:33:27 volumio volumio[855]: info: Exploding uri music-library/NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff in service mpd Oct 19 08:33:27 volumio volumio[855]: info: ALBUMART /albumart?cacheid=518&web=//extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FSENNHEISER%2F01%20-%20Steve%20Strauss%20-%20Mr.%20Bones.dff&metadata=false Oct 19 08:33:27 volumio volumio[855]: info: URI /mnt/NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff Oct 19 08:33:27 volumio volumio[855]: info: Exploding uri music-library/NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff in service mpd Oct 19 08:33:27 volumio volumio[855]: info: ALBUMART /albumart?cacheid=518&web=//extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FSENNHEISER%2F02%20-%20Chris%20Jones%20-%20No%20Sanctuary%20Here.dff&metadata=false Oct 19 08:33:27 volumio volumio[855]: info: URI /mnt/NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff Oct 19 08:33:27 volumio volumio[855]: info: Exploding uri music-library/NAS/music/SENNHEISER/02 - Sara K. - Stars.dff in service mpd Oct 19 08:33:27 volumio volumio[855]: info: ALBUMART /albumart?cacheid=518&web=//extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FSENNHEISER%2F02%20-%20Sara%20K.%20-%20Stars.dff&metadata=false Oct 19 08:33:27 volumio volumio[855]: info: URI /mnt/NAS/music/SENNHEISER/02 - Sara K. - Stars.dff Oct 19 08:33:27 volumio volumio[855]: info: Exploding uri music-library/NAS/music/SENNHEISER/03 - Sara K. - Turned My Upside Down.dff in service mpd Oct 19 08:33:27 volumio volumio[855]: info: ALBUMART /albumart?cacheid=518&web=//extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FSENNHEISER%2F03%20-%20Sara%20K.%20-%20Turned%20My%20Upside%20Down.dff&metadata=false Oct 19 08:33:27 volumio volumio[855]: info: URI /mnt/NAS/music/SENNHEISER/03 - Sara K. - Turned My Upside Down.dff Oct 19 08:33:27 volumio volumio[855]: info: Exploding uri music-library/NAS/music/SENNHEISER/03 - Sara K. - When I Didn´t Care.dff in service mpd Oct 19 08:33:27 volumio volumio[855]: info: ALBUMART /albumart?cacheid=518&web=//extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FSENNHEISER%2F03%20-%20Sara%20K.%20-%20When%20I%20Didn%C2%B4t%20Care.dff&metadata=false Oct 19 08:33:27 volumio volumio[855]: info: URI /mnt/NAS/music/SENNHEISER/03 - Sara K. - When I Didn´t Care.dff Oct 19 08:33:27 volumio volumio[855]: info: Exploding uri music-library/NAS/music/SENNHEISER/04 - Sara K. & Chris Jones - Stop Those Bells.dff in service mpd Oct 19 08:33:27 volumio volumio[855]: info: ALBUMART /albumart?cacheid=518&web=//extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FSENNHEISER%2F04%20-%20Sara%20K.%20%26%20Chris%20Jones%20-%20Stop%20Those%20Bells.dff&metadata=false Oct 19 08:33:27 volumio volumio[855]: info: URI /mnt/NAS/music/SENNHEISER/04 - Sara K. & Chris Jones - Stop Those Bells.dff Oct 19 08:33:27 volumio volumio[855]: info: Exploding uri music-library/NAS/music/SENNHEISER/04 - Steve Strauss - Closer.dff in service mpd Oct 19 08:33:27 volumio volumio[855]: info: ALBUMART /albumart?cacheid=518&web=//extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FSENNHEISER%2F04%20-%20Steve%20Strauss%20-%20Closer.dff&metadata=false Oct 19 08:33:27 volumio volumio[855]: info: URI /mnt/NAS/music/SENNHEISER/04 - Steve Strauss - Closer.dff Oct 19 08:33:27 volumio volumio[855]: info: Exploding uri music-library/NAS/music/SENNHEISER/05 - bassface swing trio - I Love Paris.dff in service mpd Oct 19 08:33:27 volumio volumio[855]: info: ALBUMART /albumart?cacheid=518&web=//extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FSENNHEISER%2F05%20-%20bassface%20swing%20trio%20-%20I%20Love%20Paris.dff&metadata=false Oct 19 08:33:27 volumio volumio[855]: info: URI /mnt/NAS/music/SENNHEISER/05 - bassface swing trio - I Love Paris.dff Oct 19 08:33:27 volumio volumio[855]: info: Exploding uri music-library/NAS/music/SENNHEISER/05 - Christian Willisohn - Caruso.dff in service mpd Oct 19 08:33:27 volumio volumio[855]: info: ALBUMART /albumart?cacheid=518&web=//extralarge&path=%2Fmnt%2FNAS%2Fmusic%2FSENNHEISER%2F05%20-%20Christian%20Willisohn%20-%20Caruso.dff&metadata=false Oct 19 08:33:27 volumio volumio[855]: info: URI /mnt/NAS/music/SENNHEISER/05 - Christian Willisohn - Caruso.dff Oct 19 08:33:30 volumio volumio[855]: info: Preload queue cleared Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::ClearQueue Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::stop Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::stPlaybackTimer Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::updateTrackBlock Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrackBlock Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::serviceStop Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::serviceStop Oct 19 08:33:30 volumio volumio[855]: info: ControllerMpd::stop Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::clearPlayQueue Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::saveQueue Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::volumioPushQueue Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::addQueueItems Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::addQueueItems Oct 19 08:33:30 volumio volumio[855]: info: Preload queue cleared Oct 19 08:33:30 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff Oct 19 08:33:30 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff Oct 19 08:33:30 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff Oct 19 08:33:30 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff Oct 19 08:33:30 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff Oct 19 08:33:30 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff Oct 19 08:33:30 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/02 - Sara K. - Stars.dff Oct 19 08:33:30 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/02 - Sara K. - Stars.dff Oct 19 08:33:30 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/03 - Sara K. - Turned My Upside Down.dff Oct 19 08:33:30 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/03 - Sara K. - Turned My Upside Down.dff Oct 19 08:33:30 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/03 - Sara K. - When I Didn´t Care.dff Oct 19 08:33:30 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/03 - Sara K. - When I Didn´t Care.dff Oct 19 08:33:30 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/04 - Sara K. & Chris Jones - Stop Those Bells.dff Oct 19 08:33:30 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/04 - Sara K. & Chris Jones - Stop Those Bells.dff Oct 19 08:33:30 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/04 - Steve Strauss - Closer.dff Oct 19 08:33:30 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/04 - Steve Strauss - Closer.dff Oct 19 08:33:30 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/05 - bassface swing trio - I Love Paris.dff Oct 19 08:33:30 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/05 - bassface swing trio - I Love Paris.dff Oct 19 08:33:30 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/05 - Christian Willisohn - Caruso.dff Oct 19 08:33:30 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/05 - Christian Willisohn - Caruso.dff Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::volumioPushQueue Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::saveQueue Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::updateTrackBlock Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrackBlock Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::volumioPlay Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::play index 0 Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::stop Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::play index undefined Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::startPlaybackTimer Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand stop took 35 milliseconds Oct 19 08:33:30 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand stop took 6 milliseconds Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:30 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:30 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:33:30 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand status took 8 milliseconds Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand clear took 8 milliseconds Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand status took 7 milliseconds Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand status took 6 milliseconds Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff" Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:30 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:30 volumio volumio[855]: 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} Oct 19 08:33:30 volumio volumio[855]: verbose: CURRENT POSITION 0 Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::syncState stateService stop Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus stop Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:30 volumio volumio[855]: info: No code Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:30 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:30 volumio volumio[855]: 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} Oct 19 08:33:30 volumio volumio[855]: verbose: CURRENT POSITION 0 Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::syncState stateService stop Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus stop Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:30 volumio volumio[855]: info: No code Oct 19 08:33:30 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:30 volumio volumio[855]: info: ------------------------------ 78ms Oct 19 08:33:30 volumio volumio[855]: info: ------------------------------ 76ms Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:33:30 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:33:30 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:33:30 volumio volumio[855]: error: updateQueue error: null Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:33:30 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:33:30 volumio volumio[855]: info: ------------------------------ 84ms Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand playlistinfo took 79 milliseconds Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand add "NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff" took 79 milliseconds Oct 19 08:33:30 volumio volumio[855]: info: ------------------------------ 10ms Oct 19 08:33:30 volumio volumio[855]: info: ------------------------------ 8ms Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand play Oct 19 08:33:30 volumio volumio[855]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 19 08:33:30 volumio volumio[855]: info: ------------------------------ 99ms Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:33:30 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:33:30 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:33:30 volumio volumio[855]: info: ------------------------------ 20ms Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand play took 16 milliseconds Oct 19 08:33:30 volumio volumio[855]: info: ------------------------------ 13ms Oct 19 08:33:30 volumio volumio[855]: info: ------------------------------ 12ms Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:30 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:30 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:30 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:30 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand status took 11 milliseconds Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand status took 10 milliseconds Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand status took 7 milliseconds Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:30 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:30 volumio volumio[855]: info: Oct 19 08:33:30 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:30 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand status took 13 milliseconds Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand status took 10 milliseconds Oct 19 08:33:30 volumio volumio[855]: info: sendMpdCommand status took 8 milliseconds Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:30 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:33:30 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:30 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:30 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:30 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1115,"duration":313,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01 - Beo Brockhausen - Wolkenmeer.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff","trackType":"dff"} Oct 19 08:33:30 volumio volumio[855]: verbose: CURRENT POSITION 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus stop Oct 19 08:33:31 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1115,"duration":313,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01 - Beo Brockhausen - Wolkenmeer.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff","trackType":"dff"} Oct 19 08:33:31 volumio volumio[855]: verbose: CURRENT POSITION 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:33:31 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:31 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1115,"duration":313,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01 - Beo Brockhausen - Wolkenmeer.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff","trackType":"dff"} Oct 19 08:33:31 volumio volumio[855]: verbose: CURRENT POSITION 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:33:31 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:31 volumio volumio[855]: info: ------------------------------ 61ms Oct 19 08:33:31 volumio volumio[855]: info: ------------------------------ 79ms Oct 19 08:33:31 volumio volumio[855]: info: ------------------------------ 78ms Oct 19 08:33:31 volumio volumio[855]: info: sendMpdCommand playlistinfo took 53 milliseconds Oct 19 08:33:31 volumio volumio[855]: info: sendMpdCommand playlistinfo took 52 milliseconds Oct 19 08:33:31 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:33:31 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1115,"duration":313,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01 - Beo Brockhausen - Wolkenmeer.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff","trackType":"dff"} Oct 19 08:33:31 volumio volumio[855]: verbose: CURRENT POSITION 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:33:31 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:31 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1115,"duration":313,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01 - Beo Brockhausen - Wolkenmeer.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff","trackType":"dff"} Oct 19 08:33:31 volumio volumio[855]: verbose: CURRENT POSITION 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:33:31 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:31 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1115,"duration":313,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01 - Beo Brockhausen - Wolkenmeer.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff","trackType":"dff"} Oct 19 08:33:31 volumio volumio[855]: verbose: CURRENT POSITION 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:33:31 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:31 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:31 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:31 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:31 volumio volumio[855]: info: ------------------------------ 136ms Oct 19 08:33:31 volumio volumio[855]: info: ------------------------------ 128ms Oct 19 08:33:31 volumio volumio[855]: info: ------------------------------ 127ms Oct 19 08:33:34 volumio volumio[855]: info: Preload queue cleared Oct 19 08:33:34 volumio volumio[855]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 19 08:33:34 volumio volumio[855]: info: CoreStateMachine::ClearQueue Oct 19 08:33:34 volumio volumio[855]: info: CoreStateMachine::stop Oct 19 08:33:34 volumio volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 08:33:34 volumio volumio[855]: info: CoreStateMachine::stPlaybackTimer Oct 19 08:33:34 volumio volumio[855]: info: CoreStateMachine::updateTrackBlock Oct 19 08:33:34 volumio volumio[855]: info: CorePlayQueue::getTrackBlock Oct 19 08:33:34 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:34 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:34 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 08:33:34 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:34 volumio volumio[855]: info: CoreStateMachine::serviceStop Oct 19 08:33:34 volumio volumio[855]: info: CorePlayQueue::getTrack 0 Oct 19 08:33:34 volumio volumio[855]: info: CoreCommandRouter::serviceStop Oct 19 08:33:34 volumio volumio[855]: info: ControllerMpd::stop Oct 19 08:33:34 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 08:33:34 volumio volumio[855]: info: CorePlayQueue::clearPlayQueue Oct 19 08:33:34 volumio volumio[855]: info: CorePlayQueue::saveQueue Oct 19 08:33:34 volumio volumio[855]: info: CoreCommandRouter::volumioPushQueue Oct 19 08:33:34 volumio volumio[855]: info: CoreStateMachine::addQueueItems Oct 19 08:33:34 volumio volumio[855]: info: CorePlayQueue::addQueueItems Oct 19 08:33:34 volumio volumio[855]: info: Preload queue cleared Oct 19 08:33:34 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff Oct 19 08:33:34 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff Oct 19 08:33:34 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff Oct 19 08:33:34 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff Oct 19 08:33:34 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff Oct 19 08:33:34 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff Oct 19 08:33:34 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/02 - Sara K. - Stars.dff Oct 19 08:33:34 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/02 - Sara K. - Stars.dff Oct 19 08:33:34 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/03 - Sara K. - Turned My Upside Down.dff Oct 19 08:33:34 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/03 - Sara K. - Turned My Upside Down.dff Oct 19 08:33:34 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/03 - Sara K. - When I Didn´t Care.dff Oct 19 08:33:34 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/03 - Sara K. - When I Didn´t Care.dff Oct 19 08:33:34 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/04 - Sara K. & Chris Jones - Stop Those Bells.dff Oct 19 08:33:34 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/04 - Sara K. & Chris Jones - Stop Those Bells.dff Oct 19 08:33:34 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/04 - Steve Strauss - Closer.dff Oct 19 08:33:34 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/04 - Steve Strauss - Closer.dff Oct 19 08:33:34 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/05 - bassface swing trio - I Love Paris.dff Oct 19 08:33:34 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/05 - bassface swing trio - I Love Paris.dff Oct 19 08:33:34 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/05 - Christian Willisohn - Caruso.dff Oct 19 08:33:34 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/05 - Christian Willisohn - Caruso.dff Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushQueue Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::saveQueue Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::updateTrackBlock Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrackBlock Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPlay Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::play index 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::stop Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::play index undefined Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::startPlaybackTimer Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand stop took 46 milliseconds Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand stop took 5 milliseconds Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:33:35 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand status took 9 milliseconds Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand clear took 7 milliseconds Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand status took 6 milliseconds Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand status took 4 milliseconds Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff" Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: 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} Oct 19 08:33:35 volumio volumio[855]: verbose: CURRENT POSITION 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState stateService stop Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus stop Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: No code Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: 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} Oct 19 08:33:35 volumio volumio[855]: verbose: CURRENT POSITION 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState stateService stop Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus stop Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: No code Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 86ms Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 90ms Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:33:35 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:33:35 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:33:35 volumio volumio[855]: error: updateQueue error: null Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:33:35 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 107ms Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand playlistinfo took 104 milliseconds Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand add "NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff" took 105 milliseconds Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 19ms Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 13ms Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand play Oct 19 08:33:35 volumio volumio[855]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 120ms Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:33:35 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:33:35 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 25ms Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand play took 17 milliseconds Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 14ms Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 13ms Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand status took 11 milliseconds Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand status took 16 milliseconds Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand status took 14 milliseconds Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand playlistinfo took 9 milliseconds Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Steve Strauss - Mr. Bones.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff","trackType":"dff"} Oct 19 08:33:35 volumio volumio[855]: verbose: CURRENT POSITION 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus stop Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 33ms Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:35 volumio volumio[855]: info: Oct 19 08:33:35 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand status took 24 milliseconds Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand playlistinfo took 24 milliseconds Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand status took 13 milliseconds Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Steve Strauss - Mr. Bones.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff","trackType":"dff"} Oct 19 08:33:35 volumio volumio[855]: verbose: CURRENT POSITION 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:33:35 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01 - Steve Strauss - Mr. Bones.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff","trackType":"dff"} Oct 19 08:33:35 volumio volumio[855]: verbose: CURRENT POSITION 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:33:35 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 117ms Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 115ms Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand status took 82 milliseconds Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand playlistinfo took 71 milliseconds Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand playlistinfo took 67 milliseconds Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01 - Steve Strauss - Mr. Bones.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff","trackType":"dff"} Oct 19 08:33:35 volumio volumio[855]: verbose: CURRENT POSITION 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:33:35 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01 - Steve Strauss - Mr. Bones.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff","trackType":"dff"} Oct 19 08:33:35 volumio volumio[855]: verbose: CURRENT POSITION 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:33:35 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 207ms Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 195ms Oct 19 08:33:35 volumio volumio[855]: info: sendMpdCommand playlistinfo took 110 milliseconds Oct 19 08:33:35 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:33:35 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":990,"duration":227,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"01 - Steve Strauss - Mr. Bones.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff","trackType":"dff"} Oct 19 08:33:35 volumio volumio[855]: verbose: CURRENT POSITION 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:33:35 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:33:35 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:33:35 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:33:35 volumio volumio[855]: info: ------------------------------ 228ms Oct 19 08:33:57 volumio volumio[855]: info: Adding music-library/NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff to favourites using generic method Oct 19 08:33:57 volumio volumio[855]: info: Saving local item favourites Oct 19 08:33:57 volumio volumio[855]: info: Pushing Favourites {"uri":"/mnt/NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff","favourite":false} Oct 19 08:34:45 volumio volumio[855]: info: Preload queue cleared Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::ClearQueue Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::stop Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::stPlaybackTimer Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::updateTrackBlock Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrackBlock Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::serviceStop Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 1 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::serviceStop Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::stop Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::clearPlayQueue Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::saveQueue Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushQueue Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::addQueueItems Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::addQueueItems Oct 19 08:34:45 volumio volumio[855]: info: Preload queue cleared Oct 19 08:34:45 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff Oct 19 08:34:45 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/01 - Beo Brockhausen - Wolkenmeer.dff Oct 19 08:34:45 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff Oct 19 08:34:45 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/01 - Steve Strauss - Mr. Bones.dff Oct 19 08:34:45 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff Oct 19 08:34:45 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff Oct 19 08:34:45 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/02 - Sara K. - Stars.dff Oct 19 08:34:45 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/02 - Sara K. - Stars.dff Oct 19 08:34:45 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/03 - Sara K. - Turned My Upside Down.dff Oct 19 08:34:45 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/03 - Sara K. - Turned My Upside Down.dff Oct 19 08:34:45 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/03 - Sara K. - When I Didn´t Care.dff Oct 19 08:34:45 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/03 - Sara K. - When I Didn´t Care.dff Oct 19 08:34:45 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/04 - Sara K. & Chris Jones - Stop Those Bells.dff Oct 19 08:34:45 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/04 - Sara K. & Chris Jones - Stop Those Bells.dff Oct 19 08:34:45 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/04 - Steve Strauss - Closer.dff Oct 19 08:34:45 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/04 - Steve Strauss - Closer.dff Oct 19 08:34:45 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/05 - bassface swing trio - I Love Paris.dff Oct 19 08:34:45 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/05 - bassface swing trio - I Love Paris.dff Oct 19 08:34:45 volumio volumio[855]: info: Adding Item to queue: music-library/NAS/music/SENNHEISER/05 - Christian Willisohn - Caruso.dff Oct 19 08:34:45 volumio volumio[855]: info: Using cached record of: music-library/NAS/music/SENNHEISER/05 - Christian Willisohn - Caruso.dff Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushQueue Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::saveQueue Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::updateTrackBlock Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrackBlock Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPlay Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::play index 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::stop Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::play index undefined Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::startPlaybackTimer Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::clearAddPlayTracks NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand stop Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand stop took 47 milliseconds Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand stop took 9 milliseconds Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand clear Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:34:45 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand status took 14 milliseconds Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand clear took 11 milliseconds Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand status took 8 milliseconds Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand add "NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff" Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: 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} Oct 19 08:34:45 volumio volumio[855]: verbose: CURRENT POSITION 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::syncState stateService stop Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus stop Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: info: No code Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: info: ------------------------------ 51ms Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:34:45 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:34:45 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:34:45 volumio volumio[855]: error: updateQueue error: null Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:34:45 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand status took 58 milliseconds Oct 19 08:34:45 volumio volumio[855]: info: ------------------------------ 56ms Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand playlistinfo took 53 milliseconds Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand add "NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff" took 54 milliseconds Oct 19 08:34:45 volumio volumio[855]: info: ------------------------------ 12ms Oct 19 08:34:45 volumio volumio[855]: info: ------------------------------ 11ms Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand play Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: 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} Oct 19 08:34:45 volumio volumio[855]: verbose: CURRENT POSITION 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::syncState stateService stop Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus stop Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: info: No code Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: error: ControllerMpd::pushError: TypeError: Cannot read property 'split' of undefined Oct 19 08:34:45 volumio volumio[855]: info: ------------------------------ 106ms Oct 19 08:34:45 volumio volumio[855]: info: ------------------------------ 116ms Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:34:45 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces system playlist update Oct 19 08:34:45 volumio volumio[855]: info: Ignoring MPD Status Update Oct 19 08:34:45 volumio volumio[855]: info: ------------------------------ 65ms Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand play took 60 milliseconds Oct 19 08:34:45 volumio volumio[855]: info: ------------------------------ 16ms Oct 19 08:34:45 volumio volumio[855]: info: ------------------------------ 13ms Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand status took 6 milliseconds Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:34:45 volumio volumio[855]: info: Oct 19 08:34:45 volumio volumio[855]: ---------------------------- MPD announces state update: player Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::getState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand status Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand status took 15 milliseconds Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand status took 14 milliseconds Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand status took 9 milliseconds Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Chris Jones - No Sanctuary Here.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff","trackType":"dff"} Oct 19 08:34:45 volumio volumio[855]: verbose: CURRENT POSITION 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus stop Oct 19 08:34:45 volumio volumio[855]: info: ------------------------------ 37ms Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand status took 20 milliseconds Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand playlistinfo took 11 milliseconds Oct 19 08:34:45 volumio volumio[855]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::parseState Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:34:45 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Chris Jones - No Sanctuary Here.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff","trackType":"dff"} Oct 19 08:34:45 volumio volumio[855]: verbose: CURRENT POSITION 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:34:45 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Chris Jones - No Sanctuary Here.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff","trackType":"dff"} Oct 19 08:34:45 volumio volumio[855]: verbose: CURRENT POSITION 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:34:45 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Chris Jones - No Sanctuary Here.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff","trackType":"dff"} Oct 19 08:34:45 volumio volumio[855]: verbose: CURRENT POSITION 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:34:45 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"02 - Chris Jones - No Sanctuary Here.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff","trackType":"dff"} Oct 19 08:34:45 volumio volumio[855]: verbose: CURRENT POSITION 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:34:45 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:45 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:45 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:45 volumio volumio[855]: info: ------------------------------ 159ms Oct 19 08:34:45 volumio volumio[855]: info: ------------------------------ 158ms Oct 19 08:34:45 volumio volumio[855]: info: ------------------------------ 156ms Oct 19 08:34:46 volumio volumio[855]: info: ------------------------------ 153ms Oct 19 08:34:46 volumio volumio[855]: info: sendMpdCommand playlistinfo took 128 milliseconds Oct 19 08:34:46 volumio volumio[855]: verbose: ControllerMpd::parseTrackInfo Oct 19 08:34:46 volumio volumio[855]: info: ControllerMpd::pushState Oct 19 08:34:46 volumio volumio[855]: info: CoreCommandRouter::servicePushState Oct 19 08:34:46 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:46 volumio volumio[855]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":224,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"02 - Chris Jones - No Sanctuary Here.dff","artist":null,"album":null,"uri":"NAS/music/SENNHEISER/02 - Chris Jones - No Sanctuary Here.dff","trackType":"dff"} Oct 19 08:34:46 volumio volumio[855]: verbose: CURRENT POSITION 2 Oct 19 08:34:46 volumio volumio[855]: info: CoreStateMachine::syncState stateService play Oct 19 08:34:46 volumio volumio[855]: info: CoreStateMachine::syncState currentStatus play Oct 19 08:34:46 volumio volumio[855]: info: Received an update from plugin. extracting info from payload Oct 19 08:34:46 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:46 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:46 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:46 volumio volumio[855]: info: CoreStateMachine::pushState Oct 19 08:34:46 volumio volumio[855]: info: CorePlayQueue::getTrack 2 Oct 19 08:34:46 volumio volumio[855]: info: CoreCommandRouter::volumioPushState Oct 19 08:34:46 volumio volumio[855]: info: ------------------------------ 187ms Oct 19 08:34:53 volumio volumio[855]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 08:34:53 volumio volumio[855]: Error: connect ETIMEDOUT 108.160.170.39:80 Oct 19 08:34:53 volumio volumio[855]: at TCPConnectWrap.afterConnect [as oncomplete] (net.js:1159:16) { Oct 19 08:34:53 volumio volumio[855]: errno: -110, Oct 19 08:34:53 volumio volumio[855]: code: 'ETIMEDOUT', Oct 19 08:34:53 volumio volumio[855]: syscall: 'connect', Oct 19 08:34:53 volumio volumio[855]: address: '108.160.170.39', Oct 19 08:34:53 volumio volumio[855]: port: 80 Oct 19 08:34:53 volumio volumio[855]: } Oct 19 08:34:53 volumio volumio[855]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 19 08:34:54 volumio sudo[3218]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-19 08:33 Oct 19 08:34:54 volumio sudo[3218]: 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 05:33:09 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="ee834e1c2a28de3c5d8c48611ecf1167"