Oct 15 13:20:01 volumiospc systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 87. Oct 15 13:20:01 volumiospc systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 15 13:20:01 volumiospc systemd[1]: fusiondsp.service: Consumed 3.998s CPU time. Oct 15 13:20:01 volumiospc systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... Oct 15 13:20:06 volumiospc systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 15 13:20:07 volumiospc volumio[1075]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 13:20:07 volumiospc volumio[1075]: info: CURURI: albums:// Oct 15 13:20:07 volumiospc volumio[1075]: info: listAlbums - loading Albums from cache Oct 15 13:20:07 volumiospc volumio[1075]: info: Preload queue cleared Oct 15 13:20:17 volumiospc volumio[1075]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri Oct 15 13:20:17 volumiospc volumio[1075]: info: CURURI: albums://Various%20Artists/Lux%20and%20Ivy's%20Favorites%20Volume%2006 Oct 15 13:20:17 volumiospc volumio[1075]: info: Preload queue cleared Oct 15 13:20:17 volumiospc volumio[1075]: info: Preloading song: music-library/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/03 Camel Walk.mp3 Oct 15 13:20:17 volumiospc volumio[1075]: info: Preloading song: music-library/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3 Oct 15 13:20:17 volumiospc volumio[1075]: info: Preloading song: music-library/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/28 Moon Madness.mp3 Oct 15 13:20:17 volumiospc volumio[1075]: info: Exploding uri music-library/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/03 Camel Walk.mp3 in service mpd Oct 15 13:20:17 volumiospc volumio[1075]: info: ALBUMART /albumart?cacheid=525&web=The%20Saxons/Lux%20and%20Ivy's%20Favorites%20Volume%2006/medium&path=%2Fmnt%2FNAS%2FLux%20and%20Ivy%2FLux%20and%20Ivy's%20Favorites%20Volume%2006%2F03%20Camel%20Walk.mp3&metadata=false Oct 15 13:20:17 volumiospc volumio[1075]: info: URI /mnt/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/03 Camel Walk.mp3 Oct 15 13:20:17 volumiospc volumio[1075]: info: Exploding uri music-library/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3 in service mpd Oct 15 13:20:17 volumiospc volumio[1075]: info: Exploding uri music-library/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/28 Moon Madness.mp3 in service mpd Oct 15 13:20:17 volumiospc volumio[1075]: info: ALBUMART /albumart?cacheid=525&web=jack%20nitzshe/Lux%20and%20Ivy's%20Favorites%20Volume%2006/medium&path=%2Fmnt%2FNAS%2FLux%20and%20Ivy%2FLux%20and%20Ivy's%20Favorites%20Volume%2006%2F22%20The%20Last%20Race.mp3&metadata=false Oct 15 13:20:17 volumiospc volumio[1075]: info: URI /mnt/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3 Oct 15 13:20:17 volumiospc volumio[1075]: info: ALBUMART /albumart?cacheid=525&web=Pasquale%20%26%20the%20Lunar-Tiks/Lux%20and%20Ivy's%20Favorites%20Volume%2006/medium&path=%2Fmnt%2FNAS%2FLux%20and%20Ivy%2FLux%20and%20Ivy's%20Favorites%20Volume%2006%2F28%20Moon%20Madness.mp3&metadata=false Oct 15 13:20:17 volumiospc volumio[1075]: info: URI /mnt/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/28 Moon Madness.mp3 Oct 15 13:20:17 volumiospc volumio[1075]: info: No valid Plugin REST Endpoint Oct 15 13:20:17 volumiospc kernel: traps: camillagui_back[8370] trap invalid opcode ip:7fe55cbf5cfc sp:7ffc07283d48 error:0 in _multiarray_umath.cpython-312-x86_64-linux-gnu.so[2dcfc,7fe55cbf3000+793000] Oct 15 13:20:17 volumiospc volumio[8369]: /data/plugins/audio_interface/fusiondsp/startcgui.sh: line 16: 8370 Illegal instruction ./camillagui/camillagui_backend/camillagui_backend Oct 15 13:20:17 volumiospc systemd[1]: fusiondsp.service: Main process exited, code=exited, status=132/n/a Oct 15 13:20:17 volumiospc systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 15 13:20:17 volumiospc systemd[1]: fusiondsp.service: Consumed 4.140s CPU time. Oct 15 13:20:19 volumiospc systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 88. Oct 15 13:20:19 volumiospc systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 15 13:20:19 volumiospc systemd[1]: fusiondsp.service: Consumed 4.140s CPU time. Oct 15 13:20:19 volumiospc systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPlay Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::play index undefined Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::startPlaybackTimer Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::clearAddPlayTracks NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 01/22 telstar.mp3 Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand stop took 2 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand add "NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 01/22 telstar.mp3" Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:20 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:20 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand add "NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 01/22 telstar.mp3" took 4 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:20 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:20 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:20 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 21ms Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 20ms Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand play took 17 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 16ms Oct 15 13:20:20 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 22ms Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 18ms Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:20 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:20 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:20 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:20 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:20 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:20 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand status took 24 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 24ms Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 24ms Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand status took 23 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 22ms Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand status took 20 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:20 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:20 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:20 volumiospc volumio[1075]: info: Oct 15 13:20:20 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:20 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand status took 30 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 14 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 22 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 21 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand status took 19 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand status took 18 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand status took 16 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:20 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"telstar","artist":"tornnados","album":"Lux and Ivy's Favorites Volume 01","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 01/22 telstar.mp3","trackType":"mp3"} Oct 15 13:20:20 volumiospc volumio[1075]: verbose: CURRENT POSITION 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Oct 15 13:20:20 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"telstar","artist":"tornnados","album":"Lux and Ivy's Favorites Volume 01","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 01/22 telstar.mp3","trackType":"mp3"} Oct 15 13:20:20 volumiospc volumio[1075]: verbose: CURRENT POSITION 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus play Oct 15 13:20:20 volumiospc volumio[1075]: info: Received an update from plugin. extracting info from payload Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:20 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"telstar","artist":"tornnados","album":"Lux and Ivy's Favorites Volume 01","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 01/22 telstar.mp3","trackType":"mp3"} Oct 15 13:20:20 volumiospc volumio[1075]: verbose: CURRENT POSITION 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus play Oct 15 13:20:20 volumiospc volumio[1075]: info: Received an update from plugin. extracting info from payload Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 120ms Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 155ms Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 153ms Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 110 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 106 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 106 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 108 milliseconds Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:20 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:20 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1209,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"telstar","artist":"tornnados","album":"Lux and Ivy's Favorites Volume 01","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 01/22 telstar.mp3","trackType":"mp3"} Oct 15 13:20:20 volumiospc volumio[1075]: verbose: CURRENT POSITION 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus play Oct 15 13:20:20 volumiospc volumio[1075]: info: Received an update from plugin. extracting info from payload Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:20 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1209,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"telstar","artist":"tornnados","album":"Lux and Ivy's Favorites Volume 01","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 01/22 telstar.mp3","trackType":"mp3"} Oct 15 13:20:20 volumiospc volumio[1075]: verbose: CURRENT POSITION 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus play Oct 15 13:20:20 volumiospc volumio[1075]: info: Received an update from plugin. extracting info from payload Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:20 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1209,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"telstar","artist":"tornnados","album":"Lux and Ivy's Favorites Volume 01","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 01/22 telstar.mp3","trackType":"mp3"} Oct 15 13:20:20 volumiospc volumio[1075]: verbose: CURRENT POSITION 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus play Oct 15 13:20:20 volumiospc volumio[1075]: info: Received an update from plugin. extracting info from payload Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:20 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1209,"duration":202,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"telstar","artist":"tornnados","album":"Lux and Ivy's Favorites Volume 01","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 01/22 telstar.mp3","trackType":"mp3"} Oct 15 13:20:20 volumiospc volumio[1075]: verbose: CURRENT POSITION 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus play Oct 15 13:20:20 volumiospc volumio[1075]: info: Received an update from plugin. extracting info from payload Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:20 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:20 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 402ms Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 392ms Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 405ms Oct 15 13:20:20 volumiospc volumio[1075]: info: ------------------------------ 406ms Oct 15 13:20:21 volumiospc volumio[1075]: info: FusionDsp - Volumio is playing Oct 15 13:20:21 volumiospc volumio[1075]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Oct 15 13:20:21 volumiospc volumio[1075]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 15 13:20:21 volumiospc volumio[1075]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 15 13:20:21 volumiospc volumio[1075]: info: FusionDsp - Clipping Monitor started Oct 15 13:20:21 volumiospc volumio[1075]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 15 13:20:24 volumiospc systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::volumioNext Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::next Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::stop Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::stPlaybackTimer Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::updateTrackBlock Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrackBlock Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::serviceStop Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::serviceStop Oct 15 13:20:26 volumiospc volumio[1075]: info: ControllerMpd::stop Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 13:20:26 volumiospc volumio[1075]: info: FusionDsp - Volumio is not playing Oct 15 13:20:26 volumiospc volumio[1075]: info: FusionDsp - Clipped samples monitor stopped Oct 15 13:20:26 volumiospc volumio[1075]: info: Oct 15 13:20:26 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:26 volumiospc volumio[1075]: info: sendMpdCommand stop took 76 milliseconds Oct 15 13:20:26 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::play index undefined Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:26 volumiospc volumio[1075]: info: Oct 15 13:20:26 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:26 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:26 volumiospc volumio[1075]: info: Oct 15 13:20:26 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:26 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:26 volumiospc volumio[1075]: info: Oct 15 13:20:26 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:26 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:26 volumiospc volumio[1075]: info: sendMpdCommand status took 70 milliseconds Oct 15 13:20:26 volumiospc volumio[1075]: info: sendMpdCommand status took 48 milliseconds Oct 15 13:20:26 volumiospc volumio[1075]: info: sendMpdCommand status took 47 milliseconds Oct 15 13:20:26 volumiospc volumio[1075]: info: sendMpdCommand status took 39 milliseconds Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:26 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 15 13:20:26 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 37 milliseconds Oct 15 13:20:26 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 35 milliseconds Oct 15 13:20:26 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 34 milliseconds Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:26 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:26 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"telstar","artist":"tornnados","album":"Lux and Ivy's Favorites Volume 01","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 01/22 telstar.mp3","trackType":"mp3"} Oct 15 13:20:26 volumiospc volumio[1075]: verbose: CURRENT POSITION 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService stop Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:26 volumiospc volumio[1075]: info: No code Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:26 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"telstar","artist":"tornnados","album":"Lux and Ivy's Favorites Volume 01","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 01/22 telstar.mp3","trackType":"mp3"} Oct 15 13:20:26 volumiospc volumio[1075]: verbose: CURRENT POSITION 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService stop Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:26 volumiospc volumio[1075]: info: No code Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:26 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"telstar","artist":"tornnados","album":"Lux and Ivy's Favorites Volume 01","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 01/22 telstar.mp3","trackType":"mp3"} Oct 15 13:20:26 volumiospc volumio[1075]: verbose: CURRENT POSITION 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService stop Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:26 volumiospc volumio[1075]: info: No code Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:26 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"stop","position":0,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"telstar","artist":"tornnados","album":"Lux and Ivy's Favorites Volume 01","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 01/22 telstar.mp3","trackType":"mp3"} Oct 15 13:20:26 volumiospc volumio[1075]: verbose: CURRENT POSITION 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService stop Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:26 volumiospc volumio[1075]: info: No code Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:26 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:26 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:27 volumiospc volumio[1075]: info: ------------------------------ 662ms Oct 15 13:20:27 volumiospc volumio[1075]: info: ------------------------------ 647ms Oct 15 13:20:27 volumiospc volumio[1075]: info: ------------------------------ 643ms Oct 15 13:20:27 volumiospc volumio[1075]: info: ------------------------------ 637ms Oct 15 13:20:27 volumiospc volumio[1075]: info: camilladsp respawn in 100 ms (attempt 1/10) Oct 15 13:20:27 volumiospc volumio[1075]: info: CoreCommandRouter::volumioNext Oct 15 13:20:27 volumiospc volumio[1075]: info: CoreStateMachine::next Oct 15 13:20:27 volumiospc volumio[1075]: info: CoreStateMachine::stop Oct 15 13:20:27 volumiospc volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 13:20:27 volumiospc volumio[1075]: info: CoreStateMachine::play index undefined Oct 15 13:20:27 volumiospc volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 13:20:27 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:31 volumiospc volumio[1075]: info: CoreCommandRouter::volumioGetState Oct 15 13:20:31 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 0 Oct 15 13:20:33 volumiospc kernel: traps: camillagui_back[8448] trap invalid opcode ip:7f60a2982cfc sp:7ffc2b9f2798 error:0 in _multiarray_umath.cpython-312-x86_64-linux-gnu.so[2dcfc,7f60a2980000+793000] Oct 15 13:20:33 volumiospc volumio[8447]: /data/plugins/audio_interface/fusiondsp/startcgui.sh: line 16: 8448 Illegal instruction ./camillagui/camillagui_backend/camillagui_backend Oct 15 13:20:33 volumiospc systemd[1]: fusiondsp.service: Main process exited, code=exited, status=132/n/a Oct 15 13:20:33 volumiospc systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 15 13:20:33 volumiospc systemd[1]: fusiondsp.service: Consumed 3.911s CPU time. Oct 15 13:20:34 volumiospc volumio[1075]: info: CoreCommandRouter::volumioNext Oct 15 13:20:34 volumiospc volumio[1075]: info: CoreStateMachine::next Oct 15 13:20:34 volumiospc volumio[1075]: info: CoreStateMachine::stop Oct 15 13:20:34 volumiospc volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 13:20:34 volumiospc volumio[1075]: info: CoreStateMachine::play index undefined Oct 15 13:20:34 volumiospc volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 13:20:34 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:35 volumiospc volumio[1075]: info: CoreCommandRouter::volumioNext Oct 15 13:20:35 volumiospc volumio[1075]: info: CoreStateMachine::next Oct 15 13:20:35 volumiospc volumio[1075]: info: CoreStateMachine::stop Oct 15 13:20:35 volumiospc volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 13:20:35 volumiospc volumio[1075]: info: CoreStateMachine::play index undefined Oct 15 13:20:35 volumiospc volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 13:20:35 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:36 volumiospc systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 89. Oct 15 13:20:36 volumiospc systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 15 13:20:36 volumiospc systemd[1]: fusiondsp.service: Consumed 3.911s CPU time. Oct 15 13:20:36 volumiospc systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... Oct 15 13:20:41 volumiospc systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 15 13:20:47 volumiospc kernel: traps: camillagui_back[8506] trap invalid opcode ip:7fe4642e8cfc sp:7ffd4fa41468 error:0 in _multiarray_umath.cpython-312-x86_64-linux-gnu.so[2dcfc,7fe4642e6000+793000] Oct 15 13:20:47 volumiospc volumio[8505]: /data/plugins/audio_interface/fusiondsp/startcgui.sh: line 16: 8506 Illegal instruction ./camillagui/camillagui_backend/camillagui_backend Oct 15 13:20:47 volumiospc systemd[1]: fusiondsp.service: Main process exited, code=exited, status=132/n/a Oct 15 13:20:47 volumiospc systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 15 13:20:47 volumiospc systemd[1]: fusiondsp.service: Consumed 3.401s CPU time. Oct 15 13:20:49 volumiospc volumio[1075]: info: Listing playlists Oct 15 13:20:49 volumiospc systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 90. Oct 15 13:20:49 volumiospc systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 15 13:20:49 volumiospc systemd[1]: fusiondsp.service: Consumed 3.401s CPU time. Oct 15 13:20:49 volumiospc systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... Oct 15 13:20:53 volumiospc volumio[1075]: info: Preload queue cleared Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioReplaceandPlayItems Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::ClearQueue Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::stop Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::clearPlayQueue Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::saveQueue Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushQueue Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::addQueueItems Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::addQueueItems Oct 15 13:20:53 volumiospc volumio[1075]: info: Preload queue cleared Oct 15 13:20:53 volumiospc volumio[1075]: info: Adding Item to queue: music-library/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/03 Camel Walk.mp3 Oct 15 13:20:53 volumiospc volumio[1075]: info: Using cached record of: music-library/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/03 Camel Walk.mp3 Oct 15 13:20:53 volumiospc volumio[1075]: info: Adding Item to queue: music-library/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3 Oct 15 13:20:53 volumiospc volumio[1075]: info: Using cached record of: music-library/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushQueue Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::saveQueue Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::updateTrackBlock Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrackBlock Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPlay Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::play index 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::addQueueItems Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::addQueueItems Oct 15 13:20:53 volumiospc volumio[1075]: info: Preload queue cleared Oct 15 13:20:53 volumiospc volumio[1075]: info: Adding Item to queue: music-library/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/28 Moon Madness.mp3 Oct 15 13:20:53 volumiospc volumio[1075]: info: Using cached record of: music-library/NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/28 Moon Madness.mp3 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::stop Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushQueue Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::saveQueue Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::play index undefined Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::setConsumeUpdateService undefined Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::updateTrackBlock Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrackBlock Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::startPlaybackTimer Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::clearAddPlayTracks NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3 Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand stop Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand stop took 9 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand clear Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand clear took 1 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand add "NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3" Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:53 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:53 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:53 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:53 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:53 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand add "NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3" took 15 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 15ms Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 13ms Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 10ms Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand play Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:53 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:53 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces system playlist update Oct 15 13:20:53 volumiospc volumio[1075]: info: Ignoring MPD Status Update Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 17ms Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 14ms Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand play took 10 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 9ms Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 6ms Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 5ms Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand status took 29 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand status took 25 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand status took 26 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand status took 17 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:53 volumiospc volumio[1075]: info: Oct 15 13:20:53 volumiospc volumio[1075]: ---------------------------- MPD announces state update: player Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::getState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand status Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand status took 35 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 29 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 28 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 26 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 26 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand status took 21 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand status took 17 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":160,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Last Race","artist":"jack nitzshe","album":"Lux and Ivy's Favorites Volume 06","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3","trackType":"mp3"} Oct 15 13:20:53 volumiospc volumio[1075]: verbose: CURRENT POSITION 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus stop Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":160,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Last Race","artist":"jack nitzshe","album":"Lux and Ivy's Favorites Volume 06","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3","trackType":"mp3"} Oct 15 13:20:53 volumiospc volumio[1075]: verbose: CURRENT POSITION 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus play Oct 15 13:20:53 volumiospc volumio[1075]: info: Received an update from plugin. extracting info from payload Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":160,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Last Race","artist":"jack nitzshe","album":"Lux and Ivy's Favorites Volume 06","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3","trackType":"mp3"} Oct 15 13:20:53 volumiospc volumio[1075]: verbose: CURRENT POSITION 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus play Oct 15 13:20:53 volumiospc volumio[1075]: info: Received an update from plugin. extracting info from payload Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":160,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"The Last Race","artist":"jack nitzshe","album":"Lux and Ivy's Favorites Volume 06","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3","trackType":"mp3"} Oct 15 13:20:53 volumiospc volumio[1075]: verbose: CURRENT POSITION 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus play Oct 15 13:20:53 volumiospc volumio[1075]: info: Received an update from plugin. extracting info from payload Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 159ms Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 220ms Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 215ms Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 207ms Oct 15 13:20:53 volumiospc volumio[1075]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Oct 15 13:20:53 volumiospc volumio[1075]: info: FusionDsp - ---- read samplerate from file: 44100 Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand status took 168 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 153 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 145 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 144 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseState Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::sendMpdCommand playlistinfo Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":958,"duration":160,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Last Race","artist":"jack nitzshe","album":"Lux and Ivy's Favorites Volume 06","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3","trackType":"mp3"} Oct 15 13:20:53 volumiospc volumio[1075]: verbose: CURRENT POSITION 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus play Oct 15 13:20:53 volumiospc volumio[1075]: info: Received an update from plugin. extracting info from payload Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1596,"duration":160,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Last Race","artist":"jack nitzshe","album":"Lux and Ivy's Favorites Volume 06","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3","trackType":"mp3"} Oct 15 13:20:53 volumiospc volumio[1075]: verbose: CURRENT POSITION 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus play Oct 15 13:20:53 volumiospc volumio[1075]: info: Received an update from plugin. extracting info from payload Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1596,"duration":160,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Last Race","artist":"jack nitzshe","album":"Lux and Ivy's Favorites Volume 06","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3","trackType":"mp3"} Oct 15 13:20:53 volumiospc volumio[1075]: verbose: CURRENT POSITION 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus play Oct 15 13:20:53 volumiospc volumio[1075]: info: Received an update from plugin. extracting info from payload Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 303ms Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 277ms Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 274ms Oct 15 13:20:53 volumiospc volumio[1075]: info: FusionDsp - Volumio is playing Oct 15 13:20:53 volumiospc volumio[1075]: warn: FusionDsp - Monitor WebSocket not open, skipping commands Oct 15 13:20:53 volumiospc volumio[1075]: info: FusionDsp - If filter freq >samplerate/2 then disable it Oct 15 13:20:53 volumiospc volumio[1075]: info: sendMpdCommand playlistinfo took 287 milliseconds Oct 15 13:20:53 volumiospc volumio[1075]: verbose: ControllerMpd::parseTrackInfo Oct 15 13:20:53 volumiospc volumio[1075]: info: ControllerMpd::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::servicePushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: verbose: STATE SERVICE {"status":"play","position":0,"seek":1596,"duration":160,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"128 Kbps","isStreaming":false,"title":"The Last Race","artist":"jack nitzshe","album":"Lux and Ivy's Favorites Volume 06","uri":"NAS/Lux and Ivy/Lux and Ivy's Favorites Volume 06/22 The Last Race.mp3","trackType":"mp3"} Oct 15 13:20:53 volumiospc volumio[1075]: verbose: CURRENT POSITION 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState stateService play Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::syncState currentStatus play Oct 15 13:20:53 volumiospc volumio[1075]: info: Received an update from plugin. extracting info from payload Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreStateMachine::pushState Oct 15 13:20:53 volumiospc volumio[1075]: info: CorePlayQueue::getTrack 1 Oct 15 13:20:53 volumiospc volumio[1075]: info: CoreCommandRouter::volumioPushState Oct 15 13:20:53 volumiospc volumio[1075]: info: ------------------------------ 523ms Oct 15 13:20:54 volumiospc volumio[1075]: info: FusionDsp - Clipping Monitor started Oct 15 13:20:55 volumiospc systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 15 13:20:59 volumiospc kernel: traps: camillagui_back[8553] trap invalid opcode ip:7f6bbe2efcfc sp:7ffd8d9cf258 error:0 in _multiarray_umath.cpython-312-x86_64-linux-gnu.so[2dcfc,7f6bbe2ed000+793000] Oct 15 13:20:59 volumiospc volumio[8552]: /data/plugins/audio_interface/fusiondsp/startcgui.sh: line 16: 8553 Illegal instruction ./camillagui/camillagui_backend/camillagui_backend Oct 15 13:20:59 volumiospc systemd[1]: fusiondsp.service: Main process exited, code=exited, status=132/n/a Oct 15 13:20:59 volumiospc systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 15 13:20:59 volumiospc systemd[1]: fusiondsp.service: Consumed 3.667s CPU time. Oct 15 13:21:01 volumiospc systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 91. Oct 15 13:21:01 volumiospc systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 15 13:21:01 volumiospc systemd[1]: fusiondsp.service: Consumed 3.667s CPU time. Oct 15 13:21:01 volumiospc systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... Oct 15 13:21:05 volumiospc volumio[1075]: info: CoreCommandRouter::executeOnPlugin: bandcamp , handleBrowseUri Oct 15 13:21:05 volumiospc volumio[1075]: info: [bandcamp-browse] browseUri: bandcamp Oct 15 13:21:07 volumiospc systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Oct 15 13:21:13 volumiospc kernel: traps: camillagui_back[8594] trap invalid opcode ip:7fa6af95fcfc sp:7ffdf490bb78 error:0 in _multiarray_umath.cpython-312-x86_64-linux-gnu.so[2dcfc,7fa6af95d000+793000] Oct 15 13:21:13 volumiospc volumio[8593]: /data/plugins/audio_interface/fusiondsp/startcgui.sh: line 16: 8594 Illegal instruction ./camillagui/camillagui_backend/camillagui_backend Oct 15 13:21:13 volumiospc systemd[1]: fusiondsp.service: Main process exited, code=exited, status=132/n/a Oct 15 13:21:13 volumiospc systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Oct 15 13:21:13 volumiospc systemd[1]: fusiondsp.service: Consumed 4.759s CPU time. Oct 15 13:21:14 volumiospc volumio[1075]: error: Failed to execute browseSource: Error: Failed to parse fan info: invalid data. Oct 15 13:21:14 volumiospc volumio[1075]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 13:21:14 volumiospc volumio[1075]: [UnhandledPromiseRejection: This error originated either by throwing inside of an async function without a catch block, or by rejecting a promise which was not handled with .catch(). The promise rejected with the reason "Error: Failed to parse fan info: invalid data.".] { Oct 15 13:21:14 volumiospc volumio[1075]: code: 'ERR_UNHANDLED_REJECTION' Oct 15 13:21:14 volumiospc volumio[1075]: } Oct 15 13:21:14 volumiospc volumio[1075]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 15 13:21:15 volumiospc systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 92. Oct 15 13:21:15 volumiospc systemd[1]: Stopped fusiondsp.service - FusionDsp Daemon. Oct 15 13:21:15 volumiospc systemd[1]: fusiondsp.service: Consumed 4.759s CPU time. Oct 15 13:21:15 volumiospc systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... Oct 15 13:21:20 volumiospc sudo[8652]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-10-15 13:20' Oct 15 13:21:20 volumiospc sudo[8652]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Oct 15 13:21:20 volumiospc systemd[1]: Started fusiondsp.service - FusionDsp Daemon. 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"