-- Logs begin at Thu 2025-04-24 00:19:28 EDT, end at Thu 2025-04-24 17:44:30 EDT. -- Apr 24 17:43:00 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:43:00 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:43:00 volumio volumio[910]: info: Discovery: Getting this device information Apr 24 17:43:00 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Apr 24 17:43:00 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:43:00 volumio volumio[910]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Apr 24 17:43:00 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Apr 24 17:43:00 volumio volumio[910]: error: FusionDsp - Nok! Convolution files missing Apr 24 17:43:00 volumio volumio[910]: error: FusionDsp - __________________STOP NOW__ Apr 24 17:43:00 volumio volumio[910]: error: FusionDsp - File size not found in array! Apr 24 17:43:00 volumio volumio[910]: info: FusionDsp - Nulleq applied Apr 24 17:43:00 volumio volumio[910]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 24 17:43:00 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 17:43:00 volumio volumio[910]: info: CoreCommandRouter::Close All Modals sent Apr 24 17:43:00 volumio volumio[910]: info: FusionDsp - Choose a preset Apr 24 17:43:00 volumio volumio[910]: info: FusionDsp - kef.json,test.json Apr 24 17:43:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:43:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:43:31 volumio volumio[910]: info: Discovery: Getting this device information Apr 24 17:43:31 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Apr 24 17:43:31 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:43:32 volumio volumio[910]: info: CALLMETHOD: audio_interface fusiondsp moresettings Apr 24 17:43:32 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , moresettings Apr 24 17:43:32 volumio volumio[910]: error: FusionDsp - File size not found in array! Apr 24 17:43:32 volumio volumio[910]: info: FusionDsp - Nulleq applied Apr 24 17:43:32 volumio volumio[910]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 24 17:43:32 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 17:43:32 volumio volumio[910]: info: CoreCommandRouter::Close All Modals sent Apr 24 17:43:32 volumio volumio[910]: info: FusionDsp - Choose a preset Apr 24 17:43:32 volumio volumio[910]: info: FusionDsp - kef.json,test.json Apr 24 17:43:59 volumio nmbd[773]: [2025/04/24 17:43:59.407491, 0] ../source3/nmbd/nmbd_namequery.c:109(query_name_response) Apr 24 17:43:59 volumio nmbd[773]: query_name_response: Multiple (2) responses received for a query on subnet 192.168.1.180 for name WORKGROUP<1d>. Apr 24 17:43:59 volumio nmbd[773]: This response was from IP 192.168.1.62, reporting an IP address of 192.168.1.62. Apr 24 17:43:59 volumio volumio[910]: info: CALLMETHOD: audio_interface fusiondsp usethispreset [object Object] Apr 24 17:43:59 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , usethispreset Apr 24 17:43:59 volumio volumio[910]: info: FusionDsp - Value for usedpreset: Apr 24 17:43:59 volumio volumio[910]: error: FusionDsp - Value reading JSON file: {"leftfilter":"demo_swap_left_1.pcm","attenuationl":"15.35","attenuationr":"15.35","rightfilter":"demo_swap_right_1.pcm","leftfilterlabel":"demo_swap_left_1.pcm","filter_format":"FLOAT32LE","mergedeq":"Eq1|Conv|L/data/INTERNAL/FusionDsp/filters/demo_swap_left_1.pcm|15.35|Eq2|Conv|R/data/INTERNAL/FusionDsp/filters/demo_swap_right_1.pcm|15.35|","state4preset":["None",false,false,50,0,0,0,"None",false,false,false,0,0,false]} Apr 24 17:43:59 volumio volumio[910]: info: FusionDsp - value state4preset None,false,false,50,0,0,0,None,false,false,false,0,0,false Apr 24 17:43:59 volumio volumio[910]: info: FusionDsp - File size found in array! Apr 24 17:43:59 volumio volumio[910]: error: FusionDsp - Error in eqv! Cannot split values! Apr 24 17:43:59 volumio volumio[910]: error: The "data" argument must be of type string or an instance of Buffer, TypedArray, or DataView. Received undefined {"code":"ERR_INVALID_ARG_TYPE"} Apr 24 17:44:00 volumio volumio[910]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 24 17:44:00 volumio volumio[910]: info: CoreCommandRouter::Close All Modals sent Apr 24 17:44:00 volumio volumio[910]: info: FusionDsp - test Apr 24 17:44:00 volumio volumio[910]: info: FusionDsp - kef.json,test.json Apr 24 17:44:00 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 24 17:44:00 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 24 17:44:00 volumio volumio[910]: info: Discovery: Getting this device information Apr 24 17:44:00 volumio volumio[910]: info: CoreCommandRouter::volumioGetState Apr 24 17:44:00 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 24 17:44:10 volumio volumio[910]: info: CALLMETHOD: audio_interface fusiondsp playToolsFile [object Object] Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , playToolsFile Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::ClearQueue Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::stop Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrackBlock Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::serviceStop Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::serviceStop Apr 24 17:44:10 volumio volumio[910]: info: [1745531050413] ControllerUPNPBrowser::stop Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::clearPlayQueue Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::saveQueue Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::addQueueItems Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::addQueueItems Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Adding Item to queue: INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav Apr 24 17:44:10 volumio volumio[910]: info: Exploding uri INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav in service mpd Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioClearQueue Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::ClearQueue Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::stop Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::clearPlayQueue Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::saveQueue Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:10 volumio volumio[910]: info: ALBUMART /albumart?cacheid=985&web=FusionDsp/Test%20Signal/extralarge&path=%2Fmnt%2FINTERNAL%2FFusionDsp%2Ftools%2FHouseCurveTestSignal.wav&metadata=false Apr 24 17:44:10 volumio volumio[910]: info: URI /mnt/INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand stop took 37 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::saveQueue Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrackBlock Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPlay Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::play index 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::stop Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::play index undefined Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioGetVisibleSources Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand stop took 3 milliseconds Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand clear Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums:// Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums:// Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums:// Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums:// Apr 24 17:44:10 volumio volumio[910]: info: listAlbums - loading Albums from cache Apr 24 17:44:10 volumio volumio[910]: info: listAlbums - loading Albums from cache Apr 24 17:44:10 volumio volumio[910]: info: listAlbums - loading Albums from cache Apr 24 17:44:10 volumio volumio[910]: info: listAlbums - loading Albums from cache Apr 24 17:44:10 volumio volumio[910]: info: Apr 24 17:44:10 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:10 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand clear took 72 milliseconds Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav" Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPlay Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::play index 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums:// Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPlay Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::play index 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums:// Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::stop Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:10 volumio volumio[910]: info: listAlbums - loading Albums from cache Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::stop Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:10 volumio volumio[910]: info: listAlbums - loading Albums from cache Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::play index undefined Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::play index undefined Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioGetVisibleSources Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioGetVisibleSources Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 17:44:10 volumio volumio[910]: info: Apr 24 17:44:10 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:10 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: error: updateQueue error: null Apr 24 17:44:10 volumio volumio[910]: info: Apr 24 17:44:10 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:10 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:10 volumio volumio[910]: info: ------------------------------ 44ms Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand add "INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav" took 42 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand stop took 38 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand stop took 37 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: ------------------------------ 23ms Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand play Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand clear Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand clear Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums://Jolly%20Brothers/The%20World's%20Greatest%20Polka%20Package Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums://The%20Platters/Christmas%20With%20The%20Stars Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums://Debbie%20Gibson/More%20Ultimate%20Power%20Of%20Love Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums://Sam%20%26%20Dave/Best%20Of%20The%2060's%20%5B1967%5D Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums://Champaign/80's%20Pop%20Hits%20%5BDisc%203%5D Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums://Andy%20Timmons/That%20Was%20Then%2C%20This%20Is%20Now Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums://Patrice%20Rushen/More%20Ultimate%20Power%20Of%20Love Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums://Rosemary%20Clooney/Christmas%20With%20The%20Stars Apr 24 17:44:10 volumio volumio[910]: info: Apr 24 17:44:10 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:10 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:10 volumio volumio[910]: info: Apr 24 17:44:10 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:10 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:10 volumio volumio[910]: info: Apr 24 17:44:10 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:10 volumio volumio[910]: info: ------------------------------ 13ms Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand play took 12 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand clear took 11 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand clear took 12 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav" Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav" Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums://Luciana%20Souza/Duos%20II Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums://The%20Drifters/Christmas%20With%20The%20Stars Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums://Foreigner/More%20Ultimate%20Power%20Of%20Love Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:10 volumio volumio[910]: info: CURURI: albums://Randy%20Meisner/80's%20Pop%20Hits%20%5BDisc%203%5D Apr 24 17:44:10 volumio volumio[910]: error: updateQueue error: null Apr 24 17:44:10 volumio volumio[910]: error: updateQueue error: null Apr 24 17:44:10 volumio volumio[910]: info: Apr 24 17:44:10 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:10 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:10 volumio volumio[910]: info: ------------------------------ 15ms Apr 24 17:44:10 volumio volumio[910]: info: ------------------------------ 11ms Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand status took 9 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand add "INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav" took 8 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand add "INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav" took 8 milliseconds Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand play Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand play Apr 24 17:44:10 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: 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} Apr 24 17:44:10 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:10 volumio volumio[910]: info: No code Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:10 volumio volumio[910]: info: ------------------------------ 30ms Apr 24 17:44:10 volumio volumio[910]: info: Apr 24 17:44:10 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:10 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:10 volumio volumio[910]: info: Apr 24 17:44:10 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:10 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Apr 24 17:44:10 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:10 volumio volumio[910]: info: ------------------------------ 37ms Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand play took 35 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand play took 35 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:10 volumio volumio[910]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 24 17:44:10 volumio volumio[910]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 24 17:44:10 volumio volumio[910]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 24 17:44:10 volumio volumio[910]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 24 17:44:10 volumio volumio[910]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 24 17:44:10 volumio volumio[910]: info: FusionDsp - File size found in array! Apr 24 17:44:10 volumio volumio[910]: error: FusionDsp - Error in eqv! Cannot split values! Apr 24 17:44:10 volumio volumio[910]: error: The "data" argument must be of type string or an instance of Buffer, TypedArray, or DataView. Received undefined {"code":"ERR_INVALID_ARG_TYPE"} Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F12/WWCL.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F08/CXTT.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F12/LWTM.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F08/RHFY.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F11/AWOD.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F09/FIUB.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Compilations/That Was Then, This Is Now/04 Beautiful, Strange 1.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Compilations/That Was Then, This Is Now/04 Beautiful, Strange 2.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Compilations/That Was Then, This Is Now/04 Beautiful, Strange.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Compilations/That Was Then, This Is Now/07 Cry for You 1.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Compilations/That Was Then, This Is Now/07 Cry for You 2.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Compilations/That Was Then, This Is Now/07 Cry for You.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Compilations/That Was Then, This Is Now/11 That Was Then, This Is Now 1.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Compilations/That Was Then, This Is Now/11 That Was Then, This Is Now 2.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Compilations/That Was Then, This Is Now/11 That Was Then, This Is Now.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Compilations/That Was Then, This Is Now/13 A Night to Remember 1.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Compilations/That Was Then, This Is Now/13 A Night to Remember 2.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Compilations/That Was Then, This Is Now/13 A Night to Remember.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F07/IDSY.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F02/ACOR.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F10/MPXT.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F13/KIVH.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F13/UOYQ.m4a Apr 24 17:44:10 volumio volumio[910]: info: Apr 24 17:44:10 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:10 volumio volumio[910]: info: ------------------------------ 54ms Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand status took 54 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand status took 51 milliseconds Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:44:10 volumio volumio[910]: info: Apr 24 17:44:10 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:10 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:10 volumio volumio[910]: info: Apr 24 17:44:10 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:10 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F05/OXQT.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F08/PSEU.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F04/JZGU.m4a Apr 24 17:44:10 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:10 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F04/YSFV.m4a Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand status took 10 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand playlistinfo took 10 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand playlistinfo took 9 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: ------------------------------ 7ms Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand status took 6 milliseconds Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:44:10 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Apr 24 17:44:10 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Apr 24 17:44:10 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Apr 24 17:44:10 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:44:10 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:10 volumio volumio[910]: info: ------------------------------ 76ms Apr 24 17:44:10 volumio volumio[910]: info: ------------------------------ 78ms Apr 24 17:44:10 volumio volumio[910]: info: Apr 24 17:44:10 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:10 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 1 Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand playlistinfo took 23 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand playlistinfo took 21 milliseconds Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand status took 5 milliseconds Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:44:10 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Apr 24 17:44:10 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:44:10 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:10 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Apr 24 17:44:10 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:44:10 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:10 volumio volumio[910]: info: ------------------------------ 55ms Apr 24 17:44:10 volumio volumio[910]: info: ------------------------------ 50ms Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:10 volumio volumio[910]: info: Exploding uri music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F04/YSFV.m4a in service mpd Apr 24 17:44:10 volumio volumio[910]: info: sendMpdCommand playlistinfo took 61 milliseconds Apr 24 17:44:10 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:44:10 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1349,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Apr 24 17:44:10 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:44:10 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:10 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:10 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:10 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:10 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:10 volumio volumio[910]: info: ------------------------------ 83ms Apr 24 17:44:10 volumio volumio[910]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 24 17:44:10 volumio volumio[910]: info: ALBUMART /albumart?cacheid=985&web=Randy%20Meisner/80's%20Pop%20Hits%20%5BDisc%203%5D/extralarge&path=%2Fmnt%2FUSB%2FKEVIN_S_IPO%2FiPod_Control%2FMusic%2FF04%2FYSFV.m4a&metadata=false Apr 24 17:44:10 volumio volumio[910]: info: URI /mnt/USB/KEVIN_S_IPO/iPod_Control/Music/F04/YSFV.m4a Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:10 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:14 volumio volumio[910]: info: Apr 24 17:44:14 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:14 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:14 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:14 volumio volumio[910]: info: Apr 24 17:44:14 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:14 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:14 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:14 volumio volumio[910]: info: sendMpdCommand status took 2 milliseconds Apr 24 17:44:14 volumio volumio[910]: info: sendMpdCommand status took 1 milliseconds Apr 24 17:44:14 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:14 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:44:14 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:14 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:44:14 volumio volumio[910]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 24 17:44:14 volumio volumio[910]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 24 17:44:14 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:44:14 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:44:14 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:14 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:14 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:14 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":1,"seek":45,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Apr 24 17:44:14 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:14 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Apr 24 17:44:14 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:44:14 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Apr 24 17:44:14 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:14 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:14 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:44:14 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:14 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:14 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:14 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:14 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:14 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:14 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:14 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:14 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:14 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:14 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:14 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":1,"seek":45,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Apr 24 17:44:14 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:14 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Apr 24 17:44:14 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:44:14 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Apr 24 17:44:14 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:14 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:14 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:14 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:14 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:14 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:14 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:14 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:14 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:14 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:14 volumio volumio[910]: info: ------------------------------ 23ms Apr 24 17:44:14 volumio volumio[910]: info: ------------------------------ 23ms Apr 24 17:44:14 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:14 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:14 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:14 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: CALLMETHOD: audio_interface fusiondsp playToolsFile [object Object] Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , playToolsFile Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioReplaceandPlayItems Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::ClearQueue Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::stop Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrackBlock Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::serviceStop Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::serviceStop Apr 24 17:44:17 volumio volumio[910]: info: ControllerMpd::stop Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::clearPlayQueue Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::saveQueue Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::addQueueItems Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::addQueueItems Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Adding Item to queue: INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav Apr 24 17:44:17 volumio volumio[910]: info: Using cached record of: INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioClearQueue Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::ClearQueue Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::stop Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::clearPlayQueue Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::saveQueue Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushQueue Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::saveQueue Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::updateTrackBlock Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrackBlock Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPlay Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::play index 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::stop Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::play index undefined Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioGetVisibleSources Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand stop took 30 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand stop took 14 milliseconds Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand clear Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:17 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:17 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand status took 4 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand clear took 3 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand status took 2 milliseconds Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav" Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:17 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: 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} Apr 24 17:44:17 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: No code Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 16ms Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:17 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums:// Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums:// Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums:// Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums:// Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPlay Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::play index 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums:// Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPlay Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::play index 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums:// Apr 24 17:44:17 volumio volumio[910]: info: listAlbums - loading Albums from cache Apr 24 17:44:17 volumio volumio[910]: info: listAlbums - loading Albums from cache Apr 24 17:44:17 volumio volumio[910]: info: listAlbums - loading Albums from cache Apr 24 17:44:17 volumio volumio[910]: info: listAlbums - loading Albums from cache Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::stop Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:17 volumio volumio[910]: info: listAlbums - loading Albums from cache Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::stop Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:17 volumio volumio[910]: info: listAlbums - loading Albums from cache Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::play index undefined Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::play index undefined Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioGetVisibleSources Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::startPlaybackTimer Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioGetVisibleSources Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand stop Apr 24 17:44:17 volumio volumio[910]: error: updateQueue error: null Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:17 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 83ms Apr 24 17:44:17 volumio volumio[910]: error: ControllerMpd::pushError: Error: [2@0] {playlistinfo} Bad song index Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand add "INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav" took 82 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 68ms Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand stop took 56 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand stop took 54 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 87ms Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand play Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand clear Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand clear Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:17 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:17 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:17 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:17 volumio volumio[910]: error: updateQueue error: null Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 80ms Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand play took 79 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand clear took 78 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand clear took 78 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 79ms Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav" Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav" Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:17 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:17 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums://Guns%20N'%20Roses/Appetite%20for%20Destruction Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums://The%20Fortunes/The%20Best%20Of%20The%2060's%20%5B1965%5D Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums://Nashville%20Cast/This%20Time%20(feat.%20Connie%20Britton%2C%20Charles%20Esten%20%26%20Lennon%20%26%20Maisy)%20-%20Single Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums://Firefall/More%20Ultimate%20Power%20Of%20Love Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums://Andy%20James/C.S.I.L Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums://Dionne%20Warwick/More%20Ultimate%20Power%20Of%20Love Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums://The%20Box%20Tops/Best%20Of%20The%2060's%20%5B1967%5D Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums://Hal%20Leonard/Acoustic%20Guitar%20Riffs%20-%20Third%20Edition Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums://Hal%20Leonard/Acoustic%20Guitar%20Riffs%20-%20Third%20Edition Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums://Mannheim%20Steamroller/Christmas Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums://Doo%20Woop/Collection Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Apr 24 17:44:17 volumio volumio[910]: info: CURURI: albums://The%20Beau%20Brummels/The%20Best%20Of%20The%2060's%20%5B1965%5D Apr 24 17:44:17 volumio volumio[910]: error: updateQueue error: null Apr 24 17:44:17 volumio volumio[910]: error: updateQueue error: null Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:17 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 16ms Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand status took 15 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 16ms Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand add "INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav" took 14 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 13ms Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand status took 12 milliseconds Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand play Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:17 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: 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} Apr 24 17:44:17 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: No code Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: 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} Apr 24 17:44:17 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: No code Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 66ms Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 64ms Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces system playlist update Apr 24 17:44:17 volumio volumio[910]: info: Ignoring MPD Status Update Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 60ms Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand play took 58 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand play took 58 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 8ms Apr 24 17:44:17 volumio volumio[910]: info: Apr 24 17:44:17 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:17 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:17 volumio volumio[910]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 24 17:44:17 volumio volumio[910]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 24 17:44:17 volumio volumio[910]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 24 17:44:17 volumio volumio[910]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Apr 24 17:44:17 volumio volumio[910]: info: FusionDsp - ---- read samplerate from file: 44100 Apr 24 17:44:17 volumio volumio[910]: info: FusionDsp - File size found in array! Apr 24 17:44:17 volumio volumio[910]: error: FusionDsp - Error in eqv! Cannot split values! Apr 24 17:44:17 volumio volumio[910]: error: The "data" argument must be of type string or an instance of Buffer, TypedArray, or DataView. Received undefined {"code":"ERR_INVALID_ARG_TYPE"} Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Guns N' Roses/Appetite for Destruction/01 Welcome to the Jungle 1.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Guns N' Roses/Appetite for Destruction/01 Welcome to the Jungle 2.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Guns N' Roses/Appetite for Destruction/01 Welcome to the Jungle.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Guns N' Roses/Appetite for Destruction/06 Paradise City 1.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Guns N' Roses/Appetite for Destruction/06 Paradise City 2.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Guns N' Roses/Appetite for Destruction/06 Paradise City.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Guns N' Roses/Appetite for Destruction/09 Sweet Child O' Mine 1.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Guns N' Roses/Appetite for Destruction/09 Sweet Child O' Mine 2.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Guns N' Roses/Appetite for Destruction/09 Sweet Child O' Mine.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F05/DTCB.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Nashville Cast/This Time (feat. Connie Britton, Charles/01 This Time (feat. Connie Britton, 1.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Nashville Cast/This Time (feat. Connie Britton, Charles/01 This Time (feat. Connie Britton,.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F10/JCMP.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/01 Die a Devil 1.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/01 Die a Devil 2.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/01 Die a Devil.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/04 Satellites 1.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/04 Satellites 2.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/04 Satellites.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/05 Burning Blade 1.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/05 Burning Blade 2.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/05 Burning Blade.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/07 Dark Waters 1.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/07 Dark Waters 2.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/07 Dark Waters.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/10 Cut Me Open 1.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/10 Cut Me Open 2.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/NAS/public/Andy James/C.S.I.L/10 Cut Me Open.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F05/AAOP.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F00/OVDS.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F05/WNMJ.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F05/WNMJ.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F01/LDKW.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F03/SNGZ.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F04/HUDQ.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F04/EMMP.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F05/GWZI.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F06/LEET.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F06/UWIZ.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F10/VEHR.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F11/HXSJ.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F04/WAQP.m4a Apr 24 17:44:17 volumio volumio[910]: info: Preload queue cleared Apr 24 17:44:17 volumio volumio[910]: info: Preloading song: music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F10/MZDK.m4a Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand status took 109 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand status took 104 milliseconds Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand playlistinfo took 5 milliseconds Apr 24 17:44:17 volumio volumio[910]: info: sendMpdCommand playlistinfo took 4 milliseconds Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:44:17 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:44:17 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Apr 24 17:44:17 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Apr 24 17:44:17 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Apr 24 17:44:17 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:44:17 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1212,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Apr 24 17:44:17 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:44:17 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:17 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 131ms Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 140ms Apr 24 17:44:17 volumio volumio[910]: info: ------------------------------ 134ms Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:17 volumio volumio[910]: info: Exploding uri music-library/USB/KEVIN_S_IPO/iPod_Control/Music/F10/MZDK.m4a in service mpd Apr 24 17:44:17 volumio volumio[910]: info: FusionDsp - {"Reload":{"result":"Ok"}} Apr 24 17:44:17 volumio volumio[910]: info: ALBUMART /albumart?cacheid=985&web=The%20Beau%20Brummels/The%20Best%20Of%20The%2060's%20%5B1965%5D/extralarge&path=%2Fmnt%2FUSB%2FKEVIN_S_IPO%2FiPod_Control%2FMusic%2FF10%2FMZDK.m4a&metadata=false Apr 24 17:44:17 volumio volumio[910]: info: URI /mnt/USB/KEVIN_S_IPO/iPod_Control/Music/F10/MZDK.m4a Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:17 volumio volumio[910]: info: CorePlayQueue::getTrack 1 Apr 24 17:44:21 volumio volumio[910]: info: Apr 24 17:44:21 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:21 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:21 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:21 volumio volumio[910]: info: Apr 24 17:44:21 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:21 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:21 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:21 volumio volumio[910]: info: sendMpdCommand status took 1 milliseconds Apr 24 17:44:21 volumio volumio[910]: info: sendMpdCommand status took 1 milliseconds Apr 24 17:44:21 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:21 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:44:21 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:21 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 24 17:44:21 volumio volumio[910]: info: sendMpdCommand playlistinfo took 0 milliseconds Apr 24 17:44:21 volumio volumio[910]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 24 17:44:21 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:44:21 volumio volumio[910]: verbose: ControllerMpd::parseTrackInfo Apr 24 17:44:21 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:21 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:21 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:21 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":1,"seek":45,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Apr 24 17:44:21 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:21 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Apr 24 17:44:21 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:44:21 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Apr 24 17:44:21 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:21 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:21 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:44:21 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:21 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:21 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:21 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:21 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:21 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:21 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:21 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:21 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:21 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:21 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:21 volumio volumio[910]: verbose: STATE SERVICE {"status":"play","position":1,"seek":45,"duration":6,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"HouseCurve - Greg Wilding","artist":"FusionDsp","album":"Test Signal","uri":"INTERNAL/FusionDsp/tools/HouseCurveTestSignal.wav","trackType":"wav"} Apr 24 17:44:21 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:21 volumio volumio[910]: info: CoreStateMachine::syncState stateService play Apr 24 17:44:21 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:44:21 volumio volumio[910]: info: Received an update from plugin. extracting info from payload Apr 24 17:44:21 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:21 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:21 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:21 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:21 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:21 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:21 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:21 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:21 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:21 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:21 volumio volumio[910]: info: ------------------------------ 23ms Apr 24 17:44:21 volumio volumio[910]: info: ------------------------------ 22ms Apr 24 17:44:21 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:21 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:21 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:21 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:29 volumio volumio[910]: info: Apr 24 17:44:29 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:29 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:29 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:29 volumio volumio[910]: info: Apr 24 17:44:29 volumio volumio[910]: ---------------------------- MPD announces state update: player Apr 24 17:44:29 volumio volumio[910]: info: ControllerMpd::getState Apr 24 17:44:29 volumio volumio[910]: verbose: ControllerMpd::sendMpdCommand status Apr 24 17:44:29 volumio volumio[910]: info: sendMpdCommand status took 2 milliseconds Apr 24 17:44:29 volumio volumio[910]: info: sendMpdCommand status took 1 milliseconds Apr 24 17:44:29 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:29 volumio volumio[910]: verbose: ControllerMpd::parseState Apr 24 17:44:29 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:29 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:29 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:29 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:29 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:44:29 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:29 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:29 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:29 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:29 volumio volumio[910]: 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} Apr 24 17:44:29 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:29 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Apr 24 17:44:29 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus play Apr 24 17:44:29 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:29 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:29 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:29 volumio volumio[910]: info: CoreStateMachine::stPlaybackTimer Apr 24 17:44:29 volumio volumio[910]: info: ControllerMpd::pushState Apr 24 17:44:29 volumio volumio[910]: info: CoreCommandRouter::servicePushState Apr 24 17:44:29 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:29 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:29 volumio volumio[910]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 24 17:44:29 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:29 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:29 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:29 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:29 volumio volumio[910]: 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} Apr 24 17:44:29 volumio volumio[910]: verbose: CURRENT POSITION 0 Apr 24 17:44:29 volumio volumio[910]: info: CoreStateMachine::syncState stateService stop Apr 24 17:44:29 volumio volumio[910]: info: CoreStateMachine::syncState currentStatus stop Apr 24 17:44:29 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:29 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:29 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:29 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:29 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:29 volumio volumio[910]: info: No code Apr 24 17:44:29 volumio volumio[910]: info: CoreStateMachine::pushState Apr 24 17:44:29 volumio volumio[910]: info: CorePlayQueue::getTrack 0 Apr 24 17:44:29 volumio volumio[910]: info: CoreCommandRouter::volumioPushState Apr 24 17:44:29 volumio volumio[910]: info: MRS: Pushing multiroomSync output update for this device Apr 24 17:44:29 volumio volumio[910]: info: MRS: Pushing multiroomSync output Apr 24 17:44:29 volumio volumio[910]: info: ------------------------------ 37ms Apr 24 17:44:29 volumio volumio[910]: info: ------------------------------ 34ms Apr 24 17:44:29 volumio volumio[910]: info: [ASDebug] CurState: play PrevState: play Apr 24 17:44:29 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:29 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:29 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:29 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:29 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:29 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:29 volumio volumio[910]: info: [ASDebug] CurState: stop PrevState: play Apr 24 17:44:29 volumio volumio[910]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Apr 24 17:44:30 volumio volumio[910]: info: [ASDebug] Togle GPIO: OFF Apr 24 17:44:30 volumio volumio[910]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 17:44:30 volumio volumio[910]: Error: ESPIPE: invalid seek, write Apr 24 17:44:30 volumio volumio[910]: at Object.writeSync (fs.js:711:3) Apr 24 17:44:30 volumio volumio[910]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Apr 24 17:44:30 volumio volumio[910]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Apr 24 17:44:30 volumio volumio[910]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Apr 24 17:44:30 volumio volumio[910]: at listOnTimeout (internal/timers.js:554:17) Apr 24 17:44:30 volumio volumio[910]: at processTimers (internal/timers.js:497:7) { Apr 24 17:44:30 volumio volumio[910]: errno: -29, Apr 24 17:44:30 volumio volumio[910]: syscall: 'write', Apr 24 17:44:30 volumio volumio[910]: code: 'ESPIPE' Apr 24 17:44:30 volumio volumio[910]: } Apr 24 17:44:30 volumio volumio[910]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 24 17:44:30 volumio sudo[10584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-24 17:43 Apr 24 17:44:30 volumio sudo[10584]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="e32c2c71ae78852844c70357816bdd871c155ab8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 31 Mar 2025 06:54:47 PM CEST" VOLUMIO_VERSION="3.799" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="436f3f84f6d0b4ffc344f0bc132a32e8"