-- Logs begin at Thu 2025-03-27 16:19:07 UTC, end at Tue 2025-04-22 18:08:44 UTC. -- Apr 22 18:07:05 volumio volumio[2571]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Apr 22 18:07:05 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Apr 22 18:07:05 volumio volumio[2571]: info: FusionDsp - Launching CamillaDsp GUI Apr 22 18:07:05 volumio volumio[2571]: info: FusionDsp loaded Apr 22 18:07:05 volumio sudo[18663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Apr 22 18:07:05 volumio sudo[18663]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 18:07:05 volumio sudo[18663]: pam_unix(sudo:session): session closed for user root Apr 22 18:07:05 volumio volumio[2571]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 22 18:07:05 volumio volumio[2571]: error: FusionDsp - WebSocket error: [object Object] Apr 22 18:07:05 volumio volumio[2571]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 18:07:05 volumio volumio[2571]: info: CoreCommandRouter::Close All Modals sent Apr 22 18:07:05 volumio volumio[2571]: info: FusionDsp - IP adress is ---------------------------192.168.1.7 Apr 22 18:07:05 volumio volumio[2571]: info: FusionDsp - Apr 22 18:07:05 volumio volumio[2571]: info: FusionDsp - undefined Apr 22 18:07:06 volumio volumio[2571]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 22 18:07:06 volumio volumio[2571]: 1+22 records in Apr 22 18:07:06 volumio volumio[2571]: 1+22 records out Apr 22 18:07:06 volumio volumio[2571]: 168992 bytes (169 kB, 165 KiB) copied, 0.0108172 s, 15.6 MB/s Apr 22 18:07:07 volumio volumio[2571]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 22 18:07:07 volumio volumio[2571]: 1+26 records in Apr 22 18:07:07 volumio volumio[2571]: 1+26 records out Apr 22 18:07:07 volumio volumio[2571]: 165152 bytes (165 kB, 161 KiB) copied, 0.00193017 s, 85.6 MB/s Apr 22 18:07:09 volumio volumio[2571]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 22 18:07:09 volumio volumio[2571]: 1+9 records in Apr 22 18:07:09 volumio volumio[2571]: 1+9 records out Apr 22 18:07:09 volumio volumio[2571]: 97840 bytes (98 kB, 96 KiB) copied, 0.000772993 s, 127 MB/s Apr 22 18:07:10 volumio volumio[2571]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 22 18:07:10 volumio volumio[2571]: 1+5 records in Apr 22 18:07:10 volumio volumio[2571]: 1+5 records out Apr 22 18:07:10 volumio volumio[2571]: 75568 bytes (76 kB, 74 KiB) copied, 0.000849288 s, 89.0 MB/s Apr 22 18:07:11 volumio volumio[2571]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 22 18:07:11 volumio volumio[2571]: 1+2 records in Apr 22 18:07:11 volumio volumio[2571]: 1+2 records out Apr 22 18:07:11 volumio volumio[2571]: 67104 bytes (67 kB, 66 KiB) copied, 0.00078741 s, 85.2 MB/s Apr 22 18:07:12 volumio volumio[2571]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 22 18:07:12 volumio volumio[2571]: 33+1 records in Apr 22 18:07:12 volumio volumio[2571]: 33+1 records out Apr 22 18:07:12 volumio volumio[2571]: 1106832 bytes (1.1 MB, 1.1 MiB) copied, 0.0101113 s, 109 MB/s Apr 22 18:07:12 volumio go-librespot[6933]: time="2025-04-22T18:07:12Z" level=trace msg="sent dealer ping" Apr 22 18:07:12 volumio go-librespot[6933]: time="2025-04-22T18:07:12Z" level=trace msg="received dealer pong" Apr 22 18:07:13 volumio volumio[2571]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 22 18:07:13 volumio volumio[2571]: 1+2 records in Apr 22 18:07:13 volumio volumio[2571]: 1+2 records out Apr 22 18:07:13 volumio volumio[2571]: 67104 bytes (67 kB, 66 KiB) copied, 0.000880289 s, 76.2 MB/s Apr 22 18:07:14 volumio volumio[2571]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 22 18:07:14 volumio volumio[2571]: 75+1 records in Apr 22 18:07:14 volumio volumio[2571]: 75+1 records out Apr 22 18:07:14 volumio volumio[2571]: 2487552 bytes (2.5 MB, 2.4 MiB) copied, 0.0221919 s, 112 MB/s Apr 22 18:07:15 volumio volumio[2571]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Apr 22 18:07:15 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Apr 22 18:07:15 volumio volumio[2571]: error: FusionDsp - File size not found in array! Apr 22 18:07:15 volumio volumio[2571]: info: FusionDsp - Nulleq applied Apr 22 18:07:15 volumio volumio[2571]: error: FusionDsp - WebSocket error: [object Object] Apr 22 18:07:15 volumio volumio[2571]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 18:07:15 volumio volumio[2571]: info: CoreCommandRouter::Close All Modals sent Apr 22 18:07:15 volumio volumio[2571]: info: FusionDsp - Choose a preset Apr 22 18:07:15 volumio volumio[2571]: info: FusionDsp - kef.json,test.json Apr 22 18:07:15 volumio volumio[2571]: /bin/dd: error reading '/tmp/fusiondspfifo': Resource temporarily unavailable Apr 22 18:07:15 volumio volumio[2571]: 1+1 records in Apr 22 18:07:15 volumio volumio[2571]: 1+1 records out Apr 22 18:07:15 volumio volumio[2571]: 63280 bytes (63 kB, 62 KiB) copied, 0.000183592 s, 345 MB/s Apr 22 18:07:16 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 22 18:07:16 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 22 18:07:16 volumio volumio[2571]: info: Discovery: Getting this device information Apr 22 18:07:16 volumio volumio[2571]: info: CoreCommandRouter::volumioGetState Apr 22 18:07:16 volumio volumio[2571]: info: CorePlayQueue::getTrack 20 Apr 22 18:07:16 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 22 18:07:32 volumio volumio[2571]: info: CALLMETHOD: audio_interface fusiondsp choosedsp [object Object] Apr 22 18:07:32 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , choosedsp Apr 22 18:07:32 volumio volumio[2571]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 22 18:07:32 volumio volumio[2571]: error: FusionDsp - WebSocket error: [object Object] Apr 22 18:07:32 volumio volumio[2571]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 18:07:32 volumio volumio[2571]: info: CoreCommandRouter::Close All Modals sent Apr 22 18:07:32 volumio volumio[2571]: info: FusionDsp - Choose a preset Apr 22 18:07:32 volumio volumio[2571]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Apr 22 18:07:42 volumio go-librespot[6933]: time="2025-04-22T18:07:42Z" level=trace msg="sent dealer ping" Apr 22 18:07:42 volumio go-librespot[6933]: time="2025-04-22T18:07:42Z" level=trace msg="received dealer pong" Apr 22 18:07:46 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 22 18:07:46 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 22 18:07:46 volumio volumio[2571]: info: Discovery: Getting this device information Apr 22 18:07:46 volumio volumio[2571]: info: CoreCommandRouter::volumioGetState Apr 22 18:07:46 volumio volumio[2571]: info: CorePlayQueue::getTrack 20 Apr 22 18:07:46 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 22 18:07:50 volumio volumio[2571]: info: CALLMETHOD: audio_interface fusiondsp moresettings Apr 22 18:07:50 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , moresettings Apr 22 18:07:50 volumio volumio[2571]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 22 18:07:50 volumio volumio[2571]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 18:07:50 volumio volumio[2571]: info: CoreCommandRouter::Close All Modals sent Apr 22 18:07:50 volumio volumio[2571]: info: FusionDsp - Choose a preset Apr 22 18:07:51 volumio volumio[2571]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Apr 22 18:07:52 volumio volumio[2571]: info: CALLMETHOD: audio_interface fusiondsp reseteq Apr 22 18:07:52 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , reseteq Apr 22 18:07:53 volumio volumio[2571]: info: FusionDsp - If filter freq >samplerate/2 then disable it Apr 22 18:07:53 volumio volumio[2571]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 18:07:53 volumio volumio[2571]: info: CoreCommandRouter::Close All Modals sent Apr 22 18:07:53 volumio volumio[2571]: info: FusionDsp - Choose a preset Apr 22 18:07:53 volumio volumio[2571]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Apr 22 18:08:12 volumio go-librespot[6933]: time="2025-04-22T18:08:12Z" level=trace msg="received accesspoint ping" Apr 22 18:08:12 volumio go-librespot[6933]: time="2025-04-22T18:08:12Z" level=trace msg="received accesspoint pong ack" Apr 22 18:08:12 volumio go-librespot[6933]: time="2025-04-22T18:08:12Z" level=trace msg="sent dealer ping" Apr 22 18:08:12 volumio go-librespot[6933]: time="2025-04-22T18:08:12Z" level=trace msg="received dealer pong" Apr 22 18:08:14 volumio volumio[2571]: info: CALLMETHOD: audio_interface fusiondsp manualdelay Apr 22 18:08:14 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , manualdelay Apr 22 18:08:14 volumio volumio[2571]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 18:08:14 volumio volumio[2571]: info: CoreCommandRouter::Close All Modals sent Apr 22 18:08:14 volumio volumio[2571]: info: FusionDsp - Choose a preset Apr 22 18:08:14 volumio volumio[2571]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Apr 22 18:08:16 volumio volumio[2571]: info: CALLMETHOD: audio_interface fusiondsp speakerdistance Apr 22 18:08:16 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: fusiondsp , speakerdistance Apr 22 18:08:16 volumio volumio[2571]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 22 18:08:16 volumio volumio[2571]: info: CoreCommandRouter::Close All Modals sent Apr 22 18:08:16 volumio volumio[2571]: info: FusionDsp - Choose a preset Apr 22 18:08:16 volumio volumio[2571]: info: FusionDsp - .Bass-FusionDsp.json,.Classic-FusionDsp.json,.Rock-FusionDsp.json,.Soundtrack-FusionDsp.json,.Voice-FusionDsp.json Apr 22 18:08:16 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 22 18:08:16 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 22 18:08:16 volumio volumio[2571]: info: Discovery: Getting this device information Apr 22 18:08:16 volumio volumio[2571]: info: CoreCommandRouter::volumioGetState Apr 22 18:08:16 volumio volumio[2571]: info: CorePlayQueue::getTrack 20 Apr 22 18:08:16 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 22 18:08:25 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 22 18:08:25 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 22 18:08:29 volumio volumio[2571]: info: FusionDsp - Stopping FusionDsp service Apr 22 18:08:29 volumio volumio[2571]: info: camilladsp stopping service pid 10175... Apr 22 18:08:29 volumio volumio[2571]: info: camilladsp service terminated, instance 2 Apr 22 18:08:29 volumio volumio[2571]: info: Disabling plugin fusiondsp Apr 22 18:08:29 volumio sudo[29436]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop fusiondsp.service Apr 22 18:08:29 volumio sudo[29436]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 18:08:29 volumio volumio[2571]: info: Preparing to generate the ALSA configuration file Apr 22 18:08:29 volumio systemd[1]: Stopping FusionDsp Daemon... Apr 22 18:08:29 volumio volumio[8559]: Backend configuration: Apr 22 18:08:29 volumio volumio[8559]: active_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Apr 22 18:08:29 volumio volumio[8559]: active_config_txt: null Apr 22 18:08:29 volumio volumio[8559]: camilla_host: 127.0.0.1 Apr 22 18:08:29 volumio volumio[8559]: camilla_port: 9876 Apr 22 18:08:29 volumio volumio[8559]: coeff_dir: /data/INTERNAL/FusionDsp/filters Apr 22 18:08:29 volumio volumio[8559]: config_dir: /data/configuration/audio_interface/fusiondsp Apr 22 18:08:29 volumio volumio[8559]: default_config: /data/configuration/audio_interface/fusiondsp/camilladsp.yml Apr 22 18:08:29 volumio volumio[8559]: log_file: /tmp/camilladsp.log Apr 22 18:08:29 volumio volumio[8559]: on_get_active_config: null Apr 22 18:08:29 volumio volumio[8559]: on_set_active_config: null Apr 22 18:08:29 volumio volumio[8559]: port: 5011 Apr 22 18:08:29 volumio volumio[8559]: supported_capture_types: null Apr 22 18:08:29 volumio volumio[8559]: supported_playback_types: null Apr 22 18:08:29 volumio volumio[8559]: update_config_symlink: false Apr 22 18:08:29 volumio volumio[8559]: update_config_txt: false Apr 22 18:08:29 volumio volumio[8559]: ======== Running on http://0.0.0.0:5011 ======== Apr 22 18:08:29 volumio volumio[8559]: (Press CTRL+C to quit) Apr 22 18:08:29 volumio volumio[2571]: info: Asound.conf file written Apr 22 18:08:29 volumio sudo[29440]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Apr 22 18:08:29 volumio sudo[29440]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 18:08:29 volumio sudo[29440]: pam_unix(sudo:session): session closed for user root Apr 22 18:08:29 volumio volumio[2571]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:I2SIn CLK:0' : Operation not permitted Apr 22 18:08:29 volumio volumio[2571]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN audio samplerate:0' : Operation not permitted Apr 22 18:08:29 volumio volumio[2571]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:SPDIFIN Audio Type:0' : Operation not permitted Apr 22 18:08:29 volumio volumio[2571]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:XMOS Internal Clock Validity:0' : Operation not permitted Apr 22 18:08:29 volumio kernel: out of value, fixed it Apr 22 18:08:29 volumio kernel: id=0 set inskew=0 Apr 22 18:08:29 volumio kernel: AED req_sel0 module:TDMOUT_B Apr 22 18:08:29 volumio kernel: spk_mute_set: mute flag = 0 Apr 22 18:08:29 volumio volumio[2571]: info: Output device has changed, restarting MPD Apr 22 18:08:29 volumio sudo[29445]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 18:08:29 volumio sudo[29445]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 18:08:29 volumio sudo[29445]: pam_unix(sudo:session): session closed for user root Apr 22 18:08:29 volumio volumio[2571]: info: Output device has changed, restarting Shairport Sync Apr 22 18:08:29 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:29 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 18:08:29 volumio systemd[1]: fusiondsp.service: Succeeded. Apr 22 18:08:29 volumio systemd[1]: Stopped FusionDsp Daemon. Apr 22 18:08:29 volumio sudo[29448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 18:08:29 volumio sudo[29448]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 18:08:29 volumio sudo[29436]: pam_unix(sudo:session): session closed for user root Apr 22 18:08:29 volumio systemd[1]: Stopping Music Player Daemon... Apr 22 18:08:29 volumio volumio[2571]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 18:08:29 volumio volumio[2571]: info: Done. Apr 22 18:08:29 volumio systemd[1]: mpd.service: Succeeded. Apr 22 18:08:29 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 18:08:29 volumio systemd[1]: Starting Music Player Daemon... Apr 22 18:08:29 volumio volumio[2571]: error: Upnp client error: Error: This socket has been ended by the other party Apr 22 18:08:29 volumio volumio[2571]: info: MPD Permissions set Apr 22 18:08:29 volumio volumio[2571]: info: FusionDsp - Reporting Fusion DSP Disabled Apr 22 18:08:29 volumio volumio[2571]: info: Removing fusiondspeq DSP Signal Path Element Apr 22 18:08:29 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:29 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:29 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:29 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:29 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:29 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:29 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:29 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:29 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 18:08:29 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 22 18:08:29 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:29 volumio sudo[29455]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 18:08:29 volumio sudo[29455]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 18:08:29 volumio volumio[2571]: info: Starting Shairport Sync Apr 22 18:08:29 volumio sudo[29455]: pam_unix(sudo:session): session closed for user root Apr 22 18:08:29 volumio sudo[29469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 18:08:29 volumio sudo[29469]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 18:08:29 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 22 18:08:29 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 22 18:08:29 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 22 18:08:29 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 18:08:29 volumio sudo[29469]: pam_unix(sudo:session): session closed for user root Apr 22 18:08:29 volumio volumio[2571]: info: Shairport-Sync Started Apr 22 18:08:30 volumio mpd[29470]: Apr 22 18:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 22 18:08:30 volumio systemd[1]: Started Music Player Daemon. Apr 22 18:08:30 volumio sudo[29448]: pam_unix(sudo:session): session closed for user root Apr 22 18:08:30 volumio volumio[2571]: error: updateQueue error: null Apr 22 18:08:36 volumio volumio[2571]: info: CoreCommandRouter::volumioPause Apr 22 18:08:36 volumio volumio[2571]: info: CoreStateMachine::pause Apr 22 18:08:36 volumio volumio[2571]: info: CoreStateMachine::stPlaybackTimer Apr 22 18:08:36 volumio volumio[2571]: info: CoreStateMachine::servicePause Apr 22 18:08:36 volumio volumio[2571]: info: CorePlayQueue::getTrack 20 Apr 22 18:08:36 volumio volumio[2571]: info: CoreCommandRouter::servicePause Apr 22 18:08:36 volumio volumio[2571]: info: ControllerMpd::pause Apr 22 18:08:36 volumio volumio[2571]: verbose: ControllerMpd::sendMpdCommand pause Apr 22 18:08:36 volumio volumio[2571]: info: sendMpdCommand pause took 2 milliseconds Apr 22 18:08:37 volumio volumio[2571]: info: CoreCommandRouter::volumioPause Apr 22 18:08:37 volumio volumio[2571]: info: CoreStateMachine::pause Apr 22 18:08:38 volumio volumio[2571]: info: CoreCommandRouter::volumioNext Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::next Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::stop Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::updateTrackBlock Apr 22 18:08:38 volumio volumio[2571]: info: CorePlayQueue::getTrackBlock Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::stPlaybackTimer Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::pushState Apr 22 18:08:38 volumio volumio[2571]: info: CorePlayQueue::getTrack 20 Apr 22 18:08:38 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 18:08:38 volumio volumio[2571]: info: CoreCommandRouter::volumioPushState Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::serviceStop Apr 22 18:08:38 volumio volumio[2571]: info: CorePlayQueue::getTrack 20 Apr 22 18:08:38 volumio volumio[2571]: info: CoreCommandRouter::serviceStop Apr 22 18:08:38 volumio volumio[2571]: info: ControllerMpd::stop Apr 22 18:08:38 volumio volumio[2571]: verbose: ControllerMpd::sendMpdCommand stop Apr 22 18:08:38 volumio volumio[2571]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Apr 22 18:08:38 volumio volumio[2571]: info: sendMpdCommand stop took 13 milliseconds Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::play index undefined Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::setConsumeUpdateService undefined Apr 22 18:08:38 volumio volumio[2571]: info: CorePlayQueue::getTrack 21 Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::startPlaybackTimer Apr 22 18:08:38 volumio volumio[2571]: info: CorePlayQueue::getTrack 21 Apr 22 18:08:38 volumio volumio[2571]: verbose: ControllerMpd::clearAddPlayTracks USB/9040-3EB3/Sena Şener - İnsan Gelir İnsan Geçer (Deluxe) Albüm (2018)/02. Sena Şener - Çirkin Dünya.mp3 Apr 22 18:08:38 volumio volumio[2571]: verbose: ControllerMpd::sendMpdCommand stop Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::updateTrackBlock Apr 22 18:08:38 volumio volumio[2571]: info: CorePlayQueue::getTrackBlock Apr 22 18:08:38 volumio volumio[2571]: info: sendMpdCommand stop took 0 milliseconds Apr 22 18:08:38 volumio volumio[2571]: verbose: ControllerMpd::sendMpdCommand clear Apr 22 18:08:38 volumio volumio[2571]: info: Apr 22 18:08:38 volumio volumio[2571]: ---------------------------- MPD announces system playlist update Apr 22 18:08:38 volumio volumio[2571]: info: Ignoring MPD Status Update Apr 22 18:08:38 volumio volumio[2571]: info: sendMpdCommand clear took 2 milliseconds Apr 22 18:08:38 volumio volumio[2571]: verbose: ControllerMpd::sendMpdCommand add "USB/9040-3EB3/Sena Şener - İnsan Gelir İnsan Geçer (Deluxe) Albüm (2018)/02. Sena Şener - Çirkin Dünya.mp3" Apr 22 18:08:38 volumio volumio[2571]: error: updateQueue error: null Apr 22 18:08:38 volumio volumio[2571]: info: Apr 22 18:08:38 volumio volumio[2571]: ---------------------------- MPD announces system playlist update Apr 22 18:08:38 volumio volumio[2571]: info: Ignoring MPD Status Update Apr 22 18:08:38 volumio volumio[2571]: info: ------------------------------ 14ms Apr 22 18:08:38 volumio volumio[2571]: info: sendMpdCommand add "USB/9040-3EB3/Sena Şener - İnsan Gelir İnsan Geçer (Deluxe) Albüm (2018)/02. Sena Şener - Çirkin Dünya.mp3" took 14 milliseconds Apr 22 18:08:38 volumio volumio[2571]: verbose: ControllerMpd::sendMpdCommand play Apr 22 18:08:38 volumio volumio[2571]: info: ------------------------------ 3ms Apr 22 18:08:38 volumio volumio[2571]: info: sendMpdCommand play took 3 milliseconds Apr 22 18:08:38 volumio volumio[2571]: info: Apr 22 18:08:38 volumio volumio[2571]: ---------------------------- MPD announces state update: player Apr 22 18:08:38 volumio volumio[2571]: info: ControllerMpd::getState Apr 22 18:08:38 volumio volumio[2571]: verbose: ControllerMpd::sendMpdCommand status Apr 22 18:08:38 volumio volumio[2571]: info: Apr 22 18:08:38 volumio volumio[2571]: ---------------------------- MPD announces state update: player Apr 22 18:08:38 volumio volumio[2571]: info: sendMpdCommand status took 52 milliseconds Apr 22 18:08:38 volumio volumio[2571]: info: ControllerMpd::getState Apr 22 18:08:38 volumio volumio[2571]: verbose: ControllerMpd::sendMpdCommand status Apr 22 18:08:38 volumio volumio[2571]: verbose: ControllerMpd::parseState Apr 22 18:08:38 volumio volumio[2571]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 18:08:38 volumio volumio[2571]: info: sendMpdCommand status took 2 milliseconds Apr 22 18:08:38 volumio volumio[2571]: info: sendMpdCommand playlistinfo took 1 milliseconds Apr 22 18:08:38 volumio volumio[2571]: verbose: ControllerMpd::parseState Apr 22 18:08:38 volumio volumio[2571]: verbose: ControllerMpd::sendMpdCommand playlistinfo Apr 22 18:08:38 volumio volumio[2571]: verbose: ControllerMpd::parseTrackInfo Apr 22 18:08:38 volumio volumio[2571]: info: ControllerMpd::pushState Apr 22 18:08:38 volumio volumio[2571]: info: CoreCommandRouter::servicePushState Apr 22 18:08:38 volumio volumio[2571]: info: CorePlayQueue::getTrack 21 Apr 22 18:08:38 volumio volumio[2571]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":291,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Çirkin Dünya","artist":"Sena Şener","album":"İnsan Gelir İnsan Geçer (Deluxe)","uri":"USB/9040-3EB3/Sena Şener - İnsan Gelir İnsan Geçer (Deluxe) Albüm (2018)/02. Sena Şener - Çirkin Dünya.mp3","trackType":"mp3"} Apr 22 18:08:38 volumio volumio[2571]: verbose: CURRENT POSITION 21 Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::syncState stateService play Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::syncState currentStatus stop Apr 22 18:08:38 volumio volumio[2571]: info: ------------------------------ 58ms Apr 22 18:08:38 volumio volumio[2571]: info: sendMpdCommand playlistinfo took 6 milliseconds Apr 22 18:08:38 volumio volumio[2571]: verbose: ControllerMpd::parseTrackInfo Apr 22 18:08:38 volumio volumio[2571]: info: ControllerMpd::pushState Apr 22 18:08:38 volumio volumio[2571]: info: CoreCommandRouter::servicePushState Apr 22 18:08:38 volumio volumio[2571]: info: CorePlayQueue::getTrack 21 Apr 22 18:08:38 volumio volumio[2571]: verbose: STATE SERVICE {"status":"play","position":0,"seek":857,"duration":291,"samplerate":"44.1 kHz","bitdepth":"24 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"320 Kbps","isStreaming":false,"title":"Çirkin Dünya","artist":"Sena Şener","album":"İnsan Gelir İnsan Geçer (Deluxe)","uri":"USB/9040-3EB3/Sena Şener - İnsan Gelir İnsan Geçer (Deluxe) Albüm (2018)/02. Sena Şener - Çirkin Dünya.mp3","trackType":"mp3"} Apr 22 18:08:38 volumio volumio[2571]: verbose: CURRENT POSITION 21 Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::syncState stateService play Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::syncState currentStatus play Apr 22 18:08:38 volumio volumio[2571]: info: Received an update from plugin. extracting info from payload Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::pushState Apr 22 18:08:38 volumio volumio[2571]: info: CorePlayQueue::getTrack 21 Apr 22 18:08:38 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 22 18:08:38 volumio volumio[2571]: info: CoreCommandRouter::volumioPushState Apr 22 18:08:38 volumio volumio[2571]: info: CoreStateMachine::pushState Apr 22 18:08:38 volumio volumio[2571]: info: CorePlayQueue::getTrack 21 Apr 22 18:08:38 volumio volumio[2571]: info: CoreCommandRouter::volumioPushState Apr 22 18:08:38 volumio volumio[2571]: info: ------------------------------ 18ms Apr 22 18:08:38 volumio volumio[2571]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Apr 22 18:08:38 volumio volumio[2571]: SPOTIFY: RECEIVED VOLUMIO VOLUME 79 Apr 22 18:08:42 volumio go-librespot[6933]: time="2025-04-22T18:08:42Z" level=trace msg="sent dealer ping" Apr 22 18:08:42 volumio go-librespot[6933]: time="2025-04-22T18:08:42Z" level=trace msg="received dealer pong" Apr 22 18:08:42 volumio volumio[2571]: info: Starting Uninstall of plugin audio_interface - fusiondsp Apr 22 18:08:42 volumio volumio[2571]: info: Uninstalling plugin fusiondsp Apr 22 18:08:42 volumio volumio[2571]: info: Disabling plugin fusiondsp Apr 22 18:08:42 volumio volumio[2571]: info: Preparing to generate the ALSA configuration file Apr 22 18:08:43 volumio volumio[2571]: info: Asound.conf file unchanged, so no further update is needed Apr 22 18:08:43 volumio volumio[2571]: info: Output device has changed, restarting MPD Apr 22 18:08:43 volumio sudo[31365]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Apr 22 18:08:43 volumio sudo[31365]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 18:08:43 volumio sudo[31365]: pam_unix(sudo:session): session closed for user root Apr 22 18:08:43 volumio volumio[2571]: info: Output device has changed, restarting Shairport Sync Apr 22 18:08:43 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:43 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 18:08:43 volumio sudo[31368]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Apr 22 18:08:43 volumio sudo[31368]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 18:08:43 volumio systemd[1]: Stopping Music Player Daemon... Apr 22 18:08:43 volumio volumio[2571]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Apr 22 18:08:43 volumio volumio[2571]: info: Checking if uninstall.sh is present Apr 22 18:08:43 volumio volumio[2571]: info: Executing uninstall.sh Apr 22 18:08:43 volumio volumio[2571]: info: MPD Permissions set Apr 22 18:08:43 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:43 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:43 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:43 volumio systemd[1]: mpd.service: Succeeded. Apr 22 18:08:43 volumio systemd[1]: Stopped Music Player Daemon. Apr 22 18:08:43 volumio systemd[1]: Starting Music Player Daemon... Apr 22 18:08:43 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Apr 22 18:08:43 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Apr 22 18:08:43 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Apr 22 18:08:43 volumio volumio[2571]: info: Starting Shairport Sync Apr 22 18:08:43 volumio sudo[31382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/uninstall.sh Apr 22 18:08:43 volumio sudo[31382]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 18:08:43 volumio sudo[31401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Apr 22 18:08:43 volumio sudo[31401]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 18:08:43 volumio sudo[31384]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Apr 22 18:08:43 volumio sudo[31384]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 18:08:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Apr 22 18:08:43 volumio systemd[1]: shairport-sync.service: Succeeded. Apr 22 18:08:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Apr 22 18:08:43 volumio sudo[31384]: pam_unix(sudo:session): session closed for user root Apr 22 18:08:43 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Apr 22 18:08:43 volumio sudo[31401]: pam_unix(sudo:session): session closed for user root Apr 22 18:08:43 volumio sudo[31412]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm /lib/systemd/system/fusiondsp.service Apr 22 18:08:43 volumio sudo[31412]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 18:08:43 volumio sudo[31412]: pam_unix(sudo:session): session closed for user root Apr 22 18:08:43 volumio volumio[2571]: info: Shairport-Sync Started Apr 22 18:08:43 volumio sudo[31418]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Apr 22 18:08:43 volumio sudo[31418]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 22 18:08:43 volumio systemd[1]: Reloading. Apr 22 18:08:43 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Apr 22 18:08:43 volumio systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Apr 22 18:08:43 volumio systemd[1]: serial-getty@ttyS0.service: Current command vanished from the unit file, execution of the command list won't be resumed. Apr 22 18:08:43 volumio sudo[31418]: pam_unix(sudo:session): session closed for user root Apr 22 18:08:43 volumio sudo[31382]: pam_unix(sudo:session): session closed for user root Apr 22 18:08:43 volumio volumio[2571]: info: Uninstall script completed Apr 22 18:08:43 volumio volumio[2571]: info: Removing plugin fusiondsp from configuration Apr 22 18:08:43 volumio volumio[2571]: info: Successfully removed fusiondsp configuration files Apr 22 18:08:43 volumio volumio[2571]: info: Plugin folders cleanup Apr 22 18:08:43 volumio volumio[2571]: info: Scanning into folder /volumio/app/plugins/ Apr 22 18:08:43 volumio volumio[2571]: info: Scanning category audio_interface Apr 22 18:08:43 volumio volumio[2571]: info: Scanning category miscellanea Apr 22 18:08:43 volumio volumio[2571]: info: Scanning category music_service Apr 22 18:08:43 volumio volumio[2571]: info: Scanning category plugins.json Apr 22 18:08:43 volumio volumio[2571]: info: Scanning category system_controller Apr 22 18:08:43 volumio volumio[2571]: info: Scanning category user_interface Apr 22 18:08:43 volumio volumio[2571]: info: Scanning into folder /data/plugins/ Apr 22 18:08:43 volumio volumio[2571]: info: Scanning category audio_interface Apr 22 18:08:43 volumio volumio[2571]: info: Cleaning folder for fusiondsp Apr 22 18:08:43 volumio volumio[2571]: info: Scanning category music_service Apr 22 18:08:43 volumio volumio[2571]: info: Plugin folders cleanup completed Apr 22 18:08:43 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 22 18:08:43 volumio volumio[2571]: info: [{"prettyName":"Spotify","name":"spop","category":"music_service","version":"4.1.4","icon":"fa-spotify","isManuallyInstalled":false,"enabled":true,"active":true},{"prettyName":"YouTube Music","name":"ytmusic","category":"music_service","version":"1.3.1","icon":"fa-youtube-play","isManuallyInstalled":false,"enabled":true,"active":true}] Apr 22 18:08:43 volumio volumio[2571]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 22 18:08:43 volumio mpd[31417]: Apr 22 18:08 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Apr 22 18:08:43 volumio volumio[2571]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 18:08:43 volumio volumio[2571]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Apr 22 18:08:43 volumio volumio[2571]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Apr 22 18:08:43 volumio volumio[2571]: at onErrorNT (internal/child_process.js:472:16) Apr 22 18:08:43 volumio volumio[2571]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Apr 22 18:08:43 volumio volumio[2571]: errno: -2, Apr 22 18:08:43 volumio volumio[2571]: code: 'ENOENT', Apr 22 18:08:43 volumio volumio[2571]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Apr 22 18:08:43 volumio volumio[2571]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Apr 22 18:08:43 volumio volumio[2571]: spawnargs: [ Apr 22 18:08:43 volumio volumio[2571]: '-p', Apr 22 18:08:43 volumio volumio[2571]: 9876, Apr 22 18:08:43 volumio volumio[2571]: '-o', Apr 22 18:08:43 volumio volumio[2571]: '/tmp/camilladsp.log', Apr 22 18:08:43 volumio volumio[2571]: '-l', Apr 22 18:08:43 volumio volumio[2571]: 'warn', Apr 22 18:08:43 volumio volumio[2571]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Apr 22 18:08:43 volumio volumio[2571]: ] Apr 22 18:08:43 volumio volumio[2571]: } Apr 22 18:08:43 volumio volumio[2571]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 22 18:08:43 volumio systemd[1]: Started Music Player Daemon. Apr 22 18:08:43 volumio sudo[31368]: pam_unix(sudo:session): session closed for user root Apr 22 18:08:44 volumio sudo[31547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-04-22 18:07 Apr 22 18:08:44 volumio sudo[31547]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 20 Sep 2024 11:19:46 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="odroidn2" VOLUMIO_DEVICENAME="Odroid-N2" VOLUMIO_HASH="d7b8fff54b3cd04f736d9bdebc0af70d"