-- Logs begin at Thu 2019-02-14 11:11:59 CET, end at Thu 2024-11-21 17:15:07 CET. -- Nov 21 17:14:32 volumiorob volumio[1057]: info: [1732205672332] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=2 Nov 21 17:14:32 volumiorob volumio[1057]: info: [1732205672962] [RadioParadise] received new metadata: {"time":435,"artist":"Deep Purple","title":"Lazy (original mix)","album":"Machine Head","year":"2002","cover":"https://img.radioparadise.com/covers/l/B000002KHB.jpg","cover_med":"https://img.radioparadise.com/covers/m/B000002KHB.jpg","cover_small":"https://img.radioparadise.com/covers/s/B000002KHB.jpg"} Nov 21 17:14:32 volumiorob volumio[1057]: info: CorePlayQueue::getTrack 0 Nov 21 17:14:32 volumiorob volumio[1057]: info: CoreCommandRouter::servicePushState Nov 21 17:14:32 volumiorob volumio[1057]: info: CorePlayQueue::getTrack 0 Nov 21 17:14:32 volumiorob volumio[1057]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"rparadise","albumart":"https://img.radioparadise.com/covers/l/B000002KHB.jpg","uri":"https://stream.radioparadise.com/rock-flac","name":"Lazy (original mix)","title":"Lazy (original mix)","artist":"Deep Purple","album":"Machine Head","streaming":true,"disableUiControls":true,"duration":440,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Nov 21 17:14:32 volumiorob volumio[1057]: verbose: CURRENT POSITION 0 Nov 21 17:14:32 volumiorob volumio[1057]: info: CoreStateMachine::syncState stateService play Nov 21 17:14:32 volumiorob volumio[1057]: info: CoreStateMachine::syncState currentStatus play Nov 21 17:14:32 volumiorob volumio[1057]: info: Received an update from plugin. extracting info from payload Nov 21 17:14:32 volumiorob volumio[1057]: info: CoreStateMachine::pushState Nov 21 17:14:32 volumiorob volumio[1057]: info: CorePlayQueue::getTrack 0 Nov 21 17:14:32 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 21 17:14:32 volumiorob volumio[1057]: info: CoreCommandRouter::volumioPushState Nov 21 17:14:32 volumiorob volumio[1057]: info: CoreStateMachine::pushState Nov 21 17:14:32 volumiorob volumio[1057]: info: CorePlayQueue::getTrack 0 Nov 21 17:14:32 volumiorob volumio[1057]: info: CoreCommandRouter::volumioPushState Nov 21 17:14:32 volumiorob volumio[1057]: info: [1732205672971] [RadioParadise] setting new timer with duration of 440000 seconds. Nov 21 17:14:33 volumiorob volumio[1057]: info: CorePlayQueue::getTrack 0 Nov 21 17:14:33 volumiorob volumio[1057]: info: CorePlayQueue::getTrack 1 Nov 21 17:15:03 volumiorob volumio[1057]: info: Enabling plugin fusiondsp Nov 21 17:15:03 volumiorob volumio[1057]: info: Loading plugin "fusiondsp"... Nov 21 17:15:03 volumiorob volumio[1057]: info: Preparing to generate the ALSA configuration file Nov 21 17:15:03 volumiorob volumio[1057]: info: Asound.conf file unchanged, so no further update is needed Nov 21 17:15:03 volumiorob volumio[1057]: info: Output device has changed, restarting MPD Nov 21 17:15:03 volumiorob sudo[783]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 17:15:03 volumiorob sudo[783]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 17:15:03 volumiorob sudo[783]: pam_unix(sudo:session): session closed for user root Nov 21 17:15:03 volumiorob volumio[1057]: info: Output device has changed, restarting Shairport Sync Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 17:15:03 volumiorob sudo[787]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 17:15:03 volumiorob sudo[787]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 17:15:03 volumiorob systemd[1]: Stopping Music Player Daemon... Nov 21 17:15:03 volumiorob volumio[1057]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 17:15:03 volumiorob volumio[1057]: info: PLUGIN START: fusiondsp Nov 21 17:15:03 volumiorob volumio[1057]: info: Loading i18n strings for locale cs Nov 21 17:15:03 volumiorob systemd[1]: mpd.service: Succeeded. Nov 21 17:15:03 volumiorob systemd[1]: Stopped Music Player Daemon. Nov 21 17:15:03 volumiorob volumio[1057]: error: touch_display: Fetching language file: Error: i18n file complementing the system language not found. Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: FusionDsp - mixtype--------------------- Hardware Nov 21 17:15:03 volumiorob volumio[1057]: info: Preparing to generate the ALSA configuration file Nov 21 17:15:03 volumiorob volumio[1057]: info: Done. Nov 21 17:15:03 volumiorob volumio[1057]: info: MPD Permissions set Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: Asound.conf file unchanged, so no further update is needed Nov 21 17:15:03 volumiorob volumio[1057]: info: Output device has changed, restarting MPD Nov 21 17:15:03 volumiorob systemd[1]: Starting Music Player Daemon... Nov 21 17:15:03 volumiorob sudo[804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 21 17:15:03 volumiorob sudo[804]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 17:15:03 volumiorob sudo[804]: pam_unix(sudo:session): session closed for user root Nov 21 17:15:03 volumiorob volumio[1057]: info: Output device has changed, restarting Shairport Sync Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 17:15:03 volumiorob sudo[807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 21 17:15:03 volumiorob sudo[807]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 17:15:03 volumiorob sudo[793]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 21 17:15:03 volumiorob sudo[793]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 17:15:03 volumiorob systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 21 17:15:03 volumiorob sudo[793]: pam_unix(sudo:session): session closed for user root Nov 21 17:15:03 volumiorob systemd[1]: mpd.service: Succeeded. Nov 21 17:15:03 volumiorob systemd[1]: Stopped Music Player Daemon. Nov 21 17:15:03 volumiorob systemd[1]: Starting Music Player Daemon... Nov 21 17:15:03 volumiorob volumio[1057]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 21 17:15:03 volumiorob volumio[1057]: info: MPD Permissions set Nov 21 17:15:03 volumiorob volumio[1057]: info: Starting Shairport Sync Nov 21 17:15:03 volumiorob sudo[812]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 21 17:15:03 volumiorob sudo[812]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 17:15:03 volumiorob sudo[812]: pam_unix(sudo:session): session closed for user root Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob sudo[816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 21 17:15:03 volumiorob sudo[816]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 21 17:15:03 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:03 volumiorob systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 21 17:15:03 volumiorob systemd[1]: shairport-sync.service: Succeeded. Nov 21 17:15:03 volumiorob systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 21 17:15:03 volumiorob systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 21 17:15:03 volumiorob sudo[816]: pam_unix(sudo:session): session closed for user root Nov 21 17:15:04 volumiorob mpd[815]: Nov 21 17:15 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 21 17:15:04 volumiorob systemd[1]: Started Music Player Daemon. Nov 21 17:15:04 volumiorob sudo[787]: pam_unix(sudo:session): session closed for user root Nov 21 17:15:04 volumiorob sudo[807]: pam_unix(sudo:session): session closed for user root Nov 21 17:15:07 volumiorob volumio[1057]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Nov 21 17:15:07 volumiorob volumio[1057]: info: camilladsp service started and running in background, instance 1 Nov 21 17:15:07 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 21 17:15:07 volumiorob volumio[1057]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Nov 21 17:15:07 volumiorob volumio[1057]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Nov 21 17:15:07 volumiorob volumio[1057]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Nov 21 17:15:07 volumiorob volumio[1057]: info: FusionDsp loaded Nov 21 17:15:07 volumiorob volumio[1057]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 21 17:15:07 volumiorob sudo[857]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 21 17:15:07 volumiorob sudo[857]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 21 17:15:07 volumiorob volumio[1057]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 21 17:15:07 volumiorob volumio[1057]: info: Adding Signal Path Element [object Object] Nov 21 17:15:07 volumiorob volumio[1057]: info: Adding fusiondspeq DSP Signal Path Element Nov 21 17:15:07 volumiorob volumio[1057]: info: FusionDsp - ---- installed callbackRead Nov 21 17:15:07 volumiorob volumio[1057]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 17:15:07 volumiorob sudo[857]: pam_unix(sudo:session): session closed for user root Nov 21 17:15:07 volumiorob volumio[1057]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Nov 21 17:15:07 volumiorob volumio[1057]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Nov 21 17:15:07 volumiorob volumio[1057]: at onErrorNT (internal/child_process.js:465:16) Nov 21 17:15:07 volumiorob volumio[1057]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Nov 21 17:15:07 volumiorob volumio[1057]: at runNextTicks (internal/process/task_queues.js:62:3) Nov 21 17:15:07 volumiorob volumio[1057]: at listOnTimeout (internal/timers.js:523:9) Nov 21 17:15:07 volumiorob volumio[1057]: at processTimers (internal/timers.js:497:7) { Nov 21 17:15:07 volumiorob volumio[1057]: errno: -2, Nov 21 17:15:07 volumiorob volumio[1057]: code: 'ENOENT', Nov 21 17:15:07 volumiorob volumio[1057]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Nov 21 17:15:07 volumiorob volumio[1057]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Nov 21 17:15:07 volumiorob volumio[1057]: spawnargs: [ Nov 21 17:15:07 volumiorob volumio[1057]: '-p', Nov 21 17:15:07 volumiorob volumio[1057]: 9876, Nov 21 17:15:07 volumiorob volumio[1057]: '-o', Nov 21 17:15:07 volumiorob volumio[1057]: '/tmp/camilladsp.log', Nov 21 17:15:07 volumiorob volumio[1057]: '-l', Nov 21 17:15:07 volumiorob volumio[1057]: 'warn', Nov 21 17:15:07 volumiorob volumio[1057]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Nov 21 17:15:07 volumiorob volumio[1057]: ] Nov 21 17:15:07 volumiorob volumio[1057]: } Nov 21 17:15:07 volumiorob volumio[1057]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 21 17:15:07 volumiorob sudo[868]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-21 17:14 Nov 21 17:15:07 volumiorob sudo[868]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="70bcab5180cf11352f5a5197ade215ea8ee93db0" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="678d37490b3eb2102ce7c6d7a6dc3cf042ff2347" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 08 Nov 2024 06:51:56 PM CET" VOLUMIO_VERSION="3.779" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="b9009e0280f50389e80a537357ff3961"