-- Logs begin at Thu 2019-02-14 10:11:58 GMT, end at Sat 2025-08-30 10:15:31 BST. -- Aug 30 10:14:24 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Aug 30 10:14:24 volumio volumio[1001]: info: CoreStateMachine::play index undefined Aug 30 10:14:24 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:14:24 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:14:24 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Aug 30 10:14:24 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:14:24 volumio volumio[1001]: verbose: ControllerMpd::clearAddPlayTracks USB/LukeSkywalker/Music/DX300/MUSIC/玉置浩二/ワインレッドの心/1-11 - 朝陽君.dsf Aug 30 10:14:24 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 10:14:24 volumio volumio[1001]: info: sendMpdCommand stop took 1 milliseconds Aug 30 10:14:24 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 10:14:24 volumio volumio[1001]: info: Aug 30 10:14:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Aug 30 10:14:24 volumio volumio[1001]: info: Ignoring MPD Status Update Aug 30 10:14:24 volumio volumio[1001]: info: sendMpdCommand clear took 2 milliseconds Aug 30 10:14:24 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "USB/LukeSkywalker/Music/DX300/MUSIC/玉置浩二/ワインレッドの心/1-11 - 朝陽君.dsf" Aug 30 10:14:24 volumio volumio[1001]: info: Aug 30 10:14:24 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Aug 30 10:14:24 volumio volumio[1001]: info: Ignoring MPD Status Update Aug 30 10:14:24 volumio volumio[1001]: error: updateQueue error: null Aug 30 10:14:24 volumio volumio[1001]: error: updateQueue error: null Aug 30 10:14:24 volumio volumio[1001]: info: ------------------------------ 3ms Aug 30 10:14:24 volumio volumio[1001]: info: ------------------------------ 2ms Aug 30 10:14:26 volumio volumio[1001]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 30 10:14:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Aug 30 10:14:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 30 10:14:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Aug 30 10:14:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 10:14:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 10:14:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 10:14:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 10:14:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 10:14:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 10:14:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 30 10:14:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Aug 30 10:14:26 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 30 10:14:38 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 30 10:14:38 volumio volumio[1001]: info: CURURI: albums:// Aug 30 10:14:38 volumio volumio[1001]: info: listAlbums - loading Albums from cache Aug 30 10:14:38 volumio volumio[1001]: info: Preload queue cleared Aug 30 10:14:42 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 30 10:14:42 volumio volumio[1001]: info: CURURI: playlists Aug 30 10:14:42 volumio volumio[1001]: info: Listing playlists Aug 30 10:14:42 volumio volumio[1001]: info: Preload queue cleared Aug 30 10:14:47 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 30 10:14:47 volumio volumio[1001]: info: CURURI: genres:// Aug 30 10:14:47 volumio volumio[1001]: info: Preload queue cleared Aug 30 10:14:48 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Aug 30 10:14:48 volumio volumio[1001]: info: CURURI: genres://Celtic Aug 30 10:14:48 volumio volumio[1001]: info: Preload queue cleared Aug 30 10:14:48 volumio volumio[1001]: info: Preload queue cleared Aug 30 10:14:55 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Aug 30 10:14:55 volumio volumio[1001]: info: [1756545295066] [80s80s] handleBrowseUri curUri: 80s80s Aug 30 10:14:55 volumio volumio[1001]: info: Preload queue cleared Aug 30 10:14:56 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: 80s80s , handleBrowseUri Aug 30 10:14:56 volumio volumio[1001]: info: [1756545296132] [80s80s] handleBrowseUri curUri: 80s80s/eighties Aug 30 10:14:56 volumio volumio[1001]: info: [1756545296133] [80s80s] getRadioContent url: eighties Aug 30 10:14:56 volumio volumio[1001]: info: Preload queue cleared Aug 30 10:14:57 volumio volumio[1001]: info: Preload queue cleared Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::volumioReplaceandPlayItems Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::ClearQueue Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::stop Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::clearPlayQueue Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::saveQueue Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushQueue Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::addQueueItems Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::addQueueItems Aug 30 10:14:57 volumio volumio[1001]: info: Preload queue cleared Aug 30 10:14:57 volumio volumio[1001]: info: Adding Item to queue: webeighties/0 Aug 30 10:14:57 volumio volumio[1001]: info: Exploding uri webeighties/0 in service 80s80s Aug 30 10:14:57 volumio volumio[1001]: info: [1756545297341] [80s80s] explodeUri: webeighties/0 Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushQueue Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::saveQueue Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::updateTrackBlock Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::getTrackBlock Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::play index 0 Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::stop Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::play index undefined Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::startPlaybackTimer Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::volumioGetVisibleSources Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Aug 30 10:14:57 volumio volumio[1001]: info: [1756545297353] [80s80s] clearAddPlayTrack url: http://streams.80s80s.de/web/mp3-192/volumio Aug 30 10:14:57 volumio volumio[1001]: info: [1756545297353] [80s80s] getContentOfUrl started with url http://iris-80s80s.loverad.io/flow.json?station=62&count=2 Aug 30 10:14:57 volumio volumio[1001]: info: [1756545297578] [80s80s] received new event containing 2 songs. Aug 30 10:14:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand stop Aug 30 10:14:57 volumio volumio[1001]: info: sendMpdCommand stop took 0 milliseconds Aug 30 10:14:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand clear Aug 30 10:14:57 volumio volumio[1001]: info: Aug 30 10:14:57 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Aug 30 10:14:57 volumio volumio[1001]: info: Ignoring MPD Status Update Aug 30 10:14:57 volumio volumio[1001]: info: sendMpdCommand clear took 1 milliseconds Aug 30 10:14:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand consume 1 Aug 30 10:14:57 volumio volumio[1001]: info: Aug 30 10:14:57 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Aug 30 10:14:57 volumio volumio[1001]: info: Ignoring MPD Status Update Aug 30 10:14:57 volumio volumio[1001]: error: updateQueue error: null Aug 30 10:14:57 volumio volumio[1001]: info: Aug 30 10:14:57 volumio volumio[1001]: ---------------------------- MPD announces state update: options Aug 30 10:14:57 volumio volumio[1001]: error: updateQueue error: null Aug 30 10:14:57 volumio volumio[1001]: info: ------------------------------ 4ms Aug 30 10:14:57 volumio volumio[1001]: info: sendMpdCommand consume 1 took 3 milliseconds Aug 30 10:14:57 volumio volumio[1001]: info: ControllerMpd::getState Aug 30 10:14:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Aug 30 10:14:57 volumio volumio[1001]: info: ------------------------------ 4ms Aug 30 10:14:57 volumio volumio[1001]: info: [1756545297587] [80s80s] adding url: http://streams.80s80s.de/web/mp3-192/volumio Aug 30 10:14:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" Aug 30 10:14:57 volumio volumio[1001]: info: Aug 30 10:14:57 volumio volumio[1001]: ---------------------------- MPD announces state update: options Aug 30 10:14:57 volumio volumio[1001]: info: ControllerMpd::getState Aug 30 10:14:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Aug 30 10:14:57 volumio volumio[1001]: info: Aug 30 10:14:57 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Aug 30 10:14:57 volumio volumio[1001]: info: Ignoring MPD Status Update Aug 30 10:14:57 volumio volumio[1001]: info: sendMpdCommand status took 3 milliseconds Aug 30 10:14:57 volumio volumio[1001]: info: sendMpdCommand add "http://streams.80s80s.de/web/mp3-192/volumio" took 3 milliseconds Aug 30 10:14:57 volumio volumio[1001]: info: sendMpdCommand status took 2 milliseconds Aug 30 10:14:57 volumio volumio[1001]: verbose: ControllerMpd::parseState Aug 30 10:14:57 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand play Aug 30 10:14:57 volumio volumio[1001]: verbose: ControllerMpd::parseState Aug 30 10:14:57 volumio volumio[1001]: info: ControllerMpd::pushState Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 30 10:14:57 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Aug 30 10:14:57 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:14:57 volumio volumio[1001]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 30 10:14:57 volumio volumio[1001]: info: ControllerMpd::pushState Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 30 10:14:57 volumio volumio[1001]: info: MRS: Pushing multiroomSync output update for this device Aug 30 10:14:57 volumio volumio[1001]: info: MRS: Pushing multiroomSync output Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::volumioGetState Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:14:57 volumio volumio[1001]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 30 10:14:57 volumio volumio[1001]: info: ------------------------------ 25ms Aug 30 10:14:57 volumio volumio[1001]: info: ------------------------------ 26ms Aug 30 10:14:57 volumio volumio[1001]: info: Aug 30 10:14:57 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Aug 30 10:14:57 volumio volumio[1001]: info: Ignoring MPD Status Update Aug 30 10:14:57 volumio volumio[1001]: info: ------------------------------ 27ms Aug 30 10:14:57 volumio volumio[1001]: info: sendMpdCommand play took 25 milliseconds Aug 30 10:14:57 volumio volumio[1001]: info: ------------------------------ 2ms Aug 30 10:14:57 volumio volumio[1001]: info: [1756545297618] [80s80s] Pushing the next song state: The Stranglers - Always the Sun Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:14:57 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:14:57 volumio volumio[1001]: verbose: STATE SERVICE {"status":"play","service":"80s80s","type":"track","trackType":"80s80s Radio","radioType":"80s80s","albumart":"https://is4-ssl.mzstatic.com/image/thumb/Music124/v4/35/b2/1b/35b21b8f-bbc8-750a-1c26-bc8144d84305/source/600x600bb.jpg","name":"The Stranglers - Always the Sun","title":"Always the Sun","artist":"80s80s Real 80s Radio","album":"","streaming":true,"disableUiControls":true,"duration":"225","seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Aug 30 10:14:57 volumio volumio[1001]: verbose: CURRENT POSITION 0 Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::syncState stateService play Aug 30 10:14:57 volumio volumio[1001]: info: CoreStateMachine::syncState currentStatus stop Aug 30 10:14:57 volumio volumio[1001]: info: [1756545297621] [80s80s] PlayNextTrack API delay: 30 Aug 30 10:14:57 volumio volumio[1001]: info: [1756545297639] [80s80s] Setting timer to: 225000 milliseconds. Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:14:57 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:14:57 volumio volumio[1001]: info: Prefetching next song Aug 30 10:15:00 volumio volumio[1001]: info: Aug 30 10:15:00 volumio volumio[1001]: ---------------------------- MPD announces state update: player Aug 30 10:15:00 volumio volumio[1001]: info: ControllerMpd::getState Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Aug 30 10:15:00 volumio volumio[1001]: info: Aug 30 10:15:00 volumio volumio[1001]: ---------------------------- MPD announces state update: player Aug 30 10:15:00 volumio volumio[1001]: info: ControllerMpd::getState Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Aug 30 10:15:00 volumio volumio[1001]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 30 10:15:00 volumio volumio[1001]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 30 10:15:00 volumio volumio[1001]: info: camilladsp stopping service pid 9563... Aug 30 10:15:00 volumio volumio[1001]: info: camilladsp service terminated, instance 1 Aug 30 10:15:00 volumio volumio[1001]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 30 10:15:00 volumio volumio[1001]: info: camilladsp service started and running in background, instance 1 Aug 30 10:15:00 volumio volumio[1001]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Aug 30 10:15:00 volumio volumio[1001]: info: FusionDsp - ---- read samplerate from file: 44100 Aug 30 10:15:00 volumio volumio[1001]: info: FusionDsp - If filter freq >samplerate/2 then disable it Aug 30 10:15:00 volumio volumio[1001]: info: Aug 30 10:15:00 volumio volumio[1001]: ---------------------------- MPD announces state update: player Aug 30 10:15:00 volumio volumio[1001]: info: sendMpdCommand status took 68 milliseconds Aug 30 10:15:00 volumio volumio[1001]: info: sendMpdCommand status took 67 milliseconds Aug 30 10:15:00 volumio volumio[1001]: info: ControllerMpd::getState Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::parseState Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::parseState Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 10:15:00 volumio volumio[1001]: info: Aug 30 10:15:00 volumio volumio[1001]: ---------------------------- MPD announces state update: player Aug 30 10:15:00 volumio volumio[1001]: info: ControllerMpd::getState Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Aug 30 10:15:00 volumio volumio[1001]: info: Aug 30 10:15:00 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Aug 30 10:15:00 volumio volumio[1001]: info: Ignoring MPD Status Update Aug 30 10:15:00 volumio volumio[1001]: info: Aug 30 10:15:00 volumio volumio[1001]: ---------------------------- MPD announces state update: player Aug 30 10:15:00 volumio volumio[1001]: info: ControllerMpd::getState Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Aug 30 10:15:00 volumio volumio[1001]: info: sendMpdCommand status took 4 milliseconds Aug 30 10:15:00 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 30 10:15:00 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 4 milliseconds Aug 30 10:15:00 volumio volumio[1001]: info: sendMpdCommand status took 3 milliseconds Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::parseState Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::parseState Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 10:15:00 volumio volumio[1001]: info: ControllerMpd::pushState Aug 30 10:15:00 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Aug 30 10:15:00 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:15:00 volumio volumio[1001]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 30 10:15:00 volumio volumio[1001]: info: ControllerMpd::pushState Aug 30 10:15:00 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Aug 30 10:15:00 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:15:00 volumio volumio[1001]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 30 10:15:00 volumio volumio[1001]: info: ------------------------------ 77ms Aug 30 10:15:00 volumio volumio[1001]: info: ------------------------------ 76ms Aug 30 10:15:00 volumio volumio[1001]: info: Aug 30 10:15:00 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Aug 30 10:15:00 volumio volumio[1001]: info: Ignoring MPD Status Update Aug 30 10:15:00 volumio volumio[1001]: info: Aug 30 10:15:00 volumio volumio[1001]: ---------------------------- MPD announces state update: player Aug 30 10:15:00 volumio volumio[1001]: info: ControllerMpd::getState Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Aug 30 10:15:00 volumio volumio[1001]: error: FusionDsp - WebSocket error: [object Object] Aug 30 10:15:00 volumio volumio[1001]: info: ------------------------------ 32ms Aug 30 10:15:00 volumio volumio[1001]: info: sendMpdCommand status took 31 milliseconds Aug 30 10:15:00 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 30 milliseconds Aug 30 10:15:00 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 29 milliseconds Aug 30 10:15:00 volumio volumio[1001]: info: ------------------------------ 27ms Aug 30 10:15:00 volumio volumio[1001]: info: sendMpdCommand status took 27 milliseconds Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::parseState Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::parseState Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 10:15:00 volumio volumio[1001]: info: ControllerMpd::pushState Aug 30 10:15:00 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Aug 30 10:15:00 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:15:00 volumio volumio[1001]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 30 10:15:00 volumio volumio[1001]: info: ControllerMpd::pushState Aug 30 10:15:00 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Aug 30 10:15:00 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:15:00 volumio volumio[1001]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 30 10:15:00 volumio volumio[1001]: info: ------------------------------ 41ms Aug 30 10:15:00 volumio volumio[1001]: info: ------------------------------ 38ms Aug 30 10:15:00 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 3 milliseconds Aug 30 10:15:00 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Aug 30 10:15:00 volumio volumio[1001]: info: ControllerMpd::pushState Aug 30 10:15:00 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Aug 30 10:15:00 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:15:00 volumio volumio[1001]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 30 10:15:00 volumio volumio[1001]: info: ControllerMpd::pushState Aug 30 10:15:00 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Aug 30 10:15:00 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:15:00 volumio volumio[1001]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 30 10:15:00 volumio volumio[1001]: info: ------------------------------ 38ms Aug 30 10:15:00 volumio volumio[1001]: info: ------------------------------ 33ms Aug 30 10:15:01 volumio volumio[1001]: info: Aug 30 10:15:01 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Aug 30 10:15:01 volumio volumio[1001]: info: Ignoring MPD Status Update Aug 30 10:15:01 volumio volumio[1001]: info: Aug 30 10:15:01 volumio volumio[1001]: ---------------------------- MPD announces state update: player Aug 30 10:15:01 volumio volumio[1001]: info: ControllerMpd::getState Aug 30 10:15:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Aug 30 10:15:01 volumio volumio[1001]: info: Aug 30 10:15:01 volumio volumio[1001]: ---------------------------- MPD announces system playlist update Aug 30 10:15:01 volumio volumio[1001]: info: Ignoring MPD Status Update Aug 30 10:15:01 volumio volumio[1001]: info: Aug 30 10:15:01 volumio volumio[1001]: ---------------------------- MPD announces state update: player Aug 30 10:15:01 volumio volumio[1001]: info: ControllerMpd::getState Aug 30 10:15:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand status Aug 30 10:15:01 volumio volumio[1001]: info: ------------------------------ 4ms Aug 30 10:15:01 volumio volumio[1001]: info: sendMpdCommand status took 3 milliseconds Aug 30 10:15:01 volumio volumio[1001]: info: ------------------------------ 4ms Aug 30 10:15:01 volumio volumio[1001]: verbose: ControllerMpd::parseState Aug 30 10:15:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 10:15:01 volumio volumio[1001]: info: sendMpdCommand status took 3 milliseconds Aug 30 10:15:01 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 10:15:01 volumio volumio[1001]: verbose: ControllerMpd::parseState Aug 30 10:15:01 volumio volumio[1001]: verbose: ControllerMpd::sendMpdCommand playlistinfo Aug 30 10:15:01 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Aug 30 10:15:01 volumio volumio[1001]: info: ControllerMpd::pushState Aug 30 10:15:01 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Aug 30 10:15:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:15:01 volumio volumio[1001]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 30 10:15:01 volumio volumio[1001]: info: ------------------------------ 7ms Aug 30 10:15:01 volumio volumio[1001]: info: sendMpdCommand playlistinfo took 1 milliseconds Aug 30 10:15:01 volumio volumio[1001]: verbose: ControllerMpd::parseTrackInfo Aug 30 10:15:01 volumio volumio[1001]: info: ControllerMpd::pushState Aug 30 10:15:01 volumio volumio[1001]: info: CoreCommandRouter::servicePushState Aug 30 10:15:01 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:15:01 volumio volumio[1001]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current 80s80s Received mpd Aug 30 10:15:01 volumio volumio[1001]: info: ------------------------------ 6ms Aug 30 10:15:03 volumio volumio[1001]: info: CoreCommandRouter::volumioPlay Aug 30 10:15:03 volumio volumio[1001]: info: CoreStateMachine::play index undefined Aug 30 10:15:03 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:15:03 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:15:31 volumio volumio[1001]: info: CoreCommandRouter::volumioNext Aug 30 10:15:31 volumio volumio[1001]: info: CoreStateMachine::next Aug 30 10:15:31 volumio volumio[1001]: info: CoreStateMachine::stop Aug 30 10:15:31 volumio volumio[1001]: info: CoreStateMachine::setConsumeUpdateService undefined Aug 30 10:15:31 volumio volumio[1001]: info: CoreStateMachine::stPlaybackTimer Aug 30 10:15:31 volumio volumio[1001]: info: CoreStateMachine::updateTrackBlock Aug 30 10:15:31 volumio volumio[1001]: info: CorePlayQueue::getTrackBlock Aug 30 10:15:31 volumio volumio[1001]: info: CoreStateMachine::pushState Aug 30 10:15:31 volumio volumio[1001]: info: CorePlayQueue::getTrack 0 Aug 30 10:15:31 volumio volumio[1001]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 30 10:15:31 volumio volumio[1001]: info: CoreCommandRouter::volumioPushState Aug 30 10:15:31 volumio volumio[1001]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 10:15:31 volumio volumio[1001]: Error: Failed to convert napi value String into rust type `f64` on TimelineInfo.duration Aug 30 10:15:31 volumio volumio[1001]: at FerrumStreamingControlTechnology.updateStateOnPlayer (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:72:12) Aug 30 10:15:31 volumio volumio[1001]: at FerrumStreamingControlTechnology.pushState (/data/plugins/user_interface/ferrum_streaming_control_technology/index.js:183:10) Aug 30 10:15:31 volumio volumio[1001]: at /volumio/app/index.js:326:81 Aug 30 10:15:31 volumio volumio[1001]: at fastMap (/volumio/node_modules/fast.js/array/map.js:21:17) Aug 30 10:15:31 volumio volumio[1001]: at Function.fastMap [as map] (/volumio/node_modules/fast.js/map.js:18:12) Aug 30 10:15:31 volumio volumio[1001]: at CoreCommandRouter.volumioPushState (/volumio/app/index.js:324:13) Aug 30 10:15:31 volumio volumio[1001]: at CoreStateMachine.pushState (/volumio/app/statemachine.js:532:22) Aug 30 10:15:31 volumio volumio[1001]: at CoreStateMachine.stop (/volumio/app/statemachine.js:1245:12) Aug 30 10:15:31 volumio volumio[1001]: at CoreStateMachine.next (/volumio/app/statemachine.js:1178:12) Aug 30 10:15:31 volumio volumio[1001]: at CoreCommandRouter.volumioNext (/volumio/app/index.js:103:28) Aug 30 10:15:31 volumio volumio[1001]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:274:26) Aug 30 10:15:31 volumio volumio[1001]: at Socket.emit (events.js:315:20) Aug 30 10:15:31 volumio volumio[1001]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 30 10:15:31 volumio volumio[1001]: at processTicksAndRejections (internal/process/task_queues.js:75:11) { Aug 30 10:15:31 volumio volumio[1001]: code: 'NumberExpected' Aug 30 10:15:31 volumio volumio[1001]: } Aug 30 10:15:31 volumio volumio[1001]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 30 10:15:31 volumio sudo[10623]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-08-30 10:14 Aug 30 10:15:31 volumio sudo[10623]: 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="29866754e5f1d7e4d0f581c10d9f22852f6f21db" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat 26 Jul 2025 10:25:36 AM CEST" VOLUMIO_VERSION="3.832" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="cf6e14681b2ecbbdc9a62186b0f3114c"