Oct 03 18:45:09 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 03 18:45:09 volumio volumio[1115]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 03 18:45:09 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 03 18:45:09 volumio volumio[1115]: info: Received Get System Version Oct 03 18:45:09 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 03 18:45:09 volumio volumio[1115]: info: Received Get System Info Oct 03 18:45:09 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 03 18:45:09 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 03 18:45:09 volumio volumio[1115]: info: Discovery: Getting this device information Oct 03 18:45:09 volumio volumio[1115]: info: CoreCommandRouter::volumioGetState Oct 03 18:45:09 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:45:09 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 03 18:45:41 volumio volumio[1115]: info: Clearing queue after UPNP request Oct 03 18:45:42 volumio volumio[1115]: info: CoreStateMachine::ClearQueue Oct 03 18:45:42 volumio volumio[1115]: info: CoreStateMachine::stop Oct 03 18:45:42 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 18:45:42 volumio volumio[1115]: info: CorePlayQueue::clearPlayQueue Oct 03 18:45:42 volumio volumio[1115]: info: CorePlayQueue::saveQueue Oct 03 18:45:42 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:45:42 volumio volumio[1115]: info: CoreCommandRouter::volumioPushQueue Oct 03 18:45:42 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:45:42 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:45:43 volumio volumio[1115]: info: Starting UPNP Playback Oct 03 18:45:43 volumio volumio[1115]: info: Preparing playback through UPNP Oct 03 18:45:43 volumio volumio[1115]: info: CoreCommandRouter::volumioGetState Oct 03 18:45:43 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:45:43 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 03 18:45:44 volumio volumio[1115]: info: Oct 03 18:45:44 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:45:44 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:45:44 volumio volumio[1115]: info: ------------------------------ 1ms Oct 03 18:45:44 volumio volumio[1115]: info: Oct 03 18:45:44 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:45:44 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:45:44 volumio volumio[1115]: info: ------------------------------ 0ms Oct 03 18:45:44 volumio volumio[1115]: info: Oct 03 18:45:44 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:45:44 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:45:44 volumio volumio[1115]: info: ------------------------------ 0ms Oct 03 18:45:44 volumio volumio[1115]: info: Oct 03 18:45:44 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:45:44 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:45:44 volumio volumio[1115]: info: ------------------------------ 0ms Oct 03 18:45:44 volumio volumio[1115]: info: Oct 03 18:45:44 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:45:44 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:45:44 volumio volumio[1115]: info: ------------------------------ 0ms Oct 03 18:45:44 volumio volumio[1115]: info: Oct 03 18:45:44 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:45:44 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:45:44 volumio volumio[1115]: info: ------------------------------ 1ms Oct 03 18:45:44 volumio volumio[1115]: info: Oct 03 18:45:44 volumio volumio[1115]: ---------------------------- MPD announces state update: player Oct 03 18:45:44 volumio volumio[1115]: info: ControllerMpd::getState Oct 03 18:45:44 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand status Oct 03 18:45:44 volumio volumio[1115]: info: Oct 03 18:45:44 volumio volumio[1115]: ---------------------------- MPD announces state update: player Oct 03 18:45:44 volumio volumio[1115]: info: sendMpdCommand status took 3 milliseconds Oct 03 18:45:44 volumio volumio[1115]: info: ControllerMpd::getState Oct 03 18:45:44 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand status Oct 03 18:45:44 volumio volumio[1115]: verbose: ControllerMpd::parseState Oct 03 18:45:44 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 18:45:44 volumio volumio[1115]: info: sendMpdCommand status took 1 milliseconds Oct 03 18:45:44 volumio volumio[1115]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 03 18:45:44 volumio volumio[1115]: verbose: ControllerMpd::parseState Oct 03 18:45:44 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 18:45:44 volumio volumio[1115]: verbose: ControllerMpd::parseTrackInfo Oct 03 18:45:44 volumio volumio[1115]: info: ControllerMpd::pushState Oct 03 18:45:44 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Oct 03 18:45:44 volumio volumio[1115]: verbose: In UPNP mode Oct 03 18:45:44 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Qua Cơn Mê","artist":"Hoàng Lan","album":"Qua Cơn Mê","uri":"http://192.168.1.33:16018/mconnect/tidal/375720947.flac","trackType":"tidal"} Oct 03 18:45:44 volumio volumio[1115]: verbose: CURRENT POSITION 0 Oct 03 18:45:44 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Oct 03 18:45:44 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus stop Oct 03 18:45:44 volumio volumio[1115]: info: ------------------------------ 7ms Oct 03 18:45:44 volumio volumio[1115]: info: sendMpdCommand playlistinfo took 2 milliseconds Oct 03 18:45:44 volumio volumio[1115]: verbose: ControllerMpd::parseTrackInfo Oct 03 18:45:44 volumio volumio[1115]: info: ControllerMpd::pushState Oct 03 18:45:44 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Oct 03 18:45:44 volumio volumio[1115]: verbose: In UPNP mode Oct 03 18:45:44 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Qua Cơn Mê","artist":"Hoàng Lan","album":"Qua Cơn Mê","uri":"http://192.168.1.33:16018/mconnect/tidal/375720947.flac","trackType":"tidal"} Oct 03 18:45:44 volumio volumio[1115]: verbose: CURRENT POSITION 0 Oct 03 18:45:44 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Oct 03 18:45:44 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus play Oct 03 18:45:44 volumio volumio[1115]: info: Received an update from plugin. extracting info from payload Oct 03 18:45:44 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 03 18:45:44 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:45:44 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 18:45:44 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:45:44 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:45:44 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:45:44 volumio volumio[1115]: info: ------------------------------ 11ms Oct 03 18:45:44 volumio volumio[1115]: info: FusionDsp - Volumio is playing Oct 03 18:45:44 volumio volumio[1115]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Oct 03 18:45:44 volumio volumio[1115]: info: FusionDsp - Volumio is playing Oct 03 18:45:44 volumio volumio[1115]: info: CoreCommandRouter::volumioGetQueue Oct 03 18:45:44 volumio volumio[1115]: info: CoreStateMachine::getQueue Oct 03 18:45:44 volumio volumio[1115]: info: CorePlayQueue::getQueue Oct 03 18:45:44 volumio volumio[1115]: info: CoreCommandRouter::volumioGetQueue Oct 03 18:45:44 volumio volumio[1115]: info: CoreStateMachine::getQueue Oct 03 18:45:44 volumio volumio[1115]: info: CorePlayQueue::getQueue Oct 03 18:45:44 volumio volumio[1115]: info: FusionDsp - Clipping Monitor started Oct 03 18:45:44 volumio volumio[1115]: info: FusionDsp - Clipping Monitor started Oct 03 18:45:44 volumio volumio[1115]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Oct 03 18:45:44 volumio volumio[1115]: info: FusionDsp - ---- read samplerate from file: 384000 Oct 03 18:45:44 volumio volumio[1115]: info: camilladsp stopping service pid 2223... Oct 03 18:45:45 volumio volumio[1115]: grep: /proc/2223/cmdline: binary file matches Oct 03 18:45:45 volumio volumio[1115]: info: camilladsp service terminated, instance 1 Oct 03 18:45:45 volumio volumio[1115]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 03 18:45:45 volumio volumio[1115]: info: camilladsp service started and running in background, instance 1 Oct 03 18:45:45 volumio volumio[1115]: info: FusionDsp - Clipping Monitor reconnecting in 2000ms Oct 03 18:45:45 volumio volumio[1115]: info: FusionDsp - Clipping Monitor reconnecting in 4000ms Oct 03 18:45:45 volumio volumio[1115]: info: camilladsp respawn in 100 ms (attempt 1/10) Oct 03 18:45:47 volumio volumio[1115]: info: FusionDsp - Clipping Monitor started Oct 03 18:46:03 volumio volumio[1115]: info: Oct 03 18:46:03 volumio volumio[1115]: ---------------------------- MPD announces state update: player Oct 03 18:46:03 volumio volumio[1115]: info: ControllerMpd::getState Oct 03 18:46:03 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand status Oct 03 18:46:03 volumio volumio[1115]: info: sendMpdCommand status took 0 milliseconds Oct 03 18:46:03 volumio volumio[1115]: verbose: ControllerMpd::parseState Oct 03 18:46:03 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 18:46:03 volumio volumio[1115]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 03 18:46:03 volumio volumio[1115]: verbose: ControllerMpd::parseTrackInfo Oct 03 18:46:03 volumio volumio[1115]: info: ControllerMpd::pushState Oct 03 18:46:03 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Oct 03 18:46:03 volumio volumio[1115]: verbose: In UPNP mode Oct 03 18:46:03 volumio volumio[1115]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":16606,"duration":254,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1753 Kbps","isStreaming":false,"title":"Qua Cơn Mê","artist":"Hoàng Lan","album":"Qua Cơn Mê","uri":"http://192.168.1.33:16018/mconnect/tidal/375720947.flac","trackType":"tidal"} Oct 03 18:46:03 volumio volumio[1115]: verbose: CURRENT POSITION 0 Oct 03 18:46:03 volumio volumio[1115]: info: CoreStateMachine::syncState stateService pause Oct 03 18:46:03 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus play Oct 03 18:46:03 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:46:03 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 18:46:03 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:03 volumio volumio[1115]: info: CoreStateMachine::stPlaybackTimer Oct 03 18:46:03 volumio volumio[1115]: info: ------------------------------ 5ms Oct 03 18:46:03 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:03 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:03 volumio volumio[1115]: info: CoreCommandRouter::volumioGetQueue Oct 03 18:46:03 volumio volumio[1115]: info: CoreStateMachine::getQueue Oct 03 18:46:03 volumio volumio[1115]: info: CorePlayQueue::getQueue Oct 03 18:46:04 volumio volumio[1115]: info: camilladsp respawn in 200 ms (attempt 2/10) Oct 03 18:46:05 volumio volumio[1115]: info: Oct 03 18:46:05 volumio volumio[1115]: ---------------------------- MPD announces state update: player Oct 03 18:46:05 volumio volumio[1115]: info: ControllerMpd::getState Oct 03 18:46:05 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand status Oct 03 18:46:05 volumio volumio[1115]: info: sendMpdCommand status took 1 milliseconds Oct 03 18:46:05 volumio volumio[1115]: verbose: ControllerMpd::parseState Oct 03 18:46:05 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 18:46:05 volumio volumio[1115]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 03 18:46:05 volumio volumio[1115]: verbose: ControllerMpd::parseTrackInfo Oct 03 18:46:05 volumio volumio[1115]: info: ControllerMpd::pushState Oct 03 18:46:05 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Oct 03 18:46:05 volumio volumio[1115]: verbose: In UPNP mode Oct 03 18:46:05 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","position":0,"seek":16618,"duration":254,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1764 Kbps","isStreaming":false,"title":"Qua Cơn Mê","artist":"Hoàng Lan","album":"Qua Cơn Mê","uri":"http://192.168.1.33:16018/mconnect/tidal/375720947.flac","trackType":"tidal"} Oct 03 18:46:05 volumio volumio[1115]: verbose: CURRENT POSITION 0 Oct 03 18:46:05 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Oct 03 18:46:05 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus play Oct 03 18:46:05 volumio volumio[1115]: info: Received an update from plugin. extracting info from payload Oct 03 18:46:05 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 03 18:46:05 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:46:05 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 18:46:05 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:05 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:46:05 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:05 volumio volumio[1115]: info: ------------------------------ 15ms Oct 03 18:46:05 volumio volumio[1115]: info: FusionDsp - Volumio is playing Oct 03 18:46:05 volumio volumio[1115]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Oct 03 18:46:05 volumio volumio[1115]: info: FusionDsp - Volumio is playing Oct 03 18:46:05 volumio volumio[1115]: info: CoreCommandRouter::volumioGetQueue Oct 03 18:46:05 volumio volumio[1115]: info: CoreStateMachine::getQueue Oct 03 18:46:05 volumio volumio[1115]: info: CorePlayQueue::getQueue Oct 03 18:46:05 volumio volumio[1115]: info: CoreCommandRouter::volumioGetQueue Oct 03 18:46:05 volumio volumio[1115]: info: CoreStateMachine::getQueue Oct 03 18:46:05 volumio volumio[1115]: info: CorePlayQueue::getQueue Oct 03 18:46:05 volumio volumio[1115]: info: FusionDsp - Clipping Monitor started Oct 03 18:46:05 volumio volumio[1115]: info: FusionDsp - Clipping Monitor started Oct 03 18:46:05 volumio volumio[1115]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Oct 03 18:46:05 volumio volumio[1115]: info: FusionDsp - ---- read samplerate from file: 384000 Oct 03 18:46:05 volumio volumio[1115]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 03 18:46:10 volumio volumio[1115]: info: Clearing queue after UPNP request Oct 03 18:46:10 volumio volumio[1115]: info: CoreStateMachine::ClearQueue Oct 03 18:46:10 volumio volumio[1115]: info: CoreStateMachine::stop Oct 03 18:46:10 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 18:46:10 volumio volumio[1115]: info: CoreStateMachine::stPlaybackTimer Oct 03 18:46:10 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Oct 03 18:46:10 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Oct 03 18:46:10 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:46:10 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:46:10 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 18:46:10 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:10 volumio volumio[1115]: info: CoreStateMachine::serviceStop Oct 03 18:46:10 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:46:10 volumio volumio[1115]: info: ControllerMpd::stop Oct 03 18:46:10 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 18:46:10 volumio volumio[1115]: info: CorePlayQueue::clearPlayQueue Oct 03 18:46:10 volumio volumio[1115]: info: CorePlayQueue::saveQueue Oct 03 18:46:10 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:10 volumio volumio[1115]: info: CoreCommandRouter::volumioPushQueue Oct 03 18:46:10 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:10 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:10 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:10 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:10 volumio volumio[1115]: info: Oct 03 18:46:10 volumio volumio[1115]: ---------------------------- MPD announces state update: player Oct 03 18:46:10 volumio volumio[1115]: info: sendMpdCommand stop took 11 milliseconds Oct 03 18:46:10 volumio volumio[1115]: info: ControllerMpd::getState Oct 03 18:46:10 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand status Oct 03 18:46:10 volumio volumio[1115]: info: Oct 03 18:46:10 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:46:10 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:46:10 volumio volumio[1115]: info: sendMpdCommand status took 1 milliseconds Oct 03 18:46:10 volumio volumio[1115]: verbose: ControllerMpd::parseState Oct 03 18:46:10 volumio volumio[1115]: info: ControllerMpd::pushState Oct 03 18:46:10 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Oct 03 18:46:10 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:46:10 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:46:10 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:10 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:46:10 volumio volumio[1115]: 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 03 18:46:10 volumio volumio[1115]: verbose: CURRENT POSITION 0 Oct 03 18:46:10 volumio volumio[1115]: info: CoreStateMachine::syncState stateService stop Oct 03 18:46:10 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus stop Oct 03 18:46:10 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:46:10 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:46:10 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:10 volumio volumio[1115]: info: No code Oct 03 18:46:10 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:46:10 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:46:10 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:10 volumio volumio[1115]: info: ------------------------------ 11ms Oct 03 18:46:10 volumio volumio[1115]: error: updateQueue error: null Oct 03 18:46:10 volumio volumio[1115]: info: ------------------------------ 10ms Oct 03 18:46:10 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:10 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:10 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:10 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:10 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:10 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:10 volumio volumio[1115]: info: camilladsp respawn in 400 ms (attempt 3/10) Oct 03 18:46:22 volumio volumio[1115]: info: Clearing queue after UPNP request Oct 03 18:46:22 volumio volumio[1115]: info: CoreStateMachine::ClearQueue Oct 03 18:46:22 volumio volumio[1115]: info: CoreStateMachine::stop Oct 03 18:46:22 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 18:46:22 volumio volumio[1115]: info: CorePlayQueue::clearPlayQueue Oct 03 18:46:22 volumio volumio[1115]: info: CorePlayQueue::saveQueue Oct 03 18:46:22 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:22 volumio volumio[1115]: info: CoreCommandRouter::volumioPushQueue Oct 03 18:46:22 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:22 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:22 volumio volumio[1115]: info: Oct 03 18:46:22 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:46:22 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:46:22 volumio volumio[1115]: error: updateQueue error: null Oct 03 18:46:22 volumio volumio[1115]: info: ------------------------------ 0ms Oct 03 18:46:23 volumio volumio[1115]: info: Clearing queue after UPNP request Oct 03 18:46:23 volumio volumio[1115]: info: CoreStateMachine::ClearQueue Oct 03 18:46:23 volumio volumio[1115]: info: CoreStateMachine::stop Oct 03 18:46:23 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 18:46:23 volumio volumio[1115]: info: CorePlayQueue::clearPlayQueue Oct 03 18:46:23 volumio volumio[1115]: info: CorePlayQueue::saveQueue Oct 03 18:46:23 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:23 volumio volumio[1115]: info: CoreCommandRouter::volumioPushQueue Oct 03 18:46:23 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:23 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:23 volumio volumio[1115]: info: Oct 03 18:46:23 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:46:23 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:46:23 volumio volumio[1115]: error: updateQueue error: null Oct 03 18:46:23 volumio volumio[1115]: info: ------------------------------ 0ms Oct 03 18:46:23 volumio volumio[1115]: info: Clearing queue after UPNP request Oct 03 18:46:24 volumio volumio[1115]: info: CoreStateMachine::ClearQueue Oct 03 18:46:24 volumio volumio[1115]: info: CoreStateMachine::stop Oct 03 18:46:24 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 18:46:24 volumio volumio[1115]: info: CorePlayQueue::clearPlayQueue Oct 03 18:46:24 volumio volumio[1115]: info: CorePlayQueue::saveQueue Oct 03 18:46:24 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:24 volumio volumio[1115]: info: CoreCommandRouter::volumioPushQueue Oct 03 18:46:24 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:24 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:24 volumio volumio[1115]: info: Oct 03 18:46:24 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:46:24 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:46:24 volumio volumio[1115]: error: updateQueue error: null Oct 03 18:46:24 volumio volumio[1115]: info: ------------------------------ 1ms Oct 03 18:46:24 volumio volumio[1115]: info: Clearing queue after UPNP request Oct 03 18:46:24 volumio volumio[1115]: info: CoreStateMachine::ClearQueue Oct 03 18:46:24 volumio volumio[1115]: info: CoreStateMachine::stop Oct 03 18:46:24 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 18:46:24 volumio volumio[1115]: info: CorePlayQueue::clearPlayQueue Oct 03 18:46:24 volumio volumio[1115]: info: CorePlayQueue::saveQueue Oct 03 18:46:24 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:24 volumio volumio[1115]: info: CoreCommandRouter::volumioPushQueue Oct 03 18:46:24 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:24 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:24 volumio volumio[1115]: info: Oct 03 18:46:24 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:46:24 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:46:24 volumio volumio[1115]: error: updateQueue error: null Oct 03 18:46:24 volumio volumio[1115]: info: ------------------------------ 1ms Oct 03 18:46:24 volumio volumio[1115]: info: Starting UPNP Playback Oct 03 18:46:24 volumio volumio[1115]: info: Preparing playback through UPNP Oct 03 18:46:24 volumio volumio[1115]: info: CoreCommandRouter::volumioGetState Oct 03 18:46:24 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:46:24 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService mpd Oct 03 18:46:25 volumio volumio[1115]: info: Oct 03 18:46:25 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:46:25 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:46:25 volumio volumio[1115]: info: ------------------------------ 1ms Oct 03 18:46:25 volumio volumio[1115]: info: Oct 03 18:46:25 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:46:25 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:46:25 volumio volumio[1115]: info: ------------------------------ 1ms Oct 03 18:46:25 volumio volumio[1115]: info: Oct 03 18:46:25 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:46:25 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:46:25 volumio volumio[1115]: info: ------------------------------ 0ms Oct 03 18:46:25 volumio volumio[1115]: info: Oct 03 18:46:25 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:46:25 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:46:25 volumio volumio[1115]: info: ------------------------------ 0ms Oct 03 18:46:25 volumio volumio[1115]: info: Oct 03 18:46:25 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:46:25 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:46:25 volumio volumio[1115]: info: ------------------------------ 0ms Oct 03 18:46:25 volumio volumio[1115]: info: Oct 03 18:46:25 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:46:25 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:46:25 volumio volumio[1115]: info: ------------------------------ 0ms Oct 03 18:46:25 volumio volumio[1115]: info: Oct 03 18:46:25 volumio volumio[1115]: ---------------------------- MPD announces state update: player Oct 03 18:46:25 volumio volumio[1115]: info: ControllerMpd::getState Oct 03 18:46:25 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand status Oct 03 18:46:25 volumio volumio[1115]: info: Oct 03 18:46:25 volumio volumio[1115]: ---------------------------- MPD announces state update: player Oct 03 18:46:25 volumio volumio[1115]: info: sendMpdCommand status took 2 milliseconds Oct 03 18:46:25 volumio volumio[1115]: info: ControllerMpd::getState Oct 03 18:46:25 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand status Oct 03 18:46:25 volumio volumio[1115]: verbose: ControllerMpd::parseState Oct 03 18:46:25 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 18:46:25 volumio volumio[1115]: info: sendMpdCommand status took 1 milliseconds Oct 03 18:46:25 volumio volumio[1115]: info: sendMpdCommand playlistinfo took 1 milliseconds Oct 03 18:46:25 volumio volumio[1115]: verbose: ControllerMpd::parseState Oct 03 18:46:25 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 03 18:46:25 volumio volumio[1115]: verbose: ControllerMpd::parseTrackInfo Oct 03 18:46:25 volumio volumio[1115]: info: ControllerMpd::pushState Oct 03 18:46:25 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Oct 03 18:46:25 volumio volumio[1115]: verbose: In UPNP mode Oct 03 18:46:25 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Qua Cơn Mê","artist":"Hoàng Lan","album":"Qua Cơn Mê","uri":"http://192.168.1.33:16018/mconnect/tidal/375720947.flac","trackType":"tidal"} Oct 03 18:46:25 volumio volumio[1115]: verbose: CURRENT POSITION 0 Oct 03 18:46:25 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Oct 03 18:46:25 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus stop Oct 03 18:46:25 volumio volumio[1115]: info: ------------------------------ 4ms Oct 03 18:46:25 volumio volumio[1115]: info: sendMpdCommand playlistinfo took 0 milliseconds Oct 03 18:46:25 volumio volumio[1115]: verbose: ControllerMpd::parseTrackInfo Oct 03 18:46:25 volumio volumio[1115]: info: ControllerMpd::pushState Oct 03 18:46:25 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Oct 03 18:46:25 volumio volumio[1115]: verbose: In UPNP mode Oct 03 18:46:25 volumio volumio[1115]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":254,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"Qua Cơn Mê","artist":"Hoàng Lan","album":"Qua Cơn Mê","uri":"http://192.168.1.33:16018/mconnect/tidal/375720947.flac","trackType":"tidal"} Oct 03 18:46:25 volumio volumio[1115]: verbose: CURRENT POSITION 0 Oct 03 18:46:25 volumio volumio[1115]: info: CoreStateMachine::syncState stateService play Oct 03 18:46:25 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus play Oct 03 18:46:25 volumio volumio[1115]: info: Received an update from plugin. extracting info from payload Oct 03 18:46:25 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Oct 03 18:46:25 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:46:25 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 18:46:25 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:25 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:46:25 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:25 volumio volumio[1115]: info: ------------------------------ 7ms Oct 03 18:46:25 volumio volumio[1115]: info: FusionDsp - Volumio is playing Oct 03 18:46:25 volumio volumio[1115]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Oct 03 18:46:25 volumio volumio[1115]: info: FusionDsp - Volumio is playing Oct 03 18:46:25 volumio volumio[1115]: info: CoreCommandRouter::volumioGetQueue Oct 03 18:46:25 volumio volumio[1115]: info: CoreStateMachine::getQueue Oct 03 18:46:25 volumio volumio[1115]: info: CorePlayQueue::getQueue Oct 03 18:46:25 volumio volumio[1115]: info: CoreCommandRouter::volumioGetQueue Oct 03 18:46:25 volumio volumio[1115]: info: CoreStateMachine::getQueue Oct 03 18:46:25 volumio volumio[1115]: info: CorePlayQueue::getQueue Oct 03 18:46:25 volumio volumio[1115]: info: FusionDsp - Clipping Monitor started Oct 03 18:46:25 volumio volumio[1115]: info: FusionDsp - Clipping Monitor started Oct 03 18:46:25 volumio volumio[1115]: info: FusionDsp - ---- read samplerate, raw: 384000,S32_LE,2,32 Oct 03 18:46:25 volumio volumio[1115]: info: FusionDsp - ---- read samplerate from file: 384000 Oct 03 18:46:25 volumio volumio[1115]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 03 18:46:40 volumio ntpd[1043]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 03 18:46:40 volumio ntpd[1043]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Oct 03 18:46:40 volumio ntpd[1043]: DNS: Pool skipping: 45.252.250.189 Oct 03 18:46:40 volumio ntpd[1043]: DNS: Pool skipping: 103.221.223.143 Oct 03 18:46:40 volumio ntpd[1043]: DNS: Pool skipping: 160.22.74.161 Oct 03 18:46:40 volumio ntpd[1043]: DNS: Pool skipping: 115.165.161.155 Oct 03 18:46:40 volumio ntpd[1043]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Oct 03 18:46:41 volumio ntpd[1043]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 03 18:46:41 volumio ntpd[1043]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Oct 03 18:46:41 volumio ntpd[1043]: DNS: Pool skipping: 103.221.223.143 Oct 03 18:46:41 volumio ntpd[1043]: DNS: Pool skipping: 45.252.250.189 Oct 03 18:46:41 volumio ntpd[1043]: DNS: Pool skipping: 160.22.74.161 Oct 03 18:46:41 volumio ntpd[1043]: DNS: Pool skipping: 103.184.124.254 Oct 03 18:46:41 volumio ntpd[1043]: DNS: Pool skipping: 2001:df4:bac0::e35b:f223 Oct 03 18:46:41 volumio ntpd[1043]: DNS: Pool taking: 2400:e920:0:5::14 Oct 03 18:46:41 volumio ntpd[1043]: DNS: Pool skipping: 2401:5b60:0:2::21 Oct 03 18:46:41 volumio ntpd[1043]: DNS: Pool skipping: 2001:df7:c600:13:f816:3eff:fe1d:b3b5 Oct 03 18:46:41 volumio ntpd[1043]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Oct 03 18:46:42 volumio ntpd[1043]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 03 18:46:42 volumio ntpd[1043]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Oct 03 18:46:42 volumio ntpd[1043]: DNS: Pool skipping: 45.252.250.189 Oct 03 18:46:42 volumio ntpd[1043]: DNS: Pool skipping: 103.221.223.143 Oct 03 18:46:42 volumio ntpd[1043]: DNS: Pool skipping: 160.22.74.161 Oct 03 18:46:42 volumio ntpd[1043]: DNS: Pool skipping: 115.165.161.155 Oct 03 18:46:42 volumio ntpd[1043]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Oct 03 18:46:43 volumio ntpd[1043]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Oct 03 18:46:43 volumio ntpd[1043]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Oct 03 18:46:43 volumio ntpd[1043]: DNS: Pool skipping: 160.22.74.161 Oct 03 18:46:43 volumio ntpd[1043]: DNS: Pool skipping: 45.252.250.189 Oct 03 18:46:43 volumio ntpd[1043]: DNS: Pool skipping: 103.221.223.143 Oct 03 18:46:43 volumio ntpd[1043]: DNS: Pool skipping: 115.165.161.155 Oct 03 18:46:43 volumio ntpd[1043]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Oct 03 18:46:46 volumio volumio[1115]: info: Clearing queue after UPNP request Oct 03 18:46:47 volumio volumio[1115]: info: CoreStateMachine::ClearQueue Oct 03 18:46:47 volumio volumio[1115]: info: CoreStateMachine::stop Oct 03 18:46:47 volumio volumio[1115]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 03 18:46:47 volumio volumio[1115]: info: CoreStateMachine::stPlaybackTimer Oct 03 18:46:47 volumio volumio[1115]: info: CoreStateMachine::updateTrackBlock Oct 03 18:46:47 volumio volumio[1115]: info: CorePlayQueue::getTrackBlock Oct 03 18:46:47 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:46:47 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:46:47 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 03 18:46:47 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:47 volumio volumio[1115]: info: CoreStateMachine::serviceStop Oct 03 18:46:47 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:46:47 volumio volumio[1115]: info: ControllerMpd::stop Oct 03 18:46:47 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand stop Oct 03 18:46:47 volumio volumio[1115]: info: CorePlayQueue::clearPlayQueue Oct 03 18:46:47 volumio volumio[1115]: info: CorePlayQueue::saveQueue Oct 03 18:46:47 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:47 volumio volumio[1115]: info: CoreCommandRouter::volumioPushQueue Oct 03 18:46:47 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:47 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:47 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:47 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:47 volumio volumio[1115]: info: Oct 03 18:46:47 volumio volumio[1115]: ---------------------------- MPD announces system playlist update Oct 03 18:46:47 volumio volumio[1115]: info: Ignoring MPD Status Update Oct 03 18:46:47 volumio volumio[1115]: info: Oct 03 18:46:47 volumio volumio[1115]: ---------------------------- MPD announces state update: player Oct 03 18:46:47 volumio volumio[1115]: info: sendMpdCommand stop took 13 milliseconds Oct 03 18:46:47 volumio volumio[1115]: info: ControllerMpd::getState Oct 03 18:46:47 volumio volumio[1115]: verbose: ControllerMpd::sendMpdCommand status Oct 03 18:46:47 volumio volumio[1115]: error: updateQueue error: null Oct 03 18:46:47 volumio volumio[1115]: info: ------------------------------ 2ms Oct 03 18:46:47 volumio volumio[1115]: info: sendMpdCommand status took 1 milliseconds Oct 03 18:46:47 volumio volumio[1115]: verbose: ControllerMpd::parseState Oct 03 18:46:47 volumio volumio[1115]: info: ControllerMpd::pushState Oct 03 18:46:47 volumio volumio[1115]: info: CoreCommandRouter::servicePushState Oct 03 18:46:47 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:46:47 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:46:47 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:47 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:46:47 volumio volumio[1115]: 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 03 18:46:47 volumio volumio[1115]: verbose: CURRENT POSITION 0 Oct 03 18:46:47 volumio volumio[1115]: info: CoreStateMachine::syncState stateService stop Oct 03 18:46:47 volumio volumio[1115]: info: CoreStateMachine::syncState currentStatus stop Oct 03 18:46:47 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:46:47 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:46:47 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:47 volumio volumio[1115]: info: No code Oct 03 18:46:47 volumio volumio[1115]: info: CoreStateMachine::pushState Oct 03 18:46:47 volumio volumio[1115]: info: CorePlayQueue::getTrack 0 Oct 03 18:46:47 volumio volumio[1115]: info: CoreCommandRouter::volumioPushState Oct 03 18:46:47 volumio volumio[1115]: info: ------------------------------ 14ms Oct 03 18:46:47 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:47 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:47 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:47 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:47 volumio volumio[1115]: info: FusionDsp - Volumio is not playing Oct 03 18:46:47 volumio volumio[1115]: info: FusionDsp - Clipped samples monitor stopped Oct 03 18:46:47 volumio volumio[1115]: info: camilladsp respawn in 100 ms (attempt 1/10) Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 18:46:54 volumio volumio[1115]: info: Retrieving Cloud Streaming UI Oct 03 18:46:54 volumio volumio[1115]: info: Getting Tidal Cloud Configuration Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 18:46:54 volumio volumio[1115]: info: Getting Qobuz Cloud Configuration Oct 03 18:46:54 volumio volumio[1115]: info: Asking plugin for UI Config Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 18:46:54 volumio volumio[1115]: info: Getting Spotify Cloud Configuration Oct 03 18:46:54 volumio volumio[1115]: info: Asking plugin for UI Config Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 18:46:54 volumio volumio[1115]: info: Saving Spotify Acccount Oct 03 18:46:54 volumio volumio[1115]: info: Got Tidal Cloud Configuration Oct 03 18:46:54 volumio volumio[1115]: info: Got it Oct 03 18:46:54 volumio volumio[1115]: info: Got it Oct 03 18:46:54 volumio volumio[1115]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 03 18:46:54 volumio volumio[1115]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::volumioGetBrowseSources Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::volumioGetBrowseSources Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::volumioGetBrowseSources Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 03 18:46:54 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 03 18:46:56 volumio volumio[1115]: info: Disabling MyMusic plugin upnp Oct 03 18:46:56 volumio sudo[3411]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop upmpdcli.service Oct 03 18:46:56 volumio sudo[3411]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 03 18:46:56 volumio systemd[1]: Stopping upmpdcli.service - UPnP Renderer front-end to MPD... Oct 03 18:46:57 volumio volumio[1115]: info: Enabling MyMusic plugin upnp Oct 03 18:46:57 volumio volumio[1115]: info: Enabling plugin upnp Oct 03 18:46:57 volumio volumio[1115]: info: Loading plugin "upnp"... Oct 03 18:46:57 volumio volumio[1115]: info: [1791028017869] Starting Upmpd Daemon Oct 03 18:46:57 volumio volumio[1115]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Oct 03 18:46:57 volumio volumio[1115]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 18:46:57 volumio volumio[1115]: Error: listen EADDRINUSE: address already in use :::6599 Oct 03 18:46:57 volumio volumio[1115]: at Server.setupListenHandle [as _listen2] (node:net:1872:16) Oct 03 18:46:57 volumio volumio[1115]: at listenInCluster (node:net:1920:12) Oct 03 18:46:57 volumio volumio[1115]: at Server.listen (node:net:2008:7) Oct 03 18:46:57 volumio volumio[1115]: at UpnpInterface.onVolumioStart (/volumio/app/plugins/audio_interface/upnp/index.js:78:17) Oct 03 18:46:57 volumio volumio[1115]: at PluginManager.loadCorePlugin (/volumio/app/pluginmanager.js:255:38) Oct 03 18:46:57 volumio volumio[1115]: at Promise._successFn (/volumio/app/pluginmanager.js:1855:19) Oct 03 18:46:57 volumio volumio[1115]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Oct 03 18:46:57 volumio volumio[1115]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Oct 03 18:46:57 volumio volumio[1115]: code: 'EADDRINUSE', Oct 03 18:46:57 volumio volumio[1115]: errno: -98, Oct 03 18:46:57 volumio volumio[1115]: syscall: 'listen', Oct 03 18:46:57 volumio volumio[1115]: address: '::', Oct 03 18:46:57 volumio volumio[1115]: port: 6599 Oct 03 18:46:57 volumio volumio[1115]: } Oct 03 18:46:57 volumio volumio[1115]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 03 18:46:57 volumio sudo[3442]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-03 18:45' Oct 03 18:46:57 volumio sudo[3442]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"