-- Logs begin at Thu 2019-02-14 12:12:00 EET, end at Sun 2024-08-25 18:55:54 EEST. -- Aug 25 18:54:08 volumiopi3b ntpd[814]: receive: Unexpected origin timestamp 0xea75d207.baa08f89 does not match aorg 0000000000.00000000 from server@83.212.109.72 xmt 0xea75d220.172579a0 Aug 25 18:54:08 volumiopi3b ntpd[814]: receive: Unexpected origin timestamp 0xea75d207.baaeb6bd does not match aorg 0000000000.00000000 from server@85.72.56.142 xmt 0xea75d220.17da6cb5 Aug 25 18:54:08 volumiopi3b ntpd[814]: receive: Unexpected origin timestamp 0xea75d207.baad4b09 does not match aorg 0000000000.00000000 from server@193.239.214.227 xmt 0xea75d220.16f4e2ae Aug 25 18:54:08 volumiopi3b ntpd[814]: receive: Unexpected origin timestamp 0xea75d207.bab2d676 does not match aorg 0000000000.00000000 from server@193.239.214.226 xmt 0xea75d220.171db016 Aug 25 18:54:08 volumiopi3b ntpd[814]: receive: Unexpected origin timestamp 0xea75d207.baab3ab1 does not match aorg 0000000000.00000000 from server@44.32.63.52 xmt 0xea75d220.214e4728 Aug 25 18:54:08 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioGetQueue Aug 25 18:54:08 volumiopi3b volumio[984]: info: CoreStateMachine::getQueue Aug 25 18:54:08 volumiopi3b volumio[984]: info: CorePlayQueue::getQueue Aug 25 18:54:12 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 25 18:54:12 volumiopi3b volumio[984]: info: CURURI: music-library/USB/Seagate/Unknown_artist-Unknown_album Aug 25 18:54:12 volumiopi3b volumio[984]: info: Preload queue cleared Aug 25 18:54:12 volumiopi3b volumio[984]: info: Preloading song: music-library/USB/Seagate/Unknown_artist-Unknown_album/1.Track_1.flac Aug 25 18:54:12 volumiopi3b volumio[984]: info: Preloading song: music-library/USB/Seagate/Unknown_artist-Unknown_album/2.Track_2.flac Aug 25 18:54:12 volumiopi3b volumio[984]: info: Preloading song: music-library/USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac Aug 25 18:54:12 volumiopi3b volumio[984]: info: Exploding uri music-library/USB/Seagate/Unknown_artist-Unknown_album/1.Track_1.flac in service mpd Aug 25 18:54:12 volumiopi3b volumio[984]: info: ALBUMART /albumart?cacheid=836&web=Unknown%20artist/Unknown%20album/extralarge&path=%2Fmnt%2FUSB%2FSeagate%2FUnknown_artist-Unknown_album%2F1.Track_1.flac&metadata=false Aug 25 18:54:12 volumiopi3b volumio[984]: info: URI /mnt/USB/Seagate/Unknown_artist-Unknown_album/1.Track_1.flac Aug 25 18:54:12 volumiopi3b volumio[984]: info: Exploding uri music-library/USB/Seagate/Unknown_artist-Unknown_album/2.Track_2.flac in service mpd Aug 25 18:54:12 volumiopi3b volumio[984]: info: ALBUMART /albumart?cacheid=836&web=Unknown%20artist/Unknown%20album/extralarge&path=%2Fmnt%2FUSB%2FSeagate%2FUnknown_artist-Unknown_album%2F2.Track_2.flac&metadata=false Aug 25 18:54:12 volumiopi3b volumio[984]: info: URI /mnt/USB/Seagate/Unknown_artist-Unknown_album/2.Track_2.flac Aug 25 18:54:12 volumiopi3b volumio[984]: info: Exploding uri music-library/USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac in service mpd Aug 25 18:54:13 volumiopi3b volumio[984]: info: ALBUMART /albumart?cacheid=836&web=Unknown%20artist/Unknown%20album/extralarge&path=%2Fmnt%2FUSB%2FSeagate%2FUnknown_artist-Unknown_album%2F3.Track_3.flac&metadata=false Aug 25 18:54:13 volumiopi3b volumio[984]: info: URI /mnt/USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac Aug 25 18:54:15 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 25 18:54:15 volumiopi3b volumio[984]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 25 18:54:15 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 25 18:54:15 volumiopi3b volumio[984]: info: Received Get System Version Aug 25 18:54:15 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 25 18:54:15 volumiopi3b volumio[984]: info: Received Get System Info Aug 25 18:54:15 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 25 18:54:15 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 25 18:54:15 volumiopi3b volumio[984]: info: Discovery: Getting this device information Aug 25 18:54:15 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioGetState Aug 25 18:54:15 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:15 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 25 18:54:24 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 25 18:54:24 volumiopi3b volumio[984]: info: CURURI: albums:// Aug 25 18:54:24 volumiopi3b volumio[984]: info: listAlbums - loading Albums from cache Aug 25 18:54:24 volumiopi3b volumio[984]: info: Preload queue cleared Aug 25 18:54:33 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 25 18:54:33 volumiopi3b volumio[984]: info: CURURI: music-library Aug 25 18:54:33 volumiopi3b volumio[984]: info: Preload queue cleared Aug 25 18:54:36 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 25 18:54:36 volumiopi3b volumio[984]: info: CURURI: music-library/USB Aug 25 18:54:36 volumiopi3b volumio[984]: info: Preload queue cleared Aug 25 18:54:40 volumiopi3b volumio[984]: info: Preload queue cleared Aug 25 18:54:40 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 25 18:54:40 volumiopi3b volumio[984]: info: CoreStateMachine::ClearQueue Aug 25 18:54:40 volumiopi3b volumio[984]: info: CoreStateMachine::stop Aug 25 18:54:40 volumiopi3b volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 18:54:40 volumiopi3b volumio[984]: info: CorePlayQueue::clearPlayQueue Aug 25 18:54:40 volumiopi3b volumio[984]: info: CorePlayQueue::saveQueue Aug 25 18:54:40 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushQueue Aug 25 18:54:40 volumiopi3b volumio[984]: info: CoreStateMachine::addQueueItems Aug 25 18:54:40 volumiopi3b volumio[984]: info: CorePlayQueue::addQueueItems Aug 25 18:54:40 volumiopi3b volumio[984]: info: Preload queue cleared Aug 25 18:54:40 volumiopi3b volumio[984]: info: Adding Item to queue: music-library/USB/Seagate Aug 25 18:54:40 volumiopi3b volumio[984]: info: Exploding uri music-library/USB/Seagate in service mpd Aug 25 18:54:40 volumiopi3b volumio[984]: info: ALBUMART /albumart?cacheid=836&web=Unknown%20artist/Unknown%20album/extralarge&path=%2Fmnt%2FUSB%2FSeagate%2FUnknown_artist-Unknown_album%2F1.Track_1.flac&metadata=false Aug 25 18:54:40 volumiopi3b volumio[984]: info: URI /mnt/USB/Seagate/Unknown_artist-Unknown_album/1.Track_1.flac Aug 25 18:54:40 volumiopi3b volumio[984]: info: ALBUMART /albumart?cacheid=836&web=Unknown%20artist/Unknown%20album/extralarge&path=%2Fmnt%2FUSB%2FSeagate%2FUnknown_artist-Unknown_album%2F2.Track_2.flac&metadata=false Aug 25 18:54:40 volumiopi3b volumio[984]: info: URI /mnt/USB/Seagate/Unknown_artist-Unknown_album/2.Track_2.flac Aug 25 18:54:40 volumiopi3b volumio[984]: info: ALBUMART /albumart?cacheid=836&web=Unknown%20artist/Unknown%20album/extralarge&path=%2Fmnt%2FUSB%2FSeagate%2FUnknown_artist-Unknown_album%2F3.Track_3.flac&metadata=false Aug 25 18:54:40 volumiopi3b volumio[984]: info: URI /mnt/USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushQueue Aug 25 18:54:41 volumiopi3b volumio[984]: info: CorePlayQueue::saveQueue Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreStateMachine::updateTrackBlock Aug 25 18:54:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrackBlock Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPlay Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreStateMachine::play index 0 Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreStateMachine::stop Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreStateMachine::play index undefined Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 18:54:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreStateMachine::startPlaybackTimer Aug 25 18:54:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: ControllerMpd::clearAddPlayTracks USB/Seagate/Unknown_artist-Unknown_album/1.Track_1.flac Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 18:54:41 volumiopi3b volumio[984]: info: sendMpdCommand stop took 6 milliseconds Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand clear Aug 25 18:54:41 volumiopi3b volumio[984]: info: Aug 25 18:54:41 volumiopi3b volumio[984]: ---------------------------- MPD announces system playlist update Aug 25 18:54:41 volumiopi3b volumio[984]: info: Ignoring MPD Status Update Aug 25 18:54:41 volumiopi3b volumio[984]: info: sendMpdCommand clear took 4 milliseconds Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand add "USB/Seagate/Unknown_artist-Unknown_album/1.Track_1.flac" Aug 25 18:54:41 volumiopi3b volumio[984]: error: updateQueue error: null Aug 25 18:54:41 volumiopi3b volumio[984]: info: Aug 25 18:54:41 volumiopi3b volumio[984]: ---------------------------- MPD announces system playlist update Aug 25 18:54:41 volumiopi3b volumio[984]: info: Ignoring MPD Status Update Aug 25 18:54:41 volumiopi3b volumio[984]: info: ------------------------------ 8ms Aug 25 18:54:41 volumiopi3b volumio[984]: info: sendMpdCommand add "USB/Seagate/Unknown_artist-Unknown_album/1.Track_1.flac" took 5 milliseconds Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand play Aug 25 18:54:41 volumiopi3b volumio[984]: info: ------------------------------ 6ms Aug 25 18:54:41 volumiopi3b volumio[984]: info: sendMpdCommand play took 5 milliseconds Aug 25 18:54:41 volumiopi3b volumio[984]: info: Aug 25 18:54:41 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: player Aug 25 18:54:41 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:54:41 volumiopi3b volumio[984]: info: sendMpdCommand status took 200 milliseconds Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:54:41 volumiopi3b volumio[984]: info: Aug 25 18:54:41 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: player Aug 25 18:54:41 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:54:41 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:54:41 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:54:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":514,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 1","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/1.Track_1.flac","trackType":"flac"} Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: CURRENT POSITION 0 Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService play Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus stop Aug 25 18:54:41 volumiopi3b volumio[984]: info: ------------------------------ 218ms Aug 25 18:54:41 volumiopi3b volumio[984]: info: sendMpdCommand status took 9 milliseconds Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:54:41 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:54:41 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:54:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":514,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"368 Kbps","isStreaming":false,"title":"Track 1","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/1.Track_1.flac","trackType":"flac"} Aug 25 18:54:41 volumiopi3b volumio[984]: verbose: CURRENT POSITION 0 Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService play Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus play Aug 25 18:54:41 volumiopi3b volumio[984]: info: Received an update from plugin. extracting info from payload Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:41 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:41 volumiopi3b volumio[984]: info: ------------------------------ 63ms Aug 25 18:54:41 volumiopi3b volumio[984]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 25 18:54:44 volumiopi3b volumio[984]: info: Executing endpoint metavolumio Aug 25 18:54:44 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 18:54:44 volumiopi3b volumio[984]: info: Executing endpoint metavolumio Aug 25 18:54:44 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 18:54:44 volumiopi3b volumio[984]: info: Executing endpoint metavolumio Aug 25 18:54:44 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 18:54:44 volumiopi3b volumio[984]: info: Executing endpoint metavolumio Aug 25 18:54:44 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 18:54:44 volumiopi3b volumio[984]: info: Executing endpoint metavolumio Aug 25 18:54:44 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 18:54:44 volumiopi3b volumio[984]: info: Executing endpoint metavolumio Aug 25 18:54:44 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioNext Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::next Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::stop Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::stPlaybackTimer Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::updateTrackBlock Aug 25 18:54:46 volumiopi3b volumio[984]: info: CorePlayQueue::getTrackBlock Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::serviceStop Aug 25 18:54:46 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreCommandRouter::serviceStop Aug 25 18:54:46 volumiopi3b volumio[984]: info: ControllerMpd::stop Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 18:54:46 volumiopi3b volumio[984]: info: Aug 25 18:54:46 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: player Aug 25 18:54:46 volumiopi3b volumio[984]: info: sendMpdCommand stop took 31 milliseconds Aug 25 18:54:46 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::play index undefined Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 18:54:46 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 1 Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::startPlaybackTimer Aug 25 18:54:46 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 1 Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::clearAddPlayTracks USB/Seagate/Unknown_artist-Unknown_album/2.Track_2.flac Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::updateTrackBlock Aug 25 18:54:46 volumiopi3b volumio[984]: info: CorePlayQueue::getTrackBlock Aug 25 18:54:46 volumiopi3b volumio[984]: info: sendMpdCommand status took 10 milliseconds Aug 25 18:54:46 volumiopi3b volumio[984]: info: sendMpdCommand stop took 5 milliseconds Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand clear Aug 25 18:54:46 volumiopi3b volumio[984]: info: Aug 25 18:54:46 volumiopi3b volumio[984]: ---------------------------- MPD announces system playlist update Aug 25 18:54:46 volumiopi3b volumio[984]: info: Ignoring MPD Status Update Aug 25 18:54:46 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 30 milliseconds Aug 25 18:54:46 volumiopi3b volumio[984]: info: sendMpdCommand clear took 30 milliseconds Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand add "USB/Seagate/Unknown_artist-Unknown_album/2.Track_2.flac" Aug 25 18:54:46 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 1 Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 1 Aug 25 18:54:46 volumiopi3b volumio[984]: 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":"Track 1","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/1.Track_1.flac","trackType":"flac"} Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: CURRENT POSITION 1 Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService stop Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus stop Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 1 Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: No code Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 1 Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: ------------------------------ 110ms Aug 25 18:54:46 volumiopi3b volumio[984]: error: updateQueue error: null Aug 25 18:54:46 volumiopi3b volumio[984]: info: Aug 25 18:54:46 volumiopi3b volumio[984]: ---------------------------- MPD announces system playlist update Aug 25 18:54:46 volumiopi3b volumio[984]: info: Ignoring MPD Status Update Aug 25 18:54:46 volumiopi3b volumio[984]: info: ------------------------------ 73ms Aug 25 18:54:46 volumiopi3b volumio[984]: info: sendMpdCommand add "USB/Seagate/Unknown_artist-Unknown_album/2.Track_2.flac" took 67 milliseconds Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand play Aug 25 18:54:46 volumiopi3b volumio[984]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 25 18:54:46 volumiopi3b volumio[984]: info: Aug 25 18:54:46 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: player Aug 25 18:54:46 volumiopi3b volumio[984]: info: ------------------------------ 56ms Aug 25 18:54:46 volumiopi3b volumio[984]: info: sendMpdCommand play took 53 milliseconds Aug 25 18:54:46 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:54:46 volumiopi3b volumio[984]: info: Aug 25 18:54:46 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: player Aug 25 18:54:46 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:54:46 volumiopi3b volumio[984]: info: sendMpdCommand status took 8 milliseconds Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:54:46 volumiopi3b volumio[984]: info: sendMpdCommand status took 8 milliseconds Aug 25 18:54:46 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:54:46 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 1 Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"541 Kbps","isStreaming":false,"title":"Track 2","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/2.Track_2.flac","trackType":"flac"} Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: CURRENT POSITION 1 Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService play Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus stop Aug 25 18:54:46 volumiopi3b volumio[984]: info: ------------------------------ 34ms Aug 25 18:54:46 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:54:46 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 1 Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":262,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"541 Kbps","isStreaming":false,"title":"Track 2","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/2.Track_2.flac","trackType":"flac"} Aug 25 18:54:46 volumiopi3b volumio[984]: verbose: CURRENT POSITION 1 Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService play Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus play Aug 25 18:54:46 volumiopi3b volumio[984]: info: Received an update from plugin. extracting info from payload Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 1 Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 1 Aug 25 18:54:46 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:46 volumiopi3b volumio[984]: info: ------------------------------ 96ms Aug 25 18:54:46 volumiopi3b volumio[984]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioNext Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreStateMachine::next Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreStateMachine::stop Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreStateMachine::stPlaybackTimer Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreStateMachine::updateTrackBlock Aug 25 18:54:47 volumiopi3b volumio[984]: info: CorePlayQueue::getTrackBlock Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:47 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 1 Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreStateMachine::serviceStop Aug 25 18:54:47 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 1 Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreCommandRouter::serviceStop Aug 25 18:54:47 volumiopi3b volumio[984]: info: ControllerMpd::stop Aug 25 18:54:47 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 18:54:47 volumiopi3b volumio[984]: info: Aug 25 18:54:47 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: player Aug 25 18:54:47 volumiopi3b volumio[984]: info: sendMpdCommand stop took 53 milliseconds Aug 25 18:54:47 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:54:47 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreStateMachine::play index undefined Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 18:54:47 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 2 Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreStateMachine::startPlaybackTimer Aug 25 18:54:47 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 2 Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioGetVisibleSources Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 25 18:54:47 volumiopi3b volumio[984]: verbose: ControllerMpd::clearAddPlayTracks USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac Aug 25 18:54:47 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreStateMachine::updateTrackBlock Aug 25 18:54:47 volumiopi3b volumio[984]: info: CorePlayQueue::getTrackBlock Aug 25 18:54:47 volumiopi3b volumio[984]: info: sendMpdCommand status took 16 milliseconds Aug 25 18:54:47 volumiopi3b volumio[984]: info: sendMpdCommand stop took 6 milliseconds Aug 25 18:54:47 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:54:47 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:54:47 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand clear Aug 25 18:54:47 volumiopi3b volumio[984]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 25 18:54:47 volumiopi3b volumio[984]: info: Aug 25 18:54:47 volumiopi3b volumio[984]: ---------------------------- MPD announces system playlist update Aug 25 18:54:47 volumiopi3b volumio[984]: info: Ignoring MPD Status Update Aug 25 18:54:47 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 8 milliseconds Aug 25 18:54:47 volumiopi3b volumio[984]: info: sendMpdCommand clear took 8 milliseconds Aug 25 18:54:47 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:54:47 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand add "USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac" Aug 25 18:54:47 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:47 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 2 Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:54:47 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 2 Aug 25 18:54:48 volumiopi3b volumio[984]: 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":"Track 2","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/2.Track_2.flac","trackType":"flac"} Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: CURRENT POSITION 2 Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService stop Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus stop Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 2 Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: No code Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 2 Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: ------------------------------ 90ms Aug 25 18:54:48 volumiopi3b volumio[984]: error: updateQueue error: null Aug 25 18:54:48 volumiopi3b volumio[984]: info: Aug 25 18:54:48 volumiopi3b volumio[984]: ---------------------------- MPD announces system playlist update Aug 25 18:54:48 volumiopi3b volumio[984]: info: Ignoring MPD Status Update Aug 25 18:54:48 volumiopi3b volumio[984]: info: ------------------------------ 69ms Aug 25 18:54:48 volumiopi3b volumio[984]: info: sendMpdCommand add "USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac" took 64 milliseconds Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand play Aug 25 18:54:48 volumiopi3b volumio[984]: info: Aug 25 18:54:48 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: player Aug 25 18:54:48 volumiopi3b volumio[984]: info: ------------------------------ 41ms Aug 25 18:54:48 volumiopi3b volumio[984]: info: sendMpdCommand play took 38 milliseconds Aug 25 18:54:48 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:54:48 volumiopi3b volumio[984]: info: Aug 25 18:54:48 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: player Aug 25 18:54:48 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:54:48 volumiopi3b volumio[984]: info: sendMpdCommand status took 6 milliseconds Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:54:48 volumiopi3b volumio[984]: info: sendMpdCommand status took 6 milliseconds Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:54:48 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 6 milliseconds Aug 25 18:54:48 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:54:48 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 2 Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":341,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"514 Kbps","isStreaming":false,"title":"Track 3","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac","trackType":"flac"} Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: CURRENT POSITION 2 Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService play Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus stop Aug 25 18:54:48 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 2 Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":341,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"514 Kbps","isStreaming":false,"title":"Track 3","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac","trackType":"flac"} Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: CURRENT POSITION 2 Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService play Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus play Aug 25 18:54:48 volumiopi3b volumio[984]: info: Received an update from plugin. extracting info from payload Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 2 Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 2 Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: ------------------------------ 80ms Aug 25 18:54:48 volumiopi3b volumio[984]: info: ------------------------------ 102ms Aug 25 18:54:48 volumiopi3b volumio[984]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioNext Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::next Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::stop Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::stPlaybackTimer Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::updateTrackBlock Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrackBlock Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 2 Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::serviceStop Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 2 Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::serviceStop Aug 25 18:54:48 volumiopi3b volumio[984]: info: ControllerMpd::stop Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand stop Aug 25 18:54:48 volumiopi3b volumio[984]: info: Aug 25 18:54:48 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: player Aug 25 18:54:48 volumiopi3b volumio[984]: info: sendMpdCommand stop took 23 milliseconds Aug 25 18:54:48 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::play index undefined Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 3 Aug 25 18:54:48 volumiopi3b volumio[984]: info: sendMpdCommand status took 28 milliseconds Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:54:48 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 2 milliseconds Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:54:48 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:48 volumiopi3b volumio[984]: 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":"Track 3","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac","trackType":"flac"} Aug 25 18:54:48 volumiopi3b volumio[984]: verbose: CURRENT POSITION 0 Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService stop Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus stop Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: No code Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:48 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:48 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:49 volumiopi3b volumio[984]: info: ------------------------------ 93ms Aug 25 18:54:49 volumiopi3b volumio[984]: info: touch_display: Setting screensaver timeout to 120 seconds. Aug 25 18:54:51 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 25 18:54:51 volumiopi3b volumio[984]: info: CURURI: music-library/USB/Seagate Aug 25 18:54:51 volumiopi3b volumio[984]: error: Failed request for metavolumio API Aug 25 18:54:51 volumiopi3b volumio[984]: info: Preload queue cleared Aug 25 18:54:51 volumiopi3b volumio[984]: error: Failed request for metavolumio API Aug 25 18:54:51 volumiopi3b volumio[984]: error: Failed request for metavolumio API Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand update Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:54:58 volumiopi3b volumio[984]: info: Aug 25 18:54:58 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: update Aug 25 18:54:58 volumiopi3b volumio[984]: info: sendMpdCommand update took 13 milliseconds Aug 25 18:54:58 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:54:58 volumiopi3b volumio[984]: info: sendMpdCommand status took 11 milliseconds Aug 25 18:54:58 volumiopi3b volumio[984]: info: sendMpdCommand status took 4 milliseconds Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:54:58 volumiopi3b volumio[984]: info: Command Router : Notfying DB Updatetrue Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:54:58 volumiopi3b volumio[984]: info: Aug 25 18:54:58 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: update Aug 25 18:54:58 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:54:58 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 25 milliseconds Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:54:58 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac","trackType":"flac"} Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: CURRENT POSITION 0 Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService stop Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus stop Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: No code Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: ------------------------------ 250ms Aug 25 18:54:58 volumiopi3b volumio[984]: info: sendMpdCommand status took 141 milliseconds Aug 25 18:54:58 volumiopi3b volumio[984]: info: sendMpdCommand status took 140 milliseconds Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:54:58 volumiopi3b volumio[984]: info: Command Router : Notfying DB Updatefalse Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreCommandRouter::Close All Modals sent Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:54:58 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 17 milliseconds Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:54:58 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:58 volumiopi3b volumio[984]: 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":"Track 3","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac","trackType":"flac"} Aug 25 18:54:58 volumiopi3b volumio[984]: verbose: CURRENT POSITION 0 Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService stop Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus stop Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: No code Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:54:58 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:54:58 volumiopi3b volumio[984]: info: ------------------------------ 337ms Aug 25 18:55:09 volumiopi3b ntpd[814]: Soliciting pool server 162.159.200.123 Aug 25 18:55:13 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 25 18:55:13 volumiopi3b volumio[984]: info: CURURI: music-library/USB/Seagate Aug 25 18:55:13 volumiopi3b volumio[984]: info: Preload queue cleared Aug 25 18:55:20 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioGetState Aug 25 18:55:20 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:23 volumiopi3b volumio[984]: info: Executing endpoint metavolumio Aug 25 18:55:23 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 18:55:23 volumiopi3b volumio[984]: info: Executing endpoint metavolumio Aug 25 18:55:23 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 18:55:23 volumiopi3b volumio[984]: info: Executing endpoint metavolumio Aug 25 18:55:23 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:26 volumiopi3b volumio[984]: info: Retrieving Cloud Streaming UI Aug 25 18:55:26 volumiopi3b volumio[984]: info: Getting Tidal Cloud Configuration Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:26 volumiopi3b volumio[984]: info: Getting Qobuz Cloud Configuration Aug 25 18:55:26 volumiopi3b volumio[984]: info: Asking plugin for UI Config Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:26 volumiopi3b volumio[984]: info: Getting Spotify Cloud Configuration Aug 25 18:55:26 volumiopi3b volumio[984]: info: Asking plugin for UI Config Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:26 volumiopi3b volumio[984]: info: Saving Spotify Acccount Aug 25 18:55:26 volumiopi3b volumio[984]: info: Got it Aug 25 18:55:26 volumiopi3b volumio[984]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Aug 25 18:55:26 volumiopi3b volumio[984]: info: Got it Aug 25 18:55:26 volumiopi3b volumio[984]: info: Got Tidal Cloud Configuration Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioGetBrowseSources Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioGetBrowseSources Aug 25 18:55:26 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioGetBrowseSources Aug 25 18:55:29 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 18:55:30 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Aug 25 18:55:30 volumiopi3b volumio[984]: error: Failed request for metavolumio API Aug 25 18:55:33 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , updateDb Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand update Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:55:36 volumiopi3b volumio[984]: info: Aug 25 18:55:36 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: update Aug 25 18:55:36 volumiopi3b volumio[984]: info: sendMpdCommand update took 9 milliseconds Aug 25 18:55:36 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:55:36 volumiopi3b volumio[984]: info: sendMpdCommand status took 7 milliseconds Aug 25 18:55:36 volumiopi3b volumio[984]: info: sendMpdCommand status took 4 milliseconds Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:55:36 volumiopi3b volumio[984]: info: Command Router : Notfying DB Updatetrue Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreCommandRouter::Close All Modals sent Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:55:36 volumiopi3b volumio[984]: info: Aug 25 18:55:36 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: update Aug 25 18:55:36 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:55:36 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 15 milliseconds Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:55:36 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac","trackType":"flac"} Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: CURRENT POSITION 0 Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService stop Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus stop Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: No code Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: ------------------------------ 223ms Aug 25 18:55:36 volumiopi3b volumio[984]: info: sendMpdCommand status took 98 milliseconds Aug 25 18:55:36 volumiopi3b volumio[984]: info: sendMpdCommand status took 96 milliseconds Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:55:36 volumiopi3b volumio[984]: info: Command Router : Notfying DB Updatefalse Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreCommandRouter::Close All Modals sent Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:55:36 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 12 milliseconds Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:55:36 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:36 volumiopi3b volumio[984]: 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":"Track 3","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac","trackType":"flac"} Aug 25 18:55:36 volumiopi3b volumio[984]: verbose: CURRENT POSITION 0 Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService stop Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus stop Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: No code Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:36 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:36 volumiopi3b volumio[984]: info: ------------------------------ 235ms Aug 25 18:55:37 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand rescan Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:55:41 volumiopi3b volumio[984]: info: Aug 25 18:55:41 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: update Aug 25 18:55:41 volumiopi3b volumio[984]: info: sendMpdCommand rescan took 9 milliseconds Aug 25 18:55:41 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:55:41 volumiopi3b volumio[984]: info: sendMpdCommand status took 6 milliseconds Aug 25 18:55:41 volumiopi3b volumio[984]: info: sendMpdCommand status took 3 milliseconds Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:55:41 volumiopi3b volumio[984]: info: Command Router : Notfying DB Updatetrue Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::Close All Modals sent Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:55:41 volumiopi3b volumio[984]: info: MPD Database updated - AlbumList cache refreshed Aug 25 18:55:41 volumiopi3b volumio[984]: info: Aug 25 18:55:41 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: database Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:55:41 volumiopi3b volumio[984]: info: Aug 25 18:55:41 volumiopi3b volumio[984]: ---------------------------- MPD announces state update: update Aug 25 18:55:41 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:55:41 volumiopi3b volumio[984]: info: ControllerMpd::getState Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand status Aug 25 18:55:41 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 30 milliseconds Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:55:41 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Track 3","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac","trackType":"flac"} Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: CURRENT POSITION 0 Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService stop Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus stop Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: No code Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: ------------------------------ 199ms Aug 25 18:55:41 volumiopi3b volumio[984]: info: sendMpdCommand status took 107 milliseconds Aug 25 18:55:41 volumiopi3b volumio[984]: info: sendMpdCommand status took 105 milliseconds Aug 25 18:55:41 volumiopi3b volumio[984]: info: sendMpdCommand status took 104 milliseconds Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:55:41 volumiopi3b volumio[984]: info: Command Router : Notfying DB Updatefalse Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::Close All Modals sent Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::parseState Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 25 18:55:41 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 25 18:55:41 volumiopi3b volumio[984]: info: sendMpdCommand playlistinfo took 14 milliseconds Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: ControllerMpd::parseTrackInfo Aug 25 18:55:41 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:41 volumiopi3b volumio[984]: 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":"Track 3","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac","trackType":"flac"} Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: CURRENT POSITION 0 Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService stop Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus stop Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: No code Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: ControllerMpd::pushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::servicePushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:41 volumiopi3b volumio[984]: 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":"Track 3","artist":"Unknown artist","album":"Unknown album","uri":"USB/Seagate/Unknown_artist-Unknown_album/3.Track_3.flac","trackType":"flac"} Aug 25 18:55:41 volumiopi3b volumio[984]: verbose: CURRENT POSITION 0 Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::syncState stateService stop Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::syncState currentStatus stop Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: No code Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreStateMachine::pushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:41 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioPushState Aug 25 18:55:41 volumiopi3b volumio[984]: info: ------------------------------ 317ms Aug 25 18:55:41 volumiopi3b volumio[984]: info: ------------------------------ 318ms Aug 25 18:55:43 volumiopi3b volumio[984]: info: Tunnel connection is inactive, restarting it Aug 25 18:55:43 volumiopi3b volumio[984]: info: Starting Tunnel 1 Aug 25 18:55:43 volumiopi3b volumio[984]: info: Starting Tunnel Connection Checker Aug 25 18:55:43 volumiopi3b sudo[1999]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Aug 25 18:55:43 volumiopi3b sudo[1999]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:43 volumiopi3b systemd[1]: Stopping MyVolumio SSH Tunnel... Aug 25 18:55:43 volumiopi3b autossh[1617]: received signal to exit (15) Aug 25 18:55:43 volumiopi3b systemd[1]: sshtunnel.service: Main process exited, code=killed, status=15/TERM Aug 25 18:55:43 volumiopi3b volumio[984]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:43 volumiopi3b systemd[1]: sshtunnel.service: Succeeded. Aug 25 18:55:43 volumiopi3b systemd[1]: Stopped MyVolumio SSH Tunnel. Aug 25 18:55:43 volumiopi3b volumio[984]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Aug 25 18:55:43 volumiopi3b systemd[1]: Started MyVolumio SSH Tunnel. Aug 25 18:55:43 volumiopi3b sudo[1999]: pam_unix(sudo:session): session closed for user root Aug 25 18:55:43 volumiopi3b volumio[984]: info: Remote SSH Started Aug 25 18:55:43 volumiopi3b volumio[984]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Aug 25 18:55:43 volumiopi3b autossh[2002]: port set to 0, monitoring disabled Aug 25 18:55:43 volumiopi3b autossh[2002]: starting ssh (count 1) Aug 25 18:55:43 volumiopi3b autossh[2002]: ssh child pid is 2005 Aug 25 18:55:43 volumiopi3b volumio[984]: info: CoreCommandRouter::volumioGetState Aug 25 18:55:43 volumiopi3b volumio[984]: info: CorePlayQueue::getTrack 0 Aug 25 18:55:43 volumiopi3b volumiossh-tunnel[2001]: Warning: Permanently added '[eu7.myvolumio.org]:2222,[167.71.43.69]:2222' (RSA) to the list of known hosts. Aug 25 18:55:45 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 18:55:49 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Aug 25 18:55:50 volumiopi3b volumio[984]: info: Disabling MyMusic plugin upnp Aug 25 18:55:50 volumiopi3b sudo[2008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Aug 25 18:55:50 volumiopi3b sudo[2008]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 25 18:55:50 volumiopi3b systemd[1]: Stopping UPnP Renderer front-end to MPD... Aug 25 18:55:50 volumiopi3b volumio[984]: ------------------------------------ BT MESSAGE: BT STATUS: running Aug 25 18:55:51 volumiopi3b volumio[984]: error: Upnp client error: Error: This socket has been ended by the other party Aug 25 18:55:53 volumiopi3b volumio[984]: info: Enabling MyMusic plugin upnp Aug 25 18:55:53 volumiopi3b volumio[984]: info: Enabling plugin upnp Aug 25 18:55:53 volumiopi3b volumio[984]: info: Loading plugin "upnp"... Aug 25 18:55:53 volumiopi3b volumio[984]: info: [1724601353041] Starting Upmpd Daemon Aug 25 18:55:53 volumiopi3b volumio[984]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 25 18:55:53 volumiopi3b volumio[984]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 18:55:53 volumiopi3b volumio[984]: Error: listen EADDRINUSE: address already in use :::6599 Aug 25 18:55:53 volumiopi3b volumio[984]: at Server.setupListenHandle [as _listen2] (net.js:1318:16) Aug 25 18:55:53 volumiopi3b volumio[984]: at listenInCluster (net.js:1366:12) Aug 25 18:55:53 volumiopi3b volumio[984]: at Server.listen (net.js:1452:7) Aug 25 18:55:53 volumiopi3b volumio[984]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Aug 25 18:55:53 volumiopi3b volumio[984]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Aug 25 18:55:53 volumiopi3b volumio[984]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Aug 25 18:55:53 volumiopi3b volumio[984]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Aug 25 18:55:53 volumiopi3b volumio[984]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Aug 25 18:55:53 volumiopi3b volumio[984]: code: 'EADDRINUSE', Aug 25 18:55:53 volumiopi3b volumio[984]: errno: -98, Aug 25 18:55:53 volumiopi3b volumio[984]: syscall: 'listen', Aug 25 18:55:53 volumiopi3b volumio[984]: address: '::', Aug 25 18:55:53 volumiopi3b volumio[984]: port: 6599 Aug 25 18:55:53 volumiopi3b volumio[984]: } Aug 25 18:55:53 volumiopi3b volumio[984]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 25 18:55:54 volumiopi3b sudo[2061]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-25 18:54 Aug 25 18:55:54 volumiopi3b sudo[2061]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"