OK
I see something wrong… Sorry for that. I need to make some modifications. I’ll tell you when ready.
No problem! No hurry, life is still possible without Brutefir… 
Thank you for your dedicated support.
Dominique
It is not everyday someone ask for this plugin… so you’re welcome !
well, I uploaded a new version. When writing, I’m using it on a pine64 + usbdac.
So it should work for you…
Just a point, if you ant to use the equalizer, factory preset do nothing because all values are 0… but custom preset are ok.
let me know!
https://github.com/balbuze/volumio-plugins/tree/master/plugins/audio_interface/brutefir2
Thank you,
I will try this afternoon. No EQ, only phase linearisation with Rephase.
I keep you posted.
Dominique
Just made the new install. 0.4.9 installed.
I am very sorry, same problem again. This is the log:
volumio@volumio:~$ sudo journalctl -f
-- Logs begin at Sun 2018-04-01 12:46:55 UTC. --
Apr 02 10:05:18 volumio volumio[854]: info: Connecting to daemon
Apr 02 10:05:18 volumio volumio[854]: Huumm, is brutefir running ?
Apr 02 10:05:19 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 10:05:19 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 10:05:19 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 10:05:19 volumio systemd[1]: brutefir.service start request repeated too quickly, refusing to start.
Apr 02 10:05:19 volumio systemd[1]: Failed to start brutefir Daemon.
Apr 02 10:05:19 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 10:06:05 volumio sudo[2381]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f
Apr 02 10:06:05 volumio sudo[2381]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Apr 02 10:06:11 volumio volumio[854]: info: CALLMETHOD: audio_interface brutefir saveBrutefirconfigAccount2 [object Object]
Apr 02 10:06:11 volumio volumio[854]: info: CoreCommandRouter::executeOnPlugin: brutefir , saveBrutefirconfigAccount2
Apr 02 10:06:11 volumio volumio[854]: hw:5
Apr 02 10:06:11 volumio volumio[854]: /data/INTERNAL/brutefirfilters/
Apr 02 10:06:11 volumio volumio[854]: File exists.
Apr 02 10:06:11 volumio volumio[854]: File exists.
Apr 02 10:06:11 volumio sudo[2392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart brutefir.service
Apr 02 10:06:11 volumio sudo[2392]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 10:06:11 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 10:06:11 volumio systemd[1]: Started brutefir Daemon.
Apr 02 10:06:11 volumio sudo[2392]: pam_unix(sudo:session): session closed for user root
Apr 02 10:06:11 volumio volumio[2397]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 10:06:11 volumio volumio[2397]: Internal resolution is 64 bit floating point.
Apr 02 10:06:11 volumio volumio[2397]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 10:06:11 volumio sudo[2403]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start brutefir.service
Apr 02 10:06:11 volumio sudo[2403]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 10:06:11 volumio systemd[1]: Started brutefir Daemon.
Apr 02 10:06:11 volumio sudo[2403]: pam_unix(sudo:session): session closed for user root
Apr 02 10:06:11 volumio volumio[854]: info: Brutefir Daemon Started
Apr 02 10:06:11 volumio volumio[2397]: Loading 4 coefficient sets...finished.
Apr 02 10:06:11 volumio volumio[2397]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 10:06:11 volumio volumio[2397]: Warning: no support for clock cycle counter on this platform.
Apr 02 10:06:11 volumio volumio[2397]: Timers for benchmarking may be unreliable.
Apr 02 10:06:11 volumio volumio[2397]: Filters in process 0: 0 2
Apr 02 10:06:11 volumio volumio[2397]: Filters in process 1: 1 3
Apr 02 10:06:11 volumio volumio[2397]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 10:06:11 volumio volumio[2397]: Failed to set sample format to S16_LE: Invalid argument.
Apr 02 10:06:11 volumio volumio[2397]: Failed to init output device.
Apr 02 10:06:11 volumio volumio[2397]: Failed to initialise digital audio interfaces.
Apr 02 10:06:11 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 10:06:11 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 10:06:11 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 10:06:11 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 10:06:11 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 10:06:11 volumio systemd[1]: Started brutefir Daemon.
Apr 02 10:06:11 volumio volumio[2413]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 10:06:11 volumio volumio[2413]: Internal resolution is 64 bit floating point.
Apr 02 10:06:11 volumio volumio[2413]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 10:06:11 volumio volumio[2413]: Loading 4 coefficient sets...finished.
Apr 02 10:06:11 volumio volumio[2413]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 10:06:11 volumio volumio[2413]: Warning: no support for clock cycle counter on this platform.
Apr 02 10:06:11 volumio volumio[2413]: Timers for benchmarking may be unreliable.
Apr 02 10:06:11 volumio volumio[2413]: Filters in process 0: 0 2
Apr 02 10:06:11 volumio volumio[2413]: Filters in process 1: 1 3
Apr 02 10:06:11 volumio volumio[2413]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 10:06:11 volumio volumio[2413]: Failed to set sample format to S16_LE: Invalid argument.
Apr 02 10:06:11 volumio volumio[2413]: Failed to init output device.
Apr 02 10:06:11 volumio volumio[2413]: Failed to initialise digital audio interfaces.
Apr 02 10:06:11 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 10:06:11 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 10:06:12 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 10:06:12 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 10:06:12 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 10:06:12 volumio systemd[1]: Started brutefir Daemon.
Apr 02 10:06:12 volumio volumio[2416]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 10:06:12 volumio volumio[2416]: Internal resolution is 64 bit floating point.
Apr 02 10:06:12 volumio volumio[2416]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 10:06:12 volumio volumio[2416]: Loading 4 coefficient sets...finished.
Apr 02 10:06:12 volumio volumio[2416]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 10:06:12 volumio volumio[2416]: Warning: no support for clock cycle counter on this platform.
Apr 02 10:06:12 volumio volumio[2416]: Timers for benchmarking may be unreliable.
Apr 02 10:06:12 volumio volumio[2416]: Filters in process 0: 0 2
Apr 02 10:06:12 volumio volumio[2416]: Filters in process 1: 1 3
Apr 02 10:06:12 volumio volumio[2416]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 10:06:12 volumio volumio[2416]: Failed to set sample format to S16_LE: Invalid argument.
Apr 02 10:06:12 volumio volumio[2416]: Failed to init output device.
Apr 02 10:06:12 volumio volumio[2416]: Failed to initialise digital audio interfaces.
Apr 02 10:06:12 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 10:06:12 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 10:06:12 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 10:06:12 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 10:06:12 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 10:06:12 volumio systemd[1]: Started brutefir Daemon.
Apr 02 10:06:12 volumio volumio[2420]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 10:06:12 volumio volumio[2420]: Internal resolution is 64 bit floating point.
Apr 02 10:06:12 volumio volumio[2420]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 10:06:12 volumio volumio[2420]: Loading 4 coefficient sets...finished.
Apr 02 10:06:12 volumio volumio[2420]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 10:06:12 volumio volumio[2420]: Warning: no support for clock cycle counter on this platform.
Apr 02 10:06:12 volumio volumio[2420]: Timers for benchmarking may be unreliable.
Apr 02 10:06:12 volumio volumio[2420]: Filters in process 0: 0 2
Apr 02 10:06:12 volumio volumio[2420]: Filters in process 1: 1 3
Apr 02 10:06:12 volumio volumio[2420]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 10:06:12 volumio volumio[2420]: Failed to set sample format to S16_LE: Invalid argument.
Apr 02 10:06:12 volumio volumio[2420]: Failed to init output device.
Apr 02 10:06:12 volumio volumio[2420]: Failed to initialise digital audio interfaces.
Apr 02 10:06:12 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 10:06:12 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 10:06:13 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 10:06:13 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 10:06:13 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 10:06:13 volumio systemd[1]: Started brutefir Daemon.
Apr 02 10:06:13 volumio volumio[2423]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 10:06:13 volumio volumio[2423]: Internal resolution is 64 bit floating point.
Apr 02 10:06:13 volumio volumio[2423]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 10:06:13 volumio volumio[854]: info: Connecting to daemon
Apr 02 10:06:13 volumio volumio[854]: Huumm, is brutefir running ?
Apr 02 10:06:13 volumio volumio[2423]: Loading 4 coefficient sets...finished.
Apr 02 10:06:13 volumio volumio[2423]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 10:06:13 volumio volumio[2423]: Warning: no support for clock cycle counter on this platform.
Apr 02 10:06:13 volumio volumio[2423]: Timers for benchmarking may be unreliable.
Apr 02 10:06:13 volumio volumio[2423]: Filters in process 0: 0 2
Apr 02 10:06:13 volumio volumio[2423]: Filters in process 1: 1 3
Apr 02 10:06:13 volumio volumio[2423]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 10:06:13 volumio volumio[2423]: Failed to set sample format to S16_LE: Invalid argument.
Apr 02 10:06:13 volumio volumio[2423]: Failed to init output device.
Apr 02 10:06:13 volumio volumio[2423]: Failed to initialise digital audio interfaces.
Apr 02 10:06:13 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 10:06:13 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 10:06:13 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 10:06:13 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 10:06:13 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 10:06:13 volumio systemd[1]: brutefir.service start request repeated too quickly, refusing to start.
Apr 02 10:06:13 volumio systemd[1]: Failed to start brutefir Daemon.
Apr 02 10:06:13 volumio systemd[1]: Unit brutefir.service entered failed state.
Dominique
it seems your dac does not support output format. Try to change it to S32_LE and save. Stop playing a track when doing this.
Try other settings if still fail…
After many fails, I finally got a configuration where I do not get the red pop up window.
I have selected loopback, I can play a track, but I get no sound.
We get closer…
Dominique
Why do you select loopback? You don’t have to change anything in Volumio setting!
So disabled the plugin, reconfigure the correct output in Volumio. Test with a track. If OK, enable the plugin. Play a track. Start with 44.1/16b flac.
Sorry for the confusion, I thought this “loopback” option was ne essary to use the plug in.
Will try again…
First attemp to restart the plugin generated the red popup. I disabled the plugin again, and then retarted. No red pop up. Green annoucing successfull restart.
I started a 16/44 track, no sound. Log file below:
volumio@volumio:~$ sudo journalctl -f
-- Logs begin at Sun 2018-04-01 12:46:55 UTC. --
Apr 02 12:28:35 volumio systemd[8458]: Starting Basic System.
Apr 02 12:28:35 volumio systemd[8458]: Reached target Basic System.
Apr 02 12:28:35 volumio systemd[8458]: Starting Default.
Apr 02 12:28:35 volumio systemd[8458]: Reached target Default.
Apr 02 12:28:35 volumio systemd[8458]: Startup finished in 36ms.
Apr 02 12:28:35 volumio systemd[1]: Started User Manager for UID 1000.
Apr 02 12:28:35 volumio sshd[8446]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory
Apr 02 12:28:35 volumio sshd[8446]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory
Apr 02 12:28:41 volumio sudo[8479]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f
Apr 02 12:28:41 volumio sudo[8479]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Apr 02 12:28:46 volumio volumio[841]: info: Enabling plugin brutefir
Apr 02 12:28:46 volumio volumio[841]: info: Loading plugin "brutefir"...
Apr 02 12:28:46 volumio volumio[841]: info: Applying required configuration parameters for plugin brutefir
Apr 02 12:28:46 volumio volumio[841]: info: PLUGIN START: brutefir
Apr 02 12:28:46 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:46 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:46 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:46 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:46 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:46 volumio sudo[8496]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2
Apr 02 12:28:46 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:46 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:46 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:46 volumio sudo[8496]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 12:28:46 volumio volumio[841]: hw:Loopback
Apr 02 12:28:46 volumio volumio[841]: /data/INTERNAL/brutefirfilters/
Apr 02 12:28:46 volumio volumio[841]: File exists.
Apr 02 12:28:46 volumio volumio[841]: File exists.
Apr 02 12:28:46 volumio sudo[8496]: pam_unix(sudo:session): session closed for user root
Apr 02 12:28:46 volumio volumio[841]: info: snd_aloop loaded
Apr 02 12:28:46 volumio sudo[8505]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart brutefir.service
Apr 02 12:28:46 volumio sudo[8505]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 12:28:46 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 12:28:46 volumio systemd[1]: Started brutefir Daemon.
Apr 02 12:28:46 volumio sudo[8505]: pam_unix(sudo:session): session closed for user root
Apr 02 12:28:46 volumio volumio[8510]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 12:28:46 volumio volumio[8510]: Internal resolution is 64 bit floating point.
Apr 02 12:28:46 volumio volumio[8510]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 12:28:46 volumio sudo[8516]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start brutefir.service
Apr 02 12:28:46 volumio sudo[8516]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 12:28:46 volumio systemd[1]: Started brutefir Daemon.
Apr 02 12:28:46 volumio sudo[8516]: pam_unix(sudo:session): session closed for user root
Apr 02 12:28:46 volumio volumio[841]: info: Brutefir Daemon Started
Apr 02 12:28:46 volumio volumio[8510]: Loading 4 coefficient sets...finished.
Apr 02 12:28:46 volumio volumio[8510]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 12:28:46 volumio volumio[8510]: Warning: no support for clock cycle counter on this platform.
Apr 02 12:28:46 volumio volumio[8510]: Timers for benchmarking may be unreliable.
Apr 02 12:28:46 volumio volumio[8510]: Filters in process 0: 0 2
Apr 02 12:28:46 volumio volumio[8510]: Filters in process 1: 1 3
Apr 02 12:28:46 volumio volumio[8510]: Realtime priority 1 set for cli process (pid 8526)
Apr 02 12:28:46 volumio volumio[8510]: Creating inverse inplace FFTW plan of size 32768 using wisdom...finished
Apr 02 12:28:46 volumio volumio[8510]: Realtime priority 3 set for input process (pid 8511)
Apr 02 12:28:47 volumio volumio[8510]: Realtime priority 4 set for filter process (pid 8524)
Apr 02 12:28:47 volumio volumio[8510]: Realtime priority 4 set for filter process (pid 8525)
Apr 02 12:28:47 volumio volumio[8510]: Realtime priority 3 set for output process (pid 8528)
Apr 02 12:28:47 volumio volumio[8510]: Fixed I/O-delay is 16384 samples
Apr 02 12:28:47 volumio volumio[8510]: Audio processing starts now
Apr 02 12:28:48 volumio volumio[841]: info: Connecting to daemon
Apr 02 12:28:48 volumio volumio[841]: myeq or preset =true
Apr 02 12:28:48 volumio volumio[841]: cmd sent to brutefir = lmc eq 0 mag 25/0, 31/0, 40/0, 50/0, 63/0, 80/5.5, 100/0, 125/0, 160/0, 200/0, 250/0, 315/0, 400/0, 500/0, 630/0, 800/0, 1000/0, 1250/0, 1600/0, 2000/0, 2500/0, 3150/0, 4000/0, 5000/0, 6300/0, 8000/0, 10000/0, 12500/0, 16000/0, 20000/0
Apr 02 12:28:48 volumio volumio[841]: Received:
Apr 02 12:28:48 volumio volumio[841]: Welcome to BruteFIR, type "help" for help.
Apr 02 12:28:48 volumio volumio[841]: >
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , disableI2SDAC
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 02 12:28:49 volumio volumio[841]: info: Output device has changed, restarting MPD
Apr 02 12:28:49 volumio volumio[841]: info: Output device has changed, restarting Shairport Sync
Apr 02 12:28:49 volumio sudo[8532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 02 12:28:49 volumio sudo[8532]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 12:28:49 volumio sudo[8534]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 02 12:28:49 volumio sudo[8532]: pam_unix(sudo:session): session closed for user root
Apr 02 12:28:49 volumio sudo[8534]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 02 12:28:49 volumio systemd[1]: Stopping Music Player Daemon...
Apr 02 12:28:49 volumio volumio[841]: info: Device Loopback does not have any Mixer Control Available, setting a softvol device
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Apr 02 12:28:49 volumio volumio[841]: info: Updating Volume Controller Parameters: Device: Loopback Name: Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 12:28:49 volumio volumio[841]: info: MPD Permissions set
Apr 02 12:28:49 volumio sudo[8553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l
Apr 02 12:28:49 volumio sudo[8553]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 12:28:49 volumio sudo[8553]: pam_unix(sudo:session): session closed for user root
Apr 02 12:28:49 volumio volumio[841]: info: No Overlays Loaded
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 12:28:49 volumio systemd[1]: Starting Music Player Daemon...
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 12:28:49 volumio volumio[841]: error: Upnp client error: Error: This socket has been ended by the other party
Apr 02 12:28:49 volumio sudo[8568]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay
Apr 02 12:28:49 volumio sudo[8568]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 12:28:49 volumio systemd[1]: Stopping ShairportSync AirTunes receiver...
Apr 02 12:28:49 volumio systemd[1]: Starting ShairportSync AirTunes receiver...
Apr 02 12:28:49 volumio systemd[1]: Started ShairportSync AirTunes receiver.
Apr 02 12:28:49 volumio sudo[8568]: pam_unix(sudo:session): session closed for user root
Apr 02 12:28:49 volumio shairport-sync[8574]: Successful Startup
Apr 02 12:28:49 volumio volumio[841]: info: [1522672129416] Shairport-Sync Started
Apr 02 12:28:49 volumio volumio[841]: info: Connecting to daemon brutefir
Apr 02 12:28:49 volumio volumio[841]: myeq or preset =true
Apr 02 12:28:49 volumio volumio[841]: cmd sent to brutefir = lmc eq 0 mag 25/0, 31/0, 40/0, 50/0, 63/0, 80/5.5, 100/0, 125/0, 160/0, 200/0, 250/0, 315/0, 400/0, 500/0, 630/0, 800/0, 1000/0, 1250/0, 1600/0, 2000/0, 2500/0, 3150/0, 4000/0, 5000/0, 6300/0, 8000/0, 10000/0, 12500/0, 16000/0, 20000/0
Apr 02 12:28:49 volumio volumio[841]: info: Done.
Apr 02 12:28:49 volumio volumio[841]: Received:
Apr 02 12:28:49 volumio volumio[841]: Welcome to BruteFIR, type "help" for help.
Apr 02 12:28:49 volumio volumio[841]: >
Apr 02 12:28:50 volumio systemd[1]: Started Music Player Daemon.
Apr 02 12:28:50 volumio sudo[8534]: pam_unix(sudo:session): session closed for user root
Apr 02 12:28:50 volumio volumio[841]: info:
Apr 02 12:28:56 volumio volumio[841]: { device: '5',
Apr 02 12:28:56 volumio volumio[841]: name: 'Yamaha CD Player',
Apr 02 12:28:56 volumio volumio[841]: mixer: '',
Apr 02 12:28:56 volumio volumio[841]: mixertype: 'None',
Apr 02 12:28:56 volumio volumio[841]: maxvolume: '100',
Apr 02 12:28:56 volumio volumio[841]: volumecurve: 'logarithmic',
Apr 02 12:28:56 volumio volumio[841]: volumestart: '100',
Apr 02 12:28:56 volumio volumio[841]: volumesteps: '10' }
Apr 02 12:28:56 volumio volumio[841]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Apr 02 12:28:56 volumio volumio[841]: info: Updating Volume Controller Parameters: Device: 5 Name: Yamaha CD Player Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10
Apr 02 12:28:56 volumio volumio[841]: { device: '5',
Apr 02 12:28:56 volumio volumio[841]: name: 'Yamaha CD Player',
Apr 02 12:28:56 volumio volumio[841]: mixer: '',
Apr 02 12:28:56 volumio volumio[841]: mixertype: 'None',
Apr 02 12:28:56 volumio volumio[841]: maxvolume: '100',
Apr 02 12:28:56 volumio volumio[841]: volumecurve: 'logarithmic',
Apr 02 12:28:56 volumio volumio[841]: volumestart: '100',
Apr 02 12:28:56 volumio volumio[841]: volumesteps: '10' }
Apr 02 12:28:56 volumio volumio[841]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Apr 02 12:28:56 volumio volumio[841]: info: Updating Volume Controller Parameters: Device: 5 Name: Yamaha CD Player Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10
Apr 02 12:28:56 volumio volumio[841]: { device: 'Loopback',
Apr 02 12:28:56 volumio volumio[841]: name: 'Yamaha CD Player through brutefir',
Apr 02 12:28:56 volumio volumio[841]: mixer: '',
Apr 02 12:28:56 volumio volumio[841]: mixertype: 'None',
Apr 02 12:28:56 volumio volumio[841]: maxvolume: '100',
Apr 02 12:28:56 volumio volumio[841]: volumecurve: 'logarithmic',
Apr 02 12:28:56 volumio volumio[841]: volumestart: '100',
Apr 02 12:28:56 volumio volumio[841]: volumesteps: '10' }
Apr 02 12:28:56 volumio volumio[841]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Apr 02 12:28:56 volumio volumio[841]: info: Updating Volume Controller Parameters: Device: Loopback Name: Yamaha CD Player through brutefir Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10
Apr 02 12:28:56 volumio volumio[841]: { device: 'Loopback',
Apr 02 12:28:56 volumio volumio[841]: name: 'Yamaha CD Player through brutefir through brutefir',
Apr 02 12:28:56 volumio volumio[841]: mixer: '',
Apr 02 12:28:56 volumio volumio[841]: mixertype: 'None',
Apr 02 12:28:56 volumio volumio[841]: maxvolume: '100',
Apr 02 12:28:56 volumio volumio[841]: volumecurve: 'logarithmic',
Apr 02 12:28:56 volumio volumio[841]: volumestart: '100',
Apr 02 12:28:56 volumio volumio[841]: volumesteps: '10' }
Apr 02 12:28:56 volumio volumio[841]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Apr 02 12:28:56 volumio volumio[841]: info: Updating Volume Controller Parameters: Device: Loopback Name: Yamaha CD Player through brutefir through brutefir Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10
Apr 02 12:28:56 volumio volumio[841]: { device: 'Loopback',
Apr 02 12:28:56 volumio volumio[841]: name: 'Yamaha CD Player through brutefir through brutefir',
Apr 02 12:28:56 volumio volumio[841]: mixer: '',
Apr 02 12:28:56 volumio volumio[841]: mixertype: 'None',
Apr 02 12:28:56 volumio volumio[841]: maxvolume: '100',
Apr 02 12:28:56 volumio volumio[841]: volumecurve: 'logarithmic',
Apr 02 12:28:56 volumio volumio[841]: volumestart: '100',
Apr 02 12:28:56 volumio volumio[841]: volumesteps: '10' }
Apr 02 12:28:56 volumio volumio[841]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Apr 02 12:28:56 volumio volumio[841]: info: Updating Volume Controller Parameters: Device: Loopback Name: Yamaha CD Player through brutefir through brutefir Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10
Apr 02 12:28:56 volumio volumio[841]: { device: '5',
Apr 02 12:28:56 volumio volumio[841]: name: 'Yamaha CD Player',
Apr 02 12:28:56 volumio volumio[841]: mixer: '',
Apr 02 12:28:56 volumio volumio[841]: mixertype: 'None',
Apr 02 12:28:56 volumio volumio[841]: maxvolume: '100',
Apr 02 12:28:56 volumio volumio[841]: volumecurve: 'logarithmic',
Apr 02 12:28:56 volumio volumio[841]: volumestart: '100',
Apr 02 12:28:56 volumio volumio[841]: volumesteps: '10' }
Apr 02 12:28:56 volumio volumio[841]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Apr 02 12:28:56 volumio volumio[841]: info: Updating Volume Controller Parameters: Device: 5 Name: Yamaha CD Player Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10
Apr 02 12:28:56 volumio volumio[841]: { device: '5',
Apr 02 12:28:56 volumio volumio[841]: name: 'Yamaha CD Player',
Apr 02 12:28:56 volumio volumio[841]: mixer: '',
Apr 02 12:28:56 volumio volumio[841]: mixertype: 'None',
Apr 02 12:28:56 volumio volumio[841]: maxvolume: '100',
Apr 02 12:28:56 volumio volumio[841]: volumecurve: 'logarithmic',
Apr 02 12:28:56 volumio volumio[841]: volumestart: '100',
Apr 02 12:28:56 volumio volumio[841]: volumesteps: '10' }
Apr 02 12:28:56 volumio volumio[841]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Apr 02 12:28:56 volumio volumio[841]: info: Updating Volume Controller Parameters: Device: 5 Name: Yamaha CD Player Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10
Apr 02 12:28:56 volumio volumio[841]: { device: 'Loopback',
Apr 02 12:28:56 volumio volumio[841]: name: 'Yamaha CD Player through brutefir',
Apr 02 12:28:56 volumio volumio[841]: mixer: '',
Apr 02 12:28:56 volumio volumio[841]: mixertype: 'None',
Apr 02 12:28:56 volumio volumio[841]: maxvolume: '100',
Apr 02 12:28:56 volumio volumio[841]: volumecurve: 'logarithmic',
Apr 02 12:28:56 volumio volumio[841]: volumestart: '100',
Apr 02 12:28:56 volumio volumio[841]: volumesteps: '10' }
Apr 02 12:28:56 volumio volumio[841]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Apr 02 12:28:56 volumio volumio[841]: info: Updating Volume Controller Parameters: Device: Loopback Name: Yamaha CD Player through brutefir Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10
Apr 02 12:28:56 volumio volumio[841]: { device: 'Loopback',
Apr 02 12:28:56 volumio volumio[841]: name: 'Yamaha CD Player through brutefir through brutefir',
Apr 02 12:28:56 volumio volumio[841]: mixer: '',
Apr 02 12:28:56 volumio volumio[841]: mixertype: 'None',
Apr 02 12:28:56 volumio volumio[841]: maxvolume: '100',
Apr 02 12:28:56 volumio volumio[841]: volumecurve: 'logarithmic',
Apr 02 12:28:56 volumio volumio[841]: volumestart: '100',
Apr 02 12:28:56 volumio volumio[841]: volumesteps: '10' }
Apr 02 12:28:56 volumio volumio[841]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Apr 02 12:28:56 volumio volumio[841]: info: Updating Volume Controller Parameters: Device: Loopback Name: Yamaha CD Player through brutefir through brutefir Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10
Apr 02 12:28:58 volumio volumio[841]: info: CoreCommandRouter::volumioPlay
Apr 02 12:28:58 volumio volumio[841]: UNSET VOLATILE
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::play index 6
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::stop
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 02 12:28:58 volumio volumio[841]: UNSET VOLATILE
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::stPlaybackTimer
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::updateTrackBlock
Apr 02 12:28:58 volumio volumio[841]: info: CorePlayQueue::getTrackBlock
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::pushState
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::getState
Apr 02 12:28:58 volumio volumio[841]: info: CorePlayQueue::getTrack 4
Apr 02 12:28:58 volumio volumio[841]: info: CoreCommandRouter::volumioPushState
Apr 02 12:28:58 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 12:28:58 volumio volumio[841]: info: interfaceApi::pushState
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::serviceStop
Apr 02 12:28:58 volumio volumio[841]: info: CorePlayQueue::getTrack 4
Apr 02 12:28:58 volumio volumio[841]: info: CoreCommandRouter::serviceStop
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::stop
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::sendMpdCommand stop
Apr 02 12:28:58 volumio volumio[841]: info: sending command...
Apr 02 12:28:58 volumio volumio[841]: info: parsing response...
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::play index undefined
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 02 12:28:58 volumio volumio[841]: info: CorePlayQueue::getTrack 6
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::startPlaybackTimer
Apr 02 12:28:58 volumio volumio[841]: info: CorePlayQueue::getTrack 6
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::clearAddPlayTracks USB/Music/CHANSON FRANCAISE/Arabesque Jane Birkin/La Valse De Melody.aif
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::sendMpdCommand stop
Apr 02 12:28:58 volumio volumio[841]: info: sending command...
Apr 02 12:28:58 volumio volumio[841]: info: parsing response...
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::sendMpdCommand clear
Apr 02 12:28:58 volumio volumio[841]: info: sending command...
Apr 02 12:28:58 volumio volumio[841]: info:
Apr 02 12:28:58 volumio volumio[841]: ---------------------------- MPD announces system playlist update
Apr 02 12:28:58 volumio volumio[841]: info: Ignoring MPD Status Update
Apr 02 12:28:58 volumio volumio[841]: info: parsing response...
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::sendMpdCommand add "USB/Music/CHANSON FRANCAISE/Arabesque Jane Birkin/La Valse De Melody.aif"
Apr 02 12:28:58 volumio volumio[841]: info: sending command...
Apr 02 12:28:58 volumio volumio[841]: info:
Apr 02 12:28:58 volumio volumio[841]: info:
Apr 02 12:28:58 volumio volumio[841]: ---------------------------- MPD announces system playlist update
Apr 02 12:28:58 volumio volumio[841]: info: Ignoring MPD Status Update
Apr 02 12:28:58 volumio volumio[841]: info: ------------------------------ 11ms
Apr 02 12:28:58 volumio volumio[841]: info: parsing response...
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::sendMpdCommand play
Apr 02 12:28:58 volumio volumio[841]: info: sending command...
Apr 02 12:28:58 volumio volumio[841]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/Music/CHANSON FRANCAISE/Léo Ferré/L'Intégrale 1960-1974, Vol. 06 - Poètes, Vos Papiers! 1969 - 1970/06 La Mémoire et la Mer.flac","favourite":false}
Apr 02 12:28:58 volumio volumio[841]: info:
Apr 02 12:28:58 volumio volumio[841]: ---------------------------- MPD announces state update: player
Apr 02 12:28:58 volumio volumio[841]: info: ------------------------------ 23ms
Apr 02 12:28:58 volumio volumio[841]: info: parsing response...
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::getState
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::sendMpdCommand status
Apr 02 12:28:58 volumio volumio[841]: info: sending command...
Apr 02 12:28:58 volumio volumio[841]: info:
Apr 02 12:28:58 volumio volumio[841]: ---------------------------- MPD announces state update: player
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::getState
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::sendMpdCommand status
Apr 02 12:28:58 volumio volumio[841]: info: parsing response...
Apr 02 12:28:58 volumio volumio[841]: info: sending command...
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::parseState
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 12:28:58 volumio volumio[841]: info: sending command...
Apr 02 12:28:58 volumio volumio[841]: info: parsing response...
Apr 02 12:28:58 volumio volumio[841]: info: parsing response...
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::parseState
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::parseTrackInfo
Apr 02 12:28:58 volumio volumio[841]: info: sending command...
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::pushState
Apr 02 12:28:58 volumio volumio[841]: info: CoreCommandRouter::servicePushState
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::syncState
Apr 02 12:28:58 volumio volumio[841]: info: CorePlayQueue::getTrack 6
Apr 02 12:28:58 volumio volumio[841]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":382,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"La Valse De Melody","artist":"Jane Birkin","album":"Arabesque","uri":"USB/Music/CHANSON FRANCAISE/Arabesque Jane Birkin/La Valse De Melody.aif","trackType":"aif"}
Apr 02 12:28:58 volumio volumio[841]: info: CURRENT POSITION 6
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::syncState stateService play
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::syncState currentStatus stop
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::pushState
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::getState
Apr 02 12:28:58 volumio volumio[841]: info: CorePlayQueue::getTrack 6
Apr 02 12:28:58 volumio volumio[841]: info: CoreCommandRouter::volumioPushState
Apr 02 12:28:58 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 12:28:58 volumio volumio[841]: info: interfaceApi::pushState
Apr 02 12:28:58 volumio volumio[841]: info: ------------------------------ 74ms
Apr 02 12:28:58 volumio volumio[841]: info: parsing response...
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::parseTrackInfo
Apr 02 12:28:58 volumio volumio[841]: info: ControllerMpd::pushState
Apr 02 12:28:58 volumio volumio[841]: info: CoreCommandRouter::servicePushState
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::syncState
Apr 02 12:28:58 volumio volumio[841]: info: CorePlayQueue::getTrack 6
Apr 02 12:28:58 volumio volumio[841]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":382,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"La Valse De Melody","artist":"Jane Birkin","album":"Arabesque","uri":"USB/Music/CHANSON FRANCAISE/Arabesque Jane Birkin/La Valse De Melody.aif","trackType":"aif"}
Apr 02 12:28:58 volumio volumio[841]: info: CURRENT POSITION 6
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::syncState stateService play
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::syncState currentStatus play
Apr 02 12:28:58 volumio volumio[841]: info: Received an update from plaugin. extracting info from payload
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::pushState
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::getState
Apr 02 12:28:58 volumio volumio[841]: info: CorePlayQueue::getTrack 6
Apr 02 12:28:58 volumio volumio[841]: info: CoreCommandRouter::volumioPushState
Apr 02 12:28:58 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 12:28:58 volumio volumio[841]: info: interfaceApi::pushState
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::pushState
Apr 02 12:28:58 volumio volumio[841]: info: CoreStateMachine::getState
Apr 02 12:28:58 volumio volumio[841]: info: CorePlayQueue::getTrack 6
Apr 02 12:28:58 volumio volumio[841]: info: CoreCommandRouter::volumioPushState
Apr 02 12:28:58 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 12:28:58 volumio volumio[841]: info: interfaceApi::pushState
Apr 02 12:28:58 volumio volumio[841]: info: ------------------------------ 128ms
Apr 02 12:28:58 volumio volumio[841]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/Music/CHANSON FRANCAISE/Arabesque Jane Birkin/La Valse De Melody.aif","favourite":false}
Apr 02 12:28:58 volumio volumio[841]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/Music/CHANSON FRANCAISE/Arabesque Jane Birkin/La Valse De Melody.aif","favourite":false}
Apr 02 12:28:58 volumio volumio[841]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/Music/CHANSON FRANCAISE/Arabesque Jane Birkin/La Valse De Melody.aif","favourite":false}
Apr 02 12:29:06 volumio volumio[841]: info: CoreCommandRouter::volumioPause
Apr 02 12:29:06 volumio volumio[841]: info: CoreStateMachine::pause
Apr 02 12:29:06 volumio volumio[841]: info: CoreStateMachine::stPlaybackTimer
Apr 02 12:29:06 volumio volumio[841]: info: CoreStateMachine::servicePause
Apr 02 12:29:06 volumio volumio[841]: info: CorePlayQueue::getTrack 6
Apr 02 12:29:06 volumio volumio[841]: info: CoreCommandRouter::servicePause
Apr 02 12:29:06 volumio volumio[841]: info: ControllerMpd::pause
Apr 02 12:29:06 volumio volumio[841]: info: ControllerMpd::sendMpdCommand pause
Apr 02 12:29:06 volumio volumio[841]: info: sending command...
Apr 02 12:29:06 volumio volumio[841]: info:
Apr 02 12:29:06 volumio volumio[841]: ---------------------------- MPD announces state update: player
Apr 02 12:29:06 volumio volumio[841]: info: parsing response...
Apr 02 12:29:06 volumio volumio[841]: info: ControllerMpd::getState
Apr 02 12:29:06 volumio volumio[841]: info: ControllerMpd::sendMpdCommand status
Apr 02 12:29:06 volumio volumio[841]: info: sending command...
Apr 02 12:29:06 volumio volumio[841]: info: parsing response...
Apr 02 12:29:06 volumio volumio[841]: info: ControllerMpd::parseState
Apr 02 12:29:06 volumio volumio[841]: info: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 12:29:06 volumio volumio[841]: info: sending command...
Apr 02 12:29:06 volumio volumio[841]: info: parsing response...
Apr 02 12:29:06 volumio volumio[841]: info: ControllerMpd::parseTrackInfo
Apr 02 12:29:06 volumio volumio[841]: info: ControllerMpd::pushState
Apr 02 12:29:06 volumio volumio[841]: info: CoreCommandRouter::servicePushState
Apr 02 12:29:06 volumio volumio[841]: info: CoreStateMachine::syncState
Apr 02 12:29:06 volumio volumio[841]: info: CorePlayQueue::getTrack 6
Apr 02 12:29:06 volumio volumio[841]: info: STATE SERVICE {"status":"pause","position":0,"seek":8080,"duration":382,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"La Valse De Melody","artist":"Jane Birkin","album":"Arabesque","uri":"USB/Music/CHANSON FRANCAISE/Arabesque Jane Birkin/La Valse De Melody.aif","trackType":"aif"}
Apr 02 12:29:06 volumio volumio[841]: info: CURRENT POSITION 6
Apr 02 12:29:06 volumio volumio[841]: info: CoreStateMachine::syncState stateService pause
Apr 02 12:29:06 volumio volumio[841]: info: CoreStateMachine::syncState currentStatus pause
Apr 02 12:29:06 volumio volumio[841]: info: CoreStateMachine::pushState
Apr 02 12:29:06 volumio volumio[841]: info: CoreStateMachine::getState
Apr 02 12:29:06 volumio volumio[841]: info: CorePlayQueue::getTrack 6
Apr 02 12:29:06 volumio volumio[841]: info: CoreCommandRouter::volumioPushState
Apr 02 12:29:06 volumio volumio[841]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 12:29:06 volumio volumio[841]: info: interfaceApi::pushState
Apr 02 12:29:06 volumio volumio[841]: info: CoreStateMachine::stPlaybackTimer
Apr 02 12:29:06 volumio volumio[841]: info: ------------------------------ 54ms
Apr 02 12:29:06 volumio volumio[841]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/Music/CHANSON FRANCAISE/Arabesque Jane Birkin/La Valse De Melody.aif","favourite":false}not easy…
I see
Apr 02 12:28:56 volumio volumio[841]: name: 'Yamaha CD Player through brutefir through brutefir',
Please can you do exactly (even if you already done it) the following :
disable plugin
configure yamaha cd player as output in volumio setting, save.
Test with a track.
Reboot
Enable the plugin
Save advanced settings.
Wait the popup saying brutefir is ok
play a track.
Check in volumio setting, it should be
'Yamaha CD Player through brutefir'
sorry if not easier… 
So I have followed the instruction carefully. The log start after the restart of Volumio, when I try to enable the plugin:
Volumio Debian GNU/Linux comes with ABSOLUTELY NO WARRANTY, to the extent
permitted by applicable law.
volumio@volumio:~$ sudo journalctl -f
-- Logs begin at Mon 2018-04-02 13:21:11 UTC. --
Apr 02 13:23:15 volumio systemd[1589]: Starting Basic System.
Apr 02 13:23:15 volumio systemd[1589]: Reached target Basic System.
Apr 02 13:23:15 volumio systemd[1589]: Starting Default.
Apr 02 13:23:15 volumio systemd[1589]: Reached target Default.
Apr 02 13:23:15 volumio systemd[1589]: Startup finished in 36ms.
Apr 02 13:23:15 volumio systemd[1]: Started User Manager for UID 1000.
Apr 02 13:23:15 volumio sshd[1569]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory
Apr 02 13:23:15 volumio sshd[1569]: lastlog_openseek: Couldn't stat /var/log/lastlog: No such file or directory
Apr 02 13:23:21 volumio sudo[1607]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/bin/journalctl -f
Apr 02 13:23:21 volumio sudo[1607]: pam_unix(sudo:session): session opened for user root by volumio(uid=0)
Apr 02 13:23:28 volumio volumio[876]: info: [{"prettyName":"Brutefir for Volumio 0.4.9","name":"brutefir","category":"audio_interface","version":"0.4.9","icon":"fa-sliders fa-rotate-90","enabled":false,"active":false}]
Apr 02 13:23:32 volumio volumio[876]: info: Enabling plugin brutefir
Apr 02 13:23:32 volumio volumio[876]: info: Loading plugin "brutefir"...
Apr 02 13:23:33 volumio volumio[876]: info: Applying required configuration parameters for plugin brutefir
Apr 02 13:23:33 volumio volumio[876]: info: PLUGIN START: brutefir
Apr 02 13:23:33 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:33 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:33 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:33 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:33 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:33 volumio sudo[1630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2
Apr 02 13:23:33 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:33 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:33 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:33 volumio sudo[1630]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 13:23:33 volumio volumio[876]: hw:5
Apr 02 13:23:33 volumio sudo[1630]: pam_unix(sudo:session): session closed for user root
Apr 02 13:23:33 volumio volumio[876]: /data/INTERNAL/brutefirfilters/
Apr 02 13:23:33 volumio volumio[876]: File exists.
Apr 02 13:23:33 volumio volumio[876]: File exists.
Apr 02 13:23:33 volumio volumio[876]: info: snd_aloop loaded
Apr 02 13:23:33 volumio sudo[1646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart brutefir.service
Apr 02 13:23:33 volumio sudo[1646]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 13:23:33 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 13:23:33 volumio systemd[1]: Started brutefir Daemon.
Apr 02 13:23:33 volumio sudo[1646]: pam_unix(sudo:session): session closed for user root
Apr 02 13:23:33 volumio volumio[1651]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 13:23:33 volumio sudo[1657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start brutefir.service
Apr 02 13:23:33 volumio sudo[1657]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 13:23:33 volumio volumio[1651]: Internal resolution is 64 bit floating point.
Apr 02 13:23:33 volumio systemd[1]: Started brutefir Daemon.
Apr 02 13:23:33 volumio sudo[1657]: pam_unix(sudo:session): session closed for user root
Apr 02 13:23:33 volumio volumio[876]: info: Brutefir Daemon Started
Apr 02 13:23:33 volumio volumio[1651]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 13:23:33 volumio volumio[1651]: Loading 4 coefficient sets...finished.
Apr 02 13:23:33 volumio volumio[1651]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 13:23:33 volumio volumio[1651]: Warning: no support for clock cycle counter on this platform.
Apr 02 13:23:33 volumio volumio[1651]: Timers for benchmarking may be unreliable.
Apr 02 13:23:33 volumio volumio[1651]: Filters in process 0: 0 2
Apr 02 13:23:33 volumio volumio[1651]: Filters in process 1: 1 3
Apr 02 13:23:33 volumio volumio[1651]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 13:23:33 volumio volumio[1651]: Failed to set sample format to S32_LE: Invalid argument.
Apr 02 13:23:33 volumio volumio[1651]: Failed to init output device.
Apr 02 13:23:33 volumio volumio[1651]: Failed to initialise digital audio interfaces.
Apr 02 13:23:33 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 13:23:33 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 13:23:33 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 13:23:33 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 13:23:33 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 13:23:33 volumio systemd[1]: Started brutefir Daemon.
Apr 02 13:23:33 volumio volumio[1666]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 13:23:33 volumio volumio[1666]: Internal resolution is 64 bit floating point.
Apr 02 13:23:33 volumio volumio[1666]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 13:23:34 volumio volumio[1666]: Loading 4 coefficient sets...finished.
Apr 02 13:23:34 volumio volumio[1666]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 13:23:34 volumio volumio[1666]: Warning: no support for clock cycle counter on this platform.
Apr 02 13:23:34 volumio volumio[1666]: Timers for benchmarking may be unreliable.
Apr 02 13:23:34 volumio volumio[1666]: Filters in process 0: 0 2
Apr 02 13:23:34 volumio volumio[1666]: Filters in process 1: 1 3
Apr 02 13:23:34 volumio volumio[1666]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 13:23:34 volumio volumio[1666]: Failed to set sample format to S32_LE: Invalid argument.
Apr 02 13:23:34 volumio volumio[1666]: Failed to init output device.
Apr 02 13:23:34 volumio volumio[1666]: Failed to initialise digital audio interfaces.
Apr 02 13:23:34 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 13:23:34 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 13:23:34 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 13:23:34 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 13:23:34 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 13:23:34 volumio systemd[1]: Started brutefir Daemon.
Apr 02 13:23:34 volumio volumio[1670]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 13:23:34 volumio volumio[1670]: Internal resolution is 64 bit floating point.
Apr 02 13:23:34 volumio volumio[1670]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 13:23:34 volumio volumio[1670]: Loading 4 coefficient sets...finished.
Apr 02 13:23:34 volumio volumio[1670]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 13:23:34 volumio volumio[1670]: Warning: no support for clock cycle counter on this platform.
Apr 02 13:23:34 volumio volumio[1670]: Timers for benchmarking may be unreliable.
Apr 02 13:23:34 volumio volumio[1670]: Filters in process 0: 0 2
Apr 02 13:23:34 volumio volumio[1670]: Filters in process 1: 1 3
Apr 02 13:23:34 volumio volumio[1670]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 13:23:34 volumio volumio[1670]: Failed to set sample format to S32_LE: Invalid argument.
Apr 02 13:23:34 volumio volumio[1670]: Failed to init output device.
Apr 02 13:23:34 volumio volumio[1670]: Failed to initialise digital audio interfaces.
Apr 02 13:23:34 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 13:23:34 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 13:23:34 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 13:23:34 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 13:23:34 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 13:23:34 volumio systemd[1]: Started brutefir Daemon.
Apr 02 13:23:34 volumio volumio[1673]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 13:23:34 volumio volumio[1673]: Internal resolution is 64 bit floating point.
Apr 02 13:23:34 volumio volumio[1673]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 13:23:35 volumio volumio[1673]: Loading 4 coefficient sets...finished.
Apr 02 13:23:35 volumio volumio[1673]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 13:23:35 volumio volumio[1673]: Warning: no support for clock cycle counter on this platform.
Apr 02 13:23:35 volumio volumio[1673]: Timers for benchmarking may be unreliable.
Apr 02 13:23:35 volumio volumio[1673]: Filters in process 0: 0 2
Apr 02 13:23:35 volumio volumio[1673]: Filters in process 1: 1 3
Apr 02 13:23:35 volumio volumio[1673]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 13:23:35 volumio volumio[1673]: Failed to set sample format to S32_LE: Invalid argument.
Apr 02 13:23:35 volumio volumio[1673]: Failed to init output device.
Apr 02 13:23:35 volumio volumio[1673]: Failed to initialise digital audio interfaces.
Apr 02 13:23:35 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 13:23:35 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 13:23:35 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 13:23:35 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 13:23:35 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 13:23:35 volumio systemd[1]: Started brutefir Daemon.
Apr 02 13:23:35 volumio volumio[1677]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 13:23:35 volumio volumio[1677]: Internal resolution is 64 bit floating point.
Apr 02 13:23:35 volumio volumio[1677]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 13:23:35 volumio volumio[876]: info: Connecting to daemon
Apr 02 13:23:35 volumio volumio[876]: Huumm, is brutefir running ?
Apr 02 13:23:35 volumio volumio[1677]: Loading 4 coefficient sets...finished.
Apr 02 13:23:35 volumio volumio[1677]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 13:23:35 volumio volumio[1677]: Warning: no support for clock cycle counter on this platform.
Apr 02 13:23:35 volumio volumio[1677]: Timers for benchmarking may be unreliable.
Apr 02 13:23:35 volumio volumio[1677]: Filters in process 0: 0 2
Apr 02 13:23:35 volumio volumio[1677]: Filters in process 1: 1 3
Apr 02 13:23:35 volumio volumio[1677]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 13:23:35 volumio volumio[1677]: Failed to set sample format to S32_LE: Invalid argument.
Apr 02 13:23:35 volumio volumio[1677]: Failed to init output device.
Apr 02 13:23:35 volumio volumio[1677]: Failed to initialise digital audio interfaces.
Apr 02 13:23:35 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 13:23:35 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 13:23:35 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , disableI2SDAC
Apr 02 13:23:35 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 13:23:35 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions
Apr 02 13:23:35 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 02 13:23:35 volumio volumio[876]: info: Output device has changed, restarting MPD
Apr 02 13:23:35 volumio sudo[1681]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf
Apr 02 13:23:35 volumio volumio[876]: info: Output device has changed, restarting Shairport Sync
Apr 02 13:23:35 volumio sudo[1681]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 13:23:35 volumio sudo[1681]: pam_unix(sudo:session): session closed for user root
Apr 02 13:23:35 volumio sudo[1685]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service
Apr 02 13:23:35 volumio sudo[1685]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 13:23:35 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 13:23:35 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 13:23:35 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 13:23:35 volumio systemd[1]: brutefir.service start request repeated too quickly, refusing to start.
Apr 02 13:23:35 volumio systemd[1]: Failed to start brutefir Daemon.
Apr 02 13:23:35 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 13:23:35 volumio systemd[1]: Stopping Music Player Daemon...
Apr 02 13:23:35 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 02 13:23:35 volumio volumio[876]: info: Device Loopback does not have any Mixer Control Available, setting a softvol device
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Apr 02 13:23:36 volumio volumio[876]: info: Updating Volume Controller Parameters: Device: Loopback Name: Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 13:23:36 volumio volumio[876]: info: MPD Permissions set
Apr 02 13:23:36 volumio sudo[1702]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l
Apr 02 13:23:36 volumio sudo[1702]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 13:23:36 volumio sudo[1702]: pam_unix(sudo:session): session closed for user root
Apr 02 13:23:36 volumio systemd[1]: Starting Music Player Daemon...
Apr 02 13:23:36 volumio volumio[876]: info: No Overlays Loaded
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 13:23:36 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 13:23:36 volumio sudo[1717]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart airplay
Apr 02 13:23:36 volumio sudo[1717]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 13:23:36 volumio systemd[1]: Stopping ShairportSync AirTunes receiver...
Apr 02 13:23:36 volumio systemd[1]: Starting ShairportSync AirTunes receiver...
Apr 02 13:23:36 volumio systemd[1]: Started ShairportSync AirTunes receiver.
Apr 02 13:23:36 volumio sudo[1717]: pam_unix(sudo:session): session closed for user root
Apr 02 13:23:36 volumio shairport-sync[1723]: Successful Startup
Apr 02 13:23:36 volumio volumio[876]: info: [1522675416358] Shairport-Sync Started
Apr 02 13:23:36 volumio volumio[876]: error: Upnp client error: Error: This socket has been ended by the other party
Apr 02 13:23:37 volumio systemd[1]: Started Music Player Daemon.
Apr 02 13:23:37 volumio sudo[1685]: pam_unix(sudo:session): session closed for user root
Apr 02 13:23:37 volumio volumio[876]: info:
Apr 02 13:23:43 volumio volumio[876]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 13:23:43 volumio volumio[876]: list of available filters: anothertest2.wav,domTsubcorXXL.wav,flat.wav,readme.txt,rephase.raw,rew.wav,rewmanual.wav
Apr 02 13:23:43 volumio volumio[876]: { device: '5',
Apr 02 13:23:43 volumio volumio[876]: name: 'Yamaha CD Player',
Apr 02 13:23:43 volumio volumio[876]: mixer: '',
Apr 02 13:23:43 volumio volumio[876]: mixertype: 'None',
Apr 02 13:23:43 volumio volumio[876]: maxvolume: '100',
Apr 02 13:23:43 volumio volumio[876]: volumecurve: 'logarithmic',
Apr 02 13:23:43 volumio volumio[876]: volumestart: '100',
Apr 02 13:23:43 volumio volumio[876]: volumesteps: '10' }
Apr 02 13:23:43 volumio volumio[876]: info: CoreCommandRouter::volumioUpdateVolumeSettings
Apr 02 13:23:43 volumio volumio[876]: info: Updating Volume Controller Parameters: Device: 5 Name: Yamaha CD Player Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 10
Apr 02 13:23:58 volumio volumio[876]: info: CALLMETHOD: audio_interface brutefir saveBrutefirconfigAccount2 [object Object]
Apr 02 13:23:58 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: brutefir , saveBrutefirconfigAccount2
Apr 02 13:23:58 volumio volumio[876]: hw:5
Apr 02 13:23:58 volumio volumio[876]: /data/INTERNAL/brutefirfilters/
Apr 02 13:23:58 volumio volumio[876]: File exists.
Apr 02 13:23:58 volumio volumio[876]: File exists.
Apr 02 13:23:58 volumio sudo[1744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart brutefir.service
Apr 02 13:23:58 volumio sudo[1744]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 13:23:58 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 13:23:58 volumio systemd[1]: Started brutefir Daemon.
Apr 02 13:23:58 volumio sudo[1744]: pam_unix(sudo:session): session closed for user root
Apr 02 13:23:58 volumio volumio[1749]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 13:23:58 volumio volumio[1749]: Internal resolution is 64 bit floating point.
Apr 02 13:23:58 volumio volumio[1749]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 13:23:58 volumio sudo[1755]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start brutefir.service
Apr 02 13:23:58 volumio sudo[1755]: pam_unix(sudo:session): session opened for user root by (uid=0)
Apr 02 13:23:58 volumio systemd[1]: Started brutefir Daemon.
Apr 02 13:23:58 volumio sudo[1755]: pam_unix(sudo:session): session closed for user root
Apr 02 13:23:58 volumio volumio[876]: info: Brutefir Daemon Started
Apr 02 13:23:58 volumio volumio[1749]: Loading 4 coefficient sets...finished.
Apr 02 13:23:58 volumio volumio[1749]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 13:23:58 volumio volumio[1749]: Warning: no support for clock cycle counter on this platform.
Apr 02 13:23:58 volumio volumio[1749]: Timers for benchmarking may be unreliable.
Apr 02 13:23:58 volumio volumio[1749]: Filters in process 0: 0 2
Apr 02 13:23:58 volumio volumio[1749]: Filters in process 1: 1 3
Apr 02 13:23:58 volumio volumio[1749]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 13:23:58 volumio volumio[1749]: Failed to set sample format to S32_LE: Invalid argument.
Apr 02 13:23:58 volumio volumio[1749]: Failed to init output device.
Apr 02 13:23:58 volumio volumio[1749]: Failed to initialise digital audio interfaces.
Apr 02 13:23:58 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 13:23:58 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 13:23:58 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 13:23:58 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 13:23:58 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 13:23:58 volumio systemd[1]: Started brutefir Daemon.
Apr 02 13:23:58 volumio volumio[1764]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 13:23:58 volumio volumio[1764]: Internal resolution is 64 bit floating point.
Apr 02 13:23:58 volumio volumio[1764]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 13:23:59 volumio volumio[1764]: Loading 4 coefficient sets...finished.
Apr 02 13:23:59 volumio volumio[1764]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 13:23:59 volumio volumio[1764]: Warning: no support for clock cycle counter on this platform.
Apr 02 13:23:59 volumio volumio[1764]: Timers for benchmarking may be unreliable.
Apr 02 13:23:59 volumio volumio[1764]: Filters in process 0: 0 2
Apr 02 13:23:59 volumio volumio[1764]: Filters in process 1: 1 3
Apr 02 13:23:59 volumio volumio[1764]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 13:23:59 volumio volumio[1764]: Failed to set sample format to S32_LE: Invalid argument.
Apr 02 13:23:59 volumio volumio[1764]: Failed to init output device.
Apr 02 13:23:59 volumio volumio[1764]: Failed to initialise digital audio interfaces.
Apr 02 13:23:59 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 13:23:59 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 13:23:59 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 13:23:59 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 13:23:59 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 13:23:59 volumio systemd[1]: Started brutefir Daemon.
Apr 02 13:23:59 volumio volumio[1767]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 13:23:59 volumio volumio[1767]: Internal resolution is 64 bit floating point.
Apr 02 13:23:59 volumio volumio[1767]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 13:23:59 volumio volumio[1767]: Loading 4 coefficient sets...finished.
Apr 02 13:23:59 volumio volumio[1767]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 13:23:59 volumio volumio[1767]: Warning: no support for clock cycle counter on this platform.
Apr 02 13:23:59 volumio volumio[1767]: Timers for benchmarking may be unreliable.
Apr 02 13:23:59 volumio volumio[1767]: Filters in process 0: 0 2
Apr 02 13:23:59 volumio volumio[1767]: Filters in process 1: 1 3
Apr 02 13:23:59 volumio volumio[1767]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 13:23:59 volumio volumio[1767]: Failed to set sample format to S32_LE: Invalid argument.
Apr 02 13:23:59 volumio volumio[1767]: Failed to init output device.
Apr 02 13:23:59 volumio volumio[1767]: Failed to initialise digital audio interfaces.
Apr 02 13:23:59 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 13:23:59 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 13:23:59 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 13:23:59 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 13:23:59 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 13:23:59 volumio systemd[1]: Started brutefir Daemon.
Apr 02 13:23:59 volumio volumio[1770]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 13:23:59 volumio volumio[1770]: Internal resolution is 64 bit floating point.
Apr 02 13:23:59 volumio volumio[1770]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 13:24:00 volumio volumio[1770]: Loading 4 coefficient sets...finished.
Apr 02 13:24:00 volumio volumio[1770]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 13:24:00 volumio volumio[1770]: Warning: no support for clock cycle counter on this platform.
Apr 02 13:24:00 volumio volumio[1770]: Timers for benchmarking may be unreliable.
Apr 02 13:24:00 volumio volumio[1770]: Filters in process 0: 0 2
Apr 02 13:24:00 volumio volumio[1770]: Filters in process 1: 1 3
Apr 02 13:24:00 volumio volumio[1770]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 13:24:00 volumio volumio[1770]: Failed to set sample format to S32_LE: Invalid argument.
Apr 02 13:24:00 volumio volumio[1770]: Failed to init output device.
Apr 02 13:24:00 volumio volumio[1770]: Failed to initialise digital audio interfaces.
Apr 02 13:24:00 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 13:24:00 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 13:24:00 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 13:24:00 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 13:24:00 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 13:24:00 volumio systemd[1]: Started brutefir Daemon.
Apr 02 13:24:00 volumio volumio[1773]: BruteFIR v1.0m (November 2013) (c) Anders Torger
Apr 02 13:24:00 volumio volumio[1773]: Internal resolution is 64 bit floating point.
Apr 02 13:24:00 volumio volumio[1773]: Creating 4 FFTW plans of size 16384...finished.
Apr 02 13:24:00 volumio volumio[876]: info: Connecting to daemon
Apr 02 13:24:00 volumio volumio[876]: Huumm, is brutefir running ?
Apr 02 13:24:00 volumio volumio[1773]: Loading 4 coefficient sets...finished.
Apr 02 13:24:00 volumio volumio[1773]: Realtime priorities are min = 2, usermax = 1, mid = 3 and max = 4.
Apr 02 13:24:00 volumio volumio[1773]: Warning: no support for clock cycle counter on this platform.
Apr 02 13:24:00 volumio volumio[1773]: Timers for benchmarking may be unreliable.
Apr 02 13:24:00 volumio volumio[1773]: Filters in process 0: 0 2
Apr 02 13:24:00 volumio volumio[1773]: Filters in process 1: 1 3
Apr 02 13:24:00 volumio volumio[1773]: ALSA I/O: Could not set audio output parameters for "hw:5":
Apr 02 13:24:00 volumio volumio[1773]: Failed to set sample format to S32_LE: Invalid argument.
Apr 02 13:24:00 volumio volumio[1773]: Failed to init output device.
Apr 02 13:24:00 volumio volumio[1773]: Failed to initialise digital audio interfaces.
Apr 02 13:24:00 volumio systemd[1]: brutefir.service: main process exited, code=exited, status=1/FAILURE
Apr 02 13:24:00 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 13:24:00 volumio systemd[1]: brutefir.service holdoff time over, scheduling restart.
Apr 02 13:24:00 volumio systemd[1]: Stopping brutefir Daemon...
Apr 02 13:24:00 volumio systemd[1]: Starting brutefir Daemon...
Apr 02 13:24:00 volumio systemd[1]: brutefir.service start request repeated too quickly, refusing to start.
Apr 02 13:24:00 volumio systemd[1]: Failed to start brutefir Daemon.
Apr 02 13:24:00 volumio systemd[1]: Unit brutefir.service entered failed state.
Apr 02 13:24:12 volumio volumio[876]: info: CoreCommandRouter::getUIConfigOnPlugin
Apr 02 13:24:12 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions
Apr 02 13:24:12 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam
Apr 02 13:24:12 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus
Apr 02 13:24:12 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 13:24:12 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 13:24:12 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 13:24:12 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 13:24:12 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 13:24:12 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 13:24:12 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam
Apr 02 13:24:33 volumio volumio[876]: info: CoreCommandRouter::volumioGetQueue
Apr 02 13:24:33 volumio volumio[876]: info: CoreStateMachine::getQueue
Apr 02 13:24:33 volumio volumio[876]: info: CorePlayQueue::getQueue
Apr 02 13:24:39 volumio volumio[876]: info: CoreCommandRouter::volumioGetBrowseSources
Apr 02 13:24:41 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 02 13:24:41 volumio volumio[876]: info: CURURI: music-library
Apr 02 13:24:43 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 02 13:24:43 volumio volumio[876]: info: CURURI: music-library/USB
Apr 02 13:24:44 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 02 13:24:44 volumio volumio[876]: info: CURURI: music-library/USB/Music
Apr 02 13:24:49 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 02 13:24:49 volumio volumio[876]: info: CURURI: music-library/USB/Music/POP ROCK
Apr 02 13:24:51 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: mpd , handleBrowseUri
Apr 02 13:24:51 volumio volumio[876]: info: CURURI: music-library/USB/Music/POP ROCK/Into The Labyrinth Dead Can Dance
Apr 02 13:24:55 volumio volumio[876]: info: CoreCommandRouter::volumioAddQueueItems
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::addQueueItems
Apr 02 13:24:55 volumio volumio[876]: info: CorePlayQueue::addQueueItems
Apr 02 13:24:55 volumio volumio[876]: info: Adding Item to queue: music-library/USB/Music/POP ROCK/Into The Labyrinth Dead Can Dance/The Spider's Stratagem.aif
Apr 02 13:24:55 volumio volumio[876]: info: Exploding uri music-library/USB/Music/POP ROCK/Into The Labyrinth Dead Can Dance/The Spider's Stratagem.aif in service mpd
Apr 02 13:24:55 volumio volumio[876]: info: ALBUMART /albumart?cacheid=706&web=Dead%20Can%20Dance/Into%20The%20Labyrinth/extralarge&path=%2Fmnt%2FUSB%2FMusic%2FPOP%20ROCK%2FInto%20The%20Labyrinth%20Dead%20Can%20Dance%2FThe%20Spider's%20Stratagem.aif&metadata=true
Apr 02 13:24:55 volumio volumio[876]: info: URI /mnt/USB/Music/POP ROCK/Into The Labyrinth Dead Can Dance/The Spider's Stratagem.aif
Apr 02 13:24:55 volumio volumio[876]: info: CorePlayQueue::saveQueue
Apr 02 13:24:55 volumio volumio[876]: info: CoreCommandRouter::volumioPushQueue
Apr 02 13:24:55 volumio volumio[876]: info: interfaceApi::pushQueue
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::updateTrackBlock
Apr 02 13:24:55 volumio volumio[876]: info: CorePlayQueue::getTrackBlock
Apr 02 13:24:55 volumio volumio[876]: info: CoreCommandRouter::volumioPlay
Apr 02 13:24:55 volumio volumio[876]: UNSET VOLATILE
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::play index 0
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::stop
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 02 13:24:55 volumio volumio[876]: UNSET VOLATILE
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::play index undefined
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::setConsumeUpdateService undefined
Apr 02 13:24:55 volumio volumio[876]: info: CorePlayQueue::getTrack 0
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::startPlaybackTimer
Apr 02 13:24:55 volumio volumio[876]: info: CorePlayQueue::getTrack 0
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::clearAddPlayTracks USB/Music/POP ROCK/Into The Labyrinth Dead Can Dance/The Spider's Stratagem.aif
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::sendMpdCommand stop
Apr 02 13:24:55 volumio volumio[876]: info: sending command...
Apr 02 13:24:55 volumio volumio[876]: info: parsing response...
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::sendMpdCommand clear
Apr 02 13:24:55 volumio volumio[876]: info: sending command...
Apr 02 13:24:55 volumio volumio[876]: info:
Apr 02 13:24:55 volumio volumio[876]: ---------------------------- MPD announces system playlist update
Apr 02 13:24:55 volumio volumio[876]: info: Ignoring MPD Status Update
Apr 02 13:24:55 volumio volumio[876]: info: parsing response...
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::sendMpdCommand add "USB/Music/POP ROCK/Into The Labyrinth Dead Can Dance/The Spider's Stratagem.aif"
Apr 02 13:24:55 volumio volumio[876]: info: sending command...
Apr 02 13:24:55 volumio volumio[876]: info:
Apr 02 13:24:55 volumio volumio[876]: info:
Apr 02 13:24:55 volumio volumio[876]: ---------------------------- MPD announces system playlist update
Apr 02 13:24:55 volumio volumio[876]: info: Ignoring MPD Status Update
Apr 02 13:24:55 volumio volumio[876]: info: ------------------------------ 14ms
Apr 02 13:24:55 volumio volumio[876]: info: parsing response...
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::sendMpdCommand play
Apr 02 13:24:55 volumio volumio[876]: info: sending command...
Apr 02 13:24:55 volumio volumio[876]: info: ------------------------------ 10ms
Apr 02 13:24:55 volumio volumio[876]: info: parsing response...
Apr 02 13:24:55 volumio volumio[876]: info:
Apr 02 13:24:55 volumio volumio[876]: ---------------------------- MPD announces state update: player
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::getState
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::sendMpdCommand status
Apr 02 13:24:55 volumio volumio[876]: info: sending command...
Apr 02 13:24:55 volumio volumio[876]: info:
Apr 02 13:24:55 volumio volumio[876]: ---------------------------- MPD announces state update: player
Apr 02 13:24:55 volumio volumio[876]: info: parsing response...
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::getState
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::sendMpdCommand status
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::parseState
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 13:24:55 volumio volumio[876]: info: sending command...
Apr 02 13:24:55 volumio volumio[876]: info: sending command...
Apr 02 13:24:55 volumio volumio[876]: info: parsing response...
Apr 02 13:24:55 volumio volumio[876]: info: parsing response...
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::parseState
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::parseTrackInfo
Apr 02 13:24:55 volumio volumio[876]: info: sending command...
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::pushState
Apr 02 13:24:55 volumio volumio[876]: info: CoreCommandRouter::servicePushState
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::syncState
Apr 02 13:24:55 volumio volumio[876]: info: CorePlayQueue::getTrack 0
Apr 02 13:24:55 volumio volumio[876]: info: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":402,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"The Spider's Stratagem","artist":"Dead Can Dance","album":"Into The Labyrinth","uri":"USB/Music/POP ROCK/Into The Labyrinth Dead Can Dance/The Spider's Stratagem.aif","trackType":"aif"}
Apr 02 13:24:55 volumio volumio[876]: info: CURRENT POSITION 0
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::syncState stateService play
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::syncState currentStatus stop
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::pushState
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::getState
Apr 02 13:24:55 volumio volumio[876]: info: CorePlayQueue::getTrack 0
Apr 02 13:24:55 volumio volumio[876]: info: CoreCommandRouter::volumioPushState
Apr 02 13:24:55 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 13:24:55 volumio volumio[876]: info: interfaceApi::pushState
Apr 02 13:24:55 volumio volumio[876]: info: ------------------------------ 79ms
Apr 02 13:24:55 volumio volumio[876]: info: parsing response...
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::parseTrackInfo
Apr 02 13:24:55 volumio volumio[876]: info: ControllerMpd::pushState
Apr 02 13:24:55 volumio volumio[876]: info: CoreCommandRouter::servicePushState
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::syncState
Apr 02 13:24:55 volumio volumio[876]: info: CorePlayQueue::getTrack 0
Apr 02 13:24:55 volumio volumio[876]: info: STATE SERVICE {"status":"play","position":0,"seek":464,"duration":402,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"The Spider's Stratagem","artist":"Dead Can Dance","album":"Into The Labyrinth","uri":"USB/Music/POP ROCK/Into The Labyrinth Dead Can Dance/The Spider's Stratagem.aif","trackType":"aif"}
Apr 02 13:24:55 volumio volumio[876]: info: CURRENT POSITION 0
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::syncState stateService play
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::syncState currentStatus play
Apr 02 13:24:55 volumio volumio[876]: info: Received an update from plaugin. extracting info from payload
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::pushState
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::getState
Apr 02 13:24:55 volumio volumio[876]: info: CorePlayQueue::getTrack 0
Apr 02 13:24:55 volumio volumio[876]: info: CoreCommandRouter::volumioPushState
Apr 02 13:24:55 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 13:24:55 volumio volumio[876]: info: interfaceApi::pushState
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::pushState
Apr 02 13:24:55 volumio volumio[876]: info: CoreStateMachine::getState
Apr 02 13:24:55 volumio volumio[876]: info: CorePlayQueue::getTrack 0
Apr 02 13:24:55 volumio volumio[876]: info: CoreCommandRouter::volumioPushState
Apr 02 13:24:55 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 13:24:55 volumio volumio[876]: info: interfaceApi::pushState
Apr 02 13:24:55 volumio volumio[876]: info: ------------------------------ 143ms
Apr 02 13:24:55 volumio volumio[876]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/Music/POP ROCK/Into The Labyrinth Dead Can Dance/The Spider's Stratagem.aif","favourite":false}
Apr 02 13:24:55 volumio volumio[876]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/Music/POP ROCK/Into The Labyrinth Dead Can Dance/The Spider's Stratagem.aif","favourite":false}
Apr 02 13:24:55 volumio volumio[876]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/Music/POP ROCK/Into The Labyrinth Dead Can Dance/The Spider's Stratagem.aif","favourite":false}
Apr 02 13:25:01 volumio volumio[876]: info: CoreCommandRouter::volumioPause
Apr 02 13:25:01 volumio volumio[876]: info: CoreStateMachine::pause
Apr 02 13:25:01 volumio volumio[876]: info: CoreStateMachine::stPlaybackTimer
Apr 02 13:25:02 volumio volumio[876]: info: CoreStateMachine::servicePause
Apr 02 13:25:02 volumio volumio[876]: info: CorePlayQueue::getTrack 0
Apr 02 13:25:02 volumio volumio[876]: info: CoreCommandRouter::servicePause
Apr 02 13:25:02 volumio volumio[876]: info: ControllerMpd::pause
Apr 02 13:25:02 volumio volumio[876]: info: ControllerMpd::sendMpdCommand pause
Apr 02 13:25:02 volumio volumio[876]: info: sending command...
Apr 02 13:25:02 volumio volumio[876]: info: parsing response...
Apr 02 13:25:02 volumio volumio[876]: info:
Apr 02 13:25:02 volumio volumio[876]: ---------------------------- MPD announces state update: player
Apr 02 13:25:02 volumio volumio[876]: info: ControllerMpd::getState
Apr 02 13:25:02 volumio volumio[876]: info: ControllerMpd::sendMpdCommand status
Apr 02 13:25:02 volumio volumio[876]: info: sending command...
Apr 02 13:25:02 volumio volumio[876]: info: parsing response...
Apr 02 13:25:02 volumio volumio[876]: info: ControllerMpd::parseState
Apr 02 13:25:02 volumio volumio[876]: info: ControllerMpd::sendMpdCommand playlistinfo
Apr 02 13:25:02 volumio volumio[876]: info: sending command...
Apr 02 13:25:02 volumio volumio[876]: info: parsing response...
Apr 02 13:25:02 volumio volumio[876]: info: ControllerMpd::parseTrackInfo
Apr 02 13:25:02 volumio volumio[876]: info: ControllerMpd::pushState
Apr 02 13:25:02 volumio volumio[876]: info: CoreCommandRouter::servicePushState
Apr 02 13:25:02 volumio volumio[876]: info: CoreStateMachine::syncState
Apr 02 13:25:02 volumio volumio[876]: info: CorePlayQueue::getTrack 0
Apr 02 13:25:02 volumio volumio[876]: info: STATE SERVICE {"status":"pause","position":0,"seek":6873,"duration":402,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"isStreaming":false,"title":"The Spider's Stratagem","artist":"Dead Can Dance","album":"Into The Labyrinth","uri":"USB/Music/POP ROCK/Into The Labyrinth Dead Can Dance/The Spider's Stratagem.aif","trackType":"aif"}
Apr 02 13:25:02 volumio volumio[876]: info: CURRENT POSITION 0
Apr 02 13:25:02 volumio volumio[876]: info: CoreStateMachine::syncState stateService pause
Apr 02 13:25:02 volumio volumio[876]: info: CoreStateMachine::syncState currentStatus pause
Apr 02 13:25:02 volumio volumio[876]: info: CoreStateMachine::pushState
Apr 02 13:25:02 volumio volumio[876]: info: CoreStateMachine::getState
Apr 02 13:25:02 volumio volumio[876]: info: CorePlayQueue::getTrack 0
Apr 02 13:25:02 volumio volumio[876]: info: CoreCommandRouter::volumioPushState
Apr 02 13:25:02 volumio volumio[876]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Apr 02 13:25:02 volumio volumio[876]: info: interfaceApi::pushState
Apr 02 13:25:02 volumio volumio[876]: info: CoreStateMachine::stPlaybackTimer
Apr 02 13:25:02 volumio volumio[876]: info: ------------------------------ 53ms
Apr 02 13:25:02 volumio volumio[876]: info: Pushing Favourites {"service":"mpd","uri":"mnt/USB/Music/POP ROCK/Into The Labyrinth Dead Can Dance/The Spider's Stratagem.aif","favourite":false}
At the end of the process, “Yamaha CD player through brutefir” is displayed, but it doesn’t work.
Edit: Seems to be related to output format, but I tried them all before.
My DAC is probably not compatible.
Dominique
Damn! Even S16_LE ! It’s more complicated to get brutefir running with all hardware than I taught… But I think there is solution playing with the parameters…
If I want to play with different parameters:
- Should I focus on ouput only?
- Should I disable and reactivate the plugin each time I change the output parameter?
Dominque
Don’t disable the plugin. You can change other parameters
I am afraid I have tried all parameters, with no success. I will have to give up for the moment.
Thank you for the support!
Dominique
I too have the same problem with no output to my IQAudio DAC.
I have tried these steps:
I tested by disabling the plugin and setting output to just my DAC → and I have audio.
I have volumio version 2.389 on RPI3 with IQAudio DAC. Brutefir v0.4.9 installed.
I have the plugin (installed) and loaded, with green active light. journalctl logs look fine for brutefir and seems to load correct parameters.
Tracks can go to pay state but there is no audio outputted. the volume ui is also not adjustable, it stays and defaults back to 100% even when I manually try to change it.
In audio output I have Output device as IQAudio DAc through brutefir but does the I2S DAC setting need to be on and with the IQAUdio DAC selected there as well?
For volume control, the brutefir plugin (I think) set the mixer type to None, mixer control name to None, should these be set to software?
I have struggled long and hard to get brutefir installed, and feel I am quite close to getting this working right now. Any help would be appreciated.
Thanks
Output device → IQAudio DAC through brutefir
I looked into /data/configuration/audio_interface/brutefir/volumio-brutefir-config and see that the:
output device was set to hw:Loopback, I then changed this to hw:IQaudIODAC and disable then re-enable the plugin.
Behold hw:Loopback is back in the file.
I again disable the plugin, edit the volumio-brutefir-config to hw:IQaudIODAC, but this time dont re-enable the plugin, but start brutefir like this:
sudo brutefir volumio-brutefir-config
Brutefir starts up -> logs audio processing starts now
I play something in volumio and what do you know-> there is sound.
I then stop the brutefir service, restart the plugin but again no audio. I relook at the volumio-brutefir-config and the output device was set again back to hw:Loopback.
Why is the plugin setting this on start? Which file is responsible for setting this? I see no place to edit the brutefir output on the plugin ui settings.
Thanks
The plugin is designed to provide a zero configuration for the input/output of brutefir.
From a working and well configured volumio (output/ mixer volume), just enable the plugin, wait about 10 sec. Then play a track form the queue ( don’t simply press play for the first time).
From what I read in your post, it seems you change the output to loopback in volumio. Don’t change anything in volumio settings !
The plugin will update the config of brutefir automatically. Any changes wil be overwritten.
It has been tested with iqaudio dac and rpi2 and it works out of the box…
Please, follow exactly these steps.
let me know.
thanks for the pointer-> I managed to get set my mixer to digital and not analog as it was. the volume then worked. I enabled the plugin and it worked first time. Perfect Thank you.
Lots of clicking and popping but after playing around with the plugin settings, its pretty stable. Occasionally clicks that are still annoying. but on the whole Im quite happy that I managed to get a spare rpi to use as a dsp convolver. Beats hands down even buying the cheapest commercial audio dsp.
thank you for this plugin.
And works well with shairport-sync which is how I plan to use it. CPU at 12% per channel.
Any idea to reduce clicks further/ ideal set of brutefir settings?