-- Logs begin at Thu 2019-02-14 19:11:59 +09, end at Mon 2025-01-13 17:06:24 +09. -- Jan 13 17:05:00 volumio-living go-librespot[9660]: time="2025-01-13T17:05:00+09:00" level=debug msg="authenticated as rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:05:00 volumio-living go-librespot[9660]: time="2025-01-13T17:05:00+09:00" level=debug msg="dealer connection opened" Jan 13 17:05:00 volumio-living go-librespot[9660]: time="2025-01-13T17:05:00+09:00" level=debug msg="initializing zeroconf session, username: rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:05:00 volumio-living go-librespot[9660]: time="2025-01-13T17:05:00+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 13 17:05:00 volumio-living go-librespot[9660]: time="2025-01-13T17:05:00+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 13 17:05:00 volumio-living go-librespot[9660]: time="2025-01-13T17:05:00+09:00" level=debug msg="received connection id: NDQzM2Q0YjYtMjkxZC00N2EwLTg3MDQtOWM2ZTE1MDc4YmY5K2RlYWxlcit0Y3A6Ly8wYWM5NDk2OS5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArQkU0RUMwMjI0ODVBQTc4QzA5MDc4Njc3ODRFQTdERUJBNTYwQkY1QUM5M0ExRjY5QzAzNkM2RTk3Nzc5Rjg5OA==" Jan 13 17:05:00 volumio-living go-librespot[9660]: time="2025-01-13T17:05:00+09:00" level=debug msg="put connect state because NEW_DEVICE" Jan 13 17:05:00 volumio-living go-librespot[9660]: time="2025-01-13T17:05:00+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 13 17:05:00 volumio-living go-librespot[9660]: time="2025-01-13T17:05:00+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 13 17:05:00 volumio-living go-librespot[9660]: time="2025-01-13T17:05:00+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 13 17:05:00 volumio-living go-librespot[9660]: time="2025-01-13T17:05:00+09:00" level=debug msg="autoplay enabled: false" Jan 13 17:05:00 volumio-living volumio[9769]: info: Loading plugin "network"... Jan 13 17:05:00 volumio-living volumio[9769]: info: Refreshing Cached IP Addresses Jan 13 17:05:00 volumio-living sudo[9796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:05:00 volumio-living sudo[9796]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:00 volumio-living sudo[9798]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:05:00 volumio-living sudo[9798]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:00 volumio-living sudo[9796]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:00 volumio-living sudo[9798]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:00 volumio-living volumio[9769]: info: Loading plugin "services"... Jan 13 17:05:00 volumio-living volumio[9769]: info: Loading plugin "alsa_controller"... Jan 13 17:05:00 volumio-living sudo[9807]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 13 17:05:00 volumio-living sudo[9807]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:00 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:05:00 volumio-living volumio[9769]: info: Loading plugin "wizard"... Jan 13 17:05:00 volumio-living volumio[9769]: info: Loading plugin "networkfs"... Jan 13 17:05:00 volumio-living volumio[9769]: info: Starting Udev Watcher for removable devices Jan 13 17:05:00 volumio-living volumio[9769]: info: Ignoring mount for partition: boot Jan 13 17:05:00 volumio-living volumio[9769]: info: Ignoring mount for partition: volumio Jan 13 17:05:00 volumio-living volumio[9769]: info: Ignoring mount for partition: volumio_data Jan 13 17:05:00 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:05:00 volumio-living volumio[9769]: info: Loading plugin "volumio_command_line_client"... Jan 13 17:05:00 volumio-living volumio[9769]: info: Loading plugin "upnp"... Jan 13 17:05:00 volumio-living volumio[9769]: info: [1736755500756] Starting Upmpd Daemon Jan 13 17:05:00 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:05:00 volumio-living volumio[9769]: info: Loading plugin "my_music"... Jan 13 17:05:00 volumio-living volumio[9769]: info: Loading plugin "mpd"... Jan 13 17:05:01 volumio-living volumio[9769]: info: Loading plugin "upnp_browser"... Jan 13 17:05:01 volumio-living volumio[9794]: Traceback (most recent call last): Jan 13 17:05:01 volumio-living volumio[9794]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:01 volumio-living volumio[9794]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:01 volumio-living volumio[9794]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:01 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:01 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:01 volumio-living volumio[9769]: info: Loading plugin "alarm-clock"... Jan 13 17:05:01 volumio-living volumio[9769]: info: Loading plugin "airplay_emulation"... Jan 13 17:05:01 volumio-living volumio[9769]: info: Starting Shairport Sync Jan 13 17:05:01 volumio-living volumio[9769]: info: Loading plugin "last_100"... Jan 13 17:05:01 volumio-living volumio[9769]: info: Loading plugin "webradio"... Jan 13 17:05:01 volumio-living volumio[9769]: info: Loading plugin "i2s_dacs"... Jan 13 17:05:01 volumio-living volumio[9769]: info: I2S DAC not set, start Auto-detection Jan 13 17:05:01 volumio-living volumio[9769]: info: Loading plugin "volumiodiscovery"... Jan 13 17:05:01 volumio-living volumio[9769]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:01 volumio-living volumio[9769]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:01 volumio-living volumio[9769]: *** WARNING *** For more information see Jan 13 17:05:01 volumio-living node[9769]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:01 volumio-living volumio[9769]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:01 volumio-living volumio[9769]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:01 volumio-living volumio[9769]: *** WARNING *** For more information see Jan 13 17:05:01 volumio-living node[9769]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:01 volumio-living node[9769]: *** WARNING *** For more information see Jan 13 17:05:01 volumio-living node[9769]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:01 volumio-living node[9769]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:01 volumio-living node[9769]: *** WARNING *** For more information see Jan 13 17:05:01 volumio-living volumio[9769]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 17:05:01 volumio-living volumio[9769]: info: Discovery: Started advertising with name: Volumio-Living Jan 13 17:05:01 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:05:01 volumio-living volumio[9769]: info: Loading plugin "spop"... Jan 13 17:05:03 volumio-living volumio[9769]: info: Loading plugin "ytmusic"... Jan 13 17:05:03 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:03 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 130. Jan 13 17:05:03 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:03 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:04 volumio-living sudo[9807]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:04 volumio-living volumio[9828]: Traceback (most recent call last): Jan 13 17:05:04 volumio-living volumio[9828]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:04 volumio-living volumio[9828]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:04 volumio-living volumio[9828]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:05 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:05 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:06 volumio-living volumio[9769]: info: Loading plugin "outputs"... Jan 13 17:05:06 volumio-living volumio[9769]: info: Loading plugin "albumart"... Jan 13 17:05:06 volumio-living volumio[9769]: info: Plugin example_plugin is not enabled Jan 13 17:05:06 volumio-living volumio[9769]: info: Loading plugin "inputs"... Jan 13 17:05:06 volumio-living volumio[9769]: info: Loading plugin "updater_comm"... Jan 13 17:05:06 volumio-living volumio[9769]: info: Plugin mpdemulation is not enabled Jan 13 17:05:06 volumio-living volumio[9769]: info: Loading plugin "rest_api"... Jan 13 17:05:06 volumio-living volumio[9769]: info: Loading plugin "websocket"... Jan 13 17:05:06 volumio-living volumio[9769]: info: Starting Socket.io Server version 2.3.0 Jan 13 17:05:06 volumio-living volumio[9769]: info: Loading plugin "fusiondsp"... Jan 13 17:05:06 volumio-living volumio[9769]: Forking 3 albumart workers Jan 13 17:05:06 volumio-living volumio[9769]: info: Applying required configuration parameters for plugin fusiondsp Jan 13 17:05:06 volumio-living volumio[9769]: info: Loading i18n strings for locale ja Jan 13 17:05:06 volumio-living volumio[9769]: Updating browse sources language Jan 13 17:05:06 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:07 volumio-living volumio-remote-updater[552]: [2025-01-13 17:05:07] [connect] Successful connection Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreCommandRouter::initPlayerControls Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:05:07 volumio-living volumio[9769]: Express server listening on port 3000 Jan 13 17:05:07 volumio-living volumio[9769]: [Metrics] WebUI: 9s 314.99ms Jan 13 17:05:07 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:07 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 131. Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreStateMachine::resetVolumioState Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreStateMachine::getcurrentVolume Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:05:07 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:07 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:07 volumio-living volumio[9769]: info: Volumio Network Manager: Network status updated: 2 Jan 13 17:05:07 volumio-living volumio[9769]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 17:05:07 volumio-living volumio[9769]: verbose: New Socket.io Connection to 192.168.0.154:3000 from 192.168.0.23 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 13 17:05:07 volumio-living volumio[9769]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 13 17:05:07 volumio-living volumio-remote-updater[552]: [2025-01-13 17:05:07] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736755507 101 Jan 13 17:05:07 volumio-living volumio[9769]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Jan 13 17:05:07 volumio-living volumio[9769]: verbose: New Socket.io Connection to 192.168.0.154:3000 from 192.168.0.23 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 13 17:05:07 volumio-living volumio[9769]: Starting albumart workers Jan 13 17:05:07 volumio-living volumio[9769]: info: Reloading queue from file Jan 13 17:05:07 volumio-living volumio[9769]: Starting albumart workers Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 13 17:05:07 volumio-living volumio[9769]: info: VolumeController:: Volume=100 Mute =false Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreStateMachine::pushState Jan 13 17:05:07 volumio-living volumio[9769]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreCommandRouter::volumioPushState Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreStateMachine::updateTrackBlock Jan 13 17:05:07 volumio-living volumio[9769]: info: CorePlayQueue::getTrackBlock Jan 13 17:05:07 volumio-living volumio[9769]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:05:08 volumio-living volumio[9769]: Starting albumart workers Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreStateMachine::setRepeat null single undefined Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreStateMachine::pushState Jan 13 17:05:08 volumio-living volumio[9769]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreCommandRouter::volumioPushState Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreStateMachine::setRandom null Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreStateMachine::pushState Jan 13 17:05:08 volumio-living volumio[9769]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreCommandRouter::volumioPushState Jan 13 17:05:08 volumio-living volumio[9769]: info: Setting Device type: Raspberry PI Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreCommandRouter::volumioGetState Jan 13 17:05:08 volumio-living volumio[9769]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:08 volumio-living volumio[9769]: info: Completed loading Core Plugins Jan 13 17:05:08 volumio-living volumio[9769]: info: Preparing to generate the ALSA configuration file Jan 13 17:05:08 volumio-living volumio[9769]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 13 17:05:08 volumio-living volumio[9769]: info: Reading ALSA contributions from plugins. Jan 13 17:05:08 volumio-living volumio[9769]: info: Discovery: adding c92b663e-9b92-48a7-8bf1-18d92af07ae4 Jan 13 17:05:08 volumio-living volumio[9769]: info: Discovery: Found device Volumio-Living Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreCommandRouter::volumioGetState Jan 13 17:05:08 volumio-living volumio[9769]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:08 volumio-living volumio[9769]: info: VolumeController:: Volume=100 Mute =false Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreStateMachine::pushState Jan 13 17:05:08 volumio-living volumio[9769]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreCommandRouter::volumioPushState Jan 13 17:05:08 volumio-living volumio[9769]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:05:08 volumio-living volumio[9769]: info: Output device has changed, restarting MPD Jan 13 17:05:08 volumio-living volumio[9769]: info: Output device has changed, restarting Shairport Sync Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:08 volumio-living sudo[9872]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:05:08 volumio-living sudo[9872]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:08 volumio-living sudo[9872]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:08 volumio-living sudo[9874]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:05:08 volumio-living sudo[9874]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:08 volumio-living systemd[1]: Stopping Music Player Daemon... Jan 13 17:05:08 volumio-living volumio[9769]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:05:08 volumio-living volumio[9769]: info: ___________ START PLUGINS ___________ Jan 13 17:05:08 volumio-living volumio[9769]: info: ControllerMpd::onStart: Initializing MPD Jan 13 17:05:08 volumio-living volumio[9769]: info: Creating MPD Configuration file Jan 13 17:05:08 volumio-living systemd[1]: mpd.service: Succeeded. Jan 13 17:05:08 volumio-living systemd[1]: Stopped Music Player Daemon. Jan 13 17:05:08 volumio-living sudo[9881]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:05:08 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:05:08 volumio-living sudo[9881]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:08 volumio-living sudo[9881]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:05:08 volumio-living volumio[9769]: info: [1736755508628] CoreMusicLibrary::Adding element DLNAサーバー Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:08 volumio-living sudo[9885]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:05:08 volumio-living sudo[9885]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:08 volumio-living sudo[9882]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:05:08 volumio-living sudo[9882]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:08 volumio-living sudo[9882]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:08 volumio-living systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 13 17:05:08 volumio-living systemd[1]: mpd.service: Succeeded. Jan 13 17:05:08 volumio-living systemd[1]: Stopped Music Player Daemon. Jan 13 17:05:08 volumio-living volumio[9769]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:08 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:08 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:05:09 volumio-living volumio[9769]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:05:09 volumio-living volumio[9769]: info: [1736755509019] CoreMusicLibrary::Adding element Last_100 Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:05:09 volumio-living volumio[9769]: info: [1736755509041] CoreMusicLibrary::Adding element Webradio Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:05:09 volumio-living volumio[9858]: Traceback (most recent call last): Jan 13 17:05:09 volumio-living volumio[9858]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:09 volumio-living volumio[9858]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:09 volumio-living volumio[9858]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:09 volumio-living sudo[9891]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:05:09 volumio-living sudo[9891]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:09 volumio-living volumio[9769]: info: Initializing BBC Radios Jan 13 17:05:09 volumio-living sudo[9891]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:09 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:09 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:09 volumio-living volumio[9769]: info: Creating Spotify config file Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:05:09 volumio-living volumio[9769]: info: [1736755509412] CoreMusicLibrary::Adding element YouTube Music Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:09 volumio-living volumio[9769]: Cannot find translation for source YouTube Music Jan 13 17:05:09 volumio-living volumio[9769]: info: Loading i18n strings for locale ja Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: FusionDsp - mixtype--------------------- Hardware Jan 13 17:05:09 volumio-living volumio[9769]: info: Volumio Calling Home Jan 13 17:05:09 volumio-living volumio[9769]: info: Preparing to generate the ALSA configuration file Jan 13 17:05:09 volumio-living volumio[9769]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 13 17:05:09 volumio-living volumio[9769]: info: Reading ALSA contributions from plugins. Jan 13 17:05:09 volumio-living volumio[9769]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Jan 13 17:05:09 volumio-living volumio[9769]: info: MPD Permissions set Jan 13 17:05:09 volumio-living volumio[9769]: info: MPD Permissions set Jan 13 17:05:09 volumio-living volumio[9769]: info: Spotify config file written Jan 13 17:05:09 volumio-living volumio[9769]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living sudo[9935]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 13 17:05:09 volumio-living sudo[9935]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living systemd[1]: Stopping go-librespot Daemon... Jan 13 17:05:09 volumio-living systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jan 13 17:05:09 volumio-living systemd[1]: go-librespot-daemon.service: Succeeded. Jan 13 17:05:09 volumio-living systemd[1]: Stopped go-librespot Daemon. Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:09 volumio-living volumio[9769]: info: No need to fix Spotify hosts Jan 13 17:05:09 volumio-living systemd[1]: Started go-librespot Daemon. Jan 13 17:05:09 volumio-living go-librespot[9941]: Librespot-go daemon starting... Jan 13 17:05:09 volumio-living sudo[9935]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:09 volumio-living go-librespot[9941]: time="2025-01-13T17:05:09+09:00" level=info msg="generated new device id: 71491f4a8f419024fd3431a00769d98339deebff" Jan 13 17:05:09 volumio-living go-librespot[9941]: time="2025-01-13T17:05:09+09:00" level=debug msg="stored credentials found for rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:05:09 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 17:05:10 volumio-living volumio[9769]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:05:10 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:10 volumio-living volumio[9769]: info: CoreCommandRouter::volumioGetState Jan 13 17:05:10 volumio-living volumio[9769]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:10 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 13 17:05:10 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 13 17:05:10 volumio-living volumio[9769]: info: Received Get System Info Jan 13 17:05:10 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 17:05:10 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 17:05:10 volumio-living volumio[9769]: info: Discovery: Getting this device information Jan 13 17:05:10 volumio-living volumio[9769]: info: CoreCommandRouter::volumioGetState Jan 13 17:05:10 volumio-living volumio[9769]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:10 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 17:05:10 volumio-living volumio[9769]: info: CoreCommandRouter::volumioGetState Jan 13 17:05:10 volumio-living volumio[9769]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:10 volumio-living volumio[9769]: info: Listing playlists Jan 13 17:05:10 volumio-living go-librespot[9941]: time="2025-01-13T17:05:10+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 13 17:05:10 volumio-living go-librespot[9941]: time="2025-01-13T17:05:10+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 13 17:05:10 volumio-living go-librespot[9941]: time="2025-01-13T17:05:10+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 13 17:05:10 volumio-living go-librespot[9941]: time="2025-01-13T17:05:10+09:00" level=debug msg="zeroconf server listening on port 43951" Jan 13 17:05:10 volumio-living volumio[9769]: info: Volumio called home Jan 13 17:05:10 volumio-living volumio[9769]: info: Starting Shairport Sync Jan 13 17:05:10 volumio-living volumio[9769]: info: Starting Shairport Sync Jan 13 17:05:10 volumio-living sudo[9954]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 17:05:10 volumio-living sudo[9954]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:10 volumio-living volumio[9769]: info: Starting Shairport Sync Jan 13 17:05:10 volumio-living sudo[9956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 17:05:10 volumio-living sudo[9956]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:10 volumio-living sudo[9959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 17:05:10 volumio-living sudo[9959]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:10 volumio-living systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 13 17:05:10 volumio-living systemd[1]: shairport-sync.service: Succeeded. Jan 13 17:05:10 volumio-living systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 13 17:05:10 volumio-living go-librespot[9941]: time="2025-01-13T17:05:10+09:00" level=debug msg="obtained new client token: AAB6jrBDtDnOr80hYRRiV8jXrc6RdTtBpbWfsP0fa+LQaVKPOucs/CtkKrAGDCgm0e1ZFZ99SIapCh+ex4B+C6ag2sB+QIAUgYV7gXTPCE0bAVXR7WrpCq5PUs0D3kJr+Qz+MY4d8zMTk3Q5itJJVgIkVyTw+4GKSw9VKVEcXFep9Zh4GSk4iWn/kohVw0Ln1ZM/gnFXRrsIs0Iv1uH9z98nPwkaVGzQBRipFJiCRmWuz2w3tPELuo/w8YqF/uo=" Jan 13 17:05:10 volumio-living systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 13 17:05:10 volumio-living volumio[9769]: info: CoreCommandRouter::volumioGetState Jan 13 17:05:10 volumio-living sudo[9954]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:10 volumio-living volumio[9769]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:10 volumio-living systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 13 17:05:10 volumio-living systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jan 13 17:05:10 volumio-living systemd[1]: shairport-sync.service: Succeeded. Jan 13 17:05:10 volumio-living systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 13 17:05:10 volumio-living systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 13 17:05:10 volumio-living sudo[9959]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:10 volumio-living sudo[9956]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:10 volumio-living go-librespot[9941]: time="2025-01-13T17:05:10+09:00" level=info msg="connected to ap-gae2.spotify.com:4070" Jan 13 17:05:10 volumio-living volumio[9769]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 13 17:05:10 volumio-living volumio[9769]: SPOTIFY: BQBRG56Fg3M_ckm7cN3QnAusjHsdKDHIOHDi6FM-PAUZNpq6SKWCe0lnI7N2NfszM55zj33rAODNwD8OUnZzwB8DuP8YfV4Fdkofg1aSVdm5zH8sw7rSmdm7TC-5io_YyMvxVviUBdIoYcd8Q_sdgEOmH3K94QC9QR-zHnNX-mG_oPYTWz_PR9mUA5R_y0Dq3IJdOHLTY1AVSAQeA4ypMXuzJ_bf0XfEgXlW-JT9eIQ79xesG2wlIQKFrkDz1VdLsDcKX0qy75w Jan 13 17:05:10 volumio-living volumio[9769]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 13 17:05:10 volumio-living volumio[9769]: info: New Spotify access token = BQBRG56Fg3M_ckm7cN3QnAusjHsdKDHIOHDi6FM-PAUZNpq6SKWCe0lnI7N2NfszM55zj33rAODNwD8OUnZzwB8DuP8YfV4Fdkofg1aSVdm5zH8sw7rSmdm7TC-5io_YyMvxVviUBdIoYcd8Q_sdgEOmH3K94QC9QR-zHnNX-mG_oPYTWz_PR9mUA5R_y0Dq3IJdOHLTY1AVSAQeA4ypMXuzJ_bf0XfEgXlW-JT9eIQ79xesG2wlIQKFrkDz1VdLsDcKX0qy75w Jan 13 17:05:10 volumio-living volumio[9769]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 13 17:05:10 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:10 volumio-living volumio[9769]: info: Shairport-Sync Started Jan 13 17:05:10 volumio-living volumio[9769]: Error adding Membership: Error: addMembership EINVAL Jan 13 17:05:10 volumio-living volumio[9769]: info: Shairport-Sync Started Jan 13 17:05:10 volumio-living volumio[9769]: info: Shairport-Sync Started Jan 13 17:05:10 volumio-living go-librespot[9941]: time="2025-01-13T17:05:10+09:00" level=debug msg="completed keyexchange" Jan 13 17:05:10 volumio-living volumio[9769]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:05:10 volumio-living volumio[9769]: info: Output device has changed, restarting MPD Jan 13 17:05:10 volumio-living sudo[9981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:05:10 volumio-living sudo[9981]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:10 volumio-living volumio[9769]: info: Output device has changed, restarting Shairport Sync Jan 13 17:05:10 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:10 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:10 volumio-living sudo[9981]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:10 volumio-living sudo[9984]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:05:10 volumio-living sudo[9984]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:10 volumio-living systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 13 17:05:10 volumio-living systemd[1]: mpd.service: Succeeded. Jan 13 17:05:10 volumio-living systemd[1]: Stopped Music Player Daemon. Jan 13 17:05:10 volumio-living volumio[9769]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:05:10 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:05:10 volumio-living sudo[9991]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:05:10 volumio-living sudo[9991]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:10 volumio-living sudo[9991]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:10 volumio-living sudo[9989]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:05:10 volumio-living sudo[9989]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:11 volumio-living sudo[9989]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:11 volumio-living sudo[9994]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:05:11 volumio-living sudo[9994]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:11 volumio-living sudo[9994]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:11 volumio-living volumio[9769]: info: MPD Permissions set Jan 13 17:05:11 volumio-living go-librespot[9941]: time="2025-01-13T17:05:11+09:00" level=debug msg="completed challenge" Jan 13 17:05:11 volumio-living go-librespot[9941]: time="2025-01-13T17:05:11+09:00" level=debug msg="authenticated as rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:11 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:11 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 132. Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:11 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:11 volumio-living go-librespot[9941]: time="2025-01-13T17:05:11+09:00" level=debug msg="authenticated as rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:05:11 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 13 17:05:11 volumio-living volumio[9769]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 13 17:05:11 volumio-living volumio[9769]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jan 13 17:05:11 volumio-living volumio[9769]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 13 17:05:11 volumio-living volumio[9769]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jan 13 17:05:11 volumio-living volumio[9769]: info: camilladsp service started and running in background, instance 1 Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:11 volumio-living go-librespot[9941]: time="2025-01-13T17:05:11+09:00" level=debug msg="dealer connection opened" Jan 13 17:05:11 volumio-living go-librespot[9941]: time="2025-01-13T17:05:11+09:00" level=debug msg="initializing zeroconf session, username: rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:05:11 volumio-living go-librespot[9941]: time="2025-01-13T17:05:11+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 13 17:05:11 volumio-living go-librespot[9941]: time="2025-01-13T17:05:11+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 13 17:05:11 volumio-living go-librespot[9941]: time="2025-01-13T17:05:11+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 13 17:05:11 volumio-living go-librespot[9941]: time="2025-01-13T17:05:11+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 13 17:05:11 volumio-living go-librespot[9941]: time="2025-01-13T17:05:11+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 13 17:05:11 volumio-living go-librespot[9941]: time="2025-01-13T17:05:11+09:00" level=debug msg="autoplay enabled: false" Jan 13 17:05:11 volumio-living volumio[9769]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 13 17:05:11 volumio-living volumio[9769]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jan 13 17:05:11 volumio-living volumio[9769]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 13 17:05:11 volumio-living go-librespot[9941]: time="2025-01-13T17:05:11+09:00" level=debug msg="received connection id: YmM0NzY1YjUtNmQyMi00NGNiLWIxMWUtYjVhMjc2MzQzY2U5K2RlYWxlcit0Y3A6Ly8wYWM5NDg5NS5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArMjBENkQ2QjdDNDVEQTU1MDRBMEE1MTYzQjBCQzhEQTczMTQ2M0Q0NzdFN0EzMzczNzgzNEZBNkY0OThFQkJFNw==" Jan 13 17:05:11 volumio-living volumio[9769]: info: FusionDsp loaded Jan 13 17:05:11 volumio-living volumio[9769]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 17:05:11 volumio-living sudo[10016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 13 17:05:11 volumio-living sudo[10016]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:11 volumio-living volumio[9769]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 13 17:05:11 volumio-living volumio[9769]: info: Adding Signal Path Element [object Object] Jan 13 17:05:11 volumio-living volumio[9769]: info: Adding fusiondspeq DSP Signal Path Element Jan 13 17:05:11 volumio-living volumio[9769]: info: FusionDsp - ---- installed callbackRead Jan 13 17:05:11 volumio-living volumio[9769]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:05:11 volumio-living sudo[10016]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:11 volumio-living volumio[9769]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jan 13 17:05:11 volumio-living volumio[9769]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jan 13 17:05:11 volumio-living volumio[9769]: at onErrorNT (internal/child_process.js:465:16) Jan 13 17:05:11 volumio-living volumio[9769]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 13 17:05:11 volumio-living volumio[9769]: at runNextTicks (internal/process/task_queues.js:62:3) Jan 13 17:05:11 volumio-living volumio[9769]: at listOnTimeout (internal/timers.js:523:9) Jan 13 17:05:11 volumio-living volumio[9769]: at processTimers (internal/timers.js:497:7) { Jan 13 17:05:11 volumio-living volumio[9769]: errno: -2, Jan 13 17:05:11 volumio-living volumio[9769]: code: 'ENOENT', Jan 13 17:05:11 volumio-living volumio[9769]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jan 13 17:05:11 volumio-living volumio[9769]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jan 13 17:05:11 volumio-living volumio[9769]: spawnargs: [ Jan 13 17:05:11 volumio-living volumio[9769]: '-p', Jan 13 17:05:11 volumio-living volumio[9769]: 9876, Jan 13 17:05:11 volumio-living volumio[9769]: '-o', Jan 13 17:05:11 volumio-living volumio[9769]: '/tmp/camilladsp.log', Jan 13 17:05:11 volumio-living volumio[9769]: '-l', Jan 13 17:05:11 volumio-living volumio[9769]: 'warn', Jan 13 17:05:11 volumio-living volumio[9769]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jan 13 17:05:11 volumio-living volumio[9769]: ] Jan 13 17:05:11 volumio-living volumio[9769]: } Jan 13 17:05:11 volumio-living volumio[9769]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:05:12 volumio-living sudo[10027]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 17:04 Jan 13 17:05:12 volumio-living sudo[10027]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:12 volumio-living mpd[9996]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 17:05:12 volumio-living mpd[9996]: output: No 'audio_output' defined in config file Jan 13 17:05:12 volumio-living sudo[10027]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:12 volumio-living mpd[9996]: output: Successfully detected a sndio audio device Jan 13 17:05:12 volumio-living mpd[9996]: zeroconf: No global port, disabling zeroconf Jan 13 17:05:12 volumio-living systemd[1]: Started Music Player Daemon. Jan 13 17:05:12 volumio-living sudo[9874]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:12 volumio-living sudo[9984]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:12 volumio-living sudo[9885]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:13 volumio-living volumio[10005]: Traceback (most recent call last): Jan 13 17:05:13 volumio-living volumio[10005]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:13 volumio-living volumio[10005]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:13 volumio-living volumio[10005]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:13 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:13 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:13 volumio-living volumio-remote-updater[552]: [2025-01-13 17:05:13] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 13 17:05:13 volumio-living volumio-remote-updater[552]: [2025-01-13 17:05:13] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 13 17:05:13 volumio-living systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:13 volumio-living systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 13 17:05:13 volumio-living systemd[1]: Started dynamicswap service. Jan 13 17:05:13 volumio-living systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:05:13 volumio-living systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 13 17:05:13 volumio-living systemd[1]: volumio.service: Scheduled restart job, restart counter is at 31. Jan 13 17:05:13 volumio-living systemd[1]: Started dynamicswap service. Jan 13 17:05:13 volumio-living systemd[1]: Stopped Volumio Backend Module. Jan 13 17:05:13 volumio-living systemd[1]: Started Volumio Backend Module. Jan 13 17:05:13 volumio-living systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:05:15 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:15 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 133. Jan 13 17:05:15 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:15 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:15 volumio-living volumio[10041]: info: ------------------------------------------- Jan 13 17:05:15 volumio-living volumio[10041]: info: ----- Volumio3 ---- Jan 13 17:05:15 volumio-living volumio[10041]: info: ------------------------------------------- Jan 13 17:05:15 volumio-living volumio[10041]: info: ----- System startup ---- Jan 13 17:05:15 volumio-living volumio[10041]: info: ------------------------------------------- Jan 13 17:05:16 volumio-living volumio[10041]: info: MYVOLUMIO Environment detected Jan 13 17:05:16 volumio-living volumio[10041]: info: Plugin folders cleanup Jan 13 17:05:16 volumio-living volumio[10041]: info: Scanning into folder /volumio/app/plugins/ Jan 13 17:05:16 volumio-living volumio[10041]: info: Scanning category audio_interface Jan 13 17:05:16 volumio-living volumio[10041]: info: Scanning category miscellanea Jan 13 17:05:16 volumio-living volumio[10041]: info: Scanning category music_service Jan 13 17:05:16 volumio-living volumio[10041]: info: Scanning category plugins.json Jan 13 17:05:16 volumio-living volumio[10041]: info: Scanning category system_controller Jan 13 17:05:16 volumio-living volumio[10041]: info: Scanning category user_interface Jan 13 17:05:16 volumio-living volumio[10041]: info: Scanning into folder /data/plugins/ Jan 13 17:05:16 volumio-living volumio[10041]: info: Scanning category audio_interface Jan 13 17:05:16 volumio-living volumio[10041]: info: Scanning category music_service Jan 13 17:05:16 volumio-living volumio[10041]: info: Plugin folders cleanup completed Jan 13 17:05:16 volumio-living volumio[10041]: info: ------------------------------------------- Jan 13 17:05:16 volumio-living volumio[10041]: info: ----- Core plugins startup ---- Jan 13 17:05:16 volumio-living volumio[10041]: info: ------------------------------------------- Jan 13 17:05:16 volumio-living volumio[10041]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 17:05:16 volumio-living volumio[10041]: info: Adding plugin upnp to MyMusic Plugins Jan 13 17:05:16 volumio-living volumio[10041]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 17:05:16 volumio-living volumio[10041]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 17:05:16 volumio-living volumio[10041]: info: Loading plugins from folder /data/plugins/ Jan 13 17:05:16 volumio-living volumio[10041]: info: Loading plugin "system"... Jan 13 17:05:16 volumio-living volumio[10041]: info: Loading plugin "appearance"... Jan 13 17:05:16 volumio-living volumio[10061]: Traceback (most recent call last): Jan 13 17:05:16 volumio-living volumio[10061]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:16 volumio-living volumio[10061]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:16 volumio-living volumio[10061]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:16 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:16 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:16 volumio-living go-librespot[9941]: time="2025-01-13T17:05:16+09:00" level=debug msg="put connect state because NEW_DEVICE" Jan 13 17:05:17 volumio-living volumio[10041]: info: Loading plugin "network"... Jan 13 17:05:17 volumio-living volumio[10041]: info: Refreshing Cached IP Addresses Jan 13 17:05:17 volumio-living sudo[10068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:05:17 volumio-living sudo[10068]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:17 volumio-living sudo[10068]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:17 volumio-living sudo[10070]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:05:17 volumio-living sudo[10070]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:17 volumio-living sudo[10070]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:17 volumio-living volumio[10041]: info: Loading plugin "services"... Jan 13 17:05:17 volumio-living volumio[10041]: info: Loading plugin "alsa_controller"... Jan 13 17:05:17 volumio-living sudo[10083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 13 17:05:17 volumio-living sudo[10083]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:17 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:05:17 volumio-living volumio[10041]: info: Loading plugin "wizard"... Jan 13 17:05:17 volumio-living volumio[10041]: info: Loading plugin "networkfs"... Jan 13 17:05:17 volumio-living volumio[10041]: info: Starting Udev Watcher for removable devices Jan 13 17:05:17 volumio-living volumio[10041]: info: Ignoring mount for partition: boot Jan 13 17:05:17 volumio-living volumio[10041]: info: Ignoring mount for partition: volumio Jan 13 17:05:17 volumio-living volumio[10041]: info: Ignoring mount for partition: volumio_data Jan 13 17:05:17 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:05:17 volumio-living volumio[10041]: info: Loading plugin "volumio_command_line_client"... Jan 13 17:05:17 volumio-living volumio[10041]: info: Loading plugin "upnp"... Jan 13 17:05:17 volumio-living volumio[10041]: info: [1736755517681] Starting Upmpd Daemon Jan 13 17:05:17 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:05:17 volumio-living volumio[10041]: info: Loading plugin "my_music"... Jan 13 17:05:17 volumio-living volumio[10041]: info: Loading plugin "mpd"... Jan 13 17:05:18 volumio-living volumio[10041]: info: Loading plugin "upnp_browser"... Jan 13 17:05:18 volumio-living volumio-remote-updater[552]: [2025-01-13 17:05:18] [connect] Successful connection Jan 13 17:05:18 volumio-living volumio[10041]: info: Loading plugin "alarm-clock"... Jan 13 17:05:18 volumio-living volumio[10041]: info: Loading plugin "airplay_emulation"... Jan 13 17:05:18 volumio-living volumio[10041]: info: Starting Shairport Sync Jan 13 17:05:18 volumio-living volumio[10041]: info: Loading plugin "last_100"... Jan 13 17:05:18 volumio-living volumio[10041]: info: Loading plugin "webradio"... Jan 13 17:05:18 volumio-living volumio[10041]: info: Loading plugin "i2s_dacs"... Jan 13 17:05:18 volumio-living volumio[10041]: info: I2S DAC not set, start Auto-detection Jan 13 17:05:18 volumio-living volumio[10041]: info: Loading plugin "volumiodiscovery"... Jan 13 17:05:18 volumio-living volumio[10041]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:18 volumio-living volumio[10041]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:18 volumio-living volumio[10041]: *** WARNING *** For more information see Jan 13 17:05:18 volumio-living volumio[10041]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:18 volumio-living node[10041]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:18 volumio-living volumio[10041]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:18 volumio-living volumio[10041]: *** WARNING *** For more information see Jan 13 17:05:18 volumio-living node[10041]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:18 volumio-living node[10041]: *** WARNING *** For more information see Jan 13 17:05:18 volumio-living node[10041]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:18 volumio-living node[10041]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:18 volumio-living node[10041]: *** WARNING *** For more information see Jan 13 17:05:18 volumio-living volumio[10041]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 17:05:18 volumio-living volumio[10041]: info: Discovery: Started advertising with name: Volumio-Living Jan 13 17:05:18 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:05:18 volumio-living volumio[10041]: info: Loading plugin "spop"... Jan 13 17:05:18 volumio-living wpa_supplicant[838]: RRM: Ignoring radio measurement request: Not RRM network Jan 13 17:05:18 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:18 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 134. Jan 13 17:05:18 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:19 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:20 volumio-living volumio[10041]: info: Loading plugin "ytmusic"... Jan 13 17:05:20 volumio-living volumio[10097]: Traceback (most recent call last): Jan 13 17:05:20 volumio-living volumio[10097]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:20 volumio-living volumio[10097]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:20 volumio-living volumio[10097]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:20 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:20 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:21 volumio-living sudo[10083]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:22 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:22 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 135. Jan 13 17:05:22 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:22 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:23 volumio-living volumio[10041]: info: Loading plugin "outputs"... Jan 13 17:05:23 volumio-living volumio[10041]: info: Loading plugin "albumart"... Jan 13 17:05:23 volumio-living volumio[10041]: info: Plugin example_plugin is not enabled Jan 13 17:05:23 volumio-living volumio[10041]: info: Loading plugin "inputs"... Jan 13 17:05:23 volumio-living volumio[10041]: info: Loading plugin "updater_comm"... Jan 13 17:05:23 volumio-living volumio[10041]: info: Plugin mpdemulation is not enabled Jan 13 17:05:23 volumio-living volumio[10041]: info: Loading plugin "rest_api"... Jan 13 17:05:23 volumio-living volumio[10041]: info: Loading plugin "websocket"... Jan 13 17:05:23 volumio-living volumio[10041]: info: Starting Socket.io Server version 2.3.0 Jan 13 17:05:23 volumio-living volumio[10041]: info: Loading plugin "fusiondsp"... Jan 13 17:05:23 volumio-living volumio[10041]: Forking 3 albumart workers Jan 13 17:05:23 volumio-living volumio[10041]: info: Applying required configuration parameters for plugin fusiondsp Jan 13 17:05:23 volumio-living volumio[10041]: info: Loading i18n strings for locale ja Jan 13 17:05:23 volumio-living volumio[10041]: Updating browse sources language Jan 13 17:05:23 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::initPlayerControls Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:05:24 volumio-living volumio[10041]: Express server listening on port 3000 Jan 13 17:05:24 volumio-living volumio[10041]: [Metrics] WebUI: 9s 476.16ms Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreStateMachine::resetVolumioState Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreStateMachine::getcurrentVolume Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:05:24 volumio-living volumio[10098]: Traceback (most recent call last): Jan 13 17:05:24 volumio-living volumio[10098]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:24 volumio-living volumio[10098]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:24 volumio-living volumio[10098]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:24 volumio-living volumio[10041]: info: Volumio Network Manager: Network status updated: 2 Jan 13 17:05:24 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:24 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:24 volumio-living volumio[10041]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 17:05:24 volumio-living volumio[10041]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jan 13 17:05:24 volumio-living volumio[10041]: verbose: New Socket.io Connection to 192.168.0.154:3000 from 192.168.0.23 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 13 17:05:24 volumio-living volumio[10041]: info: Reloading queue from file Jan 13 17:05:24 volumio-living volumio[10041]: info: Setting Device type: Raspberry PI Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreStateMachine::setRepeat null single undefined Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreStateMachine::pushState Jan 13 17:05:24 volumio-living volumio[10041]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::volumioPushState Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreStateMachine::setRandom null Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreStateMachine::pushState Jan 13 17:05:24 volumio-living volumio[10041]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::volumioPushState Jan 13 17:05:24 volumio-living volumio[10041]: info: VolumeController:: Volume=100 Mute =false Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreStateMachine::pushState Jan 13 17:05:24 volumio-living volumio[10041]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::volumioPushState Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreStateMachine::updateTrackBlock Jan 13 17:05:24 volumio-living volumio[10041]: info: CorePlayQueue::getTrackBlock Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:05:24 volumio-living volumio[10041]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 13 17:05:24 volumio-living volumio[10041]: Starting albumart workers Jan 13 17:05:24 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 17:05:24 volumio-living volumio[10041]: Starting albumart workers Jan 13 17:05:25 volumio-living volumio[10041]: Starting albumart workers Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::volumioGetState Jan 13 17:05:25 volumio-living volumio[10041]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 13 17:05:25 volumio-living volumio[10041]: info: Received Get System Info Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 17:05:25 volumio-living volumio[10041]: info: Discovery: Getting this device information Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::volumioGetState Jan 13 17:05:25 volumio-living volumio[10041]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::volumioGetState Jan 13 17:05:25 volumio-living volumio[10041]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:25 volumio-living volumio[10041]: info: Listing playlists Jan 13 17:05:25 volumio-living volumio[10041]: info: Discovery: adding c92b663e-9b92-48a7-8bf1-18d92af07ae4 Jan 13 17:05:25 volumio-living volumio[10041]: info: Discovery: Found device Volumio-Living Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::volumioGetState Jan 13 17:05:25 volumio-living volumio[10041]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:25 volumio-living volumio[10041]: info: VolumeController:: Volume=100 Mute =false Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreStateMachine::pushState Jan 13 17:05:25 volumio-living volumio[10041]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::volumioPushState Jan 13 17:05:25 volumio-living volumio[10041]: info: Completed loading Core Plugins Jan 13 17:05:25 volumio-living volumio[10041]: info: Preparing to generate the ALSA configuration file Jan 13 17:05:25 volumio-living volumio[10041]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 13 17:05:25 volumio-living volumio[10041]: info: Reading ALSA contributions from plugins. Jan 13 17:05:25 volumio-living volumio[10041]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:05:25 volumio-living volumio[10041]: info: Output device has changed, restarting MPD Jan 13 17:05:25 volumio-living volumio[10041]: info: Output device has changed, restarting Shairport Sync Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:25 volumio-living sudo[10144]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:25 volumio-living sudo[10144]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:25 volumio-living sudo[10146]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:05:25 volumio-living sudo[10146]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:25 volumio-living sudo[10144]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:25 volumio-living systemd[1]: Stopping Music Player Daemon... Jan 13 17:05:25 volumio-living systemd[1]: mpd.service: Succeeded. Jan 13 17:05:25 volumio-living systemd[1]: Stopped Music Player Daemon. Jan 13 17:05:25 volumio-living volumio[10041]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:05:25 volumio-living volumio[10041]: info: ___________ START PLUGINS ___________ Jan 13 17:05:25 volumio-living volumio[10041]: info: ControllerMpd::onStart: Initializing MPD Jan 13 17:05:25 volumio-living volumio[10041]: info: Creating MPD Configuration file Jan 13 17:05:25 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:05:25 volumio-living volumio[10041]: info: [1736755525741] CoreMusicLibrary::Adding element DLNAサーバー Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:25 volumio-living sudo[10154]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:05:25 volumio-living sudo[10154]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:25 volumio-living sudo[10154]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:25 volumio-living sudo[10160]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:05:25 volumio-living sudo[10160]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:25 volumio-living systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 13 17:05:25 volumio-living systemd[1]: mpd.service: Succeeded. Jan 13 17:05:25 volumio-living systemd[1]: Stopped Music Player Daemon. Jan 13 17:05:25 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:05:25 volumio-living volumio[10041]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:25 volumio-living volumio[10041]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:05:25 volumio-living volumio[10041]: info: [1736755525998] CoreMusicLibrary::Adding element Last_100 Jan 13 17:05:25 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:05:26 volumio-living volumio[10041]: info: [1736755526003] CoreMusicLibrary::Adding element Webradio Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:05:26 volumio-living volumio[10041]: info: Initializing BBC Radios Jan 13 17:05:26 volumio-living sudo[10173]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:05:26 volumio-living sudo[10173]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:26 volumio-living sudo[10173]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: Creating Spotify config file Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:05:26 volumio-living volumio[10041]: info: [1736755526219] CoreMusicLibrary::Adding element YouTube Music Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:26 volumio-living volumio[10041]: Cannot find translation for source YouTube Music Jan 13 17:05:26 volumio-living volumio[10041]: info: Loading i18n strings for locale ja Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: FusionDsp - mixtype--------------------- Hardware Jan 13 17:05:26 volumio-living volumio[10041]: info: Volumio Calling Home Jan 13 17:05:26 volumio-living volumio[10041]: info: Preparing to generate the ALSA configuration file Jan 13 17:05:26 volumio-living volumio[10041]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 13 17:05:26 volumio-living volumio[10041]: info: Reading ALSA contributions from plugins. Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:05:26 volumio-living volumio[10041]: info: MPD Permissions set Jan 13 17:05:26 volumio-living volumio[10041]: info: MPD Permissions set Jan 13 17:05:26 volumio-living volumio[10041]: verbose: New Socket.io Connection to 192.168.0.154:3000 from 192.168.0.23 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 13 17:05:26 volumio-living volumio[10041]: info: Spotify config file written Jan 13 17:05:26 volumio-living sudo[10207]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 13 17:05:26 volumio-living volumio[10041]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 13 17:05:26 volumio-living sudo[10207]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:26 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 136. Jan 13 17:05:26 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:26 volumio-living volumio[10041]: info: No need to fix Spotify hosts Jan 13 17:05:26 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:26 volumio-living systemd[1]: Stopping go-librespot Daemon... Jan 13 17:05:26 volumio-living systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jan 13 17:05:26 volumio-living systemd[1]: go-librespot-daemon.service: Succeeded. Jan 13 17:05:26 volumio-living systemd[1]: Stopped go-librespot Daemon. Jan 13 17:05:26 volumio-living systemd[1]: Started go-librespot Daemon. Jan 13 17:05:26 volumio-living sudo[10207]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:26 volumio-living go-librespot[10218]: Librespot-go daemon starting... Jan 13 17:05:26 volumio-living go-librespot[10218]: time="2025-01-13T17:05:26+09:00" level=info msg="generated new device id: e64fb77184bbc1af5e610082ba44d55c361b1507" Jan 13 17:05:26 volumio-living go-librespot[10218]: time="2025-01-13T17:05:26+09:00" level=debug msg="stored credentials found for rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:05:27 volumio-living go-librespot[10218]: time="2025-01-13T17:05:27+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Jan 13 17:05:27 volumio-living go-librespot[10218]: time="2025-01-13T17:05:27+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Jan 13 17:05:27 volumio-living go-librespot[10218]: time="2025-01-13T17:05:27+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Jan 13 17:05:27 volumio-living go-librespot[10218]: time="2025-01-13T17:05:27+09:00" level=debug msg="zeroconf server listening on port 43255" Jan 13 17:05:27 volumio-living go-librespot[10218]: time="2025-01-13T17:05:27+09:00" level=debug msg="obtained new client token: AAB58GzNfHKfHIOLcusY79v2pI4M3b6+NuVAxR6R9roAI7fOtQDpRKF6nQjhsCn7yEGD5djXAx4M+IYr608Gfnf8S3oFZL21DT561QbKJnZNYQhgk8GF8aJTFUEQh45PuFC4jTaP5yKYLCN+HKAfqHxlquXSuYwwFc60sh2uD8EXgQEWaBI00ZbmI+gZCw5zfnUj3jRsaTephU+Z2ANoAa7SqF3ICvf9LhuQeEquM9ex+iyLrBK2jshDpDKDgM8=" Jan 13 17:05:27 volumio-living go-librespot[10218]: time="2025-01-13T17:05:27+09:00" level=info msg="connected to ap-gae2.spotify.com:4070" Jan 13 17:05:27 volumio-living go-librespot[10218]: time="2025-01-13T17:05:27+09:00" level=debug msg="completed keyexchange" Jan 13 17:05:27 volumio-living mpd[10193]: Jan 13 17:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 17:05:27 volumio-living systemd[1]: Started Music Player Daemon. Jan 13 17:05:27 volumio-living sudo[10146]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:27 volumio-living sudo[10160]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:28 volumio-living go-librespot[10218]: time="2025-01-13T17:05:28+09:00" level=debug msg="completed challenge" Jan 13 17:05:28 volumio-living go-librespot[10218]: time="2025-01-13T17:05:28+09:00" level=debug msg="authenticated as rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:05:28 volumio-living volumio[10210]: Traceback (most recent call last): Jan 13 17:05:28 volumio-living volumio[10210]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:28 volumio-living volumio[10210]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:28 volumio-living volumio[10210]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:28 volumio-living go-librespot[10218]: time="2025-01-13T17:05:28+09:00" level=debug msg="authenticated as rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:05:28 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:28 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:28 volumio-living go-librespot[10218]: time="2025-01-13T17:05:28+09:00" level=debug msg="dealer connection opened" Jan 13 17:05:28 volumio-living go-librespot[10218]: time="2025-01-13T17:05:28+09:00" level=debug msg="initializing zeroconf session, username: rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:05:28 volumio-living go-librespot[10218]: time="2025-01-13T17:05:28+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 13 17:05:28 volumio-living go-librespot[10218]: time="2025-01-13T17:05:28+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 13 17:05:28 volumio-living go-librespot[10218]: time="2025-01-13T17:05:28+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 13 17:05:28 volumio-living go-librespot[10218]: time="2025-01-13T17:05:28+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 13 17:05:28 volumio-living go-librespot[10218]: time="2025-01-13T17:05:28+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 13 17:05:28 volumio-living go-librespot[10218]: time="2025-01-13T17:05:28+09:00" level=debug msg="autoplay enabled: false" Jan 13 17:05:28 volumio-living go-librespot[10218]: time="2025-01-13T17:05:28+09:00" level=debug msg="received connection id: MGE3ZGY0NGEtMTE4Yy00MWVmLWFkNDAtOTY3NjIzMGIyZjVhK2RlYWxlcit0Y3A6Ly8wYWM5NDhiYi5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArMjU1RkRFNERCNTJFRDQ3NUNENDIxNDdERDFCRjQxNzc4RjIxQUMxNzU1ODNDQjY1RDFFNzRGOTA3M0Y3QjQ3Nw==" Jan 13 17:05:28 volumio-living go-librespot[10218]: time="2025-01-13T17:05:28+09:00" level=debug msg="put connect state because NEW_DEVICE" Jan 13 17:05:30 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:30 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 137. Jan 13 17:05:30 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:30 volumio-living volumio-remote-updater[552]: [2025-01-13 17:05:30] [connect] Successful connection Jan 13 17:05:30 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:31 volumio-living sudo[10245]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:05:31 volumio-living sudo[10245]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:31 volumio-living sudo[10245]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:31 volumio-living sudo[10248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:05:31 volumio-living sudo[10248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:31 volumio-living sudo[10248]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:31 volumio-living volumio[10041]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 13 17:05:31 volumio-living volumio[10041]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jan 13 17:05:31 volumio-living volumio[10041]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 13 17:05:31 volumio-living volumio[10041]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jan 13 17:05:31 volumio-living volumio[10041]: info: camilladsp service started and running in background, instance 1 Jan 13 17:05:31 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:31 volumio-living volumio[10041]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 13 17:05:31 volumio-living volumio[10041]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jan 13 17:05:31 volumio-living volumio[10041]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 13 17:05:31 volumio-living volumio[10041]: info: FusionDsp loaded Jan 13 17:05:31 volumio-living volumio[10041]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 17:05:31 volumio-living sudo[10260]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 13 17:05:31 volumio-living sudo[10260]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:31 volumio-living volumio[10041]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 13 17:05:31 volumio-living volumio[10041]: info: Adding Signal Path Element [object Object] Jan 13 17:05:31 volumio-living volumio[10041]: info: Adding fusiondspeq DSP Signal Path Element Jan 13 17:05:31 volumio-living volumio[10041]: info: FusionDsp - ---- installed callbackRead Jan 13 17:05:31 volumio-living sudo[10260]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:31 volumio-living volumio[10041]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:05:31 volumio-living volumio[10041]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jan 13 17:05:31 volumio-living volumio[10041]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jan 13 17:05:31 volumio-living volumio[10041]: at onErrorNT (internal/child_process.js:465:16) Jan 13 17:05:31 volumio-living volumio[10041]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 13 17:05:31 volumio-living volumio[10041]: at runNextTicks (internal/process/task_queues.js:62:3) Jan 13 17:05:31 volumio-living volumio[10041]: at listOnTimeout (internal/timers.js:523:9) Jan 13 17:05:31 volumio-living volumio[10041]: at processTimers (internal/timers.js:497:7) { Jan 13 17:05:31 volumio-living volumio[10041]: errno: -2, Jan 13 17:05:31 volumio-living volumio[10041]: code: 'ENOENT', Jan 13 17:05:31 volumio-living volumio[10041]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jan 13 17:05:31 volumio-living volumio[10041]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jan 13 17:05:31 volumio-living volumio[10041]: spawnargs: [ Jan 13 17:05:31 volumio-living volumio[10041]: '-p', Jan 13 17:05:31 volumio-living volumio[10041]: 9876, Jan 13 17:05:31 volumio-living volumio[10041]: '-o', Jan 13 17:05:31 volumio-living volumio[10041]: '/tmp/camilladsp.log', Jan 13 17:05:31 volumio-living volumio[10041]: '-l', Jan 13 17:05:31 volumio-living volumio[10041]: 'warn', Jan 13 17:05:31 volumio-living volumio[10041]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jan 13 17:05:31 volumio-living volumio[10041]: ] Jan 13 17:05:31 volumio-living volumio[10041]: } Jan 13 17:05:31 volumio-living volumio[10041]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:05:32 volumio-living volumio[10243]: Traceback (most recent call last): Jan 13 17:05:32 volumio-living volumio[10243]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:32 volumio-living volumio[10243]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:32 volumio-living volumio[10243]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:32 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:32 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:32 volumio-living sudo[10271]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 17:04 Jan 13 17:05:32 volumio-living sudo[10271]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:32 volumio-living sudo[10271]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:32 volumio-living volumio-remote-updater[552]: [2025-01-13 17:05:32] [info] asio async_read_at_least error: system:104 (Connection reset by peer) Jan 13 17:05:32 volumio-living volumio-remote-updater[552]: [2025-01-13 17:05:32] [error] handle_read_http_response error: websocketpp.transport:2 (Underlying Transport Error) Jan 13 17:05:32 volumio-living volumio-remote-updater[552]: [2025-01-13 17:05:32] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 13 17:05:32 volumio-living systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:32 volumio-living systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 13 17:05:32 volumio-living systemd[1]: Started dynamicswap service. Jan 13 17:05:32 volumio-living systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:05:32 volumio-living systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 13 17:05:32 volumio-living systemd[1]: volumio.service: Scheduled restart job, restart counter is at 32. Jan 13 17:05:32 volumio-living systemd[1]: Started dynamicswap service. Jan 13 17:05:32 volumio-living systemd[1]: Stopped Volumio Backend Module. Jan 13 17:05:32 volumio-living systemd[1]: Started Volumio Backend Module. Jan 13 17:05:33 volumio-living systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:05:34 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:34 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 138. Jan 13 17:05:34 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:34 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:34 volumio-living volumio[10285]: info: ------------------------------------------- Jan 13 17:05:34 volumio-living volumio[10285]: info: ----- Volumio3 ---- Jan 13 17:05:34 volumio-living volumio[10285]: info: ------------------------------------------- Jan 13 17:05:34 volumio-living volumio[10285]: info: ----- System startup ---- Jan 13 17:05:34 volumio-living volumio[10285]: info: ------------------------------------------- Jan 13 17:05:35 volumio-living volumio[10285]: info: MYVOLUMIO Environment detected Jan 13 17:05:35 volumio-living volumio[10285]: info: Plugin folders cleanup Jan 13 17:05:35 volumio-living volumio[10285]: info: Scanning into folder /volumio/app/plugins/ Jan 13 17:05:35 volumio-living volumio[10285]: info: Scanning category audio_interface Jan 13 17:05:35 volumio-living volumio[10285]: info: Scanning category miscellanea Jan 13 17:05:35 volumio-living volumio[10285]: info: Scanning category music_service Jan 13 17:05:35 volumio-living volumio[10285]: info: Scanning category plugins.json Jan 13 17:05:35 volumio-living volumio[10285]: info: Scanning category system_controller Jan 13 17:05:35 volumio-living volumio[10285]: info: Scanning category user_interface Jan 13 17:05:35 volumio-living volumio[10285]: info: Scanning into folder /data/plugins/ Jan 13 17:05:35 volumio-living volumio[10285]: info: Scanning category audio_interface Jan 13 17:05:35 volumio-living volumio[10285]: info: Scanning category music_service Jan 13 17:05:35 volumio-living volumio[10285]: info: Plugin folders cleanup completed Jan 13 17:05:35 volumio-living volumio[10285]: info: ------------------------------------------- Jan 13 17:05:35 volumio-living volumio[10285]: info: ----- Core plugins startup ---- Jan 13 17:05:35 volumio-living volumio[10285]: info: ------------------------------------------- Jan 13 17:05:35 volumio-living volumio[10285]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 17:05:35 volumio-living volumio[10285]: info: Adding plugin upnp to MyMusic Plugins Jan 13 17:05:35 volumio-living volumio[10285]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 17:05:35 volumio-living volumio[10285]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 17:05:35 volumio-living volumio[10285]: info: Loading plugins from folder /data/plugins/ Jan 13 17:05:35 volumio-living volumio[10285]: info: Loading plugin "system"... Jan 13 17:05:35 volumio-living volumio[10285]: info: Loading plugin "appearance"... Jan 13 17:05:35 volumio-living volumio[10297]: Traceback (most recent call last): Jan 13 17:05:35 volumio-living volumio[10297]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:35 volumio-living volumio[10297]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:35 volumio-living volumio[10297]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:36 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:36 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:36 volumio-living volumio[10285]: info: Loading plugin "network"... Jan 13 17:05:36 volumio-living volumio[10285]: info: Refreshing Cached IP Addresses Jan 13 17:05:36 volumio-living sudo[10312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:05:36 volumio-living sudo[10312]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:36 volumio-living sudo[10312]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:36 volumio-living sudo[10314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:05:36 volumio-living sudo[10314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:36 volumio-living sudo[10314]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:37 volumio-living volumio[10285]: info: Loading plugin "services"... Jan 13 17:05:37 volumio-living volumio[10285]: info: Loading plugin "alsa_controller"... Jan 13 17:05:37 volumio-living sudo[10327]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 13 17:05:37 volumio-living sudo[10327]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:37 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:05:37 volumio-living volumio[10285]: info: Loading plugin "wizard"... Jan 13 17:05:37 volumio-living volumio[10285]: info: Loading plugin "networkfs"... Jan 13 17:05:37 volumio-living volumio[10285]: info: Starting Udev Watcher for removable devices Jan 13 17:05:37 volumio-living volumio[10285]: info: Ignoring mount for partition: boot Jan 13 17:05:37 volumio-living volumio[10285]: info: Ignoring mount for partition: volumio Jan 13 17:05:37 volumio-living volumio[10285]: info: Ignoring mount for partition: volumio_data Jan 13 17:05:37 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:05:37 volumio-living volumio[10285]: info: Loading plugin "volumio_command_line_client"... Jan 13 17:05:37 volumio-living volumio[10285]: info: Loading plugin "upnp"... Jan 13 17:05:37 volumio-living volumio[10285]: info: [1736755537204] Starting Upmpd Daemon Jan 13 17:05:37 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:05:37 volumio-living volumio[10285]: info: Loading plugin "my_music"... Jan 13 17:05:37 volumio-living volumio[10285]: info: Loading plugin "mpd"... Jan 13 17:05:37 volumio-living volumio[10285]: info: Loading plugin "upnp_browser"... Jan 13 17:05:37 volumio-living volumio[10285]: info: Loading plugin "alarm-clock"... Jan 13 17:05:38 volumio-living volumio[10285]: info: Loading plugin "airplay_emulation"... Jan 13 17:05:38 volumio-living volumio[10285]: info: Starting Shairport Sync Jan 13 17:05:38 volumio-living volumio[10285]: info: Loading plugin "last_100"... Jan 13 17:05:38 volumio-living volumio[10285]: info: Loading plugin "webradio"... Jan 13 17:05:38 volumio-living volumio[10285]: info: Loading plugin "i2s_dacs"... Jan 13 17:05:38 volumio-living volumio[10285]: info: I2S DAC not set, start Auto-detection Jan 13 17:05:38 volumio-living volumio[10285]: info: Loading plugin "volumiodiscovery"... Jan 13 17:05:38 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:38 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 139. Jan 13 17:05:38 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:38 volumio-living volumio[10285]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:38 volumio-living volumio[10285]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:38 volumio-living volumio[10285]: *** WARNING *** For more information see Jan 13 17:05:38 volumio-living volumio[10285]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:38 volumio-living node[10285]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:38 volumio-living volumio[10285]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:38 volumio-living volumio[10285]: *** WARNING *** For more information see Jan 13 17:05:38 volumio-living node[10285]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:38 volumio-living node[10285]: *** WARNING *** For more information see Jan 13 17:05:38 volumio-living node[10285]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:38 volumio-living node[10285]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:38 volumio-living node[10285]: *** WARNING *** For more information see Jan 13 17:05:38 volumio-living volumio[10285]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 17:05:38 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:38 volumio-living volumio[10285]: info: Discovery: Started advertising with name: Volumio-Living Jan 13 17:05:38 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:05:38 volumio-living volumio[10285]: info: Loading plugin "spop"... Jan 13 17:05:39 volumio-living volumio[10285]: info: Loading plugin "ytmusic"... Jan 13 17:05:39 volumio-living volumio[10339]: Traceback (most recent call last): Jan 13 17:05:39 volumio-living volumio[10339]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:39 volumio-living volumio[10339]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:39 volumio-living volumio[10339]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:39 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:39 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:40 volumio-living sudo[10327]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:41 volumio-living wpa_supplicant[838]: RRM: Ignoring radio measurement request: Not RRM network Jan 13 17:05:41 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:41 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 140. Jan 13 17:05:41 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:42 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:42 volumio-living volumio-remote-updater[552]: [2025-01-13 17:05:42] [connect] Successful connection Jan 13 17:05:42 volumio-living volumio[10285]: info: Loading plugin "outputs"... Jan 13 17:05:42 volumio-living volumio[10285]: info: Loading plugin "albumart"... Jan 13 17:05:42 volumio-living volumio[10285]: info: Plugin example_plugin is not enabled Jan 13 17:05:42 volumio-living volumio[10285]: info: Loading plugin "inputs"... Jan 13 17:05:42 volumio-living volumio[10285]: info: Loading plugin "updater_comm"... Jan 13 17:05:43 volumio-living volumio[10285]: info: Plugin mpdemulation is not enabled Jan 13 17:05:43 volumio-living volumio[10285]: info: Loading plugin "rest_api"... Jan 13 17:05:43 volumio-living volumio[10285]: info: Loading plugin "websocket"... Jan 13 17:05:43 volumio-living volumio[10285]: info: Starting Socket.io Server version 2.3.0 Jan 13 17:05:43 volumio-living volumio[10285]: info: Loading plugin "fusiondsp"... Jan 13 17:05:43 volumio-living volumio[10285]: Forking 3 albumart workers Jan 13 17:05:43 volumio-living volumio[10285]: info: Applying required configuration parameters for plugin fusiondsp Jan 13 17:05:43 volumio-living volumio[10343]: Traceback (most recent call last): Jan 13 17:05:43 volumio-living volumio[10343]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:43 volumio-living volumio[10343]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:43 volumio-living volumio[10343]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:43 volumio-living volumio[10285]: info: Loading i18n strings for locale ja Jan 13 17:05:43 volumio-living volumio[10285]: Updating browse sources language Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:43 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:43 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreCommandRouter::initPlayerControls Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:05:43 volumio-living volumio[10285]: Express server listening on port 3000 Jan 13 17:05:43 volumio-living volumio[10285]: [Metrics] WebUI: 9s 609.79ms Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreStateMachine::resetVolumioState Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreStateMachine::getcurrentVolume Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:05:43 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:44 volumio-living volumio[10285]: info: Volumio Network Manager: Network status updated: 2 Jan 13 17:05:44 volumio-living volumio[10285]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 17:05:44 volumio-living volumio[10285]: verbose: New Socket.io Connection to 192.168.0.154:3000 from 192.168.0.23 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 13 17:05:44 volumio-living volumio[10285]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 13 17:05:44 volumio-living volumio-remote-updater[552]: [2025-01-13 17:05:44] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736755542 101 Jan 13 17:05:44 volumio-living volumio[10285]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 4 Jan 13 17:05:44 volumio-living volumio[10285]: Starting albumart workers Jan 13 17:05:44 volumio-living volumio[10285]: info: Reloading queue from file Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 13 17:05:44 volumio-living volumio[10285]: Starting albumart workers Jan 13 17:05:44 volumio-living volumio[10285]: info: VolumeController:: Volume=100 Mute =false Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreStateMachine::pushState Jan 13 17:05:44 volumio-living volumio[10285]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreCommandRouter::volumioPushState Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreStateMachine::updateTrackBlock Jan 13 17:05:44 volumio-living volumio[10285]: info: CorePlayQueue::getTrackBlock Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreStateMachine::setRepeat null single undefined Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreStateMachine::pushState Jan 13 17:05:44 volumio-living volumio[10285]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreCommandRouter::volumioPushState Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreStateMachine::setRandom null Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreStateMachine::pushState Jan 13 17:05:44 volumio-living volumio[10285]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreCommandRouter::volumioPushState Jan 13 17:05:44 volumio-living volumio[10285]: info: Setting Device type: Raspberry PI Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreCommandRouter::volumioGetState Jan 13 17:05:44 volumio-living volumio[10285]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:44 volumio-living volumio[10285]: Starting albumart workers Jan 13 17:05:44 volumio-living volumio[10285]: info: Completed loading Core Plugins Jan 13 17:05:44 volumio-living volumio[10285]: info: Preparing to generate the ALSA configuration file Jan 13 17:05:44 volumio-living volumio[10285]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 13 17:05:44 volumio-living volumio[10285]: info: Reading ALSA contributions from plugins. Jan 13 17:05:44 volumio-living volumio[10285]: info: VolumeController:: Volume=100 Mute =false Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreStateMachine::pushState Jan 13 17:05:44 volumio-living volumio[10285]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreCommandRouter::volumioPushState Jan 13 17:05:44 volumio-living volumio[10285]: info: Discovery: adding c92b663e-9b92-48a7-8bf1-18d92af07ae4 Jan 13 17:05:44 volumio-living volumio[10285]: info: Discovery: Found device Volumio-Living Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreCommandRouter::volumioGetState Jan 13 17:05:44 volumio-living volumio[10285]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:44 volumio-living volumio[10285]: info: Discovery: adding e2b8d27d-62f6-4d3d-b2b6-7a4b9d2ec0f8 Jan 13 17:05:44 volumio-living volumio[10285]: info: Discovery: Found device Volumio Jan 13 17:05:44 volumio-living volumio[10285]: info: Discovery: Connecting to remote: 192.168.0.23 Jan 13 17:05:44 volumio-living volumio[10285]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:05:44 volumio-living volumio[10285]: info: Output device has changed, restarting MPD Jan 13 17:05:44 volumio-living sudo[10389]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:05:44 volumio-living sudo[10389]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:44 volumio-living sudo[10389]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:44 volumio-living volumio[10285]: info: Output device has changed, restarting Shairport Sync Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:44 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:44 volumio-living sudo[10392]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:05:44 volumio-living sudo[10392]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:44 volumio-living systemd[1]: Stopping Music Player Daemon... Jan 13 17:05:44 volumio-living systemd[1]: mpd.service: Succeeded. Jan 13 17:05:44 volumio-living systemd[1]: Stopped Music Player Daemon. Jan 13 17:05:44 volumio-living volumio[10285]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:05:45 volumio-living volumio[10285]: info: ___________ START PLUGINS ___________ Jan 13 17:05:45 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:05:45 volumio-living volumio[10285]: info: ControllerMpd::onStart: Initializing MPD Jan 13 17:05:45 volumio-living volumio[10285]: info: Creating MPD Configuration file Jan 13 17:05:45 volumio-living sudo[10399]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:05:45 volumio-living sudo[10399]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:45 volumio-living sudo[10399]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:05:45 volumio-living volumio[10285]: info: [1736755545106] CoreMusicLibrary::Adding element DLNAサーバー Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:45 volumio-living sudo[10406]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:05:45 volumio-living sudo[10406]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:45 volumio-living sudo[10397]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:05:45 volumio-living sudo[10397]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:45 volumio-living sudo[10397]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:45 volumio-living systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 13 17:05:45 volumio-living systemd[1]: mpd.service: Succeeded. Jan 13 17:05:45 volumio-living systemd[1]: Stopped Music Player Daemon. Jan 13 17:05:45 volumio-living volumio[10285]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:45 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:05:45 volumio-living volumio[10285]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:05:45 volumio-living volumio[10285]: info: [1736755545342] CoreMusicLibrary::Adding element Last_100 Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:05:45 volumio-living volumio[10285]: info: [1736755545354] CoreMusicLibrary::Adding element Webradio Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:05:45 volumio-living volumio[10285]: info: Initializing BBC Radios Jan 13 17:05:45 volumio-living sudo[10413]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:05:45 volumio-living sudo[10413]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:45 volumio-living sudo[10413]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:45 volumio-living volumio[10285]: info: Creating Spotify config file Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:05:45 volumio-living volumio[10285]: info: [1736755545656] CoreMusicLibrary::Adding element YouTube Music Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:45 volumio-living volumio[10285]: Cannot find translation for source YouTube Music Jan 13 17:05:45 volumio-living volumio[10285]: info: Loading i18n strings for locale ja Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 13 17:05:45 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:45 volumio-living volumio[10285]: info: FusionDsp - mixtype--------------------- Hardware Jan 13 17:05:45 volumio-living volumio[10285]: info: Volumio Calling Home Jan 13 17:05:45 volumio-living volumio[10285]: info: Preparing to generate the ALSA configuration file Jan 13 17:05:45 volumio-living volumio[10285]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 13 17:05:45 volumio-living volumio[10285]: info: Reading ALSA contributions from plugins. Jan 13 17:05:45 volumio-living volumio[10285]: info: Discovery: Connected to remote: 192.168.0.23 Jan 13 17:05:45 volumio-living volumio[10285]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Jan 13 17:05:45 volumio-living volumio[10285]: info: MPD Permissions set Jan 13 17:05:45 volumio-living volumio[10285]: info: MPD Permissions set Jan 13 17:05:45 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:45 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 141. Jan 13 17:05:45 volumio-living sudo[10452]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 13 17:05:45 volumio-living volumio[10285]: info: Spotify config file written Jan 13 17:05:45 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:45 volumio-living sudo[10452]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:45 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:46 volumio-living systemd[1]: Stopping go-librespot Daemon... Jan 13 17:05:46 volumio-living systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jan 13 17:05:46 volumio-living systemd[1]: go-librespot-daemon.service: Succeeded. Jan 13 17:05:46 volumio-living systemd[1]: Stopped go-librespot Daemon. Jan 13 17:05:46 volumio-living volumio[10285]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 6 Jan 13 17:05:46 volumio-living systemd[1]: Started go-librespot Daemon. Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living sudo[10452]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living go-librespot[10454]: Librespot-go daemon starting... Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living go-librespot[10454]: time="2025-01-13T17:05:46+09:00" level=info msg="generated new device id: 180c164d5b858fed7541a07414450992b9a76c63" Jan 13 17:05:46 volumio-living go-librespot[10454]: time="2025-01-13T17:05:46+09:00" level=debug msg="stored credentials found for rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:46 volumio-living volumio[10285]: info: No need to fix Spotify hosts Jan 13 17:05:46 volumio-living volumio[10285]: info: Volumio called home Jan 13 17:05:46 volumio-living go-librespot[10454]: time="2025-01-13T17:05:46+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 13 17:05:46 volumio-living go-librespot[10454]: time="2025-01-13T17:05:46+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 13 17:05:46 volumio-living go-librespot[10454]: time="2025-01-13T17:05:46+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 13 17:05:46 volumio-living volumio[10285]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 13 17:05:46 volumio-living volumio[10285]: SPOTIFY: BQBweXeVkQzLNiKaU5nuJkam7Ke6kjKtPA9mJ30rdbJsad_HlBuheoioxY5A2WWftJqlkH6TJZQDk994OMl9AjwplIaNEWDgQROaTbuJmIK_Hftz226IHZ8bvMcryckrjo9jJh7dcLZc8uoE17z28OVNrLHRmqKavwerkta5FykoIIOrQ_qaNXomyX3aTt5kgMj7AKlRYVQ9Nh3ubY5q1-FWah5dgD4AfMWZmiYD83jKTtZTLVqxDiytDONMhhq94MaKHDsVTow Jan 13 17:05:46 volumio-living volumio[10285]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 13 17:05:46 volumio-living volumio[10285]: info: New Spotify access token = BQBweXeVkQzLNiKaU5nuJkam7Ke6kjKtPA9mJ30rdbJsad_HlBuheoioxY5A2WWftJqlkH6TJZQDk994OMl9AjwplIaNEWDgQROaTbuJmIK_Hftz226IHZ8bvMcryckrjo9jJh7dcLZc8uoE17z28OVNrLHRmqKavwerkta5FykoIIOrQ_qaNXomyX3aTt5kgMj7AKlRYVQ9Nh3ubY5q1-FWah5dgD4AfMWZmiYD83jKTtZTLVqxDiytDONMhhq94MaKHDsVTow Jan 13 17:05:46 volumio-living volumio[10285]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 13 17:05:46 volumio-living go-librespot[10454]: time="2025-01-13T17:05:46+09:00" level=debug msg="zeroconf server listening on port 41733" Jan 13 17:05:46 volumio-living volumio[10285]: info: Starting Shairport Sync Jan 13 17:05:46 volumio-living volumio[10285]: info: Starting Shairport Sync Jan 13 17:05:46 volumio-living sudo[10472]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 17:05:46 volumio-living sudo[10472]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:46 volumio-living systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 13 17:05:46 volumio-living systemd[1]: shairport-sync.service: Succeeded. Jan 13 17:05:46 volumio-living systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 13 17:05:46 volumio-living volumio[10285]: info: Starting Shairport Sync Jan 13 17:05:46 volumio-living go-librespot[10454]: time="2025-01-13T17:05:46+09:00" level=debug msg="obtained new client token: AAC+XWNv/X/pjhqxWwKMKSkjVUqyJqGlKwwWYyrJJTFax1/XMHEum9ylmxXY+2jMVouXwLzVMaYL0it5U5rHXcEpP5WfVOYZaWqWAbx8TQmpdkkWi3aEw/OMJRLWrl/j5j0TUWXGdwWWlXwyfiFMAMbREsER+opl3EXRY9LmdSoSFSK8ZtI/niufBrPAjycUPBqW1kkiW2qL5i2ZPaJ5x8yPw24T9j/CqD22dMe7nSZ/eYN+PlMy6k0nKkrBng0=" Jan 13 17:05:46 volumio-living sudo[10475]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 17:05:46 volumio-living sudo[10475]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:46 volumio-living systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 13 17:05:46 volumio-living sudo[10478]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 17:05:46 volumio-living sudo[10478]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:46 volumio-living sudo[10472]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:46 volumio-living volumio[10285]: info: CoreCommandRouter::volumioGetState Jan 13 17:05:46 volumio-living systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 13 17:05:46 volumio-living go-librespot[10454]: time="2025-01-13T17:05:46+09:00" level=info msg="connected to ap-gae2.spotify.com:4070" Jan 13 17:05:46 volumio-living volumio[10285]: info: CorePlayQueue::getTrack 0 Jan 13 17:05:46 volumio-living systemd[1]: shairport-sync.service: Succeeded. Jan 13 17:05:46 volumio-living systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 13 17:05:46 volumio-living systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 13 17:05:46 volumio-living sudo[10475]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:46 volumio-living sudo[10478]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:46 volumio-living go-librespot[10454]: time="2025-01-13T17:05:46+09:00" level=debug msg="completed keyexchange" Jan 13 17:05:46 volumio-living volumio[10285]: info: Shairport-Sync Started Jan 13 17:05:46 volumio-living volumio[10285]: Error adding Membership: Error: addMembership EINVAL Jan 13 17:05:46 volumio-living volumio[10285]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:05:47 volumio-living volumio[10285]: info: Output device has changed, restarting MPD Jan 13 17:05:47 volumio-living sudo[10487]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:05:47 volumio-living sudo[10487]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:47 volumio-living volumio[10285]: info: Output device has changed, restarting Shairport Sync Jan 13 17:05:47 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:47 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:47 volumio-living sudo[10494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:05:47 volumio-living sudo[10494]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:47 volumio-living sudo[10487]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:47 volumio-living volumio[10285]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:05:47 volumio-living systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 13 17:05:47 volumio-living systemd[1]: mpd.service: Succeeded. Jan 13 17:05:47 volumio-living systemd[1]: Stopped Music Player Daemon. Jan 13 17:05:47 volumio-living volumio[10285]: info: Shairport-Sync Started Jan 13 17:05:47 volumio-living volumio[10285]: info: Shairport-Sync Started Jan 13 17:05:47 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:05:47 volumio-living sudo[10511]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:05:47 volumio-living sudo[10511]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:47 volumio-living sudo[10511]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:47 volumio-living sudo[10514]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:05:47 volumio-living sudo[10514]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:47 volumio-living volumio[10285]: info: MPD Permissions set Jan 13 17:05:47 volumio-living sudo[10514]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:47 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:47 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:47 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:47 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:05:47 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:47 volumio-living sudo[10508]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:05:47 volumio-living sudo[10508]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:47 volumio-living sudo[10508]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:47 volumio-living volumio[10285]: info: Starting Shairport Sync Jan 13 17:05:47 volumio-living sudo[10523]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 17:05:47 volumio-living sudo[10523]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:47 volumio-living go-librespot[10454]: time="2025-01-13T17:05:47+09:00" level=debug msg="completed challenge" Jan 13 17:05:47 volumio-living sudo[10526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Jan 13 17:05:47 volumio-living sudo[10526]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:47 volumio-living volumio[10285]: SPOTIFY: User informations: {"country":"JP","display_name":"Mino","email":"minominoh754@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/rq2jvw7opfya1jfp6bd0onmmj"},"followers":{"href":null,"total":0},"href":"https://api.spotify.com/v1/users/rq2jvw7opfya1jfp6bd0onmmj","id":"rq2jvw7opfya1jfp6bd0onmmj","images":[],"product":"free","type":"user","uri":"spotify:user:rq2jvw7opfya1jfp6bd0onmmj"} Jan 13 17:05:47 volumio-living volumio[10285]: info: Spotify Successfully logged in Jan 13 17:05:47 volumio-living systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 13 17:05:47 volumio-living systemd[1]: shairport-sync.service: Succeeded. Jan 13 17:05:47 volumio-living systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 13 17:05:47 volumio-living systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 13 17:05:47 volumio-living volumio[10285]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:05:47 volumio-living volumio[10285]: info: [1736755547526] CoreMusicLibrary::Adding element Spotify Jan 13 17:05:47 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:47 volumio-living volumio[10285]: Cannot find translation for source YouTube Music Jan 13 17:05:47 volumio-living volumio[10285]: Cannot find translation for source Spotify Jan 13 17:05:47 volumio-living sudo[10523]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:47 volumio-living go-librespot[10454]: time="2025-01-13T17:05:47+09:00" level=debug msg="authenticated as rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:05:47 volumio-living volumio[10285]: info: Shairport-Sync Started Jan 13 17:05:47 volumio-living sudo[10526]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:47 volumio-living volumio[10285]: info: Upmpdcli Daemon Started Jan 13 17:05:47 volumio-living volumio[10285]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 13 17:05:47 volumio-living volumio[10285]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jan 13 17:05:47 volumio-living volumio[10285]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 13 17:05:47 volumio-living volumio[10285]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jan 13 17:05:47 volumio-living volumio[10285]: info: camilladsp service started and running in background, instance 1 Jan 13 17:05:47 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:47 volumio-living volumio[10450]: Traceback (most recent call last): Jan 13 17:05:47 volumio-living volumio[10450]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:47 volumio-living volumio[10450]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:47 volumio-living volumio[10450]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:47 volumio-living volumio[10285]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 13 17:05:47 volumio-living volumio[10285]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jan 13 17:05:47 volumio-living volumio[10285]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 13 17:05:47 volumio-living volumio[10285]: info: FusionDsp loaded Jan 13 17:05:47 volumio-living volumio[10285]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 17:05:47 volumio-living sudo[10541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 13 17:05:47 volumio-living sudo[10541]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:47 volumio-living go-librespot[10454]: time="2025-01-13T17:05:47+09:00" level=debug msg="authenticated as rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:05:47 volumio-living volumio[10285]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 13 17:05:47 volumio-living volumio[10285]: info: Adding Signal Path Element [object Object] Jan 13 17:05:47 volumio-living volumio[10285]: info: Adding fusiondspeq DSP Signal Path Element Jan 13 17:05:47 volumio-living volumio[10285]: info: FusionDsp - ---- installed callbackRead Jan 13 17:05:47 volumio-living sudo[10541]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:47 volumio-living volumio[10285]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:05:47 volumio-living volumio[10285]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jan 13 17:05:47 volumio-living volumio[10285]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jan 13 17:05:47 volumio-living volumio[10285]: at onErrorNT (internal/child_process.js:465:16) Jan 13 17:05:47 volumio-living volumio[10285]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jan 13 17:05:47 volumio-living volumio[10285]: errno: -2, Jan 13 17:05:47 volumio-living volumio[10285]: code: 'ENOENT', Jan 13 17:05:47 volumio-living volumio[10285]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jan 13 17:05:47 volumio-living volumio[10285]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jan 13 17:05:47 volumio-living volumio[10285]: spawnargs: [ Jan 13 17:05:47 volumio-living volumio[10285]: '-p', Jan 13 17:05:47 volumio-living volumio[10285]: 9876, Jan 13 17:05:47 volumio-living volumio[10285]: '-o', Jan 13 17:05:47 volumio-living volumio[10285]: '/tmp/camilladsp.log', Jan 13 17:05:47 volumio-living volumio[10285]: '-l', Jan 13 17:05:47 volumio-living volumio[10285]: 'warn', Jan 13 17:05:47 volumio-living volumio[10285]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jan 13 17:05:47 volumio-living volumio[10285]: ] Jan 13 17:05:47 volumio-living volumio[10285]: } Jan 13 17:05:47 volumio-living volumio[10285]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:05:47 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:47 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:48 volumio-living go-librespot[10454]: time="2025-01-13T17:05:48+09:00" level=debug msg="dealer connection opened" Jan 13 17:05:48 volumio-living go-librespot[10454]: time="2025-01-13T17:05:48+09:00" level=debug msg="initializing zeroconf session, username: rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:05:48 volumio-living go-librespot[10454]: time="2025-01-13T17:05:48+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 13 17:05:48 volumio-living go-librespot[10454]: time="2025-01-13T17:05:48+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 13 17:05:48 volumio-living go-librespot[10454]: time="2025-01-13T17:05:48+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 13 17:05:48 volumio-living go-librespot[10454]: time="2025-01-13T17:05:48+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 13 17:05:48 volumio-living go-librespot[10454]: time="2025-01-13T17:05:48+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 13 17:05:48 volumio-living go-librespot[10454]: time="2025-01-13T17:05:48+09:00" level=debug msg="autoplay enabled: false" Jan 13 17:05:48 volumio-living go-librespot[10454]: time="2025-01-13T17:05:48+09:00" level=debug msg="received connection id: ZmU2ZjgyNTQtZmQ4ZS00MjlkLThjM2MtZTIwZGMzMTdhMDAyK2RlYWxlcit0Y3A6Ly8wYWM5NDkzNy5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArQTIyQzE2OUUwNDk1MDZBQkM1NzhBM0VEMzY1RTFGMjgwNkQyQTk2NUQ4RDJDNzNCQUI2NEU4Q0M2MjVDMTRGNg==" Jan 13 17:05:48 volumio-living go-librespot[10454]: time="2025-01-13T17:05:48+09:00" level=debug msg="put connect state because NEW_DEVICE" Jan 13 17:05:48 volumio-living sudo[10552]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 17:04 Jan 13 17:05:48 volumio-living sudo[10552]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:48 volumio-living sudo[10552]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:48 volumio-living volumio-remote-updater[552]: [2025-01-13 17:05:48] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jan 13 17:05:48 volumio-living volumio-remote-updater[552]: [2025-01-13 17:05:48] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jan 13 17:05:48 volumio-living systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:48 volumio-living sudo[10406]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:48 volumio-living sudo[10494]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:48 volumio-living sudo[10392]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:48 volumio-living systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 13 17:05:48 volumio-living systemd[1]: Started dynamicswap service. Jan 13 17:05:48 volumio-living systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:05:49 volumio-living mpd[10521]: Jan 13 17:05 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 17:05:49 volumio-living systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 13 17:05:49 volumio-living systemd[1]: volumio.service: Scheduled restart job, restart counter is at 33. Jan 13 17:05:49 volumio-living systemd[1]: Started Music Player Daemon. Jan 13 17:05:49 volumio-living systemd[1]: Started dynamicswap service. Jan 13 17:05:49 volumio-living systemd[1]: Stopped Volumio Backend Module. Jan 13 17:05:49 volumio-living systemd[1]: Started Volumio Backend Module. Jan 13 17:05:49 volumio-living systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:05:50 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:50 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 142. Jan 13 17:05:50 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:50 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:51 volumio-living volumio[10565]: info: ------------------------------------------- Jan 13 17:05:51 volumio-living volumio[10565]: info: ----- Volumio3 ---- Jan 13 17:05:51 volumio-living volumio[10565]: info: ------------------------------------------- Jan 13 17:05:51 volumio-living volumio[10565]: info: ----- System startup ---- Jan 13 17:05:51 volumio-living volumio[10565]: info: ------------------------------------------- Jan 13 17:05:51 volumio-living volumio[10577]: Traceback (most recent call last): Jan 13 17:05:51 volumio-living volumio[10577]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:51 volumio-living volumio[10577]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:51 volumio-living volumio[10577]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:51 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:51 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:51 volumio-living volumio[10565]: info: MYVOLUMIO Environment detected Jan 13 17:05:51 volumio-living volumio[10565]: info: Plugin folders cleanup Jan 13 17:05:51 volumio-living volumio[10565]: info: Scanning into folder /volumio/app/plugins/ Jan 13 17:05:51 volumio-living volumio[10565]: info: Scanning category audio_interface Jan 13 17:05:51 volumio-living volumio[10565]: info: Scanning category miscellanea Jan 13 17:05:51 volumio-living volumio[10565]: info: Scanning category music_service Jan 13 17:05:51 volumio-living volumio[10565]: info: Scanning category plugins.json Jan 13 17:05:51 volumio-living volumio[10565]: info: Scanning category system_controller Jan 13 17:05:51 volumio-living volumio[10565]: info: Scanning category user_interface Jan 13 17:05:51 volumio-living volumio[10565]: info: Scanning into folder /data/plugins/ Jan 13 17:05:51 volumio-living volumio[10565]: info: Scanning category audio_interface Jan 13 17:05:51 volumio-living volumio[10565]: info: Scanning category music_service Jan 13 17:05:51 volumio-living volumio[10565]: info: Plugin folders cleanup completed Jan 13 17:05:51 volumio-living volumio[10565]: info: ------------------------------------------- Jan 13 17:05:51 volumio-living volumio[10565]: info: ----- Core plugins startup ---- Jan 13 17:05:51 volumio-living volumio[10565]: info: ------------------------------------------- Jan 13 17:05:51 volumio-living volumio[10565]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 17:05:51 volumio-living volumio[10565]: info: Adding plugin upnp to MyMusic Plugins Jan 13 17:05:51 volumio-living volumio[10565]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 17:05:51 volumio-living volumio[10565]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 17:05:52 volumio-living volumio[10565]: info: Loading plugins from folder /data/plugins/ Jan 13 17:05:52 volumio-living volumio[10565]: info: Loading plugin "system"... Jan 13 17:05:52 volumio-living volumio[10565]: info: Loading plugin "appearance"... Jan 13 17:05:53 volumio-living volumio[10565]: info: Loading plugin "network"... Jan 13 17:05:53 volumio-living volumio[10565]: info: Refreshing Cached IP Addresses Jan 13 17:05:53 volumio-living sudo[10633]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:05:53 volumio-living sudo[10633]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:53 volumio-living sudo[10633]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:53 volumio-living sudo[10635]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:05:53 volumio-living sudo[10635]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:53 volumio-living volumio[10565]: info: Loading plugin "services"... Jan 13 17:05:53 volumio-living sudo[10635]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:53 volumio-living volumio[10565]: info: Loading plugin "alsa_controller"... Jan 13 17:05:53 volumio-living sudo[10643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 13 17:05:53 volumio-living sudo[10643]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:05:53 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:05:53 volumio-living volumio[10565]: info: Loading plugin "wizard"... Jan 13 17:05:53 volumio-living volumio[10565]: info: Loading plugin "networkfs"... Jan 13 17:05:53 volumio-living volumio[10565]: info: Starting Udev Watcher for removable devices Jan 13 17:05:53 volumio-living volumio[10565]: info: Ignoring mount for partition: boot Jan 13 17:05:53 volumio-living volumio[10565]: info: Ignoring mount for partition: volumio Jan 13 17:05:53 volumio-living volumio[10565]: info: Ignoring mount for partition: volumio_data Jan 13 17:05:53 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:05:53 volumio-living volumio[10565]: info: Loading plugin "volumio_command_line_client"... Jan 13 17:05:53 volumio-living volumio[10565]: info: Loading plugin "upnp"... Jan 13 17:05:53 volumio-living volumio[10565]: info: [1736755553326] Starting Upmpd Daemon Jan 13 17:05:53 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:05:53 volumio-living volumio[10565]: info: Loading plugin "my_music"... Jan 13 17:05:53 volumio-living volumio[10565]: info: Loading plugin "mpd"... Jan 13 17:05:53 volumio-living volumio[10565]: info: Loading plugin "upnp_browser"... Jan 13 17:05:53 volumio-living volumio-remote-updater[552]: [2025-01-13 17:05:53] [connect] Successful connection Jan 13 17:05:53 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:53 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 143. Jan 13 17:05:53 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:53 volumio-living volumio[10565]: info: Loading plugin "alarm-clock"... Jan 13 17:05:54 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:54 volumio-living volumio[10565]: info: Loading plugin "airplay_emulation"... Jan 13 17:05:54 volumio-living volumio[10565]: info: Starting Shairport Sync Jan 13 17:05:54 volumio-living volumio[10565]: info: Loading plugin "last_100"... Jan 13 17:05:54 volumio-living volumio[10565]: info: Loading plugin "webradio"... Jan 13 17:05:54 volumio-living wpa_supplicant[838]: RRM: Ignoring radio measurement request: Not RRM network Jan 13 17:05:54 volumio-living volumio[10565]: info: Loading plugin "i2s_dacs"... Jan 13 17:05:54 volumio-living volumio[10565]: info: I2S DAC not set, start Auto-detection Jan 13 17:05:54 volumio-living volumio[10565]: info: Loading plugin "volumiodiscovery"... Jan 13 17:05:54 volumio-living volumio[10565]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:54 volumio-living volumio[10565]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:54 volumio-living volumio[10565]: *** WARNING *** For more information see Jan 13 17:05:54 volumio-living volumio[10565]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:54 volumio-living node[10565]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:54 volumio-living volumio[10565]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:54 volumio-living volumio[10565]: *** WARNING *** For more information see Jan 13 17:05:54 volumio-living node[10565]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:54 volumio-living node[10565]: *** WARNING *** For more information see Jan 13 17:05:54 volumio-living node[10565]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:05:54 volumio-living node[10565]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:05:54 volumio-living node[10565]: *** WARNING *** For more information see Jan 13 17:05:54 volumio-living volumio[10565]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 17:05:54 volumio-living volumio[10565]: info: Discovery: Started advertising with name: Volumio-Living Jan 13 17:05:54 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:05:54 volumio-living volumio[10565]: info: Loading plugin "spop"... Jan 13 17:05:55 volumio-living volumio[10660]: Traceback (most recent call last): Jan 13 17:05:55 volumio-living volumio[10660]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:55 volumio-living volumio[10660]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:55 volumio-living volumio[10660]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:55 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:55 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:55 volumio-living volumio[10565]: info: Loading plugin "ytmusic"... Jan 13 17:05:56 volumio-living sudo[10643]: pam_unix(sudo:session): session closed for user root Jan 13 17:05:57 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:05:57 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 144. Jan 13 17:05:57 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:05:57 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:05:58 volumio-living volumio[10565]: info: Loading plugin "outputs"... Jan 13 17:05:58 volumio-living volumio[10565]: info: Loading plugin "albumart"... Jan 13 17:05:58 volumio-living volumio[10565]: info: Plugin example_plugin is not enabled Jan 13 17:05:58 volumio-living volumio[10565]: info: Loading plugin "inputs"... Jan 13 17:05:58 volumio-living volumio[10565]: info: Loading plugin "updater_comm"... Jan 13 17:05:59 volumio-living volumio[10565]: info: Plugin mpdemulation is not enabled Jan 13 17:05:59 volumio-living volumio[10565]: info: Loading plugin "rest_api"... Jan 13 17:05:59 volumio-living volumio[10663]: Traceback (most recent call last): Jan 13 17:05:59 volumio-living volumio[10663]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:05:59 volumio-living volumio[10663]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:05:59 volumio-living volumio[10663]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:05:59 volumio-living volumio[10565]: info: Loading plugin "websocket"... Jan 13 17:05:59 volumio-living volumio[10565]: info: Starting Socket.io Server version 2.3.0 Jan 13 17:05:59 volumio-living volumio[10565]: info: Loading plugin "fusiondsp"... Jan 13 17:05:59 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:05:59 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:05:59 volumio-living volumio[10565]: Forking 3 albumart workers Jan 13 17:05:59 volumio-living volumio[10565]: info: Applying required configuration parameters for plugin fusiondsp Jan 13 17:05:59 volumio-living volumio[10565]: info: Loading i18n strings for locale ja Jan 13 17:05:59 volumio-living volumio[10565]: Updating browse sources language Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreCommandRouter::initPlayerControls Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:05:59 volumio-living volumio[10565]: Express server listening on port 3000 Jan 13 17:05:59 volumio-living volumio[10565]: [Metrics] WebUI: 9s 449.19ms Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreStateMachine::resetVolumioState Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreStateMachine::getcurrentVolume Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:05:59 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:06:00 volumio-living volumio[10565]: info: Volumio Network Manager: Network status updated: 2 Jan 13 17:06:00 volumio-living volumio[10565]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 17:06:00 volumio-living volumio[10565]: verbose: New Socket.io Connection to 192.168.0.154:3000 from 192.168.0.23 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Jan 13 17:06:00 volumio-living volumio[10565]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Jan 13 17:06:00 volumio-living volumio[10565]: info: Reloading queue from file Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 13 17:06:00 volumio-living volumio[10565]: info: VolumeController:: Volume=100 Mute =false Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreStateMachine::pushState Jan 13 17:06:00 volumio-living volumio[10565]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreCommandRouter::volumioPushState Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreStateMachine::updateTrackBlock Jan 13 17:06:00 volumio-living volumio[10565]: info: CorePlayQueue::getTrackBlock Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreStateMachine::setRepeat null single undefined Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreStateMachine::pushState Jan 13 17:06:00 volumio-living volumio[10565]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreCommandRouter::volumioPushState Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreStateMachine::setRandom null Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreStateMachine::pushState Jan 13 17:06:00 volumio-living volumio[10565]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreCommandRouter::volumioPushState Jan 13 17:06:00 volumio-living volumio[10565]: Starting albumart workers Jan 13 17:06:00 volumio-living volumio[10565]: info: Setting Device type: Raspberry PI Jan 13 17:06:00 volumio-living volumio[10565]: Starting albumart workers Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreCommandRouter::volumioGetState Jan 13 17:06:00 volumio-living volumio[10565]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:00 volumio-living volumio[10565]: info: Discovery: adding e2b8d27d-62f6-4d3d-b2b6-7a4b9d2ec0f8 Jan 13 17:06:00 volumio-living volumio[10565]: info: Discovery: Found device Volumio Jan 13 17:06:00 volumio-living volumio[10565]: Starting albumart workers Jan 13 17:06:00 volumio-living volumio[10565]: info: Discovery: Connecting to remote: 192.168.0.23 Jan 13 17:06:00 volumio-living volumio[10565]: info: VolumeController:: Volume=100 Mute =false Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreStateMachine::pushState Jan 13 17:06:00 volumio-living volumio[10565]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreCommandRouter::volumioPushState Jan 13 17:06:00 volumio-living volumio[10565]: info: Completed loading Core Plugins Jan 13 17:06:00 volumio-living volumio[10565]: info: Preparing to generate the ALSA configuration file Jan 13 17:06:00 volumio-living volumio[10565]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 13 17:06:00 volumio-living volumio[10565]: info: Reading ALSA contributions from plugins. Jan 13 17:06:00 volumio-living volumio[10565]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:06:00 volumio-living volumio[10565]: info: Output device has changed, restarting MPD Jan 13 17:06:00 volumio-living volumio[10565]: info: Output device has changed, restarting Shairport Sync Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:06:00 volumio-living sudo[10706]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:06:00 volumio-living sudo[10706]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:00 volumio-living sudo[10706]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:00 volumio-living sudo[10708]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:06:00 volumio-living sudo[10708]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:00 volumio-living systemd[1]: Stopping Music Player Daemon... Jan 13 17:06:00 volumio-living systemd[1]: mpd.service: Succeeded. Jan 13 17:06:00 volumio-living systemd[1]: Stopped Music Player Daemon. Jan 13 17:06:00 volumio-living volumio[10565]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:06:00 volumio-living volumio[10565]: info: ___________ START PLUGINS ___________ Jan 13 17:06:00 volumio-living volumio[10565]: info: ControllerMpd::onStart: Initializing MPD Jan 13 17:06:00 volumio-living volumio[10565]: info: Creating MPD Configuration file Jan 13 17:06:00 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:06:00 volumio-living volumio[10565]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:06:01 volumio-living volumio[10565]: info: [1736755560999] CoreMusicLibrary::Adding element DLNAサーバー Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:06:01 volumio-living sudo[10716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:06:01 volumio-living sudo[10716]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:06:01 volumio-living sudo[10718]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:06:01 volumio-living sudo[10718]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:01 volumio-living sudo[10716]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:01 volumio-living sudo[10714]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:06:01 volumio-living sudo[10714]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:01 volumio-living sudo[10714]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:01 volumio-living systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 13 17:06:01 volumio-living systemd[1]: mpd.service: Succeeded. Jan 13 17:06:01 volumio-living systemd[1]: Stopped Music Player Daemon. Jan 13 17:06:01 volumio-living volumio[10565]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:06:01 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:06:01 volumio-living volumio[10565]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:06:01 volumio-living volumio[10565]: info: [1736755561322] CoreMusicLibrary::Adding element Last_100 Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:06:01 volumio-living volumio[10565]: info: [1736755561328] CoreMusicLibrary::Adding element Webradio Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:06:01 volumio-living volumio[10565]: info: Initializing BBC Radios Jan 13 17:06:01 volumio-living sudo[10724]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:06:01 volumio-living sudo[10724]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:01 volumio-living sudo[10724]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:01 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:06:01 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 145. Jan 13 17:06:01 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:06:01 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:06:01 volumio-living volumio[10565]: info: Creating Spotify config file Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:06:01 volumio-living volumio[10565]: info: [1736755561706] CoreMusicLibrary::Adding element YouTube Music Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:06:01 volumio-living volumio[10565]: Cannot find translation for source YouTube Music Jan 13 17:06:01 volumio-living volumio[10565]: info: Loading i18n strings for locale ja Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:01 volumio-living volumio[10565]: info: FusionDsp - mixtype--------------------- Hardware Jan 13 17:06:01 volumio-living volumio[10565]: info: Volumio Calling Home Jan 13 17:06:01 volumio-living volumio[10565]: info: Preparing to generate the ALSA configuration file Jan 13 17:06:01 volumio-living volumio[10565]: info: Discovery: adding c92b663e-9b92-48a7-8bf1-18d92af07ae4 Jan 13 17:06:01 volumio-living volumio[10565]: info: Discovery: Found device Volumio-Living Jan 13 17:06:01 volumio-living volumio[10565]: info: CoreCommandRouter::volumioGetState Jan 13 17:06:01 volumio-living volumio[10565]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:01 volumio-living volumio[10565]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 13 17:06:01 volumio-living volumio[10565]: info: Reading ALSA contributions from plugins. Jan 13 17:06:01 volumio-living volumio[10565]: info: Discovery: Connected to remote: 192.168.0.23 Jan 13 17:06:01 volumio-living volumio[10565]: info: MPD Permissions set Jan 13 17:06:01 volumio-living volumio[10565]: info: MPD Permissions set Jan 13 17:06:02 volumio-living volumio[10565]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 13 17:06:02 volumio-living volumio[10565]: info: Spotify config file written Jan 13 17:06:02 volumio-living volumio[10565]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:02 volumio-living sudo[10769]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 13 17:06:02 volumio-living sudo[10769]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:02 volumio-living systemd[1]: Stopping go-librespot Daemon... Jan 13 17:06:02 volumio-living systemd[1]: go-librespot-daemon.service: Main process exited, code=killed, status=15/TERM Jan 13 17:06:02 volumio-living systemd[1]: go-librespot-daemon.service: Succeeded. Jan 13 17:06:02 volumio-living systemd[1]: Stopped go-librespot Daemon. Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:02 volumio-living volumio[10565]: info: No need to fix Spotify hosts Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 17:06:02 volumio-living systemd[1]: Started go-librespot Daemon. Jan 13 17:06:02 volumio-living go-librespot[10778]: Librespot-go daemon starting... Jan 13 17:06:02 volumio-living sudo[10769]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:02 volumio-living go-librespot[10778]: time="2025-01-13T17:06:02+09:00" level=info msg="generated new device id: c6f2a92a318050fde0783d059edc4b0dbfe2e345" Jan 13 17:06:02 volumio-living go-librespot[10778]: time="2025-01-13T17:06:02+09:00" level=debug msg="stored credentials found for rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::volumioGetState Jan 13 17:06:02 volumio-living volumio[10565]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 13 17:06:02 volumio-living volumio[10565]: info: Received Get System Info Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 17:06:02 volumio-living volumio[10565]: info: Discovery: Getting this device information Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::volumioGetState Jan 13 17:06:02 volumio-living volumio[10565]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::volumioGetState Jan 13 17:06:02 volumio-living volumio[10565]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:02 volumio-living volumio[10565]: info: Listing playlists Jan 13 17:06:02 volumio-living go-librespot[10778]: time="2025-01-13T17:06:02+09:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 13 17:06:02 volumio-living go-librespot[10778]: time="2025-01-13T17:06:02+09:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 13 17:06:02 volumio-living go-librespot[10778]: time="2025-01-13T17:06:02+09:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 13 17:06:02 volumio-living volumio[10565]: info: Volumio called home Jan 13 17:06:02 volumio-living go-librespot[10778]: time="2025-01-13T17:06:02+09:00" level=debug msg="zeroconf server listening on port 41619" Jan 13 17:06:02 volumio-living volumio[10565]: info: Starting Shairport Sync Jan 13 17:06:02 volumio-living volumio[10565]: info: Starting Shairport Sync Jan 13 17:06:02 volumio-living volumio[10565]: info: Starting Shairport Sync Jan 13 17:06:02 volumio-living sudo[10788]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 17:06:02 volumio-living sudo[10788]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:02 volumio-living sudo[10790]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 17:06:02 volumio-living sudo[10790]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:02 volumio-living sudo[10794]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jan 13 17:06:02 volumio-living systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jan 13 17:06:02 volumio-living sudo[10794]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:02 volumio-living systemd[1]: shairport-sync.service: Succeeded. Jan 13 17:06:02 volumio-living systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jan 13 17:06:02 volumio-living go-librespot[10778]: time="2025-01-13T17:06:02+09:00" level=debug msg="obtained new client token: AAAoqGjzOTt6DchGg1n049FjUOo4ijgzB+NEK7ohBLXoEau19dc2LQmT6bw3WT8wiz37nhg9TI0P20gvvGw/0udkjRvq25Bevpw6bXl9KTAGJ7BkEOEZ31DalLn4pGbcQtYl68Q0Z7Yiofoxp1icYlroIhsjSdTb+KX1ZWMEMGS/oohZxkXRcysO3c1l7bTmQdxU5j5jcB2aqD7CbxzcJkPqR9zN52oXKAtfCyf3JPm7fAksVcPKF/vhANpFg4U=" Jan 13 17:06:02 volumio-living volumio[10565]: info: CoreCommandRouter::volumioGetState Jan 13 17:06:02 volumio-living go-librespot[10778]: time="2025-01-13T17:06:02+09:00" level=info msg="connected to ap-gae2.spotify.com:4070" Jan 13 17:06:02 volumio-living volumio[10565]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:02 volumio-living systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jan 13 17:06:02 volumio-living sudo[10788]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:03 volumio-living sudo[10794]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:03 volumio-living sudo[10790]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:03 volumio-living go-librespot[10778]: time="2025-01-13T17:06:03+09:00" level=debug msg="completed keyexchange" Jan 13 17:06:03 volumio-living volumio[10565]: info: Shairport-Sync Started Jan 13 17:06:03 volumio-living volumio[10565]: Error adding Membership: Error: addMembership EINVAL Jan 13 17:06:03 volumio-living volumio[10565]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:06:03 volumio-living volumio[10565]: info: Output device has changed, restarting MPD Jan 13 17:06:03 volumio-living sudo[10811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:06:03 volumio-living sudo[10811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:03 volumio-living sudo[10811]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:03 volumio-living volumio[10565]: info: Output device has changed, restarting Shairport Sync Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:06:03 volumio-living sudo[10820]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:06:03 volumio-living sudo[10820]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:03 volumio-living volumio[10753]: Traceback (most recent call last): Jan 13 17:06:03 volumio-living volumio[10753]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:06:03 volumio-living volumio[10753]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:06:03 volumio-living volumio[10753]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:06:03 volumio-living volumio[10565]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:06:03 volumio-living systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 13 17:06:03 volumio-living systemd[1]: mpd.service: Succeeded. Jan 13 17:06:03 volumio-living systemd[1]: Stopped Music Player Daemon. Jan 13 17:06:03 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:06:03 volumio-living volumio[10565]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 13 17:06:03 volumio-living volumio[10565]: SPOTIFY: BQCKvMspGZC_5U0Z7kXmFLcXIj7LES93DwWZ2FuYqdIOAYk_74oA3ivJjgE-BM69aGrVoaLgKEaDF9-NfoPcdxrKEz6Mq_hIwMTMPIYo4tN3SyCWwJI1x1kBMv4FAmLhQ-juY2er7wTBaXyCG4BYe_ybWR8OFQ93VzmCByCXAtjgllSEIl0NuenC2ArlIaVW1inOB05k6ry-RlXrBSBn5mzlPsIiUX3V8VdjBoZuDkExqGpafTEOGZY-nhKtX-SEizu43eC-neY Jan 13 17:06:03 volumio-living volumio[10565]: SPOTIFY: ------------------------------------------------------ ACCESS TOKEN ------------------------------------------------------ Jan 13 17:06:03 volumio-living volumio[10565]: info: New Spotify access token = BQCKvMspGZC_5U0Z7kXmFLcXIj7LES93DwWZ2FuYqdIOAYk_74oA3ivJjgE-BM69aGrVoaLgKEaDF9-NfoPcdxrKEz6Mq_hIwMTMPIYo4tN3SyCWwJI1x1kBMv4FAmLhQ-juY2er7wTBaXyCG4BYe_ybWR8OFQ93VzmCByCXAtjgllSEIl0NuenC2ArlIaVW1inOB05k6ry-RlXrBSBn5mzlPsIiUX3V8VdjBoZuDkExqGpafTEOGZY-nhKtX-SEizu43eC-neY Jan 13 17:06:03 volumio-living volumio[10565]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 13 17:06:03 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:06:03 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:06:03 volumio-living volumio[10565]: info: Shairport-Sync Started Jan 13 17:06:03 volumio-living volumio[10565]: info: Shairport-Sync Started Jan 13 17:06:03 volumio-living sudo[10825]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:06:03 volumio-living sudo[10825]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:03 volumio-living sudo[10827]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:06:03 volumio-living sudo[10827]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:03 volumio-living go-librespot[10778]: time="2025-01-13T17:06:03+09:00" level=debug msg="completed challenge" Jan 13 17:06:03 volumio-living sudo[10825]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:03 volumio-living sudo[10827]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:03 volumio-living sudo[10831]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:06:03 volumio-living sudo[10831]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:03 volumio-living sudo[10831]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:03 volumio-living volumio[10565]: info: MPD Permissions set Jan 13 17:06:03 volumio-living go-librespot[10778]: time="2025-01-13T17:06:03+09:00" level=debug msg="authenticated as rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:03 volumio-living volumio[10565]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 13 17:06:03 volumio-living volumio[10565]: error: FusionDsp - ----failed to create fusiondspfifo :Error: Command failed: /bin/touch /tmp/fusiondsp_stream_params.log && /bin/chmod 666 /tmp/fusiondsp_stream_params.log && /bin/touch /tmp/camilladsp.log && /bin/chmod 666 /tmp/camilladsp.log && /usr/bin/mkfifo -m 646 /tmp/fusiondspfifo Jan 13 17:06:03 volumio-living volumio[10565]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jan 13 17:06:03 volumio-living volumio[10565]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jan 13 17:06:03 volumio-living volumio[10565]: info: camilladsp service started and running in background, instance 1 Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:03 volumio-living volumio[10565]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 13 17:06:03 volumio-living volumio[10565]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jan 13 17:06:03 volumio-living volumio[10565]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jan 13 17:06:03 volumio-living volumio[10565]: info: FusionDsp loaded Jan 13 17:06:03 volumio-living volumio[10565]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 17:06:03 volumio-living go-librespot[10778]: time="2025-01-13T17:06:03+09:00" level=debug msg="authenticated as rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:06:03 volumio-living sudo[10848]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jan 13 17:06:03 volumio-living sudo[10848]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:03 volumio-living volumio[10565]: info: FusionDsp - Reporting Fusion DSP Enabled Jan 13 17:06:03 volumio-living volumio[10565]: info: Adding Signal Path Element [object Object] Jan 13 17:06:03 volumio-living volumio[10565]: info: Adding fusiondspeq DSP Signal Path Element Jan 13 17:06:03 volumio-living volumio[10565]: info: FusionDsp - ---- installed callbackRead Jan 13 17:06:03 volumio-living volumio[10565]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:06:03 volumio-living volumio[10565]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jan 13 17:06:03 volumio-living volumio[10565]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jan 13 17:06:03 volumio-living volumio[10565]: at onErrorNT (internal/child_process.js:465:16) Jan 13 17:06:03 volumio-living volumio[10565]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jan 13 17:06:03 volumio-living volumio[10565]: at runNextTicks (internal/process/task_queues.js:62:3) Jan 13 17:06:03 volumio-living volumio[10565]: at listOnTimeout (internal/timers.js:523:9) Jan 13 17:06:03 volumio-living volumio[10565]: at processTimers (internal/timers.js:497:7) { Jan 13 17:06:03 volumio-living volumio[10565]: errno: -2, Jan 13 17:06:03 volumio-living volumio[10565]: code: 'ENOENT', Jan 13 17:06:03 volumio-living volumio[10565]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jan 13 17:06:03 volumio-living volumio[10565]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jan 13 17:06:03 volumio-living volumio[10565]: spawnargs: [ Jan 13 17:06:03 volumio-living volumio[10565]: '-p', Jan 13 17:06:03 volumio-living volumio[10565]: 9876, Jan 13 17:06:03 volumio-living volumio[10565]: '-o', Jan 13 17:06:03 volumio-living volumio[10565]: '/tmp/camilladsp.log', Jan 13 17:06:03 volumio-living volumio[10565]: '-l', Jan 13 17:06:03 volumio-living volumio[10565]: 'warn', Jan 13 17:06:03 volumio-living volumio[10565]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jan 13 17:06:03 volumio-living volumio[10565]: ] Jan 13 17:06:03 volumio-living volumio[10565]: } Jan 13 17:06:03 volumio-living volumio[10565]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:06:04 volumio-living go-librespot[10778]: time="2025-01-13T17:06:04+09:00" level=debug msg="dealer connection opened" Jan 13 17:06:04 volumio-living go-librespot[10778]: time="2025-01-13T17:06:04+09:00" level=debug msg="initializing zeroconf session, username: rq2jvw7opfya1jfp6bd0onmmj" Jan 13 17:06:04 volumio-living go-librespot[10778]: time="2025-01-13T17:06:04+09:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 13 17:06:04 volumio-living go-librespot[10778]: time="2025-01-13T17:06:04+09:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 13 17:06:04 volumio-living go-librespot[10778]: time="2025-01-13T17:06:04+09:00" level=debug msg="autoplay enabled: false" Jan 13 17:06:04 volumio-living go-librespot[10778]: time="2025-01-13T17:06:04+09:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 13 17:06:04 volumio-living go-librespot[10778]: time="2025-01-13T17:06:04+09:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 13 17:06:04 volumio-living go-librespot[10778]: time="2025-01-13T17:06:04+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 13 17:06:04 volumio-living go-librespot[10778]: time="2025-01-13T17:06:04+09:00" level=debug msg="received connection id: NDA1NmZjMTEtMmZkMi00MDAwLWEwZTEtNzRlODcyNDI3NTY4K2RlYWxlcit0Y3A6Ly8wYWM5NDkyOC5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArMUZCMUZEMjAxMjNBRjU5MzQ0MDM2QjEwRjhDNDc2M0Q2OTY1Q0Y1M0Q0MkY1RUUyM0I5MkVCQjM4MkFGNUIyMg==" Jan 13 17:06:04 volumio-living go-librespot[10778]: time="2025-01-13T17:06:04+09:00" level=debug msg="put connect state because NEW_DEVICE" Jan 13 17:06:04 volumio-living sudo[10860]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 17:05 Jan 13 17:06:04 volumio-living sudo[10860]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:04 volumio-living sudo[10860]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:04 volumio-living systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:06:04 volumio-living sudo[10820]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:04 volumio-living sudo[10718]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:04 volumio-living sudo[10708]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:04 volumio-living sudo[10848]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:04 volumio-living systemd[1]: volumio.service: Failed with result 'exit-code'. Jan 13 17:06:04 volumio-living systemd[1]: Started dynamicswap service. Jan 13 17:06:04 volumio-living systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jan 13 17:06:04 volumio-living systemd[1]: mpd.service: Failed with result 'protocol'. Jan 13 17:06:04 volumio-living systemd[1]: Failed to start Music Player Daemon. Jan 13 17:06:04 volumio-living systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:06:04 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:06:04 volumio-living systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 13 17:06:04 volumio-living systemd[1]: mpd.service: Succeeded. Jan 13 17:06:04 volumio-living systemd[1]: Stopped Music Player Daemon. Jan 13 17:06:04 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:06:04 volumio-living systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jan 13 17:06:04 volumio-living systemd[1]: volumio.service: Scheduled restart job, restart counter is at 34. Jan 13 17:06:04 volumio-living systemd[1]: Started dynamicswap service. Jan 13 17:06:04 volumio-living systemd[1]: Stopped Volumio Backend Module. Jan 13 17:06:04 volumio-living systemd[1]: Started Volumio Backend Module. Jan 13 17:06:05 volumio-living systemd[1]: dynamicswap.service: Succeeded. Jan 13 17:06:05 volumio-living sudo[10873]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:06:05 volumio-living sudo[10873]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:05 volumio-living sudo[10873]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:05 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:06:05 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 146. Jan 13 17:06:05 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:06:05 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:06:06 volumio-living volumio-remote-updater[552]: [2025-01-13 17:06:06] [info] asio async_connect error: system:111 (Connection refused) Jan 13 17:06:06 volumio-living volumio-remote-updater[552]: [2025-01-13 17:06:06] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Jan 13 17:06:06 volumio-living volumio-remote-updater[552]: [2025-01-13 17:06:06] [error] handle_connect error: Underlying Transport Error Jan 13 17:06:06 volumio-living mpd[10888]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 17:06:06 volumio-living mpd[10888]: output: No 'audio_output' defined in config file Jan 13 17:06:06 volumio-living mpd[10888]: output: Successfully detected a sndio audio device Jan 13 17:06:06 volumio-living mpd[10888]: zeroconf: No global port, disabling zeroconf Jan 13 17:06:06 volumio-living systemd[1]: Started Music Player Daemon. Jan 13 17:06:06 volumio-living mpd_monitor.sh[545]: MPD restarted due to no mpc output. Jan 13 17:06:07 volumio-living volumio[10875]: info: ------------------------------------------- Jan 13 17:06:07 volumio-living volumio[10875]: info: ----- Volumio3 ---- Jan 13 17:06:07 volumio-living volumio[10875]: info: ------------------------------------------- Jan 13 17:06:07 volumio-living volumio[10875]: info: ----- System startup ---- Jan 13 17:06:07 volumio-living volumio[10875]: info: ------------------------------------------- Jan 13 17:06:07 volumio-living volumio[10889]: Traceback (most recent call last): Jan 13 17:06:07 volumio-living volumio[10889]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:06:07 volumio-living volumio[10889]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:06:07 volumio-living volumio[10889]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:06:07 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:06:07 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:06:07 volumio-living volumio[10875]: info: MYVOLUMIO Environment detected Jan 13 17:06:08 volumio-living volumio[10875]: info: Plugin folders cleanup Jan 13 17:06:08 volumio-living volumio[10875]: info: Scanning into folder /volumio/app/plugins/ Jan 13 17:06:08 volumio-living volumio[10875]: info: Scanning category audio_interface Jan 13 17:06:08 volumio-living volumio[10875]: info: Scanning category miscellanea Jan 13 17:06:08 volumio-living volumio[10875]: info: Scanning category music_service Jan 13 17:06:08 volumio-living volumio[10875]: info: Scanning category plugins.json Jan 13 17:06:08 volumio-living volumio[10875]: info: Scanning category system_controller Jan 13 17:06:08 volumio-living volumio[10875]: info: Scanning category user_interface Jan 13 17:06:08 volumio-living volumio[10875]: info: Scanning into folder /data/plugins/ Jan 13 17:06:08 volumio-living volumio[10875]: info: Scanning category audio_interface Jan 13 17:06:08 volumio-living volumio[10875]: info: Scanning category music_service Jan 13 17:06:08 volumio-living volumio[10875]: info: Plugin folders cleanup completed Jan 13 17:06:08 volumio-living volumio[10875]: info: ------------------------------------------- Jan 13 17:06:08 volumio-living volumio[10875]: info: ----- Core plugins startup ---- Jan 13 17:06:08 volumio-living volumio[10875]: info: ------------------------------------------- Jan 13 17:06:08 volumio-living volumio[10875]: info: Loading plugins from folder /volumio/app/plugins/ Jan 13 17:06:08 volumio-living volumio[10875]: info: Adding plugin upnp to MyMusic Plugins Jan 13 17:06:08 volumio-living volumio[10875]: info: Adding plugin airplay_emulation to MyMusic Plugins Jan 13 17:06:08 volumio-living volumio[10875]: info: Adding plugin upnp_browser to MyMusic Plugins Jan 13 17:06:08 volumio-living volumio[10875]: info: Loading plugins from folder /data/plugins/ Jan 13 17:06:08 volumio-living volumio[10875]: info: Loading plugin "system"... Jan 13 17:06:08 volumio-living volumio[10875]: info: Loading plugin "appearance"... Jan 13 17:06:09 volumio-living volumio[10875]: info: Loading plugin "network"... Jan 13 17:06:09 volumio-living volumio[10875]: info: Refreshing Cached IP Addresses Jan 13 17:06:09 volumio-living sudo[10908]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:06:09 volumio-living sudo[10908]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:09 volumio-living sudo[10908]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:09 volumio-living sudo[10910]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:06:09 volumio-living sudo[10910]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:09 volumio-living sudo[10910]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:09 volumio-living volumio[10875]: info: Loading plugin "services"... Jan 13 17:06:09 volumio-living volumio[10875]: info: Loading plugin "alsa_controller"... Jan 13 17:06:09 volumio-living sudo[10922]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jan 13 17:06:09 volumio-living sudo[10922]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:09 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:06:09 volumio-living volumio[10875]: info: Loading plugin "wizard"... Jan 13 17:06:09 volumio-living volumio[10875]: info: Loading plugin "networkfs"... Jan 13 17:06:09 volumio-living volumio[10875]: info: Starting Udev Watcher for removable devices Jan 13 17:06:09 volumio-living volumio[10875]: info: Ignoring mount for partition: boot Jan 13 17:06:09 volumio-living volumio[10875]: info: Ignoring mount for partition: volumio Jan 13 17:06:09 volumio-living volumio[10875]: info: Ignoring mount for partition: volumio_data Jan 13 17:06:09 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:06:09 volumio-living volumio[10875]: info: Loading plugin "volumio_command_line_client"... Jan 13 17:06:09 volumio-living volumio[10875]: info: Loading plugin "upnp"... Jan 13 17:06:09 volumio-living volumio[10875]: info: [1736755569368] Starting Upmpd Daemon Jan 13 17:06:09 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:06:09 volumio-living volumio[10875]: info: Loading plugin "my_music"... Jan 13 17:06:09 volumio-living volumio[10875]: info: Loading plugin "mpd"... Jan 13 17:06:09 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:06:09 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 147. Jan 13 17:06:09 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:06:09 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:06:09 volumio-living volumio[10875]: info: Loading plugin "upnp_browser"... Jan 13 17:06:10 volumio-living volumio[10875]: info: Loading plugin "alarm-clock"... Jan 13 17:06:10 volumio-living volumio[10875]: info: Loading plugin "airplay_emulation"... Jan 13 17:06:10 volumio-living volumio[10875]: info: Starting Shairport Sync Jan 13 17:06:10 volumio-living volumio[10875]: info: Loading plugin "last_100"... Jan 13 17:06:10 volumio-living volumio[10875]: info: Loading plugin "webradio"... Jan 13 17:06:10 volumio-living volumio[10875]: info: Loading plugin "i2s_dacs"... Jan 13 17:06:10 volumio-living volumio[10875]: info: I2S DAC not set, start Auto-detection Jan 13 17:06:10 volumio-living volumio[10875]: info: Loading plugin "volumiodiscovery"... Jan 13 17:06:10 volumio-living wpa_supplicant[838]: RRM: Ignoring radio measurement request: Not RRM network Jan 13 17:06:10 volumio-living volumio[10875]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:06:10 volumio-living volumio[10875]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:06:10 volumio-living volumio[10875]: *** WARNING *** For more information see Jan 13 17:06:10 volumio-living volumio[10875]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:06:10 volumio-living node[10875]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jan 13 17:06:10 volumio-living volumio[10875]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:06:10 volumio-living volumio[10875]: *** WARNING *** For more information see Jan 13 17:06:10 volumio-living node[10875]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:06:10 volumio-living node[10875]: *** WARNING *** For more information see Jan 13 17:06:10 volumio-living node[10875]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jan 13 17:06:10 volumio-living node[10875]: *** WARNING *** Please fix your application to use the native API of Avahi! Jan 13 17:06:10 volumio-living node[10875]: *** WARNING *** For more information see Jan 13 17:06:10 volumio-living volumio[10875]: info: Applying required configuration parameters for plugin volumiodiscovery Jan 13 17:06:10 volumio-living volumio[10875]: info: Discovery: Started advertising with name: Volumio-Living Jan 13 17:06:10 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jan 13 17:06:10 volumio-living volumio[10875]: info: Loading plugin "spop"... Jan 13 17:06:10 volumio-living volumio[10935]: Traceback (most recent call last): Jan 13 17:06:10 volumio-living volumio[10935]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:06:10 volumio-living volumio[10935]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:06:10 volumio-living volumio[10935]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:06:11 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:06:11 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:06:11 volumio-living volumio[10875]: info: Loading plugin "ytmusic"... Jan 13 17:06:12 volumio-living sudo[10922]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:13 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:06:13 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 148. Jan 13 17:06:13 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:06:13 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:06:14 volumio-living volumio[10939]: Traceback (most recent call last): Jan 13 17:06:14 volumio-living volumio[10939]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:06:14 volumio-living volumio[10939]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:06:14 volumio-living volumio[10939]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:06:14 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:06:14 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:06:15 volumio-living volumio[10875]: info: Loading plugin "outputs"... Jan 13 17:06:15 volumio-living volumio[10875]: info: Loading plugin "albumart"... Jan 13 17:06:15 volumio-living volumio[10875]: info: Plugin example_plugin is not enabled Jan 13 17:06:15 volumio-living volumio[10875]: info: Loading plugin "inputs"... Jan 13 17:06:15 volumio-living volumio[10875]: info: Loading plugin "updater_comm"... Jan 13 17:06:15 volumio-living volumio[10875]: info: Plugin mpdemulation is not enabled Jan 13 17:06:15 volumio-living volumio[10875]: info: Loading plugin "rest_api"... Jan 13 17:06:15 volumio-living volumio[10875]: info: Loading plugin "websocket"... Jan 13 17:06:15 volumio-living volumio[10875]: info: Starting Socket.io Server version 2.3.0 Jan 13 17:06:15 volumio-living volumio[10875]: info: Loading plugin "fusiondsp"... Jan 13 17:06:15 volumio-living volumio[10875]: Forking 3 albumart workers Jan 13 17:06:15 volumio-living volumio[10875]: info: Applying required configuration parameters for plugin fusiondsp Jan 13 17:06:15 volumio-living volumio[10875]: info: Loading i18n strings for locale ja Jan 13 17:06:15 volumio-living volumio[10875]: Updating browse sources language Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreCommandRouter::initPlayerControls Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:06:15 volumio-living volumio[10875]: Express server listening on port 3000 Jan 13 17:06:15 volumio-living volumio[10875]: [Metrics] WebUI: 9s 414.40ms Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreStateMachine::resetVolumioState Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreStateMachine::getcurrentVolume Jan 13 17:06:15 volumio-living volumio[10875]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:06:16 volumio-living volumio[10875]: info: Volumio Network Manager: Network status updated: 2 Jan 13 17:06:16 volumio-living volumio[10875]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Jan 13 17:06:16 volumio-living volumio[10875]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Jan 13 17:06:16 volumio-living volumio[10875]: verbose: New Socket.io Connection to 192.168.0.154:3000 from 192.168.0.23 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jan 13 17:06:16 volumio-living volumio[10875]: info: Reloading queue from file Jan 13 17:06:16 volumio-living volumio-remote-updater[552]: [2025-01-13 17:06:16] [connect] Successful connection Jan 13 17:06:16 volumio-living volumio[10875]: info: Setting Device type: Raspberry PI Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreStateMachine::setRepeat null single undefined Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreStateMachine::pushState Jan 13 17:06:16 volumio-living volumio[10875]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::volumioPushState Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreStateMachine::setRandom null Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreStateMachine::pushState Jan 13 17:06:16 volumio-living volumio[10875]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::volumioPushState Jan 13 17:06:16 volumio-living volumio[10875]: Starting albumart workers Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , initSocket Jan 13 17:06:16 volumio-living volumio[10875]: verbose: New Socket.io Connection to 192.168.0.154 from 192.168.0.140 UA: Mozilla/5.0 (Linux; Android 10; K) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/131.0.0.0 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Jan 13 17:06:16 volumio-living volumio-remote-updater[552]: [2025-01-13 17:06:16] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1736755576 101 Jan 13 17:06:16 volumio-living volumio[10875]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 5 Jan 13 17:06:16 volumio-living volumio[10875]: info: VolumeController:: Volume=100 Mute =false Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreStateMachine::pushState Jan 13 17:06:16 volumio-living volumio[10875]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::volumioPushState Jan 13 17:06:16 volumio-living volumio[10875]: Starting albumart workers Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreStateMachine::updateTrackBlock Jan 13 17:06:16 volumio-living volumio[10875]: info: CorePlayQueue::getTrackBlock Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::volumioRetrievevolume Jan 13 17:06:16 volumio-living volumio[10875]: Starting albumart workers Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::volumioGetState Jan 13 17:06:16 volumio-living volumio[10875]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:16 volumio-living volumio[10875]: info: Discovery: adding e2b8d27d-62f6-4d3d-b2b6-7a4b9d2ec0f8 Jan 13 17:06:16 volumio-living volumio[10875]: info: Discovery: Found device Volumio Jan 13 17:06:16 volumio-living volumio[10875]: info: Discovery: Connecting to remote: 192.168.0.23 Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 13 17:06:16 volumio-living volumio[10875]: info: VolumeController:: Volume=100 Mute =false Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreStateMachine::pushState Jan 13 17:06:16 volumio-living volumio[10875]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::volumioPushState Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::volumioGetVisibleSources Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::volumioGetState Jan 13 17:06:16 volumio-living volumio[10875]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 13 17:06:16 volumio-living volumio[10875]: info: Received Get System Info Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 13 17:06:16 volumio-living volumio[10875]: info: Discovery: Getting this device information Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::volumioGetState Jan 13 17:06:16 volumio-living volumio[10875]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::volumioGetState Jan 13 17:06:16 volumio-living volumio[10875]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:16 volumio-living volumio[10875]: info: Listing playlists Jan 13 17:06:16 volumio-living volumio[10875]: info: Completed loading Core Plugins Jan 13 17:06:16 volumio-living volumio[10875]: info: Preparing to generate the ALSA configuration file Jan 13 17:06:16 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:06:16 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 149. Jan 13 17:06:16 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:06:16 volumio-living volumio[10875]: info: Discovery: adding c92b663e-9b92-48a7-8bf1-18d92af07ae4 Jan 13 17:06:16 volumio-living volumio[10875]: info: Discovery: Found device Volumio-Living Jan 13 17:06:16 volumio-living volumio[10875]: info: CoreCommandRouter::volumioGetState Jan 13 17:06:16 volumio-living volumio[10875]: info: CorePlayQueue::getTrack 0 Jan 13 17:06:16 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:06:17 volumio-living volumio[10875]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jan 13 17:06:17 volumio-living volumio[10875]: info: Reading ALSA contributions from plugins. Jan 13 17:06:17 volumio-living volumio[10875]: info: Discovery: Connected to remote: 192.168.0.23 Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:17 volumio-living volumio[10875]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 13 17:06:18 volumio-living volumio[10984]: Traceback (most recent call last): Jan 13 17:06:18 volumio-living volumio[10984]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:06:18 volumio-living volumio[10984]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:06:18 volumio-living volumio[10984]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:06:18 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:06:18 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:06:20 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:06:20 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 150. Jan 13 17:06:20 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:06:20 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:06:21 volumio-living sudo[11025]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 13 17:06:21 volumio-living sudo[11025]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:21 volumio-living sudo[11025]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:21 volumio-living volumio[10875]: info: Asound.conf file unchanged, so no further update is needed Jan 13 17:06:21 volumio-living volumio[10875]: info: Output device has changed, restarting MPD Jan 13 17:06:22 volumio-living sudo[11028]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 13 17:06:22 volumio-living sudo[11028]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:22 volumio-living sudo[11028]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:22 volumio-living sudo[11031]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:06:22 volumio-living sudo[11031]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:22 volumio-living sudo[11031]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:22 volumio-living volumio[11023]: Traceback (most recent call last): Jan 13 17:06:22 volumio-living volumio[11023]: File "/data/plugins/audio_interface/fusiondsp/cgui/main.py", line 5, in Jan 13 17:06:22 volumio-living volumio[11023]: from camilladsp_plot.validate_config import CamillaValidator Jan 13 17:06:22 volumio-living volumio[11023]: ModuleNotFoundError: No module named 'camilladsp_plot' Jan 13 17:06:22 volumio-living volumio[10875]: info: Output device has changed, restarting Shairport Sync Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:06:22 volumio-living sudo[11034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:06:22 volumio-living sudo[11034]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:22 volumio-living systemd[1]: Stopping Music Player Daemon... Jan 13 17:06:22 volumio-living systemd[1]: mpd.service: Succeeded. Jan 13 17:06:22 volumio-living systemd[1]: Stopped Music Player Daemon. Jan 13 17:06:22 volumio-living volumio[10875]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:06:22 volumio-living volumio[10875]: info: ___________ START PLUGINS ___________ Jan 13 17:06:22 volumio-living volumio[10875]: info: ControllerMpd::onStart: Initializing MPD Jan 13 17:06:22 volumio-living volumio[10875]: info: Creating MPD Configuration file Jan 13 17:06:22 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:06:22 volumio-living sudo[11041]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jan 13 17:06:22 volumio-living sudo[11041]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:22 volumio-living sudo[11041]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jan 13 17:06:22 volumio-living systemd[1]: fusiondsp.service: Main process exited, code=exited, status=1/FAILURE Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:06:22 volumio-living systemd[1]: fusiondsp.service: Failed with result 'exit-code'. Jan 13 17:06:22 volumio-living volumio[10875]: info: [1736755582255] CoreMusicLibrary::Adding element DLNAサーバー Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:06:22 volumio-living sudo[11044]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jan 13 17:06:22 volumio-living sudo[11044]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:22 volumio-living systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jan 13 17:06:22 volumio-living systemd[1]: mpd.service: Succeeded. Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:22 volumio-living systemd[1]: Stopped Music Player Daemon. Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:06:22 volumio-living systemd[1]: Starting Music Player Daemon... Jan 13 17:06:22 volumio-living volumio[10875]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:06:22 volumio-living volumio[10875]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:06:22 volumio-living volumio[10875]: info: [1736755582431] CoreMusicLibrary::Adding element Last_100 Jan 13 17:06:22 volumio-living sudo[11046]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:06:22 volumio-living volumio[10875]: info: [1736755582435] CoreMusicLibrary::Adding element Webradio Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:06:22 volumio-living sudo[11046]: pam_unix(sudo:session): session opened for user root by (uid=0) Jan 13 17:06:22 volumio-living volumio[10875]: info: Initializing BBC Radios Jan 13 17:06:22 volumio-living sudo[11046]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jan 13 17:06:22 volumio-living volumio[10875]: info: Creating Spotify config file Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 13 17:06:22 volumio-living volumio[10875]: info: [1736755582677] CoreMusicLibrary::Adding element YouTube Music Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 13 17:06:22 volumio-living volumio[10875]: Cannot find translation for source YouTube Music Jan 13 17:06:22 volumio-living volumio[10875]: info: Loading i18n strings for locale ja Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jan 13 17:06:22 volumio-living volumio[10875]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 13 17:06:22 volumio-living volumio[10875]: info: FusionDsp - mixtype--------------------- Hardware Jan 13 17:06:22 volumio-living volumio[10875]: info: Volumio Calling Home Jan 13 17:06:22 volumio-living volumio[10875]: info: Preparing to generate the ALSA configuration file Jan 13 17:06:23 volumio-living mpd[11054]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jan 13 17:06:23 volumio-living mpd[11054]: output: No 'audio_output' defined in config file Jan 13 17:06:24 volumio-living mpd[11054]: output: Successfully detected a sndio audio device Jan 13 17:06:24 volumio-living mpd[11054]: zeroconf: No global port, disabling zeroconf Jan 13 17:06:24 volumio-living systemd[1]: Started Music Player Daemon. Jan 13 17:06:24 volumio-living sudo[11034]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:24 volumio-living sudo[11044]: pam_unix(sudo:session): session closed for user root Jan 13 17:06:24 volumio-living volumio[10875]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:06:24 volumio-living volumio[10875]: TypeError: Cannot read property 'off' of undefined Jan 13 17:06:24 volumio-living volumio[10875]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Jan 13 17:06:24 volumio-living volumio[10875]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jan 13 17:06:24 volumio-living volumio[10875]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Jan 13 17:06:24 volumio-living volumio[10875]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1550:29) Jan 13 17:06:24 volumio-living volumio[10875]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1395:47) Jan 13 17:06:24 volumio-living volumio[10875]: at Socket.emit (events.js:315:20) Jan 13 17:06:24 volumio-living volumio[10875]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jan 13 17:06:24 volumio-living volumio[10875]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jan 13 17:06:24 volumio-living volumio[10875]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 13 17:06:24 volumio-living systemd[1]: fusiondsp.service: Service RestartSec=2s expired, scheduling restart. Jan 13 17:06:24 volumio-living systemd[1]: fusiondsp.service: Scheduled restart job, restart counter is at 151. Jan 13 17:06:24 volumio-living systemd[1]: Stopped FusionDsp Daemon. Jan 13 17:06:24 volumio-living systemd[1]: Started FusionDsp Daemon. Jan 13 17:06:24 volumio-living sudo[11080]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-01-13 17:05 Jan 13 17:06:24 volumio-living sudo[11080]: 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="f6610a1100d2380eef33bd3004af43153c786f96" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1c0e5daee99fe3a72cc38b0d1fe6cd78d7f001fe" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 16 Dec 2024 09:30:04 AM CET" VOLUMIO_VERSION="3.785" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="fe0ac4b79aed19ccde4b5bd83d468193"