Jun 28 12:58:06 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioGetVisibleSources Jun 28 12:58:06 volumiodisplay volumio[30684]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 28 12:58:15 volumiodisplay volumio[30684]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 28 12:58:15 volumiodisplay volumio[30684]: info: CURURI: albums:// Jun 28 12:58:15 volumiodisplay volumio[30684]: info: listAlbums - loading Albums from cache Jun 28 12:58:15 volumiodisplay volumio[30684]: info: Preload queue cleared Jun 28 12:58:19 volumiodisplay volumio[30684]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 28 12:58:19 volumiodisplay volumio[30684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 28 12:58:19 volumiodisplay volumio[30684]: info: Discovery: Getting this device information Jun 28 12:58:19 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioGetState Jun 28 12:58:19 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:19 volumiodisplay volumio[30684]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 28 12:58:24 volumiodisplay volumio[30684]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Jun 28 12:58:24 volumiodisplay volumio[30684]: info: CURURI: albums://Jean-Claude%20Borelly/Super%20Instrumental%20Vol.18 Jun 28 12:58:24 volumiodisplay volumio[30684]: info: Preload queue cleared Jun 28 12:58:24 volumiodisplay volumio[30684]: info: Preloading song: music-library/NAS/NAS/24BIT SACD/Audiofeel tracks/Audiofeel Tracks Deel 2/07 Jean-Claude Borelly - Toute La Pluie Tombe Sur Moi.mp3 Jun 28 12:58:24 volumiodisplay volumio[30684]: info: Exploding uri music-library/NAS/NAS/24BIT SACD/Audiofeel tracks/Audiofeel Tracks Deel 2/07 Jean-Claude Borelly - Toute La Pluie Tombe Sur Moi.mp3 in service mpd Jun 28 12:58:24 volumiodisplay volumio[30684]: info: ALBUMART /albumart?cacheid=316&web=Jean-Claude%20Borelly/Super%20Instrumental%20Vol.18/large&path=%2Fmnt%2FNAS%2FNAS%2F24BIT%20SACD%2FAudiofeel%20tracks%2FAudiofeel%20Tracks%20Deel%202%2F07%20Jean-Claude%20Borelly%20-%20Toute%20La%20Pluie%20Tombe%20Sur%20Moi.mp3&metadata=false Jun 28 12:58:24 volumiodisplay volumio[30684]: info: URI /mnt/NAS/NAS/24BIT SACD/Audiofeel tracks/Audiofeel Tracks Deel 2/07 Jean-Claude Borelly - Toute La Pluie Tombe Sur Moi.mp3 Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Preload queue cleared Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioReplaceandPlayItems Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::ClearQueue Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::stop Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::stPlaybackTimer Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::updateTrackBlock Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrackBlock Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::serviceStop Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::serviceStop Jun 28 12:58:25 volumiodisplay volumio[30684]: info: ControllerMpd::stop Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::clearPlayQueue Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::saveQueue Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushQueue Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::addQueueItems Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::addQueueItems Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Preload queue cleared Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Adding Item to queue: albums://Jean-Claude%20Borelly/Super%20Instrumental%20Vol.18 Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Exploding uri albums://Jean-Claude%20Borelly/Super%20Instrumental%20Vol.18 in service mpd Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushQueue Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::saveQueue Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::updateTrackBlock Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrackBlock Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPlay Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::play index 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::stop Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::play index undefined Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::startPlaybackTimer Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: ControllerMpd::clearAddPlayTracks NAS/NAS/24BIT SACD/Audiofeel tracks/Audiofeel Tracks Deel 2/07 Jean-Claude Borelly - Toute La Pluie Tombe Sur Moi.mp3 Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand stop Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Jun 28 12:58:25 volumiodisplay volumio[30684]: ---------------------------- MPD announces state update: player Jun 28 12:58:25 volumiodisplay volumio[30684]: info: sendMpdCommand stop took 251 milliseconds Jun 28 12:58:25 volumiodisplay volumio[30684]: info: ControllerMpd::getState Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand status Jun 28 12:58:25 volumiodisplay volumio[30684]: info: sendMpdCommand stop took 241 milliseconds Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand clear Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Jun 28 12:58:25 volumiodisplay volumio[30684]: ---------------------------- MPD announces state update: player Jun 28 12:58:25 volumiodisplay volumio[30684]: info: ControllerMpd::getState Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand status Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Jun 28 12:58:25 volumiodisplay volumio[30684]: ---------------------------- MPD announces state update: player Jun 28 12:58:25 volumiodisplay volumio[30684]: info: ControllerMpd::getState Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand status Jun 28 12:58:25 volumiodisplay volumio[30684]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Jun 28 12:58:25 volumiodisplay volumio[30684]: ---------------------------- MPD announces system playlist update Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Ignoring MPD Status Update Jun 28 12:58:25 volumiodisplay volumio[30684]: info: sendMpdCommand status took 3 milliseconds Jun 28 12:58:25 volumiodisplay volumio[30684]: info: sendMpdCommand clear took 2 milliseconds Jun 28 12:58:25 volumiodisplay volumio[30684]: info: sendMpdCommand status took 3 milliseconds Jun 28 12:58:25 volumiodisplay volumio[30684]: info: sendMpdCommand status took 3 milliseconds Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseState Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand add "NAS/NAS/24BIT SACD/Audiofeel tracks/Audiofeel Tracks Deel 2/07 Jean-Claude Borelly - Toute La Pluie Tombe Sur Moi.mp3" Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseState Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: ControllerMpd::pushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::servicePushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: 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} Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: CURRENT POSITION 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState stateService stop Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState currentStatus stop Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: No code Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: ControllerMpd::pushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::servicePushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: 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} Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: CURRENT POSITION 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState stateService stop Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState currentStatus stop Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: No code Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:25 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:25 volumiodisplay volumio[30684]: info: ------------------------------ 18ms Jun 28 12:58:25 volumiodisplay volumio[30684]: info: ------------------------------ 19ms Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Jun 28 12:58:25 volumiodisplay volumio[30684]: ---------------------------- MPD announces system playlist update Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Ignoring MPD Status Update Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Jun 28 12:58:25 volumiodisplay volumio[30684]: ---------------------------- MPD announces system playlist update Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Ignoring MPD Status Update Jun 28 12:58:25 volumiodisplay volumio[30684]: error: updateQueue error: null Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Jun 28 12:58:25 volumiodisplay volumio[30684]: ---------------------------- MPD announces system playlist update Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Ignoring MPD Status Update Jun 28 12:58:25 volumiodisplay volumio[30684]: info: ------------------------------ 20ms Jun 28 12:58:25 volumiodisplay volumio[30684]: info: sendMpdCommand playlistinfo took 19 milliseconds Jun 28 12:58:25 volumiodisplay volumio[30684]: info: sendMpdCommand add "NAS/NAS/24BIT SACD/Audiofeel tracks/Audiofeel Tracks Deel 2/07 Jean-Claude Borelly - Toute La Pluie Tombe Sur Moi.mp3" took 19 milliseconds Jun 28 12:58:25 volumiodisplay volumio[30684]: info: ------------------------------ 3ms Jun 28 12:58:25 volumiodisplay volumio[30684]: info: ------------------------------ 3ms Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseTrackInfo Jun 28 12:58:25 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand play Jun 28 12:58:25 volumiodisplay volumio[30684]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Jun 28 12:58:25 volumiodisplay volumio[30684]: info: ------------------------------ 23ms Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Jun 28 12:58:25 volumiodisplay volumio[30684]: ---------------------------- MPD announces system playlist update Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Ignoring MPD Status Update Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Jun 28 12:58:25 volumiodisplay volumio[30684]: ---------------------------- MPD announces system playlist update Jun 28 12:58:25 volumiodisplay volumio[30684]: info: Ignoring MPD Status Update Jun 28 12:58:26 volumiodisplay volumio[30684]: info: Jun 28 12:58:26 volumiodisplay volumio[30684]: ---------------------------- MPD announces state update: player Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ------------------------------ 280ms Jun 28 12:58:26 volumiodisplay volumio[30684]: info: sendMpdCommand play took 279 milliseconds Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ------------------------------ 278ms Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ------------------------------ 278ms Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ControllerMpd::getState Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand status Jun 28 12:58:26 volumiodisplay volumio[30684]: info: Jun 28 12:58:26 volumiodisplay volumio[30684]: ---------------------------- MPD announces state update: player Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ControllerMpd::getState Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand status Jun 28 12:58:26 volumiodisplay volumio[30684]: info: sendMpdCommand status took 2 milliseconds Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseState Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 12:58:26 volumiodisplay volumio[30684]: info: Jun 28 12:58:26 volumiodisplay volumio[30684]: ---------------------------- MPD announces state update: player Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ControllerMpd::getState Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand status Jun 28 12:58:26 volumiodisplay volumio[30684]: info: Jun 28 12:58:26 volumiodisplay volumio[30684]: ---------------------------- MPD announces state update: player Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ControllerMpd::getState Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand status Jun 28 12:58:26 volumiodisplay volumio[30684]: info: sendMpdCommand status took 3 milliseconds Jun 28 12:58:26 volumiodisplay volumio[30684]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 28 12:58:26 volumiodisplay volumio[30684]: info: sendMpdCommand status took 1 milliseconds Jun 28 12:58:26 volumiodisplay volumio[30684]: info: sendMpdCommand status took 1 milliseconds Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseState Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseTrackInfo Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseState Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseState Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ControllerMpd::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::servicePushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Toute La Pluie Tombe Sur Moi","artist":"Jean-Claude Borelly","album":"Super Instrumental Vol.18","uri":"NAS/NAS/24BIT SACD/Audiofeel tracks/Audiofeel Tracks Deel 2/07 Jean-Claude Borelly - Toute La Pluie Tombe Sur Moi.mp3","trackType":"mp3"} Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: CURRENT POSITION 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState stateService play Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState currentStatus stop Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ------------------------------ 6ms Jun 28 12:58:26 volumiodisplay volumio[30684]: info: Jun 28 12:58:26 volumiodisplay volumio[30684]: ---------------------------- MPD announces state update: player Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ControllerMpd::getState Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand status Jun 28 12:58:26 volumiodisplay volumio[30684]: info: Jun 28 12:58:26 volumiodisplay volumio[30684]: ---------------------------- MPD announces state update: player Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ControllerMpd::getState Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand status Jun 28 12:58:26 volumiodisplay volumio[30684]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 28 12:58:26 volumiodisplay volumio[30684]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 28 12:58:26 volumiodisplay volumio[30684]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 28 12:58:26 volumiodisplay volumio[30684]: info: sendMpdCommand status took 2 milliseconds Jun 28 12:58:26 volumiodisplay volumio[30684]: info: sendMpdCommand status took 2 milliseconds Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseTrackInfo Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseTrackInfo Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseTrackInfo Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseState Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseState Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ControllerMpd::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::servicePushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Toute La Pluie Tombe Sur Moi","artist":"Jean-Claude Borelly","album":"Super Instrumental Vol.18","uri":"NAS/NAS/24BIT SACD/Audiofeel tracks/Audiofeel Tracks Deel 2/07 Jean-Claude Borelly - Toute La Pluie Tombe Sur Moi.mp3","trackType":"mp3"} Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: CURRENT POSITION 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState stateService play Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState currentStatus play Jun 28 12:58:26 volumiodisplay volumio[30684]: info: Received an update from plugin. extracting info from payload Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ControllerMpd::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::servicePushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Toute La Pluie Tombe Sur Moi","artist":"Jean-Claude Borelly","album":"Super Instrumental Vol.18","uri":"NAS/NAS/24BIT SACD/Audiofeel tracks/Audiofeel Tracks Deel 2/07 Jean-Claude Borelly - Toute La Pluie Tombe Sur Moi.mp3","trackType":"mp3"} Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: CURRENT POSITION 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState stateService play Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState currentStatus play Jun 28 12:58:26 volumiodisplay volumio[30684]: info: Received an update from plugin. extracting info from payload Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ControllerMpd::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::servicePushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Toute La Pluie Tombe Sur Moi","artist":"Jean-Claude Borelly","album":"Super Instrumental Vol.18","uri":"NAS/NAS/24BIT SACD/Audiofeel tracks/Audiofeel Tracks Deel 2/07 Jean-Claude Borelly - Toute La Pluie Tombe Sur Moi.mp3","trackType":"mp3"} Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: CURRENT POSITION 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState stateService play Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState currentStatus play Jun 28 12:58:26 volumiodisplay volumio[30684]: info: Received an update from plugin. extracting info from payload Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ------------------------------ 20ms Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ------------------------------ 18ms Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ------------------------------ 18ms Jun 28 12:58:26 volumiodisplay volumio[30684]: info: sendMpdCommand playlistinfo took 15 milliseconds Jun 28 12:58:26 volumiodisplay volumio[30684]: info: sendMpdCommand playlistinfo took 16 milliseconds Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseTrackInfo Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: ControllerMpd::parseTrackInfo Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ControllerMpd::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::servicePushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Toute La Pluie Tombe Sur Moi","artist":"Jean-Claude Borelly","album":"Super Instrumental Vol.18","uri":"NAS/NAS/24BIT SACD/Audiofeel tracks/Audiofeel Tracks Deel 2/07 Jean-Claude Borelly - Toute La Pluie Tombe Sur Moi.mp3","trackType":"mp3"} Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: CURRENT POSITION 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState stateService play Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState currentStatus play Jun 28 12:58:26 volumiodisplay volumio[30684]: info: Received an update from plugin. extracting info from payload Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ControllerMpd::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::servicePushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":188,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Toute La Pluie Tombe Sur Moi","artist":"Jean-Claude Borelly","album":"Super Instrumental Vol.18","uri":"NAS/NAS/24BIT SACD/Audiofeel tracks/Audiofeel Tracks Deel 2/07 Jean-Claude Borelly - Toute La Pluie Tombe Sur Moi.mp3","trackType":"mp3"} Jun 28 12:58:26 volumiodisplay volumio[30684]: verbose: CURRENT POSITION 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState stateService play Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::syncState currentStatus play Jun 28 12:58:26 volumiodisplay volumio[30684]: info: Received an update from plugin. extracting info from payload Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreStateMachine::pushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CorePlayQueue::getTrack 0 Jun 28 12:58:26 volumiodisplay volumio[30684]: info: CoreCommandRouter::volumioPushState Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ------------------------------ 28ms Jun 28 12:58:26 volumiodisplay volumio[30684]: info: ------------------------------ 28ms Jun 28 12:58:26 volumiodisplay volumio[30684]: info: touch_display: Setting screensaver timeout to 0 seconds. Jun 28 12:58:38 volumiodisplay systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jun 28 12:58:38 volumiodisplay dbus-daemon[976]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.1833' (uid=0 pid=10940 comm="timedatectl show --property=NTPSynchronized --valu") Jun 28 12:58:38 volumiodisplay systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jun 28 12:58:38 volumiodisplay dbus-daemon[976]: [system] Successfully activated service 'org.freedesktop.timedate1' Jun 28 12:58:38 volumiodisplay systemd[1]: Started systemd-timedated.service - Time & Date Service. Jun 28 12:58:38 volumiodisplay setdatetime-helper.sh[10939]: Time is already synchronized. Jun 28 12:58:38 volumiodisplay systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jun 28 12:58:38 volumiodisplay systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jun 28 12:58:48 volumiodisplay wpa_supplicant[1369]: wlan0: CTRL-EVENT-DISCONNECTED bssid=d8:44:89:52:61:b3 reason=8 Jun 28 12:58:48 volumiodisplay dhcpcd[1083]: wlan0: carrier lost Jun 28 12:58:48 volumiodisplay wpa_supplicant[1369]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD Jun 28 12:58:48 volumiodisplay wpa_supplicant[1369]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=DE Jun 28 12:58:48 volumiodisplay avahi-daemon[1459]: Withdrawing address record for 192.168.68.71 on wlan0. Jun 28 12:58:48 volumiodisplay avahi-daemon[1459]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.71. Jun 28 12:58:48 volumiodisplay avahi-daemon[1459]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 28 12:58:48 volumiodisplay dhcpcd[1083]: wlan0: deleting route to 192.168.68.0/22 Jun 28 12:58:48 volumiodisplay dhcpcd[1083]: wlan0: deleting default route via 192.168.68.1 Jun 28 12:58:48 volumiodisplay systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 28 12:58:48 volumiodisplay systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 28 12:58:48 volumiodisplay systemd[1]: welcome.service: Deactivated successfully. Jun 28 12:58:48 volumiodisplay systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 28 12:58:48 volumiodisplay systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 28 12:58:48 volumiodisplay systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 28 12:58:48 volumiodisplay welcome[11012]: Resolved ip:[0] Jun 28 12:58:48 volumiodisplay systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 28 12:58:48 volumiodisplay systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 28 12:58:50 volumiodisplay ntpd[1199]: IO: Deleting interface #8 wlan0, 192.168.68.71#123, interface stats: received=907, sent=914, dropped=0, active_time=74402 secs Jun 28 12:58:50 volumiodisplay ntpd[1199]: PROTO: 193.123.56.220 unlink local addr 192.168.68.71 -> Jun 28 12:58:50 volumiodisplay ntpd[1199]: PROTO: 185.51.192.63 unlink local addr 192.168.68.71 -> Jun 28 12:58:50 volumiodisplay ntpd[1199]: PROTO: 185.71.60.123 unlink local addr 192.168.68.71 -> Jun 28 12:58:50 volumiodisplay ntpd[1199]: PROTO: 178.239.19.61 unlink local addr 192.168.68.71 -> Jun 28 12:58:50 volumiodisplay ntpd[1199]: PROTO: 45.138.55.61 unlink local addr 192.168.68.71 -> Jun 28 12:58:50 volumiodisplay ntpd[1199]: PROTO: 45.138.55.62 unlink local addr 192.168.68.71 -> Jun 28 12:58:50 volumiodisplay ntpd[1199]: PROTO: 193.123.38.172 unlink local addr 192.168.68.71 -> Jun 28 12:58:50 volumiodisplay ntpd[1199]: PROTO: 86.80.166.233 unlink local addr 192.168.68.71 -> Jun 28 12:58:50 volumiodisplay ntpd[1199]: PROTO: 93.115.17.154 unlink local addr 192.168.68.71 -> Jun 28 12:58:50 volumiodisplay ntpd[1199]: PROTO: 178.239.19.62 unlink local addr 192.168.68.71 -> Jun 28 12:58:50 volumiodisplay ntpd[1199]: PROTO: 94.198.159.16 unlink local addr 192.168.68.71 -> Jun 28 12:58:50 volumiodisplay ntpd[1199]: PROTO: 94.198.159.11 unlink local addr 192.168.68.71 -> Jun 28 12:58:50 volumiodisplay ntpd[1199]: PROTO: 178.239.19.58 unlink local addr 192.168.68.71 -> Jun 28 12:58:51 volumiodisplay wpa_supplicant[1369]: wlan0: Trying to associate with d8:44:89:52:68:f7 (SSID='FiberFirst' freq=5200 MHz) Jun 28 12:58:51 volumiodisplay wpa_supplicant[1369]: wlan0: Associated with d8:44:89:52:68:f7 Jun 28 12:58:51 volumiodisplay wpa_supplicant[1369]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 28 12:58:51 volumiodisplay wpa_supplicant[1369]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Jun 28 12:58:51 volumiodisplay wpa_supplicant[1369]: wlan0: WPA: Key negotiation completed with d8:44:89:52:68:f7 [PTK=CCMP GTK=CCMP] Jun 28 12:58:51 volumiodisplay wpa_supplicant[1369]: wlan0: CTRL-EVENT-CONNECTED - Connection to d8:44:89:52:68:f7 completed [id=0 id_str=] Jun 28 12:58:51 volumiodisplay dhcpcd[1083]: wlan0: carrier acquired Jun 28 12:58:51 volumiodisplay dhcpcd[1083]: wlan0: IAID 67:26:ee:89 Jun 28 12:58:52 volumiodisplay wpa_supplicant[1369]: RRM: Ignoring radio measurement request: Not RRM network Jun 28 12:58:52 volumiodisplay dhcpcd[1083]: wlan0: soliciting an IPv6 router Jun 28 12:58:52 volumiodisplay wpa_supplicant[1369]: wlan0: WNM: Preferred List Available Jun 28 12:58:52 volumiodisplay kernel: ieee80211 phy0: brcmf_p2p_send_action_frame: Unknown Frame: category 0xa, action 0x8 Jun 28 12:58:52 volumiodisplay dhcpcd[1083]: wlan0: rebinding lease of 192.168.68.71 Jun 28 12:58:52 volumiodisplay dhcpcd[1083]: wlan0: probing address 192.168.68.71/22 Jun 28 12:58:57 volumiodisplay dhcpcd[1083]: wlan0: leased 192.168.68.71 for 7200 seconds Jun 28 12:58:57 volumiodisplay avahi-daemon[1459]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.71. Jun 28 12:58:57 volumiodisplay dhcpcd[1083]: wlan0: adding route to 192.168.68.0/22 Jun 28 12:58:57 volumiodisplay avahi-daemon[1459]: New relevant interface wlan0.IPv4 for mDNS. Jun 28 12:58:57 volumiodisplay avahi-daemon[1459]: Registering new address record for 192.168.68.71 on wlan0.IPv4. Jun 28 12:58:58 volumiodisplay systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 28 12:58:58 volumiodisplay systemd[1]: Stopping ip-changed@wlan0.target - IP Address changed on wlan0... Jun 28 12:58:58 volumiodisplay systemd[1]: welcome.service: Deactivated successfully. Jun 28 12:58:58 volumiodisplay systemd[1]: Stopped welcome.service - Show a welcome message on console. Jun 28 12:58:58 volumiodisplay systemd[1]: Stopping welcome.service - Show a welcome message on console... Jun 28 12:58:58 volumiodisplay dhcpcd[1083]: wlan0: adding default route via 192.168.68.1 Jun 28 12:58:58 volumiodisplay systemd[1]: Starting welcome.service - Show a welcome message on console... Jun 28 12:58:58 volumiodisplay welcome[11048]: Resolved ip:[1] 192.168.68.71 Jun 28 12:58:58 volumiodisplay systemd[1]: Finished welcome.service - Show a welcome message on console. Jun 28 12:58:58 volumiodisplay systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Jun 28 12:58:59 volumiodisplay ntpd[1199]: IO: Listen normally on 9 wlan0 192.168.68.71:123 Jun 28 12:58:59 volumiodisplay ntpd[1199]: IO: new interface(s) found: waking up resolver Jun 28 12:59:01 volumiodisplay wpa_supplicant[1369]: RRM: Ignoring radio measurement request: Not RRM network Jun 28 12:59:02 volumiodisplay volumio[30684]: info: Volumio Network Manager: Network status updated: 0 Jun 28 12:59:02 volumiodisplay volumio[30684]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 12:59:02 volumiodisplay volumio[30684]: Error: read ETIMEDOUT Jun 28 12:59:02 volumiodisplay volumio[30684]: at TCP.onStreamRead (node:internal/stream_base_commons:217:20) { Jun 28 12:59:02 volumiodisplay volumio[30684]: errno: -110, Jun 28 12:59:02 volumiodisplay volumio[30684]: code: 'ETIMEDOUT', Jun 28 12:59:02 volumiodisplay volumio[30684]: syscall: 'read' Jun 28 12:59:02 volumiodisplay volumio[30684]: } Jun 28 12:59:02 volumiodisplay volumio[30684]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 28 12:59:02 volumiodisplay sudo[11073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-06-28 12:58' Jun 28 12:59:02 volumiodisplay sudo[11073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm 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="cee9bb5b0ee50e4898478097a64b5c6c77d5a212" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="b0310d60a07948a2a8a2e8db322034758c86aad6" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 21 15:01:23 UTC 2025" VOLUMIO_VERSION="4.013" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6df2d5f0b86e6f2c0cd4dc68c66de48c"