-- Logs begin at Sat 2024-11-09 01:57:21 CST, end at Sun 2024-11-24 13:11:57 CST. -- Nov 24 13:10:09 beta618 volumio[1229]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Nov 24 13:10:09 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Nov 24 13:10:09 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Nov 24 13:10:09 beta618 sudo[2972]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 13:10:09 beta618 sudo[2972]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:10:09 beta618 sudo[2972]: pam_unix(sudo:session): session closed for user root Nov 24 13:10:09 beta618 volumio[1229]: info: MPD Permissions set Nov 24 13:10:09 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:10:09 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:10:09 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:10:09 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:10:09 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:10:09 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:10:09 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:10:09 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:10:09 beta618 sudo[2975]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 13:10:09 beta618 sudo[2975]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:10:09 beta618 systemd[1]: Stopping Music Player Daemon... Nov 24 13:10:09 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:10:09 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:10:09 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 13:10:09 beta618 systemd[1]: mpd.service: Succeeded. Nov 24 13:10:09 beta618 systemd[1]: Stopped Music Player Daemon. Nov 24 13:10:09 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 24 13:10:09 beta618 systemd[1]: Starting Music Player Daemon... Nov 24 13:10:09 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 13:10:09 beta618 sudo[2981]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 24 13:10:09 beta618 sudo[2981]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:10:09 beta618 sudo[2981]: pam_unix(sudo:session): session closed for user root Nov 24 13:10:10 beta618 kernel: hwmon hwmon1: Undervoltage detected! Nov 24 13:10:10 beta618 mpd[2983]: Nov 24 13:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 24 13:10:11 beta618 systemd[1]: Started Music Player Daemon. Nov 24 13:10:11 beta618 sudo[2975]: pam_unix(sudo:session): session closed for user root Nov 24 13:10:11 beta618 volumio[1229]: error: updateQueue error: null Nov 24 13:10:16 beta618 volumio[1229]: info: CoreCommandRouter::volumioPlay Nov 24 13:10:16 beta618 volumio[1229]: info: CoreStateMachine::play index undefined Nov 24 13:10:16 beta618 volumio[1229]: info: CoreStateMachine::setConsumeUpdateService undefined Nov 24 13:10:16 beta618 volumio[1229]: info: CorePlayQueue::getTrack 0 Nov 24 13:10:16 beta618 volumio[1229]: info: CoreStateMachine::startPlaybackTimer Nov 24 13:10:16 beta618 volumio[1229]: info: CorePlayQueue::getTrack 0 Nov 24 13:10:16 beta618 volumio[1229]: verbose: ControllerMpd::clearAddPlayTracks USB/HCD/Paul Mauriat電影音樂集/01. I Will Always Love You.wav Nov 24 13:10:16 beta618 volumio[1229]: verbose: ControllerMpd::sendMpdCommand stop Nov 24 13:10:16 beta618 volumio[1229]: info: sendMpdCommand stop took 1 milliseconds Nov 24 13:10:16 beta618 volumio[1229]: verbose: ControllerMpd::sendMpdCommand clear Nov 24 13:10:16 beta618 volumio[1229]: info: Nov 24 13:10:16 beta618 volumio[1229]: ---------------------------- MPD announces system playlist update Nov 24 13:10:16 beta618 volumio[1229]: info: Ignoring MPD Status Update Nov 24 13:10:16 beta618 volumio[1229]: info: sendMpdCommand clear took 3 milliseconds Nov 24 13:10:16 beta618 volumio[1229]: verbose: ControllerMpd::sendMpdCommand add "USB/HCD/Paul Mauriat電影音樂集/01. I Will Always Love You.wav" Nov 24 13:10:16 beta618 volumio[1229]: error: updateQueue error: null Nov 24 13:10:16 beta618 volumio[1229]: info: Nov 24 13:10:16 beta618 volumio[1229]: ---------------------------- MPD announces system playlist update Nov 24 13:10:16 beta618 volumio[1229]: info: Ignoring MPD Status Update Nov 24 13:10:16 beta618 volumio[1229]: info: ------------------------------ 5ms Nov 24 13:10:16 beta618 volumio[1229]: info: sendMpdCommand add "USB/HCD/Paul Mauriat電影音樂集/01. I Will Always Love You.wav" took 2 milliseconds Nov 24 13:10:16 beta618 volumio[1229]: verbose: ControllerMpd::sendMpdCommand play Nov 24 13:10:16 beta618 volumio[1229]: info: ------------------------------ 2ms Nov 24 13:10:16 beta618 volumio[1229]: info: sendMpdCommand play took 2 milliseconds Nov 24 13:10:16 beta618 volumio[1229]: info: Nov 24 13:10:16 beta618 volumio[1229]: ---------------------------- MPD announces state update: player Nov 24 13:10:16 beta618 volumio[1229]: info: ControllerMpd::getState Nov 24 13:10:16 beta618 volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Nov 24 13:10:16 beta618 volumio[1229]: info: Nov 24 13:10:16 beta618 volumio[1229]: ---------------------------- MPD announces state update: player Nov 24 13:10:16 beta618 volumio[1229]: verbose: ControllerMpd::sendMpdCommand clearerror Nov 24 13:10:16 beta618 volumio[1229]: info: sendMpdCommand status took 7 milliseconds Nov 24 13:10:16 beta618 volumio[1229]: info: ControllerMpd::getState Nov 24 13:10:16 beta618 volumio[1229]: verbose: ControllerMpd::sendMpdCommand status Nov 24 13:10:16 beta618 volumio[1229]: verbose: ControllerMpd::parseState Nov 24 13:10:16 beta618 volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 13:10:16 beta618 volumio[1229]: info: sendMpdCommand clearerror took 2 milliseconds Nov 24 13:10:16 beta618 volumio[1229]: info: sendMpdCommand status took 3 milliseconds Nov 24 13:10:16 beta618 volumio[1229]: info: sendMpdCommand playlistinfo took 2 milliseconds Nov 24 13:10:16 beta618 volumio[1229]: verbose: ControllerMpd::parseState Nov 24 13:10:16 beta618 volumio[1229]: verbose: ControllerMpd::sendMpdCommand playlistinfo Nov 24 13:10:16 beta618 volumio[1229]: verbose: ControllerMpd::parseTrackInfo Nov 24 13:10:16 beta618 volumio[1229]: info: ControllerMpd::pushState Nov 24 13:10:16 beta618 volumio[1229]: info: CoreCommandRouter::servicePushState Nov 24 13:10:16 beta618 volumio[1229]: info: CorePlayQueue::getTrack 0 Nov 24 13:10:16 beta618 volumio[1229]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. I Will Always Love You.wav","artist":null,"album":null,"uri":"USB/HCD/Paul Mauriat電影音樂集/01. I Will Always Love You.wav","trackType":"wav"} Nov 24 13:10:16 beta618 volumio[1229]: verbose: CURRENT POSITION 0 Nov 24 13:10:16 beta618 volumio[1229]: info: CoreStateMachine::syncState stateService pause Nov 24 13:10:16 beta618 volumio[1229]: info: CoreStateMachine::syncState currentStatus stop Nov 24 13:10:16 beta618 volumio[1229]: info: CoreStateMachine::pushState Nov 24 13:10:16 beta618 volumio[1229]: info: CorePlayQueue::getTrack 0 Nov 24 13:10:16 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 13:10:16 beta618 volumio[1229]: info: CoreCommandRouter::volumioPushState Nov 24 13:10:16 beta618 volumio[1229]: info: MRS: Pushing multiroomSync output update for this device Nov 24 13:10:16 beta618 volumio[1229]: info: MRS: Pushing multiroomSync output Nov 24 13:10:16 beta618 volumio[1229]: info: ------------------------------ 32ms Nov 24 13:10:16 beta618 volumio[1229]: info: sendMpdCommand playlistinfo took 25 milliseconds Nov 24 13:10:16 beta618 volumio[1229]: verbose: ControllerMpd::parseTrackInfo Nov 24 13:10:16 beta618 volumio[1229]: info: ControllerMpd::pushState Nov 24 13:10:16 beta618 volumio[1229]: info: CoreCommandRouter::servicePushState Nov 24 13:10:16 beta618 volumio[1229]: info: CorePlayQueue::getTrack 0 Nov 24 13:10:16 beta618 volumio[1229]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":213,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"01. I Will Always Love You.wav","artist":null,"album":null,"uri":"USB/HCD/Paul Mauriat電影音樂集/01. I Will Always Love You.wav","trackType":"wav"} Nov 24 13:10:16 beta618 volumio[1229]: verbose: CURRENT POSITION 0 Nov 24 13:10:16 beta618 volumio[1229]: info: CoreStateMachine::syncState stateService pause Nov 24 13:10:16 beta618 volumio[1229]: info: CoreStateMachine::syncState currentStatus stop Nov 24 13:10:16 beta618 volumio[1229]: info: CoreStateMachine::pushState Nov 24 13:10:16 beta618 volumio[1229]: info: CorePlayQueue::getTrack 0 Nov 24 13:10:16 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 24 13:10:16 beta618 volumio[1229]: info: CoreCommandRouter::volumioPushState Nov 24 13:10:16 beta618 volumio[1229]: info: MRS: Pushing multiroomSync output update for this device Nov 24 13:10:16 beta618 volumio[1229]: info: MRS: Pushing multiroomSync output Nov 24 13:10:16 beta618 volumio[1229]: info: ------------------------------ 47ms Nov 24 13:10:16 beta618 kernel: hwmon hwmon1: Voltage normalised Nov 24 13:10:22 beta618 kernel: hwmon hwmon1: Undervoltage detected! Nov 24 13:10:26 beta618 kernel: hwmon hwmon1: Voltage normalised Nov 24 13:10:31 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 13:10:31 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 24 13:10:38 beta618 volumio[1229]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Nov 24 13:10:40 beta618 volumio[1229]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/armhf Nov 24 13:10:40 beta618 volumio[1229]: info: Folder /tmp/plugins removed Nov 24 13:10:40 beta618 volumio[1229]: info: Check plugin dependencies Nov 24 13:10:40 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 24 13:10:40 beta618 volumio[1229]: info: Checking if plugin already exists Nov 24 13:10:40 beta618 volumio[1229]: info: Rename folder Nov 24 13:10:40 beta618 volumio[1229]: info: Folder /tmp/downloaded_plugin.zip removed Nov 24 13:10:40 beta618 volumio[1229]: info: Move to category Nov 24 13:10:41 beta618 volumio[1229]: info: Checking if install.sh is present Nov 24 13:10:41 beta618 volumio[1229]: info: Executing install.sh Nov 24 13:10:41 beta618 sudo[3074]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Nov 24 13:10:41 beta618 sudo[3074]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:10:41 beta618 volumio[1229]: info: creating filters folder and copying demo filters Nov 24 13:10:41 beta618 volumio[1229]: info: copying demo flters Nov 24 13:10:41 beta618 volumio[1229]: info: Installing/fusiondsp dependencies Nov 24 13:10:42 beta618 sudo[3095]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update Nov 24 13:10:42 beta618 sudo[3095]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:10:42 beta618 volumio[1229]: info: Get:1 https://deb.nodesource.com/node_14.x buster InRelease [4584 B] Nov 24 13:10:43 beta618 volumio[1229]: info: Get:2 http://raspbian.raspberrypi.org/raspbian buster InRelease [15.0 kB] Nov 24 13:10:43 beta618 volumio[1229]: info: Get:3 http://archive.raspberrypi.org/debian buster InRelease [54.2 kB] Nov 24 13:10:43 beta618 kernel: hwmon hwmon1: Undervoltage detected! Nov 24 13:10:43 beta618 volumio[1229]: info: Get:4 https://deb.nodesource.com/node_14.x buster/main armhf Packages [786 B] Nov 24 13:10:44 beta618 volumio[1229]: info: Get:5 http://raspbian.raspberrypi.org/raspbian buster/main armhf Packages [13.0 MB] Nov 24 13:10:44 beta618 volumio[1229]: info: Get:6 http://archive.raspberrypi.org/debian buster/main armhf Packages [400 kB] Nov 24 13:10:47 beta618 kernel: hwmon hwmon1: Voltage normalised Nov 24 13:10:48 beta618 volumio[1229]: info: Get:7 http://raspbian.raspberrypi.org/raspbian buster/contrib armhf Packages [58.8 kB] Nov 24 13:10:49 beta618 volumio[1229]: info: Get:8 http://raspbian.raspberrypi.org/raspbian buster/non-free armhf Packages [110 kB] Nov 24 13:10:50 beta618 volumio[1229]: info: Get:9 http://raspbian.raspberrypi.org/raspbian buster/rpi armhf Packages [1360 B] Nov 24 13:10:51 beta618 kernel: hwmon hwmon1: Undervoltage detected! Nov 24 13:10:55 beta618 volumio[1229]: info: Fetched 13.7 MB in 14s (1002 kB/s) Nov 24 13:10:57 beta618 kernel: hwmon hwmon1: Voltage normalised Nov 24 13:11:00 beta618 volumio[1229]: info: Reading package lists... Nov 24 13:11:00 beta618 sudo[3095]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:00 beta618 sudo[3362]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Nov 24 13:11:00 beta618 sudo[3362]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:02 beta618 volumio[1229]: info: Reading package lists... Nov 24 13:11:02 beta618 sudo[3362]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:02 beta618 sudo[3380]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/tar -xvf fusiondsp.service.tar -C / Nov 24 13:11:02 beta618 sudo[3380]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:02 beta618 volumio[1229]: info: lib/systemd/system/fusiondsp.service Nov 24 13:11:02 beta618 sudo[3380]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:03 beta618 volumio[1229]: info: MiniUnz 1.01b, demo of zLib + Unz package written by Gilles Vollant Nov 24 13:11:03 beta618 volumio[1229]: info: more info at http://www.winimage.com/zLibDll/unzip.html Nov 24 13:11:03 beta618 volumio[1229]: info: Nov 24 13:11:03 beta618 volumio[1229]: info: Cannot open cgui-1.0.0.zip or cgui-1.0.0.zip.zip Nov 24 13:11:03 beta618 sudo[3384]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chown -R volumio cgui Nov 24 13:11:03 beta618 sudo[3384]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:03 beta618 sudo[3384]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:03 beta618 sudo[3386]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/chgrp -R volumio cgui Nov 24 13:11:03 beta618 sudo[3386]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:03 beta618 sudo[3386]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:03 beta618 sudo[3388]: root : TTY=unknown ; PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/bin/rm cgui-1.0.0.zip Nov 24 13:11:03 beta618 sudo[3388]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:03 beta618 sudo[3388]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:03 beta618 volumio[1229]: info: copying hw detection script Nov 24 13:11:03 beta618 volumio[1229]: info: Detected cpu architecture as armv7l Nov 24 13:11:05 beta618 volumio[1229]: info: camilladsp Nov 24 13:11:06 beta618 sudo[3404]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Nov 24 13:11:06 beta618 sudo[3404]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:06 beta618 sudo[3404]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:06 beta618 sudo[3406]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Nov 24 13:11:06 beta618 sudo[3406]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:06 beta618 sudo[3406]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:06 beta618 sudo[3408]: root : TTY=unknown ; PWD=/tmp ; USER=root ; COMMAND=/usr/bin/apt-get -y install drc Nov 24 13:11:06 beta618 sudo[3408]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:08 beta618 volumio[1229]: info: Reading package lists... Nov 24 13:11:08 beta618 sudo[3408]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:08 beta618 sudo[3074]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:08 beta618 volumio[1229]: info: Install script completed Nov 24 13:11:08 beta618 volumio[1229]: info: Adding reference to registry Nov 24 13:11:08 beta618 volumio[1229]: info: Done installing plugin. Nov 24 13:11:08 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Nov 24 13:11:08 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Nov 24 13:11:08 beta618 volumio[1229]: Plugin install end detected on script Nov 24 13:11:08 beta618 volumio[1229]: info: Folder /tmp/plugins removed Nov 24 13:11:08 beta618 volumio[1229]: info: Folder /tmp/downloaded_plugin.zip removed Nov 24 13:11:08 beta618 volumio[1229]: info: Folder /data/temp removed Nov 24 13:11:33 beta618 kernel: hwmon hwmon1: Undervoltage detected! Nov 24 13:11:37 beta618 kernel: hwmon hwmon1: Voltage normalised Nov 24 13:11:54 beta618 volumio[1229]: info: Enabling plugin fusiondsp Nov 24 13:11:54 beta618 volumio[1229]: info: Loading plugin "fusiondsp"... Nov 24 13:11:55 beta618 volumio[1229]: error: Could not copy default configuration to /data/configuration/audio_interface/fusiondsp/config.json Nov 24 13:11:55 beta618 volumio[1229]: info: Preparing to generate the ALSA configuration file Nov 24 13:11:55 beta618 volumio[1229]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 24 13:11:55 beta618 volumio[1229]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 24 13:11:55 beta618 volumio[1229]: info: Reading ALSA contributions from plugins. Nov 24 13:11:55 beta618 volumio[1229]: error: Cannot write /etc/asound.conf: Error: ENOSPC: no space left on device, write Nov 24 13:11:55 beta618 volumio[1229]: info: Output device has changed, restarting MPD Nov 24 13:11:55 beta618 sudo[3541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 13:11:55 beta618 sudo[3541]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:55 beta618 volumio[1229]: info: Output device has changed, restarting Shairport Sync Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:11:55 beta618 sudo[3541]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:55 beta618 sudo[3544]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 13:11:55 beta618 sudo[3544]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:55 beta618 systemd[1]: Stopping Music Player Daemon... Nov 24 13:11:55 beta618 volumio[1229]: xcb_connection_has_error() returned true Nov 24 13:11:55 beta618 volumio[1229]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 13:11:55 beta618 volumio[1229]: xcb_connection_has_error() returned true Nov 24 13:11:55 beta618 volumio[1229]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 vtcs[2876]: [2024-11-24 13:11:55.324] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Nov 24 13:11:55 beta618 vtcs[2876]: [2024-11-24 13:11:55.327] [tisoc] [error] [SpkconServer.cpp:381] recv error. client fd=8 errorno=104 error=Connection reset by peer Nov 24 13:11:55 beta618 vtcs[2876]: [2024-11-24 13:11:55.327] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Nov 24 13:11:55 beta618 systemd[1]: mpd.service: Succeeded. Nov 24 13:11:55 beta618 systemd[1]: Stopped Music Player Daemon. Nov 24 13:11:55 beta618 volumio[1229]: info: PLUGIN START: fusiondsp Nov 24 13:11:55 beta618 volumio[1229]: info: Loading i18n strings for locale en Nov 24 13:11:55 beta618 sudo[3553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 24 13:11:55 beta618 sudo[3553]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: info: FusionDsp - mixtype--------------------- Hardware Nov 24 13:11:55 beta618 volumio[1229]: info: Preparing to generate the ALSA configuration file Nov 24 13:11:55 beta618 volumio[1229]: info: Done. Nov 24 13:11:55 beta618 volumio[1229]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Nov 24 13:11:55 beta618 volumio[1229]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Nov 24 13:11:55 beta618 volumio[1229]: info: Reading ALSA contributions from plugins. Nov 24 13:11:55 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 13:11:55 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::getUIConfigOnPlugin Nov 24 13:11:55 beta618 volumio[1229]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Nov 24 13:11:55 beta618 volumio[1229]: info: MPD Permissions set Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 sudo[3560]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 24 13:11:55 beta618 systemd[1]: Starting Music Player Daemon... Nov 24 13:11:55 beta618 sudo[3560]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:55 beta618 systemd[1]: Stopping Volumio Tidal Connect Service... Nov 24 13:11:55 beta618 systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Nov 24 13:11:55 beta618 systemd[1]: vtcs.service: Succeeded. Nov 24 13:11:55 beta618 systemd[1]: Stopped Volumio Tidal Connect Service. Nov 24 13:11:55 beta618 sudo[3553]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:55 beta618 sudo[3560]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 13:11:55 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 24 13:11:55 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 13:11:55 beta618 sudo[3552]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 24 13:11:55 beta618 sudo[3552]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:55 beta618 sudo[3552]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:55 beta618 volumio[1229]: info: Starting Shairport Sync Nov 24 13:11:55 beta618 sudo[3572]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 13:11:55 beta618 sudo[3572]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:55 beta618 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 24 13:11:55 beta618 volumio[1229]: error: Cannot write /etc/asound.conf: Error: ENOSPC: no space left on device, write Nov 24 13:11:55 beta618 volumio[1229]: info: Output device has changed, restarting MPD Nov 24 13:11:55 beta618 systemd[1]: shairport-sync.service: Succeeded. Nov 24 13:11:55 beta618 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 24 13:11:55 beta618 sudo[3576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 24 13:11:55 beta618 sudo[3576]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:55 beta618 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 24 13:11:55 beta618 volumio[1229]: info: Output device has changed, restarting Shairport Sync Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:11:55 beta618 sudo[3576]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:55 beta618 sudo[3572]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:55 beta618 sudo[3579]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 24 13:11:55 beta618 sudo[3579]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:55 beta618 volumio[1229]: xcb_connection_has_error() returned true Nov 24 13:11:55 beta618 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 24 13:11:55 beta618 systemd[1]: mpd.service: Succeeded. Nov 24 13:11:55 beta618 systemd[1]: Stopped Music Player Daemon. Nov 24 13:11:55 beta618 volumio[1229]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 24 13:11:55 beta618 volumio[1229]: xcb_connection_has_error() returned true Nov 24 13:11:55 beta618 volumio[1229]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 systemd[1]: Starting Music Player Daemon... Nov 24 13:11:55 beta618 sudo[3590]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 24 13:11:55 beta618 sudo[3590]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:55 beta618 sudo[3592]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 24 13:11:55 beta618 sudo[3592]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:55 beta618 sudo[3586]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 24 13:11:55 beta618 sudo[3586]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:55 beta618 sudo[3586]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:55 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 13:11:55 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 24 13:11:55 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 24 13:11:55 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 13:11:55 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 24 13:11:55 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 13:11:55 beta618 volumio[1229]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Nov 24 13:11:55 beta618 volumio[1229]: info: MPD Permissions set Nov 24 13:11:55 beta618 volumio[1229]: info: Shairport-Sync Started Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:55 beta618 volumio[1229]: info: Starting Shairport Sync Nov 24 13:11:55 beta618 sudo[3602]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 24 13:11:55 beta618 sudo[3602]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:56 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 24 13:11:56 beta618 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 24 13:11:56 beta618 systemd[1]: shairport-sync.service: Succeeded. Nov 24 13:11:56 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: running Nov 24 13:11:56 beta618 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 24 13:11:56 beta618 volumio[1229]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Nov 24 13:11:56 beta618 sudo[3590]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:56 beta618 sudo[3592]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:56 beta618 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 24 13:11:56 beta618 sudo[3602]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:56 beta618 volumio[1229]: info: Shairport-Sync Started Nov 24 13:11:56 beta618 volumio[1229]: Configuration write error: Error: ENOENT: no such file or directory, open '/data/configuration/audio_interface/fusiondsp/config.json' Nov 24 13:11:56 beta618 mpd[3596]: Nov 24 13:11 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 24 13:11:56 beta618 systemd[1]: Started Music Player Daemon. Nov 24 13:11:56 beta618 sudo[3579]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:56 beta618 sudo[3544]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:56 beta618 volumio[1229]: error: MPD error: The expression evaluated to a falsy value: Nov 24 13:11:56 beta618 volumio[1229]: assert.ok(self.idling) Nov 24 13:11:56 beta618 volumio[1229]: error: The expression evaluated to a falsy value: Nov 24 13:11:56 beta618 volumio[1229]: assert.ok(self.idling) Nov 24 13:11:56 beta618 volumio[1229]: error: updateQueue error: null Nov 24 13:11:57 beta618 volumio[1229]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Nov 24 13:11:57 beta618 volumio[1229]: info: camilladsp service started and running in background, instance 1 Nov 24 13:11:57 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 24 13:11:57 beta618 volumio[1229]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 24 13:11:57 beta618 volumio[1229]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Nov 24 13:11:57 beta618 volumio[1229]: /bin/sh: 1: cannot create /data/configuration/audio_interface/fusiondsp/hwinfo.json: Directory nonexistent Nov 24 13:11:57 beta618 volumio[1229]: info: FusionDsp loaded Nov 24 13:11:57 beta618 volumio[1229]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Nov 24 13:11:57 beta618 sudo[3620]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Nov 24 13:11:57 beta618 sudo[3620]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 24 13:11:57 beta618 volumio[1229]: info: FusionDsp - Reporting Fusion DSP Enabled Nov 24 13:11:57 beta618 volumio[1229]: info: Adding Signal Path Element [object Object] Nov 24 13:11:57 beta618 volumio[1229]: info: Adding fusiondspeq DSP Signal Path Element Nov 24 13:11:57 beta618 volumio[1229]: info: FusionDsp - ---- installed callbackRead Nov 24 13:11:57 beta618 volumio[1229]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 13:11:57 beta618 sudo[3620]: pam_unix(sudo:session): session closed for user root Nov 24 13:11:57 beta618 volumio[1229]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp EACCES Nov 24 13:11:57 beta618 volumio[1229]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Nov 24 13:11:57 beta618 volumio[1229]: at onErrorNT (internal/child_process.js:465:16) Nov 24 13:11:57 beta618 volumio[1229]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Nov 24 13:11:57 beta618 volumio[1229]: errno: -13, Nov 24 13:11:57 beta618 volumio[1229]: code: 'EACCES', Nov 24 13:11:57 beta618 volumio[1229]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Nov 24 13:11:57 beta618 volumio[1229]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Nov 24 13:11:57 beta618 volumio[1229]: spawnargs: [ Nov 24 13:11:57 beta618 volumio[1229]: '-p', Nov 24 13:11:57 beta618 volumio[1229]: 9876, Nov 24 13:11:57 beta618 volumio[1229]: '-o', Nov 24 13:11:57 beta618 volumio[1229]: '/tmp/camilladsp.log', Nov 24 13:11:57 beta618 volumio[1229]: '-l', Nov 24 13:11:57 beta618 volumio[1229]: 'warn', Nov 24 13:11:57 beta618 volumio[1229]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Nov 24 13:11:57 beta618 volumio[1229]: ] Nov 24 13:11:57 beta618 volumio[1229]: } Nov 24 13:11:57 beta618 volumio[1229]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 24 13:11:57 beta618 sudo[3631]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-11-24 13:10 Nov 24 13:11:57 beta618 sudo[3631]: 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"