-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Sun 2025-06-29 22:24:07 CEST. -- Jun 29 22:23:09 volumio volumio[858]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Jun 29 22:23:09 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Jun 29 22:23:09 volumio volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 29 22:23:09 volumio volumio[858]: info: FusionDsp - Nulleq applied Jun 29 22:23:09 volumio volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 29 22:23:09 volumio volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:23:09 volumio volumio[858]: info: CoreCommandRouter::Close All Modals sent Jun 29 22:23:09 volumio volumio[858]: info: FusionDsp - Choose a preset Jun 29 22:23:09 volumio volumio[858]: info: FusionDsp - Jun 29 22:23:19 volumio volumio[858]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Jun 29 22:23:19 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Jun 29 22:23:19 volumio volumio[858]: error: FusionDsp - wrong value in eq1 Jun 29 22:23:26 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 22:23:26 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 22:23:26 volumio volumio[858]: info: Discovery: Getting this device information Jun 29 22:23:26 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Jun 29 22:23:26 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 22:23:27 volumio volumio[858]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Jun 29 22:23:27 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Jun 29 22:23:28 volumio volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 29 22:23:28 volumio volumio[858]: info: FusionDsp - Nulleq applied Jun 29 22:23:28 volumio volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 29 22:23:28 volumio volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:23:28 volumio volumio[858]: info: CoreCommandRouter::Close All Modals sent Jun 29 22:23:28 volumio volumio[858]: info: FusionDsp - no preset used Jun 29 22:23:28 volumio volumio[858]: info: FusionDsp - Jun 29 22:23:29 volumio volumio[858]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Jun 29 22:23:29 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Jun 29 22:23:30 volumio volumio[858]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 29 22:23:30 volumio volumio[858]: info: FusionDsp - Nulleq applied Jun 29 22:23:30 volumio volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 29 22:23:30 volumio volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:23:30 volumio volumio[858]: info: CoreCommandRouter::Close All Modals sent Jun 29 22:23:30 volumio volumio[858]: info: FusionDsp - no preset used Jun 29 22:23:30 volumio volumio[858]: info: FusionDsp - Jun 29 22:23:46 volumio volumio[858]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Jun 29 22:23:46 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Jun 29 22:23:47 volumio volumio[858]: info: FusionDsp - File size found in array! Jun 29 22:23:47 volumio volumio[858]: info: FusionDsp - Nulleq applied Jun 29 22:23:47 volumio volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:23:47 volumio volumio[858]: info: CoreCommandRouter::Close All Modals sent Jun 29 22:23:47 volumio volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 29 22:23:47 volumio volumio[858]: info: FusionDsp - test Jun 29 22:23:47 volumio volumio[858]: info: FusionDsp - kef.json,test.json Jun 29 22:23:53 volumio volumio[858]: info: CALLMETHOD: audio_interface fusiondsp moresettings Jun 29 22:23:53 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , moresettings Jun 29 22:23:53 volumio volumio[858]: info: FusionDsp - File size found in array! Jun 29 22:23:53 volumio volumio[858]: info: FusionDsp - Nulleq applied Jun 29 22:23:53 volumio volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:23:53 volumio volumio[858]: info: CoreCommandRouter::Close All Modals sent Jun 29 22:23:53 volumio volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 29 22:23:53 volumio volumio[858]: info: FusionDsp - test Jun 29 22:23:54 volumio volumio[858]: info: FusionDsp - kef.json,test.json Jun 29 22:23:56 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 29 22:23:56 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 29 22:23:56 volumio volumio[858]: info: Discovery: Getting this device information Jun 29 22:23:56 volumio volumio[858]: info: CoreCommandRouter::volumioGetState Jun 29 22:23:56 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 29 22:23:59 volumio volumio[858]: info: explodeTIDALUri took 963537 milliseconds Jun 29 22:23:59 volumio volumio[858]: error: TIDAL Browse Error: No response received Jun 29 22:23:59 volumio volumio[858]: error: Commandrouter: Cannot explode uri tidal://song/436006304 from service tidal: No response received Jun 29 22:23:59 volumio volumio[858]: info: explodeTIDALUri took 963445 milliseconds Jun 29 22:23:59 volumio volumio[858]: error: TIDAL Browse Error: No response received Jun 29 22:23:59 volumio volumio[858]: error: Commandrouter: Cannot explode uri tidal://song/271209 from service tidal: No response received Jun 29 22:24:05 volumio volumio[858]: info: CALLMETHOD: audio_interface fusiondsp saveparameq [object Object] Jun 29 22:24:05 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , saveparameq Jun 29 22:24:05 volumio volumio[858]: info: FusionDsp - Ok! Convolution files exist Jun 29 22:24:05 volumio volumio[858]: info: FusionDsp - File size found in array! Jun 29 22:24:05 volumio volumio[858]: info: FusionDsp - File size found in array! Jun 29 22:24:05 volumio volumio[858]: info: FusionDsp - Test Eq1|Conv|L/data/INTERNAL/FusionDsp/filters/demo_swap_left_1.pcm|15.35|Eq2|Conv|R/data/INTERNAL/FusionDsp/filters/demo_swap_right_1.pcm|15.35| Jun 29 22:24:05 volumio volumio[858]: info: FusionDsp - value distance L------- 0 R 0 Jun 29 22:24:05 volumio volumio[858]: info: FusionDsp - no delay needed Jun 29 22:24:05 volumio volumio[858]: info: FusionDsp - File size found in array! Jun 29 22:24:05 volumio volumio[858]: info: CoreCommandRouter::volumioPause Jun 29 22:24:05 volumio volumio[858]: info: CoreStateMachine::pause Jun 29 22:24:05 volumio volumio[858]: info: CoreStateMachine::stPlaybackTimer Jun 29 22:24:05 volumio volumio[858]: info: CoreStateMachine::servicePause Jun 29 22:24:05 volumio volumio[858]: info: CorePlayQueue::getTrack 32 Jun 29 22:24:05 volumio volumio[858]: info: CoreCommandRouter::servicePause Jun 29 22:24:05 volumio volumio[858]: info: [1751228645788] ControllerTidal::pause Jun 29 22:24:05 volumio volumio[858]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 29 22:24:05 volumio volumio[858]: info: ControllerMpd::pause Jun 29 22:24:05 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand pause Jun 29 22:24:05 volumio volumio[858]: info: Jun 29 22:24:05 volumio volumio[858]: ---------------------------- MPD announces state update: player Jun 29 22:24:05 volumio volumio[858]: info: sendMpdCommand pause took 6 milliseconds Jun 29 22:24:05 volumio volumio[858]: info: ControllerMpd::getState Jun 29 22:24:05 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Jun 29 22:24:05 volumio volumio[858]: info: Jun 29 22:24:05 volumio volumio[858]: ---------------------------- MPD announces state update: player Jun 29 22:24:05 volumio volumio[858]: info: ControllerMpd::getState Jun 29 22:24:05 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand status Jun 29 22:24:05 volumio volumio[858]: info: sendMpdCommand status took 3 milliseconds Jun 29 22:24:05 volumio volumio[858]: info: sendMpdCommand status took 1 milliseconds Jun 29 22:24:05 volumio volumio[858]: verbose: ControllerMpd::parseState Jun 29 22:24:05 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 22:24:05 volumio volumio[858]: verbose: ControllerMpd::parseState Jun 29 22:24:05 volumio volumio[858]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 29 22:24:05 volumio volumio[858]: info: sendMpdCommand playlistinfo took 3 milliseconds Jun 29 22:24:05 volumio volumio[858]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 29 22:24:05 volumio volumio[858]: verbose: ControllerMpd::parseTrackInfo Jun 29 22:24:05 volumio volumio[858]: verbose: ControllerMpd::parseTrackInfo Jun 29 22:24:05 volumio volumio[858]: info: ControllerMpd::pushState Jun 29 22:24:05 volumio volumio[858]: info: CoreCommandRouter::servicePushState Jun 29 22:24:05 volumio volumio[858]: info: CorePlayQueue::getTrack 32 Jun 29 22:24:05 volumio volumio[858]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":97696,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1088 Kbps","isStreaming":false,"title":"17894688&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/17894688&soundQuality=HI_RES","trackType":"tidal"} Jun 29 22:24:05 volumio volumio[858]: verbose: CURRENT POSITION 32 Jun 29 22:24:05 volumio volumio[858]: info: CoreStateMachine::syncState stateService pause Jun 29 22:24:05 volumio volumio[858]: info: CoreStateMachine::syncState currentStatus pause Jun 29 22:24:05 volumio volumio[858]: info: CoreStateMachine::pushState Jun 29 22:24:05 volumio volumio[858]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 29 22:24:05 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Jun 29 22:24:05 volumio volumio[858]: info: CoreStateMachine::stPlaybackTimer Jun 29 22:24:05 volumio volumio[858]: info: ControllerMpd::pushState Jun 29 22:24:05 volumio volumio[858]: info: CoreCommandRouter::servicePushState Jun 29 22:24:05 volumio volumio[858]: info: CorePlayQueue::getTrack 32 Jun 29 22:24:05 volumio volumio[858]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":97696,"duration":268,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"1088 Kbps","isStreaming":false,"title":"17894688&soundQuality=HI_RES","artist":null,"album":null,"uri":"http://127.0.0.1:3245?data=tidal://song/17894688&soundQuality=HI_RES","trackType":"tidal"} Jun 29 22:24:05 volumio volumio[858]: verbose: CURRENT POSITION 32 Jun 29 22:24:05 volumio volumio[858]: info: CoreStateMachine::syncState stateService pause Jun 29 22:24:05 volumio volumio[858]: info: CoreStateMachine::syncState currentStatus pause Jun 29 22:24:05 volumio volumio[858]: info: CoreStateMachine::pushState Jun 29 22:24:05 volumio volumio[858]: info: CoreCommandRouter::volumioPushState Jun 29 22:24:05 volumio volumio[858]: info: CoreStateMachine::stPlaybackTimer Jun 29 22:24:05 volumio volumio[858]: info: ------------------------------ 35ms Jun 29 22:24:05 volumio volumio[858]: info: ------------------------------ 34ms Jun 29 22:24:05 volumio volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 29 22:24:05 volumio volumio[858]: info: [ASDebug] CurState: pause PrevState: play Jun 29 22:24:05 volumio volumio[858]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 29 22:24:05 volumio volumio[858]: info: [ASDebug] CurState: pause PrevState: play Jun 29 22:24:05 volumio volumio[858]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Jun 29 22:24:05 volumio volumio[858]: info: PeppyMeterBasic ---peppymeterbasic status pause Jun 29 22:24:05 volumio volumio[858]: info: PeppyMeterBasic ---peppymeterbasic status pause Jun 29 22:24:05 volumio sudo[5512]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 29 22:24:05 volumio sudo[5512]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:24:05 volumio systemd[1]: Stopping peppymeterbasic Daemon... Jun 29 22:24:05 volumio systemd[1]: peppymeterbasic.service: Main process exited, code=killed, status=15/TERM Jun 29 22:24:05 volumio sudo[5515]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Jun 29 22:24:05 volumio sudo[5515]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 29 22:24:06 volumio systemd[1]: peppymeterbasic.service: Succeeded. Jun 29 22:24:06 volumio systemd[1]: Stopped peppymeterbasic Daemon. Jun 29 22:24:06 volumio volumio[858]: info: touch_display: Setting screensaver timeout to 120 seconds. Jun 29 22:24:06 volumio sudo[5515]: pam_unix(sudo:session): session closed for user root Jun 29 22:24:06 volumio volumio[858]: info: peppymeterbasic Daemon Stop Jun 29 22:24:06 volumio sudo[5512]: pam_unix(sudo:session): session closed for user root Jun 29 22:24:06 volumio volumio[858]: info: peppymeterbasic Daemon Stop Jun 29 22:24:06 volumio volumio[858]: info: FusionDsp - File size found in array! Jun 29 22:24:06 volumio volumio[858]: info: FusionDsp - {"Reload":{"result":"Ok"}} Jun 29 22:24:06 volumio volumio[858]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 29 22:24:06 volumio volumio[858]: info: CoreCommandRouter::Close All Modals sent Jun 29 22:24:06 volumio volumio[858]: info: FusionDsp - no preset used Jun 29 22:24:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Jun 29 22:24:06 volumio volumio[858]: info: FusionDsp - kef.json,test.json Jun 29 22:24:06 volumio volumio[858]: info: [ASDebug] Togle GPIO: OFF Jun 29 22:24:06 volumio volumio[858]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 29 22:24:06 volumio volumio[858]: Error: ESPIPE: invalid seek, write Jun 29 22:24:06 volumio volumio[858]: at Object.writeSync (fs.js:711:3) Jun 29 22:24:06 volumio volumio[858]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Jun 29 22:24:06 volumio volumio[858]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Jun 29 22:24:06 volumio volumio[858]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Jun 29 22:24:06 volumio volumio[858]: at listOnTimeout (internal/timers.js:554:17) Jun 29 22:24:06 volumio volumio[858]: at processTimers (internal/timers.js:497:7) { Jun 29 22:24:06 volumio volumio[858]: errno: -29, Jun 29 22:24:06 volumio volumio[858]: syscall: 'write', Jun 29 22:24:06 volumio volumio[858]: code: 'ESPIPE' Jun 29 22:24:06 volumio volumio[858]: } Jun 29 22:24:06 volumio volumio[858]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 29 22:24:07 volumio sudo[5535]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-29 22:23 Jun 29 22:24:07 volumio sudo[5535]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="a142ed2648fb91920de0dde3a60cd39b5d0ba4f6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1fc282af1865fae9b40239cedf2cc17c112eef0d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Jun 2025 03:18:50 PM CEST" VOLUMIO_VERSION="3.818" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d5105a50d571ff4623988fe02d73334b"