Oct 14 13:57:01 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:01 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:03 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 13:57:03 ferrum-broen volumio[1389]: info: CURURI: music-library Oct 14 13:57:03 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:57:04 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 13:57:04 ferrum-broen volumio[1389]: info: CURURI: music-library/USB Oct 14 13:57:04 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:57:05 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 13:57:05 ferrum-broen volumio[1389]: info: CURURI: music-library/USB/6745-2301 Oct 14 13:57:05 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::ClearQueue Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::stop Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::stPlaybackTimer Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::updateTrackBlock Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrackBlock Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::serviceStop Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::serviceStop Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::stop Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::clearPlayQueue Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::saveQueue Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushQueue Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::addQueueItems Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::addQueueItems Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/1 FLAC MJ - Liberian Girl_24_384khz.wav Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/1 FLAC MJ - Liberian Girl_24_384khz.wav Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/the_time_is_now_768k_32b.wav Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/the_time_is_now_768k_32b.wav Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushQueue Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::saveQueue Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::updateTrackBlock Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrackBlock Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPlay Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::play index 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::addQueueItems Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::addQueueItems Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/06 - Dire Straits - Sultans Of Swing.dsf Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/06 - Dire Straits - Sultans Of Swing.dsf Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/02 Roxette - Sleeping In My Car.flac Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/02 Roxette - Sleeping In My Car.flac Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/System Volume Information Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/System Volume Information Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::stop Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushQueue Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::saveQueue Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::play index undefined Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::updateTrackBlock Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrackBlock Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::startPlaybackTimer Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::clearAddPlayTracks USB/6745-2301/the_time_is_now_768k_32b.wav Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand stop took 40 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand stop took 19 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand status took 3 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand clear took 2 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand status took 2 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand add "USB/6745-2301/the_time_is_now_768k_32b.wav" Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService stop Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus stop Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: No code Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 22ms Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:57:06 ferrum-broen volumio[1389]: error: updateQueue error: null Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand status took 24 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 22ms Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand add "USB/6745-2301/the_time_is_now_768k_32b.wav" took 21 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 1ms Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 1ms Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand play Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService stop Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus stop Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: No code Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 56ms Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 57ms Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 74ms Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand play took 74 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 42ms Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 42ms Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:06 ferrum-broen volumio[1389]: info: MCU Signalled Playback Inactive Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand status took 2 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand status took 1 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand status took 1 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Oct 14 13:57:06 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand status took 8 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 6 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand status took 1 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand status took 1 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":830,"duration":64,"samplerate":768,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49152 Kbps","isStreaming":false,"title":"the_time_is_now_768k_32b.wav","artist":null,"album":null,"uri":"USB/6745-2301/the_time_is_now_768k_32b.wav","trackType":"wav"} Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus stop Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":831,"duration":64,"samplerate":768,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49152 Kbps","isStreaming":false,"title":"the_time_is_now_768k_32b.wav","artist":null,"album":null,"uri":"USB/6745-2301/the_time_is_now_768k_32b.wav","trackType":"wav"} Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":841,"duration":64,"samplerate":768,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49152 Kbps","isStreaming":false,"title":"the_time_is_now_768k_32b.wav","artist":null,"album":null,"uri":"USB/6745-2301/the_time_is_now_768k_32b.wav","trackType":"wav"} Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 21ms Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 31ms Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 30ms Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 27 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 26 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 26 milliseconds Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":846,"duration":64,"samplerate":768,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49152 Kbps","isStreaming":false,"title":"the_time_is_now_768k_32b.wav","artist":null,"album":null,"uri":"USB/6745-2301/the_time_is_now_768k_32b.wav","trackType":"wav"} Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":930,"duration":64,"samplerate":768,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49152 Kbps","isStreaming":false,"title":"the_time_is_now_768k_32b.wav","artist":null,"album":null,"uri":"USB/6745-2301/the_time_is_now_768k_32b.wav","trackType":"wav"} Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":947,"duration":64,"samplerate":768,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49152 Kbps","isStreaming":false,"title":"the_time_is_now_768k_32b.wav","artist":null,"album":null,"uri":"USB/6745-2301/the_time_is_now_768k_32b.wav","trackType":"wav"} Oct 14 13:57:06 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:06 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 74ms Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 72ms Oct 14 13:57:06 ferrum-broen volumio[1389]: info: ------------------------------ 68ms Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:06 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:06 ferrum-broen volumio[1389]: info: MCU Signalled Playback Active Oct 14 13:57:14 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:14 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:16 ferrum-broen volumio[1389]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 13:57:16 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 14 13:57:16 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 13:57:16 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 13:57:16 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 13:57:16 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 13:57:16 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 13:57:16 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 13:57:16 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 13:57:16 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 13:57:16 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 13:57:16 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 14 13:57:16 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 14 13:57:16 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , getAdditionalUiSection Oct 14 13:57:16 ferrum-broen volumio[1389]: info: [HEM] Getting UI Config for section: audio Oct 14 13:57:16 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_predefined_mode: 0 Oct 14 13:57:16 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_format: standard Oct 14 13:57:16 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_pcm_slot_size: bits32 Oct 14 13:57:16 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_dsd_pin: p13 Oct 14 13:57:16 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_dsd_channel_assignment: leftOnWck Oct 14 13:57:16 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_wck_polarity: normal Oct 14 13:57:16 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_bck_polarity: normal Oct 14 13:57:16 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_sd_polarity: normal Oct 14 13:57:16 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_mclk_freq: undefined Oct 14 13:57:16 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_mapping: undefined Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Oct 14 13:57:18 ferrum-broen volumio[1389]: info: Preparing to save Alsa Options, stopping services first Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPause Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::pause Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::stPlaybackTimer Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::servicePause Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePause Oct 14 13:57:18 ferrum-broen volumio[1389]: info: ControllerMpd::pause Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand pause Oct 14 13:57:18 ferrum-broen volumio[1389]: info: Saving Audio Output to: {"output_device":{"value":"5","label":"I2S + AES + COAX + OPTO"}} Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 13:57:18 ferrum-broen volumio[1389]: info: Found match in Cards Database for ignoring default Mixer Oct 14 13:57:18 ferrum-broen volumio[1389]: info: Device 5 does not have any Mixer Control Available Oct 14 13:57:18 ferrum-broen volumio[1389]: info: QobuzConnect: setDeactiveState invoked Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen vtcs[1999]: [2026-10-14 13:57:18.855] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Oct 14 13:57:18 ferrum-broen vtcs[1999]: [2026-10-14 13:57:18.855] [tisoc] [error] [SpkconServer.cpp:378] recv error. socket disconnected Oct 14 13:57:18 ferrum-broen volumio[1389]: info: Ignoring Mixers Options Oct 14 13:57:18 ferrum-broen volumio[1389]: info: Device 5 does not have any Mixer Control Available Oct 14 13:57:18 ferrum-broen volumio[1389]: info: QobuzConnect: setDeactiveState invoked Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioUpdateVolumeSettings Oct 14 13:57:18 ferrum-broen volumio[1389]: info: Updating Volume Controller Parameters: Device: 5 Name: I2S + AES + COAX + OPTO Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Oct 14 13:57:18 ferrum-broen volumio[1389]: info: Disabling external Volume Control Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen sudo[3131]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 13:57:18 ferrum-broen volumio[1389]: info: Preparing to generate the ALSA configuration file Oct 14 13:57:18 ferrum-broen sudo[3131]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:18 ferrum-broen sudo[3129]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 13:57:18 ferrum-broen sudo[3129]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:18 ferrum-broen sudo[3141]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 13:57:18 ferrum-broen sudo[3139]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 13:57:18 ferrum-broen sudo[3141]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:18 ferrum-broen sudo[3139]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:18 ferrum-broen systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 14 13:57:18 ferrum-broen systemd[1]: vtcs.service: Deactivated successfully. Oct 14 13:57:18 ferrum-broen systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 14 13:57:18 ferrum-broen sudo[3149]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 13:57:18 ferrum-broen sudo[3149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:18 ferrum-broen volumio[1389]: info: Oct 14 13:57:18 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:18 ferrum-broen volumio[1389]: info: sendMpdCommand pause took 126 milliseconds Oct 14 13:57:18 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:18 ferrum-broen volumio[1389]: info: Oct 14 13:57:18 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:18 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:18 ferrum-broen volumio[1389]: info: Oct 14 13:57:18 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:18 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:18 ferrum-broen sudo[3131]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:18 ferrum-broen sudo[3156]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 13:57:18 ferrum-broen sudo[3156]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:18 ferrum-broen sudo[3149]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:18 ferrum-broen volumio[1389]: info: VolumeController:: alsactl monitor closed, code: 0 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: sendMpdCommand status took 8 milliseconds Oct 14 13:57:18 ferrum-broen volumio[1389]: info: sendMpdCommand status took 8 milliseconds Oct 14 13:57:18 ferrum-broen volumio[1389]: info: sendMpdCommand status took 7 milliseconds Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:18 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 13:57:18 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 13:57:18 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:18 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13249,"duration":64,"samplerate":768,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49152 Kbps","isStreaming":false,"title":"the_time_is_now_768k_32b.wav","artist":null,"album":null,"uri":"USB/6745-2301/the_time_is_now_768k_32b.wav","trackType":"wav"} Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService pause Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus pause Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:18 ferrum-broen sudo[3139]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::stPlaybackTimer Oct 14 13:57:18 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13249,"duration":64,"samplerate":768,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49152 Kbps","isStreaming":false,"title":"the_time_is_now_768k_32b.wav","artist":null,"album":null,"uri":"USB/6745-2301/the_time_is_now_768k_32b.wav","trackType":"wav"} Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService pause Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus pause Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::stPlaybackTimer Oct 14 13:57:18 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":13249,"duration":64,"samplerate":768,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"49152 Kbps","isStreaming":false,"title":"the_time_is_now_768k_32b.wav","artist":null,"album":null,"uri":"USB/6745-2301/the_time_is_now_768k_32b.wav","trackType":"wav"} Oct 14 13:57:18 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService pause Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus pause Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:18 ferrum-broen volumio[1389]: info: CoreStateMachine::stPlaybackTimer Oct 14 13:57:18 ferrum-broen sudo[3159]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 13:57:18 ferrum-broen sudo[3159]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:18 ferrum-broen sudo[3141]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:18 ferrum-broen volumio[1389]: info: ------------------------------ 29ms Oct 14 13:57:18 ferrum-broen volumio[1389]: info: ------------------------------ 29ms Oct 14 13:57:18 ferrum-broen volumio[1389]: info: ------------------------------ 28ms Oct 14 13:57:18 ferrum-broen sudo[3129]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:18 ferrum-broen sudo[3156]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:18 ferrum-broen systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 14 13:57:19 ferrum-broen qobuz-connect[1777]: 20261014 13:57:19.001 [1777.1777] INFO SampleApp: Stopping Local configuration server Oct 14 13:57:19 ferrum-broen sudo[3166]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 13:57:19 ferrum-broen sudo[3166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , getAdditionalUiSection Oct 14 13:57:19 ferrum-broen volumio[1389]: info: [HEM] Getting UI Config for section: audio Oct 14 13:57:19 ferrum-broen volumio[1389]: info: MCU Signalled Playback Inactive Oct 14 13:57:19 ferrum-broen volumio[1389]: info: Asound.conf file written Oct 14 13:57:19 ferrum-broen sudo[3176]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Oct 14 13:57:19 ferrum-broen sudo[3176]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen sudo[3176]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen volumio[1389]: info: Output device has changed, restarting MPD Oct 14 13:57:19 ferrum-broen volumio[1389]: info: Output device has changed, restarting Shairport Sync Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 13:57:19 ferrum-broen sudo[3183]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 13:57:19 ferrum-broen sudo[3183]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen sudo[3183]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen sudo[3185]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 13:57:19 ferrum-broen sudo[3185]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen volumio[1389]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 13:57:19 ferrum-broen volumio[1389]: info: QobuzConnect: setDeactiveState invoked Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 14 13:57:19 ferrum-broen volumio[1389]: info: Output device has changed, restarting MPD Oct 14 13:57:19 ferrum-broen sudo[3192]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 13:57:19 ferrum-broen sudo[3192]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen sudo[3196]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 14 13:57:19 ferrum-broen volumio[1389]: info: Output device has changed, restarting Shairport Sync Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 13:57:19 ferrum-broen sudo[3196]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen sudo[3196]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen volumio[1389]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 14 13:57:19 ferrum-broen volumio[1389]: info: QobuzConnect: setDeactiveState invoked Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen sudo[3192]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen sudo[3205]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 13:57:19 ferrum-broen sudo[3205]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen sudo[3197]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 14 13:57:19 ferrum-broen sudo[3207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 13:57:19 ferrum-broen sudo[3207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen sudo[3197]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen sudo[3209]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 14 13:57:19 ferrum-broen sudo[3209]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen systemd[1]: mpd.service: Deactivated successfully. Oct 14 13:57:19 ferrum-broen sudo[3219]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 13:57:19 ferrum-broen sudo[3219]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 14 13:57:19 ferrum-broen systemd[1]: mpd.service: Consumed 3.949s CPU time. Oct 14 13:57:19 ferrum-broen volumio[1389]: info: MPD Permissions set Oct 14 13:57:19 ferrum-broen volumio[1389]: info: MPD Permissions set Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen systemd[1]: mpd.socket: Deactivated successfully. Oct 14 13:57:19 ferrum-broen systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Oct 14 13:57:19 ferrum-broen systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Oct 14 13:57:19 ferrum-broen sudo[3226]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Oct 14 13:57:19 ferrum-broen sudo[3226]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 14 13:57:19 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_predefined_mode: 0 Oct 14 13:57:19 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_format: standard Oct 14 13:57:19 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_pcm_slot_size: bits32 Oct 14 13:57:19 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_dsd_pin: p13 Oct 14 13:57:19 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_dsd_channel_assignment: leftOnWck Oct 14 13:57:19 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_wck_polarity: normal Oct 14 13:57:19 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_bck_polarity: normal Oct 14 13:57:19 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_sd_polarity: normal Oct 14 13:57:19 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_mclk_freq: undefined Oct 14 13:57:19 ferrum-broen volumio[1389]: info: [HEM] Config value for i2s_mapping: undefined Oct 14 13:57:19 ferrum-broen systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Oct 14 13:57:19 ferrum-broen systemd[1]: Starting mpd.service - Music Player Daemon... Oct 14 13:57:19 ferrum-broen volumio[1389]: info: Starting Shairport Sync Oct 14 13:57:19 ferrum-broen volumio[1389]: info: Starting Shairport Sync Oct 14 13:57:19 ferrum-broen sudo[3219]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen sudo[3241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 13:57:19 ferrum-broen sudo[3241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen sudo[3205]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen sudo[3209]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen sudo[3245]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 14 13:57:19 ferrum-broen sudo[3245]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen sudo[3207]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen sudo[3244]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 13:57:19 ferrum-broen sudo[3244]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen sudo[3226]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen sudo[3239]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 14 13:57:19 ferrum-broen sudo[3239]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Oct 14 13:57:19 ferrum-broen sudo[3239]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen sudo[3252]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Oct 14 13:57:19 ferrum-broen sudo[3252]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:19 ferrum-broen systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 14 13:57:19 ferrum-broen systemd[1]: shairport-sync.service: Deactivated successfully. Oct 14 13:57:19 ferrum-broen systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 13:57:19 ferrum-broen systemd[1]: shairport-sync.service: Consumed 1.654s CPU time. Oct 14 13:57:19 ferrum-broen systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 13:57:19 ferrum-broen qobuz-connect[1777]: 20261014 13:57:19.292 [1777.1777] INFO SampleApp: shat down connection on UNIX socket Oct 14 13:57:19 ferrum-broen volumio[1389]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 14 13:57:19 ferrum-broen volumio[1389]: info: QobuzConnect: setDeactiveState invoked Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:19 ferrum-broen systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 14 13:57:19 ferrum-broen systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 13:57:19 ferrum-broen sudo[3245]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen sudo[3241]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen volumio[1389]: info: Shairport-Sync Started Oct 14 13:57:19 ferrum-broen systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 13:57:19 ferrum-broen volumio[1389]: info: Shairport-Sync Started Oct 14 13:57:19 ferrum-broen sudo[3244]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen sudo[3159]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen sudo[3166]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen sudo[3252]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen volumio[1389]: info: Executing endpoint qc_getconfig Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Oct 14 13:57:19 ferrum-broen qobuz-connect[3256]: 20261014 13:57:19.348 [3256.3256] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Oct 14 13:57:19 ferrum-broen volumio[1389]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Oct 14 13:57:19 ferrum-broen volumio[1389]: info: QobuzConnect: QOBUZ Connect daemon connected Oct 14 13:57:19 ferrum-broen qobuz-connect[3256]: 20261014 13:57:19.349 [3256.3256] INFO VolumeManager: [0x16af158]: Setting new playback volume: 75 Oct 14 13:57:19 ferrum-broen qobuz-connect[3256]: 20261014 13:57:19.349 [3256.3256] INFO VolumeManager: [0x16af158]: Setting new mute state: 0 Oct 14 13:57:19 ferrum-broen qobuz-connect[3256]: 20261014 13:57:19.349 [3256.3256] INFO AudioStreamManager: [0x16aeeb0]: Setting new audio download buffer size: 1048576 Oct 14 13:57:19 ferrum-broen qobuz-connect[3256]: 20261014 13:57:19.349 [3256.3256] INFO QobuzConnect: [0x16afa20]: Client initialized! Oct 14 13:57:19 ferrum-broen qobuz-connect[3256]: 20261014 13:57:19.349 [3256.3256] INFO SampleApp: Starting Avahi advertising, name: Ferrum Broen, service name: _qobuz-connect._tcp Oct 14 13:57:19 ferrum-broen qobuz-connect[3256]: 20261014 13:57:19.359 [3256.3256] INFO LocalConfigManager: [0x16aebd8]: Starting Local Configuration server Oct 14 13:57:19 ferrum-broen qobuz-connect[3256]: 20261014 13:57:19.359 [3256.3256] INFO SampleApp: Starting Local configuration server Oct 14 13:57:19 ferrum-broen qobuz-connect[3256]: 20261014 13:57:19.359 [3256.3256] INFO SampleApp: Connected to UNIX socket client 0x1699908 Oct 14 13:57:19 ferrum-broen qobuz-connect[3256]: 20261014 13:57:19.498 [3256.3256] INFO SampleApp: Playback volume changed: 75 Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:19 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:19 ferrum-broen mpd[3254]: 2026-10-14T13:57:19 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Oct 14 13:57:19 ferrum-broen systemd[1]: Started mpd.service - Music Player Daemon. Oct 14 13:57:19 ferrum-broen sudo[3185]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen sudo[3197]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:19 ferrum-broen volumio[1389]: error: MPD error: The expression evaluated to a falsy value: Oct 14 13:57:19 ferrum-broen volumio[1389]: assert.ok(self.idling) Oct 14 13:57:19 ferrum-broen volumio[1389]: error: The expression evaluated to a falsy value: Oct 14 13:57:19 ferrum-broen volumio[1389]: assert.ok(self.idling) Oct 14 13:57:19 ferrum-broen volumio[1389]: error: updateQueue error: null Oct 14 13:57:20 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:20 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:21 ferrum-broen volumio[1389]: info: TidalConnect service stoped! Oct 14 13:57:21 ferrum-broen volumio[1389]: info: TidalConnect service stoped! Oct 14 13:57:21 ferrum-broen volumio[1389]: info: TidalConnect service stoped! Oct 14 13:57:21 ferrum-broen volumio[1389]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 14 13:57:21 ferrum-broen volumio[1389]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 14 13:57:21 ferrum-broen volumio[1389]: info: TidalConnect service stoped! Oct 14 13:57:21 ferrum-broen sudo[3293]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 14 13:57:21 ferrum-broen sudo[3293]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:21 ferrum-broen volumio[1389]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 14 13:57:21 ferrum-broen volumio[1389]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 14 13:57:22 ferrum-broen sudo[3300]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 14 13:57:22 ferrum-broen sudo[3300]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:22 ferrum-broen systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Oct 14 13:57:22 ferrum-broen sudo[3293]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:22 ferrum-broen sudo[3300]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:22 ferrum-broen volumio[1389]: info: TidalConnect service stoped! Oct 14 13:57:22 ferrum-broen volumio[1389]: info: Executing endpoint tc_getconfig Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Oct 14 13:57:22 ferrum-broen vtcs[3303]: STARTING TidalConnect services, version: 1.6.1 Oct 14 13:57:22 ferrum-broen vtcs[3303]: STARTED TidalConnect services. Oct 14 13:57:22 ferrum-broen volumio[1389]: info: Executing endpoint tc_connect Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Oct 14 13:57:22 ferrum-broen volumio[1389]: info: Connecting to TidalConnect Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:22 ferrum-broen volumio[1389]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:22 ferrum-broen volumio[1389]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Oct 14 13:57:22 ferrum-broen volumio[1389]: info: TidalConnect service stoped! Oct 14 13:57:22 ferrum-broen volumio[1389]: info: TidalConnect service stoped! Oct 14 13:57:22 ferrum-broen volumio[1389]: info: TidalConnect service stoped! Oct 14 13:57:22 ferrum-broen volumio[1389]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 14 13:57:22 ferrum-broen volumio[1389]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 14 13:57:22 ferrum-broen volumio[1389]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Oct 14 13:57:22 ferrum-broen volumio[1389]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Oct 14 13:57:22 ferrum-broen sudo[3337]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 14 13:57:22 ferrum-broen sudo[3337]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:22 ferrum-broen sudo[3339]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Oct 14 13:57:22 ferrum-broen sudo[3339]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:57:22 ferrum-broen sudo[3337]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:22 ferrum-broen sudo[3339]: pam_unix(sudo:session): session closed for user root Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioRetrieveVolumeLevels Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreStateMachine::getcurrentVolume Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioRetrievevolume Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:22 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:24 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 13:57:24 ferrum-broen volumio[1389]: info: CURURI: music-library Oct 14 13:57:24 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:57:25 ferrum-broen volumio[1389]: info: TidalConnect service started! Oct 14 13:57:25 ferrum-broen volumio[1389]: info: TidalConnect service started! Oct 14 13:57:25 ferrum-broen volumio[1389]: info: TidalConnect service started! Oct 14 13:57:25 ferrum-broen volumio[1389]: info: TidalConnect service started! Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CURURI: music-library/INTERNAL Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::ClearQueue Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::stop Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::updateTrackBlock Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrackBlock Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::stPlaybackTimer Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::serviceStop Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 1 Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::serviceStop Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ControllerMpd::stop Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::clearPlayQueue Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::saveQueue Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushQueue Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::addQueueItems Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::addQueueItems Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/INTERNAL/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Using cached record of: music-library/INTERNAL/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushQueue Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::saveQueue Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::updateTrackBlock Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrackBlock Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPlay Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::play index 0 Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::addQueueItems Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::addQueueItems Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/INTERNAL/Armin Van Buuren Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Exploding uri music-library/INTERNAL/Armin Van Buuren in service mpd Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::stop Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::play index undefined Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::startPlaybackTimer Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::clearAddPlayTracks INTERNAL/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ALBUMART /albumart?cacheid=496&web=Armin%20Van%20Buuren/Imagine%202008/extralarge&path=%2Fmnt%2FINTERNAL%2FArmin%20Van%20Buuren%2FImagine%202008%2FArmin%20Van%20Buuren%20-%2001%20-%20Imagine.flac&metadata=false Oct 14 13:57:27 ferrum-broen volumio[1389]: info: URI /mnt/INTERNAL/Armin Van Buuren/Imagine 2008/Armin Van Buuren - 01 - Imagine.flac Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ALBUMART /albumart?cacheid=496&web=Armin%20Van%20Buuren/Imagine%202008/extralarge&path=%2Fmnt%2FINTERNAL%2FArmin%20Van%20Buuren%2FImagine%202008%2FArmin%20Van%20Buuren%20-%2002%20-%20Going%20Wrong%20(with%20DJ%20Shah%20feat.%20Chris%20Jones).flac&metadata=false Oct 14 13:57:27 ferrum-broen volumio[1389]: info: URI /mnt/INTERNAL/Armin Van Buuren/Imagine 2008/Armin Van Buuren - 02 - Going Wrong (with DJ Shah feat. Chris Jones).flac Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ALBUMART /albumart?cacheid=496&web=Armin%20Van%20Buuren/Imagine%202008/extralarge&path=%2Fmnt%2FINTERNAL%2FArmin%20Van%20Buuren%2FImagine%202008%2FArmin%20Van%20Buuren%20-%2003%20-%20Unforgivable.flac&metadata=false Oct 14 13:57:27 ferrum-broen volumio[1389]: info: URI /mnt/INTERNAL/Armin Van Buuren/Imagine 2008/Armin Van Buuren - 03 - Unforgivable.flac Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ALBUMART /albumart?cacheid=496&web=Armin%20Van%20Buuren/Imagine%202008/extralarge&path=%2Fmnt%2FINTERNAL%2FArmin%20Van%20Buuren%2FImagine%202008%2FArmin%20Van%20Buuren%20-%2004%20-%20Face%20To%20Face.flac&metadata=false Oct 14 13:57:27 ferrum-broen volumio[1389]: info: URI /mnt/INTERNAL/Armin Van Buuren/Imagine 2008/Armin Van Buuren - 04 - Face To Face.flac Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ALBUMART /albumart?cacheid=496&web=Armin%20Van%20Buuren/Imagine%202008/extralarge&path=%2Fmnt%2FINTERNAL%2FArmin%20Van%20Buuren%2FImagine%202008%2FArmin%20Van%20Buuren%20-%2005%20-%20Hold%20On%20To%20Me.flac&metadata=false Oct 14 13:57:27 ferrum-broen volumio[1389]: info: URI /mnt/INTERNAL/Armin Van Buuren/Imagine 2008/Armin Van Buuren - 05 - Hold On To Me.flac Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ALBUMART /albumart?cacheid=496&web=Armin%20Van%20Buuren/Imagine%202008/extralarge&path=%2Fmnt%2FINTERNAL%2FArmin%20Van%20Buuren%2FImagine%202008%2FArmin%20Van%20Buuren%20-%2006%20-%20In%20And%20Out%20Of%20Love.flac&metadata=false Oct 14 13:57:27 ferrum-broen volumio[1389]: info: URI /mnt/INTERNAL/Armin Van Buuren/Imagine 2008/Armin Van Buuren - 06 - In And Out Of Love.flac Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ALBUMART /albumart?cacheid=496&web=Armin%20Van%20Buuren/Imagine%202008/extralarge&path=%2Fmnt%2FINTERNAL%2FArmin%20Van%20Buuren%2FImagine%202008%2FArmin%20Van%20Buuren%20-%2007%20-%20Never%20Say%20Never.flac&metadata=false Oct 14 13:57:27 ferrum-broen volumio[1389]: info: URI /mnt/INTERNAL/Armin Van Buuren/Imagine 2008/Armin Van Buuren - 07 - Never Say Never.flac Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ALBUMART /albumart?cacheid=496&web=Armin%20Van%20Buuren/Imagine%202008/extralarge&path=%2Fmnt%2FINTERNAL%2FArmin%20Van%20Buuren%2FImagine%202008%2FArmin%20Van%20Buuren%20-%2008%20-%20Rain.flac&metadata=false Oct 14 13:57:27 ferrum-broen volumio[1389]: info: URI /mnt/INTERNAL/Armin Van Buuren/Imagine 2008/Armin Van Buuren - 08 - Rain.flac Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ALBUMART /albumart?cacheid=496&web=Armin%20Van%20Buuren/Imagine%202008/extralarge&path=%2Fmnt%2FINTERNAL%2FArmin%20Van%20Buuren%2FImagine%202008%2FArmin%20Van%20Buuren%20-%2009%20-%20What%20If.flac&metadata=false Oct 14 13:57:27 ferrum-broen volumio[1389]: info: URI /mnt/INTERNAL/Armin Van Buuren/Imagine 2008/Armin Van Buuren - 09 - What If.flac Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ALBUMART /albumart?cacheid=496&web=Armin%20Van%20Buuren/Imagine%202008/extralarge&path=%2Fmnt%2FINTERNAL%2FArmin%20Van%20Buuren%2FImagine%202008%2FArmin%20Van%20Buuren%20-%2010%20-%20Fine%20Without%20You.flac&metadata=false Oct 14 13:57:27 ferrum-broen volumio[1389]: info: URI /mnt/INTERNAL/Armin Van Buuren/Imagine 2008/Armin Van Buuren - 10 - Fine Without You.flac Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ALBUMART /albumart?cacheid=496&web=Armin%20Van%20Buuren/Imagine%202008/extralarge&path=%2Fmnt%2FINTERNAL%2FArmin%20Van%20Buuren%2FImagine%202008%2FArmin%20Van%20Buuren%20-%2011%20-%20Intricacy.flac&metadata=false Oct 14 13:57:27 ferrum-broen volumio[1389]: info: URI /mnt/INTERNAL/Armin Van Buuren/Imagine 2008/Armin Van Buuren - 11 - Intricacy.flac Oct 14 13:57:27 ferrum-broen volumio[1389]: info: sendMpdCommand stop took 20 milliseconds Oct 14 13:57:27 ferrum-broen volumio[1389]: info: sendMpdCommand stop took 10 milliseconds Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushQueue Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::saveQueue Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::updateTrackBlock Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrackBlock Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Oct 14 13:57:27 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:57:27 ferrum-broen volumio[1389]: info: sendMpdCommand clear took 5 milliseconds Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand add "INTERNAL/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac" Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Oct 14 13:57:27 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:57:27 ferrum-broen volumio[1389]: error: updateQueue error: null Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Oct 14 13:57:27 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ------------------------------ 2ms Oct 14 13:57:27 ferrum-broen volumio[1389]: info: sendMpdCommand add "INTERNAL/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac" took 1 milliseconds Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ------------------------------ 1ms Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand play Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Oct 14 13:57:27 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ------------------------------ 1ms Oct 14 13:57:27 ferrum-broen volumio[1389]: info: sendMpdCommand play took 1 milliseconds Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ------------------------------ 2ms Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Oct 14 13:57:27 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Oct 14 13:57:27 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Oct 14 13:57:27 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:27 ferrum-broen volumio[1389]: info: sendMpdCommand status took 42 milliseconds Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:27 ferrum-broen volumio[1389]: info: sendMpdCommand status took 41 milliseconds Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Oct 14 13:57:27 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:27 ferrum-broen volumio[1389]: info: sendMpdCommand status took 1 milliseconds Oct 14 13:57:27 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 14 13:57:27 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 14 13:57:27 ferrum-broen volumio[1389]: info: sendMpdCommand status took 0 milliseconds Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":146,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"MQA Blue 44.1k - Puccini: O mio babbino","artist":"Jorunn Torsheim","album":"Amore e morte","uri":"INTERNAL/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac","trackType":"flac"} Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 0 Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus stop Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":146,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"597 Kbps","isStreaming":false,"title":"MQA Blue 44.1k - Puccini: O mio babbino","artist":"Jorunn Torsheim","album":"Amore e morte","uri":"INTERNAL/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac","trackType":"flac"} Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 0 Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ------------------------------ 50ms Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ------------------------------ 53ms Oct 14 13:57:27 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 12 milliseconds Oct 14 13:57:27 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 13 milliseconds Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:27 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":146,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"597 Kbps","isStreaming":false,"title":"MQA Blue 44.1k - Puccini: O mio babbino","artist":"Jorunn Torsheim","album":"Amore e morte","uri":"INTERNAL/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac","trackType":"flac"} Oct 14 13:57:27 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 0 Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:57:27 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:27 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:28 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:28 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":146,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"597 Kbps","isStreaming":false,"title":"MQA Blue 44.1k - Puccini: O mio babbino","artist":"Jorunn Torsheim","album":"Amore e morte","uri":"INTERNAL/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac","trackType":"flac"} Oct 14 13:57:28 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 0 Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:57:28 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:28 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:28 ferrum-broen volumio[1389]: info: ------------------------------ 37ms Oct 14 13:57:28 ferrum-broen volumio[1389]: info: ------------------------------ 36ms Oct 14 13:57:28 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:28 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:28 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:28 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:28 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:28 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:28 ferrum-broen volumio[1389]: info: MCU Signalled Playback Active Oct 14 13:57:30 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:57:30 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:57:30 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:57:30 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:57:30 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:57:30 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPause Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::pause Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::stPlaybackTimer Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::servicePause Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePause Oct 14 13:57:56 ferrum-broen volumio[1389]: info: ControllerMpd::pause Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand pause Oct 14 13:57:56 ferrum-broen volumio[1389]: info: Oct 14 13:57:56 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:56 ferrum-broen volumio[1389]: info: sendMpdCommand pause took 1 milliseconds Oct 14 13:57:56 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:56 ferrum-broen volumio[1389]: info: Oct 14 13:57:56 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:56 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:56 ferrum-broen volumio[1389]: info: sendMpdCommand status took 0 milliseconds Oct 14 13:57:56 ferrum-broen volumio[1389]: info: sendMpdCommand status took 0 milliseconds Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:56 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 13:57:56 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:56 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":29092,"duration":146,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"703 Kbps","isStreaming":false,"title":"MQA Blue 44.1k - Puccini: O mio babbino","artist":"Jorunn Torsheim","album":"Amore e morte","uri":"INTERNAL/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac","trackType":"flac"} Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService pause Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus pause Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::stPlaybackTimer Oct 14 13:57:56 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":29092,"duration":146,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"703 Kbps","isStreaming":false,"title":"MQA Blue 44.1k - Puccini: O mio babbino","artist":"Jorunn Torsheim","album":"Amore e morte","uri":"INTERNAL/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac","trackType":"flac"} Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService pause Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus pause Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::stPlaybackTimer Oct 14 13:57:56 ferrum-broen volumio[1389]: info: ------------------------------ 13ms Oct 14 13:57:56 ferrum-broen volumio[1389]: info: ------------------------------ 13ms Oct 14 13:57:56 ferrum-broen volumio[1389]: info: MCU Signalled Playback Inactive Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPlay Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::play index undefined Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::startPlaybackTimer Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: ControllerMpd::resume Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand play Oct 14 13:57:56 ferrum-broen volumio[1389]: info: sendMpdCommand play took 13 milliseconds Oct 14 13:57:56 ferrum-broen volumio[1389]: info: Oct 14 13:57:56 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:56 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:56 ferrum-broen volumio[1389]: info: Oct 14 13:57:56 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:57:56 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:57:56 ferrum-broen volumio[1389]: info: sendMpdCommand status took 0 milliseconds Oct 14 13:57:56 ferrum-broen volumio[1389]: info: sendMpdCommand status took 0 milliseconds Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:57:56 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 13:57:56 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:57:56 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29963,"duration":146,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"687 Kbps","isStreaming":false,"title":"MQA Blue 44.1k - Puccini: O mio babbino","artist":"Jorunn Torsheim","album":"Amore e morte","uri":"INTERNAL/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac","trackType":"flac"} Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus pause Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":29963,"duration":146,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"687 Kbps","isStreaming":false,"title":"MQA Blue 44.1k - Puccini: O mio babbino","artist":"Jorunn Torsheim","album":"Amore e morte","uri":"INTERNAL/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac","trackType":"flac"} Oct 14 13:57:56 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:57:56 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:56 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:56 ferrum-broen volumio[1389]: info: ------------------------------ 18ms Oct 14 13:57:56 ferrum-broen volumio[1389]: info: ------------------------------ 18ms Oct 14 13:57:56 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:56 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:56 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:57:56 ferrum-broen volumio[1389]: info: MCU Signalled Playback Active Oct 14 13:57:58 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:57:58 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:57:59 ferrum-broen volumio[1389]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 13:57:59 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 14 13:57:59 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 14 13:57:59 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 14 13:57:59 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 14 13:57:59 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , getAdditionalUiSection Oct 14 13:57:59 ferrum-broen volumio[1389]: info: [HEM] Getting UI Config for section: system Oct 14 13:57:59 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 14 13:58:00 ferrum-broen volumio[1389]: info: Received Get System Version Oct 14 13:58:00 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 13:58:00 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:58:00 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:58:00 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:58:00 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:58:00 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:58:00 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:58:04 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:04 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:06 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:58:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:58:06 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:58:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:58:06 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:58:06 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:58:09 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 13:58:09 ferrum-broen volumio[1389]: info: CURURI: music-library/USB Oct 14 13:58:09 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:58:10 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 14 13:58:10 ferrum-broen volumio[1389]: info: CURURI: music-library/USB/6745-2301 Oct 14 13:58:10 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::ClearQueue Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::stop Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::stPlaybackTimer Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::updateTrackBlock Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrackBlock Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::serviceStop Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::serviceStop Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ControllerMpd::stop Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::clearPlayQueue Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::saveQueue Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushQueue Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::addQueueItems Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::addQueueItems Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/1 FLAC MJ - Liberian Girl_24_384khz.wav Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/1 FLAC MJ - Liberian Girl_24_384khz.wav Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushQueue Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::saveQueue Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::updateTrackBlock Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrackBlock Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPlay Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::play index 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::addQueueItems Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::addQueueItems Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/the_time_is_now_768k_32b.wav Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/the_time_is_now_768k_32b.wav Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/06 - Dire Straits - Sultans Of Swing.dsf Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/06 - Dire Straits - Sultans Of Swing.dsf Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/02 Roxette - Sleeping In My Car.flac Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/02 Roxette - Sleeping In My Car.flac Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::stop Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushQueue Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::saveQueue Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::play index undefined Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::updateTrackBlock Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrackBlock Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::startPlaybackTimer Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::clearAddPlayTracks USB/6745-2301/1 FLAC MJ - Liberian Girl_24_384khz.wav Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Oct 14 13:58:11 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand stop took 31 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand stop took 5 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Oct 14 13:58:11 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand status took 1 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Oct 14 13:58:11 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand clear took 4 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand status took 4 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand add "USB/6745-2301/1 FLAC MJ - Liberian Girl_24_384khz.wav" Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService stop Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus stop Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: No code Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ------------------------------ 18ms Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ------------------------------ 18ms Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Oct 14 13:58:11 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:58:11 ferrum-broen volumio[1389]: error: updateQueue error: null Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Oct 14 13:58:11 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ------------------------------ 16ms Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand add "USB/6745-2301/1 FLAC MJ - Liberian Girl_24_384khz.wav" took 15 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand play Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Oct 14 13:58:11 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Oct 14 13:58:11 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ------------------------------ 11ms Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ------------------------------ 10ms Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand play took 9 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ------------------------------ 9ms Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Oct 14 13:58:11 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:58:11 ferrum-broen volumio[1389]: info: MCU Signalled Playback Inactive Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Oct 14 13:58:11 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand status took 31 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand status took 31 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Oct 14 13:58:11 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand status took 1 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand status took 1 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"384 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"1 FLAC MJ - Liberian Girl_24_384khz.wav","artist":null,"album":null,"uri":"USB/6745-2301/1 FLAC MJ - Liberian Girl_24_384khz.wav","trackType":"wav"} Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus stop Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":228,"samplerate":"384 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"18432 Kbps","isStreaming":false,"title":"1 FLAC MJ - Liberian Girl_24_384khz.wav","artist":null,"album":null,"uri":"USB/6745-2301/1 FLAC MJ - Liberian Girl_24_384khz.wav","trackType":"wav"} Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ------------------------------ 50ms Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ------------------------------ 61ms Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 30 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":223,"duration":228,"samplerate":"384 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"18432 Kbps","isStreaming":false,"title":"1 FLAC MJ - Liberian Girl_24_384khz.wav","artist":null,"album":null,"uri":"USB/6745-2301/1 FLAC MJ - Liberian Girl_24_384khz.wav","trackType":"wav"} Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":233,"duration":228,"samplerate":"384 kHz","bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"18432 Kbps","isStreaming":false,"title":"1 FLAC MJ - Liberian Girl_24_384khz.wav","artist":null,"album":null,"uri":"USB/6745-2301/1 FLAC MJ - Liberian Girl_24_384khz.wav","trackType":"wav"} Oct 14 13:58:11 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:11 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ------------------------------ 62ms Oct 14 13:58:11 ferrum-broen volumio[1389]: info: ------------------------------ 62ms Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:58:11 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:58:11 ferrum-broen volumio[1389]: info: MCU Signalled Playback Active Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::ClearQueue Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::stop Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::stPlaybackTimer Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::updateTrackBlock Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrackBlock Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::serviceStop Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 0 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::serviceStop Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ControllerMpd::stop Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::clearPlayQueue Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::saveQueue Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushQueue Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::addQueueItems Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::addQueueItems Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Preload queue cleared Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/1 FLAC MJ - Liberian Girl_24_384khz.wav Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/1 FLAC MJ - Liberian Girl_24_384khz.wav Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/the_time_is_now_768k_32b.wav Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/the_time_is_now_768k_32b.wav Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/01 Jorunn Torsheim - Puccini; O mio babbino_RSWM1_ReP16.mqa.flac Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/06 - Dire Straits - Sultans Of Swing.dsf Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/06 - Dire Straits - Sultans Of Swing.dsf Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Adding Item to queue: music-library/USB/6745-2301/02 Roxette - Sleeping In My Car.flac Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Using cached record of: music-library/USB/6745-2301/02 Roxette - Sleeping In My Car.flac Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushQueue Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::saveQueue Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::updateTrackBlock Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrackBlock Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPlay Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::play index 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::stop Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::play index undefined Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::startPlaybackTimer Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetVisibleSources Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::clearAddPlayTracks USB/6745-2301/02 Roxette - Sleeping In My Car.flac Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand stop Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Oct 14 13:58:17 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand stop took 27 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand stop took 17 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand clear Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Oct 14 13:58:17 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Oct 14 13:58:17 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand status took 1 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand clear took 1 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand status took 1 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand add "USB/6745-2301/02 Roxette - Sleeping In My Car.flac" Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService stop Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus stop Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: No code Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ------------------------------ 17ms Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Oct 14 13:58:17 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:58:17 ferrum-broen volumio[1389]: error: updateQueue error: null Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Oct 14 13:58:17 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ------------------------------ 19ms Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 19 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand add "USB/6745-2301/02 Roxette - Sleeping In My Car.flac" took 19 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ------------------------------ 3ms Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand play Oct 14 13:58:17 ferrum-broen volumio[1389]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ------------------------------ 22ms Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Oct 14 13:58:17 ferrum-broen volumio[1389]: ---------------------------- MPD announces system playlist update Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Ignoring MPD Status Update Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ------------------------------ 7ms Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand play took 6 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ------------------------------ 7ms Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Oct 14 13:58:17 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Oct 14 13:58:17 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:58:17 ferrum-broen volumio[1389]: info: MCU Signalled Playback Inactive Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Oct 14 13:58:17 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand status took 7 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand status took 7 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Oct 14 13:58:17 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand status took 2 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 4 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand status took 3 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":215,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Sleeping In My Car","artist":"Roxette","album":"XXX - The 30 Biggest Hits","uri":"USB/6745-2301/02 Roxette - Sleeping In My Car.flac","trackType":"flac"} Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus stop Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":215,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1 Kbps","isStreaming":false,"title":"Sleeping In My Car","artist":"Roxette","album":"XXX - The 30 Biggest Hits","uri":"USB/6745-2301/02 Roxette - Sleeping In My Car.flac","trackType":"flac"} Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ------------------------------ 22ms Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ------------------------------ 28ms Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 16 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 17 milliseconds Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":215,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1073 Kbps","isStreaming":false,"title":"Sleeping In My Car","artist":"Roxette","album":"XXX - The 30 Biggest Hits","uri":"USB/6745-2301/02 Roxette - Sleeping In My Car.flac","trackType":"flac"} Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"play","position":0,"seek":848,"duration":215,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1073 Kbps","isStreaming":false,"title":"Sleeping In My Car","artist":"Roxette","album":"XXX - The 30 Biggest Hits","uri":"USB/6745-2301/02 Roxette - Sleeping In My Car.flac","trackType":"flac"} Oct 14 13:58:17 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService play Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus play Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Received an update from plugin. extracting info from payload Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:17 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ------------------------------ 41ms Oct 14 13:58:17 ferrum-broen volumio[1389]: info: ------------------------------ 39ms Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:58:17 ferrum-broen volumio[1389]: info: Signalling Playback active due to playback status change Oct 14 13:58:17 ferrum-broen volumio[1389]: info: MCU Signalled Playback Active Oct 14 13:58:19 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:58:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:58:19 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:58:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:58:19 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:58:19 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:58:33 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:33 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPause Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreStateMachine::pause Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreStateMachine::stPlaybackTimer Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreStateMachine::servicePause Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePause Oct 14 13:58:34 ferrum-broen volumio[1389]: info: ControllerMpd::pause Oct 14 13:58:34 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand pause Oct 14 13:58:34 ferrum-broen volumio[1389]: info: Oct 14 13:58:34 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:58:34 ferrum-broen volumio[1389]: info: sendMpdCommand pause took 1 milliseconds Oct 14 13:58:34 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:58:34 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:58:34 ferrum-broen volumio[1389]: info: sendMpdCommand status took 1 milliseconds Oct 14 13:58:34 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:58:34 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:58:34 ferrum-broen volumio[1389]: info: Oct 14 13:58:34 ferrum-broen volumio[1389]: ---------------------------- MPD announces state update: player Oct 14 13:58:34 ferrum-broen volumio[1389]: info: ControllerMpd::getState Oct 14 13:58:34 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand status Oct 14 13:58:34 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 14 13:58:34 ferrum-broen volumio[1389]: info: sendMpdCommand status took 0 milliseconds Oct 14 13:58:34 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:58:34 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseState Oct 14 13:58:34 ferrum-broen volumio[1389]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 14 13:58:34 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:34 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17721,"duration":215,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1036 Kbps","isStreaming":false,"title":"Sleeping In My Car","artist":"Roxette","album":"XXX - The 30 Biggest Hits","uri":"USB/6745-2301/02 Roxette - Sleeping In My Car.flac","trackType":"flac"} Oct 14 13:58:34 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 4 Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService pause Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus pause Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreStateMachine::stPlaybackTimer Oct 14 13:58:34 ferrum-broen volumio[1389]: info: ------------------------------ 10ms Oct 14 13:58:34 ferrum-broen volumio[1389]: info: sendMpdCommand playlistinfo took 8 milliseconds Oct 14 13:58:34 ferrum-broen volumio[1389]: verbose: ControllerMpd::parseTrackInfo Oct 14 13:58:34 ferrum-broen volumio[1389]: info: ControllerMpd::pushState Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreCommandRouter::servicePushState Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:34 ferrum-broen volumio[1389]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":17721,"duration":215,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1036 Kbps","isStreaming":false,"title":"Sleeping In My Car","artist":"Roxette","album":"XXX - The 30 Biggest Hits","uri":"USB/6745-2301/02 Roxette - Sleeping In My Car.flac","trackType":"flac"} Oct 14 13:58:34 ferrum-broen volumio[1389]: verbose: CURRENT POSITION 4 Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState stateService pause Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreStateMachine::syncState currentStatus pause Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreStateMachine::pushState Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioPushState Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:34 ferrum-broen volumio[1389]: info: CoreStateMachine::stPlaybackTimer Oct 14 13:58:34 ferrum-broen volumio[1389]: info: ------------------------------ 18ms Oct 14 13:58:34 ferrum-broen volumio[1389]: info: MCU Signalled Playback Inactive Oct 14 13:58:35 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:58:35 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:58:35 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:58:35 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:58:35 ferrum-broen volumio[1389]: info: Executing endpoint metavolumio Oct 14 13:58:35 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Oct 14 13:58:39 ferrum-broen volumio[1389]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 14 13:58:39 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 14 13:58:39 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 14 13:58:39 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Oct 14 13:58:39 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Oct 14 13:58:39 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , getAdditionalUiSection Oct 14 13:58:39 ferrum-broen volumio[1389]: info: [HEM] Getting UI Config for section: system Oct 14 13:58:39 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 14 13:58:39 ferrum-broen volumio[1389]: info: Received Get System Version Oct 14 13:58:39 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 14 13:58:45 ferrum-broen volumio[1389]: info: Command Delete User Data Received Oct 14 13:58:45 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: inputs , setMCUFactoryReset Oct 14 13:58:45 ferrum-broen volumio[1389]: info: Signalling MCU to Factory Reset Oct 14 13:58:45 ferrum-broen volumio[1389]: info: MCU Signalled Factory reset: 1 Oct 14 13:58:45 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , handleCustomCommand Oct 14 13:58:45 ferrum-broen volumio[1389]: info: [HEM] ACTLEDDIMM received: 30 Oct 14 13:58:45 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , handleCustomCommand Oct 14 13:58:45 ferrum-broen volumio[1389]: info: [HEM] STBYLEDDIMM received: 10 Oct 14 13:58:45 ferrum-broen volumio[1389]: info: MCU Signalled Auto Boot Mode On Power Active Oct 14 13:58:45 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , handleCustomCommand Oct 14 13:58:45 ferrum-broen volumio[1389]: info: [HEM] TRIGDIR received: INPUT Oct 14 13:58:45 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: ferrumbroencontrol , handleCustomCommand Oct 14 13:58:45 ferrum-broen volumio[1389]: info: [HEM] TRIGINACTION received: 0 Oct 14 13:58:45 ferrum-broen volumio[1389]: info: CoreCommandRouter::executeOnPlugin: system , deleteUserData Oct 14 13:58:45 ferrum-broen volumio[1389]: info: Created User Data delete file, rebooting Oct 14 13:58:45 ferrum-broen volumio[1389]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Oct 14 13:58:45 ferrum-broen volumio[1389]: info: PLUGIN onReboot : networkfs Oct 14 13:58:45 ferrum-broen sudo[3501]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reboot Oct 14 13:58:45 ferrum-broen sudo[3501]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 14 13:58:45 ferrum-broen systemd-logind[1023]: The system will reboot now! Oct 14 13:58:45 ferrum-broen systemd-logind[1023]: System is rebooting. Oct 14 13:58:45 ferrum-broen sudo[3501]: pam_unix(sudo:session): session closed for user root Oct 14 13:58:45 ferrum-broen volumio[1389]: info: Rebooting Oct 14 13:58:45 ferrum-broen systemd[1]: Removed slice system-modprobe.slice - Slice /system/modprobe. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped target bluetooth.target - Bluetooth Support. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped target graphical.target - Graphical Interface. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped target ip-changed@wlan0.target - IP Address changed on wlan0. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped target rpc_pipefs.target. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped target rpcbind.target - RPC Port Mapper. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped target timers.target - Timer Units. Oct 14 13:58:45 ferrum-broen systemd[1]: apt-daily-upgrade.timer: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped apt-daily-upgrade.timer - Daily apt upgrade and clean activities. Oct 14 13:58:45 ferrum-broen systemd[1]: apt-daily.timer: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped apt-daily.timer - Daily apt download activities. Oct 14 13:58:45 ferrum-broen systemd[1]: dpkg-db-backup.timer: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped dpkg-db-backup.timer - Daily dpkg database backup timer. Oct 14 13:58:45 ferrum-broen systemd[1]: e2scrub_all.timer: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped e2scrub_all.timer - Periodic ext4 Online Metadata Check for All Filesystems. Oct 14 13:58:45 ferrum-broen systemd[1]: fstrim.timer: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped fstrim.timer - Discard unused blocks once a week. Oct 14 13:58:45 ferrum-broen systemd[1]: ntpsec-rotate-stats.timer: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped ntpsec-rotate-stats.timer - Rotate ntpd stats daily. Oct 14 13:58:45 ferrum-broen systemd[1]: setdatetime-helper.timer: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped setdatetime-helper.timer - Volumio Time Sync Watchdog Timer. Oct 14 13:58:45 ferrum-broen systemd[1]: systemd-tmpfiles-clean.timer: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped systemd-tmpfiles-clean.timer - Daily Cleanup of Temporary Directories. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped target usb-gadget.target - Hardware activated USB gadget. Oct 14 13:58:45 ferrum-broen systemd[1]: systemd-rfkill.socket: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Closed systemd-rfkill.socket - Load/Save RF Kill Switch Status /dev/rfkill Watch. Oct 14 13:58:45 ferrum-broen fsctd[1795]: [2026-10-14T11:58:45Z INFO fsct_driver::orchestrator] Orchestrator shutdown requested Oct 14 13:58:45 ferrum-broen fsctd[1795]: [2026-10-14T11:58:45Z INFO fsct_driver::ipc] IPC connection stop requested, id = de35af56-55f0-4fa5-abe6-cfb19de43628 Oct 14 13:58:45 ferrum-broen fsctd[1795]: [2026-10-14T11:58:45Z INFO fsct_driver::player_manager] Player 1 unregistered Oct 14 13:58:45 ferrum-broen fsctd[1795]: [2026-10-14T11:58:45Z INFO fsct_driver::ipc] IPC connection closed, id = de35af56-55f0-4fa5-abe6-cfb19de43628 Oct 14 13:58:45 ferrum-broen fsctd[1795]: [2026-10-14T11:58:45Z INFO fsct_driver::ipc] IPC server stopped Oct 14 13:58:45 ferrum-broen systemd[1]: Unmounting run-rpc_pipefs.mount - RPC Pipe File System... Oct 14 13:58:45 ferrum-broen bluealsa[1091]: ../src/ba-adapter.c:144: Freeing adapter: hci0 Oct 14 13:58:45 ferrum-broen systemd[1]: Stopping bluealsa.service - BlueALSA service... Oct 14 13:58:45 ferrum-broen volumio[1389]: info: [fsct] ipc connection closed Oct 14 13:58:45 ferrum-broen systemd[1]: Stopping fsct-driver.service - FSCT driver... Oct 14 13:58:45 ferrum-broen systemd[1]: Stopping mpd.service - Music Player Daemon... Oct 14 13:58:45 ferrum-broen fsctd[1795]: [2026-10-14T11:58:45Z INFO fsct_driver::usb::fsct_device] Stopping FSCT device time synchronization task Oct 14 13:58:45 ferrum-broen systemd[1]: Starting plymouth-reboot.service - Show Plymouth Reboot Screen... Oct 14 13:58:45 ferrum-broen systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Oct 14 13:58:45 ferrum-broen bluetoothd[1003]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/1 Oct 14 13:58:45 ferrum-broen qobuz-connect[3256]: 20261014 13:58:45.876 [3256.3256] INFO SampleApp: Stopping Local configuration server Oct 14 13:58:45 ferrum-broen systemd[1]: rpi-display-backlight.service - Turns off Raspberry Pi display backlight on shutdown/reboot was skipped because of an unmet condition check (ConditionPathIsDirectory=/proc/device-tree/rpi_backlight). Oct 14 13:58:45 ferrum-broen bluetoothd[1003]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/2 Oct 14 13:58:45 ferrum-broen bluetoothd[1003]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/source/2 Oct 14 13:58:45 ferrum-broen systemd[1]: Stopping systemd-random-seed.service - Load/Save Random Seed... Oct 14 13:58:45 ferrum-broen systemd[1]: Stopping volumio5-onboarding.service - Volumio5 Onboarding Server... Oct 14 13:58:45 ferrum-broen bluetoothd[1003]: Endpoint unregistered: sender=:1.6 path=/org/bluez/hci0/A2DP/SBC/sink/1 Oct 14 13:58:45 ferrum-broen systemd[1]: Stopping volumiobt.service - Volumio Bluetooth Module... Oct 14 13:58:45 ferrum-broen systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Oct 14 13:58:45 ferrum-broen volumiobt[3509]: INFO [BTSTART] Disconnecting all Bluetooth devices... Oct 14 13:58:45 ferrum-broen systemd[1]: bluealsa.service: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped bluealsa.service - BlueALSA service. Oct 14 13:58:45 ferrum-broen systemd[1]: volumio5-onboarding.service: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Oct 14 13:58:45 ferrum-broen systemd[1]: fsct-driver.service: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped fsct-driver.service - FSCT driver. Oct 14 13:58:45 ferrum-broen systemd[1]: mpd.service: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped mpd.service - Music Player Daemon. Oct 14 13:58:45 ferrum-broen systemd[1]: vtcs.service: Deactivated successfully. Oct 14 13:58:45 ferrum-broen bluetoothd[1003]: Path / reserved for Adv Monitor app :1.38 Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Oct 14 13:58:45 ferrum-broen systemd[1]: run-rpc_pipefs.mount: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Unmounted run-rpc_pipefs.mount - RPC Pipe File System. Oct 14 13:58:45 ferrum-broen bluetoothd[1003]: Adv Monitor app :1.38 disconnected from D-Bus Oct 14 13:58:45 ferrum-broen volumiobt[3523]: INFO [BTSTART] Unregistering Bluetooth agent... Oct 14 13:58:45 ferrum-broen volumiobt[3524]: No agent is registered Oct 14 13:58:45 ferrum-broen volumiobt[3524]: [NEW] Media /org/bluez/hci0 Oct 14 13:58:45 ferrum-broen volumiobt[3524]: SupportedUUIDs: 0000110a-0000-1000-8000-00805f9b34fb Oct 14 13:58:45 ferrum-broen volumiobt[3524]: SupportedUUIDs: 0000110b-0000-1000-8000-00805f9b34fb Oct 14 13:58:45 ferrum-broen volumiobt[3524]: SupportedUUIDs: 0000FDF0-0000-1000-8000-00805f9b34fb Oct 14 13:58:45 ferrum-broen bluetoothd[1003]: Adv Monitor app :1.39 disconnected from D-Bus Oct 14 13:58:45 ferrum-broen systemd[1]: systemd-random-seed.service: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped systemd-random-seed.service - Load/Save Random Seed. Oct 14 13:58:45 ferrum-broen systemd[1]: Received SIGRTMIN+20 from PID 3507 (plymouthd). Oct 14 13:58:45 ferrum-broen volumiobt[3525]: INFO [BTSTART] Stopping bluealsa-aplay (if any)... Oct 14 13:58:45 ferrum-broen volumiobt[3527]: INFO [BTSTART] Stopping A2DP agent (a2dp-agent)... Oct 14 13:58:45 ferrum-broen systemd[1]: volumiobt.service: Deactivated successfully. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped volumiobt.service - Volumio Bluetooth Module. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped target multi-user.target - Multi-User System. Oct 14 13:58:45 ferrum-broen systemd[1]: Stopped target getty.target - Login Prompts. Oct 14 13:58:45 ferrum-broen bluetoothd[1003]: Terminating Oct 14 13:58:45 ferrum-broen systemd[1]: Stopping bluetooth.service - Bluetooth service... Oct 14 13:58:45 ferrum-broen systemd[1]: Stopping cpufrequtils.service - LSB: set CPUFreq kernel parameters... Oct 14 13:58:45 ferrum-broen bluetoothd[1003]: Destroy Adv Monitor Manager Oct 14 13:58:45 ferrum-broen bluetoothd[1003]: Battery Provider Manager destroyed Oct 14 13:58:45 ferrum-broen systemd[1]: Stopping ferrum-usb-gadget.service - Ferrum BROEN minimal USB HID gadget (D056-60)... Oct 14 13:58:45 ferrum-broen volumio[1389]: ------------------------------------ BT MESSAGE: setWinningMac: none Oct 14 13:58:45 ferrum-broen bluetoothd[1003]: Stopping SDP server Oct 14 13:58:45 ferrum-broen bluetoothd[1003]: Exit Oct 14 13:58:45 ferrum-broen ntpd[1199]: ERR: ntpd exiting on signal 15 (Terminated) Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 194.146.251.114 unlink local addr 192.168.0.53 -> Oct 14 13:58:45 ferrum-broen volumio[1389]: ------------------------------------ BT MESSAGE: setWinningMac: none Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 156.17.245.123 unlink local addr 192.168.0.53 -> Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 89.161.47.133 unlink local addr 192.168.0.53 -> Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 149.156.70.60 unlink local addr 192.168.0.53 -> Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 195.46.37.22 unlink local addr 192.168.0.53 -> Oct 14 13:58:45 ferrum-broen volumio[1389]: ------------------------------------ BT MESSAGE: setWinningMac: none Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 212.127.95.218 unlink local addr 192.168.0.53 -> Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 213.222.217.10 unlink local addr 192.168.0.53 -> Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 51.38.131.245 unlink local addr 192.168.0.53 -> Oct 14 13:58:45 ferrum-broen volumio[1389]: ------------------------------------ BT MESSAGE: setWinningMac: none Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 51.68.141.5 unlink local addr 192.168.0.53 -> Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 46.175.224.7 unlink local addr 192.168.0.53 -> Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 162.159.200.123 unlink local addr 192.168.0.53 -> Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 85.115.212.251 unlink local addr 192.168.0.53 -> Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 193.70.94.182 unlink local addr 192.168.0.53 -> Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 194.146.251.102 unlink local addr 192.168.0.53 -> Oct 14 13:58:45 ferrum-broen ntpd[1199]: PROTO: 89.161.47.132 unlink local addr 192.168.0.53 -> Oct 14 13:58:46 ferrum-broen usb_gadget.sh[3530]: Stopping HID gadget... Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping ntpsec.service - Network Time Service... Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping p056-sfpmgr.service - P056 SFP Manager Daemon... Oct 14 13:58:46 ferrum-broen systemd[1]: plymouth-quit.service: Deactivated successfully. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped plymouth-quit.service - Terminate Plymouth Boot Screen. Oct 14 13:58:46 ferrum-broen systemd[1]: rpi-eeprom-update.service: Deactivated successfully. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped rpi-eeprom-update.service - Check for Raspberry Pi EEPROM updates. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping serial-getty@ttyAMA0.service - Serial Getty on ttyAMA0... Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping smbd.service - Samba SMB Daemon... Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping systemd-logind.service - User Login Management... Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping triggerhappy.service - triggerhappy global hotkey daemon... Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping volumio-ipchange.service - Volumio IP Change Monitor... Oct 14 13:58:46 ferrum-broen p056-sfpmgr[1175]: Received termination signal, exiting... Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping volumio-kiosk.service - Start Volumio Kiosk... Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping volumio-remote-updater.service - Volumio Remote OTA Updater... Oct 14 13:58:46 ferrum-broen systemd[1]: welcome.service: Deactivated successfully. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped welcome.service - Show a welcome message on console. Oct 14 13:58:46 ferrum-broen systemd[1]: bluetooth.service: Deactivated successfully. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped bluetooth.service - Bluetooth service. Oct 14 13:58:46 ferrum-broen startkiosk.sh[1431]: xinit: connection to X server lost Oct 14 13:58:46 ferrum-broen startkiosk.sh[1431]: Oct 14 13:58:46 ferrum-broen systemd[1]: volumio-remote-updater.service: Deactivated successfully. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped volumio-remote-updater.service - Volumio Remote OTA Updater. Oct 14 13:58:46 ferrum-broen systemd[1]: p056-sfpmgr.service: Deactivated successfully. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped p056-sfpmgr.service - P056 SFP Manager Daemon. Oct 14 13:58:46 ferrum-broen systemd[1]: volumio-ipchange.service: Deactivated successfully. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped volumio-ipchange.service - Volumio IP Change Monitor. Oct 14 13:58:46 ferrum-broen systemd[1]: ntpsec.service: Deactivated successfully. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped ntpsec.service - Network Time Service. Oct 14 13:58:46 ferrum-broen sudo[1778]: pam_unix(sudo:session): session closed for user root Oct 14 13:58:46 ferrum-broen systemd[1]: smbd.service: Deactivated successfully. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped smbd.service - Samba SMB Daemon. Oct 14 13:58:46 ferrum-broen systemd[1]: shairport-sync.service: Deactivated successfully. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Oct 14 13:58:46 ferrum-broen systemd[1]: shairport-sync.service: Consumed 1.603s CPU time. Oct 14 13:58:46 ferrum-broen volumio[1389]: info: Touch Event Listener Process Closed Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped target nss-lookup.target - Host and Network Name Lookups. Oct 14 13:58:46 ferrum-broen avahi-daemon[998]: Got SIGTERM, quitting. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped target sound.target - Sound Card. Oct 14 13:58:46 ferrum-broen avahi-daemon[998]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Oct 14 13:58:46 ferrum-broen avahi-daemon[998]: Leaving mDNS multicast group on interface eth0.IPv4 with address 192.168.0.53. Oct 14 13:58:46 ferrum-broen avahi-daemon[998]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping dnsmasq.service - dnsmasq - A lightweight DHCP and caching DNS server... Oct 14 13:58:46 ferrum-broen volumio[1389]: info: Discovery: Browse raised the following error Error: dns service error: unknown Oct 14 13:58:46 ferrum-broen volumio[1389]: info: Discovery: Restarting Browsing Oct 14 13:58:46 ferrum-broen volumio[1389]: error: Discovery: Advertisement error: Error: dns service error: unknown Oct 14 13:58:46 ferrum-broen volumio[1389]: error: Discovery: advertisement error: Error: dns service error: unknown Oct 14 13:58:46 ferrum-broen volumio[1389]: info: Discovery: Advertisement raised the following error Error: dns service error: unknown Oct 14 13:58:46 ferrum-broen volumio[1389]: info: Discovery: Stopping Advertising Immediately Oct 14 13:58:46 ferrum-broen volumio[1389]: info: Discovery: Stopping existing advertisement Oct 14 13:58:46 ferrum-broen winbindd[1445]: [2026/10/14 13:58:46.055141, 0] ../../source3/winbindd/winbindd_dual.c:1950(winbindd_sig_term_handler) Oct 14 13:58:46 ferrum-broen winbindd[1445]: Got sig[15] terminate (is_parent=1) Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping winbind.service - Samba Winbind Daemon... Oct 14 13:58:46 ferrum-broen winbindd[1448]: [2026/10/14 13:58:46.056741, 0] ../../source3/winbindd/winbindd_dual.c:1950(winbindd_sig_term_handler) Oct 14 13:58:46 ferrum-broen winbindd[1448]: Got sig[15] terminate (is_parent=0) Oct 14 13:58:46 ferrum-broen dnsmasq[1439]: exiting on receipt of SIGTERM Oct 14 13:58:46 ferrum-broen systemd[1]: winbind.service: Deactivated successfully. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped winbind.service - Samba Winbind Daemon. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping nmbd.service - Samba NMB Daemon... Oct 14 13:58:46 ferrum-broen nmbd[1278]: [2026/10/14 13:58:46.063131, 0] ../../source3/nmbd/nmbd.c:59(terminate) Oct 14 13:58:46 ferrum-broen nmbd[1278]: Got SIGTERM: going down... Oct 14 13:58:46 ferrum-broen systemd[1]: nmbd.service: Deactivated successfully. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped nmbd.service - Samba NMB Daemon. Oct 14 13:58:46 ferrum-broen systemd[1]: Started plymouth-reboot.service - Show Plymouth Reboot Screen. Oct 14 13:58:46 ferrum-broen systemd[1]: plymouth-switch-root-initramfs.service - Tell Plymouth To Jump To initramfs was skipped because of an unmet condition check (ConditionPathExists=/run/initramfs/bin/sh). Oct 14 13:58:46 ferrum-broen systemd[1]: cpufrequtils.service: Deactivated successfully. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped cpufrequtils.service - LSB: set CPUFreq kernel parameters. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopping loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling... Oct 14 13:58:46 ferrum-broen systemd[1]: loadcpufreq.service: Deactivated successfully. Oct 14 13:58:46 ferrum-broen systemd[1]: Stopped loadcpufreq.service - LSB: Load kernel modules needed to enable cpufreq scaling. Oct 14 13:58:47 ferrum-broen qobuz-connect[3256]: 20261014 13:58:47.450 [3256.3256] INFO SampleApp: shat down connection on UNIX socket Oct 14 13:58:47 ferrum-broen volumio[1389]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Oct 14 13:58:47 ferrum-broen volumio[1389]: info: QobuzConnect: setDeactiveState invoked Oct 14 13:58:47 ferrum-broen volumio[1389]: info: CoreCommandRouter::volumioGetState Oct 14 13:58:47 ferrum-broen volumio[1389]: info: CorePlayQueue::getTrack 4 Oct 14 13:58:47 ferrum-broen systemd[1]: qobuz-connect.service: Deactivated successfully. Oct 14 13:58:47 ferrum-broen systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Oct 14 13:58:47 ferrum-broen volumio[1389]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 13:58:47 ferrum-broen volumio[1389]: Error: read ECONNRESET Oct 14 13:58:47 ferrum-broen volumio[1389]: at Pipe.onStreamRead (node:internal/stream_base_commons:217:20) { Oct 14 13:58:47 ferrum-broen volumio[1389]: errno: -104, Oct 14 13:58:47 ferrum-broen volumio[1389]: code: 'ECONNRESET', Oct 14 13:58:47 ferrum-broen volumio[1389]: syscall: 'read' Oct 14 13:58:47 ferrum-broen volumio[1389]: } Oct 14 13:58:47 ferrum-broen volumio[1389]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 14 13:58:48 ferrum-broen sudo[3563]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-14 13:57' Oct 14 13:58:48 ferrum-broen sudo[3563]: 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="ebd772339e193fb4b3a98c68e63a0009e72d6f27" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0a52c1fb90959ba1872a2fb0225e56fe615b19ac" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="ferrumbroen" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Sep 15 13:15:23 UTC 2026" VOLUMIO_VERSION="4.212" VOLUMIO_HARDWARE="cm5" VOLUMIO_DEVICENAME="CM5" VOLUMIO_HASH="ae65edc9c21957a5515c838ebac278ee"