-- Logs begin at Tue 2024-06-04 14:46:45 EDT, end at Fri 2024-07-05 14:13:06 EDT. -- Jul 05 14:12:00 pool-pole-speakers-1 sudo[22086]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: Loading plugin "alarm-clock"... Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: Loading plugin "airplay_emulation"... Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: Starting Shairport Sync Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: Loading plugin "last_100"... Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: Loading plugin "webradio"... Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: Loading plugin "i2s_dacs"... Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: Loading plugin "volumiodiscovery"... Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: *** WARNING *** For more information see Jul 05 14:12:00 pool-pole-speakers-1 node[22054]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: *** WARNING *** For more information see Jul 05 14:12:00 pool-pole-speakers-1 node[22054]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:00 pool-pole-speakers-1 node[22054]: *** WARNING *** For more information see Jul 05 14:12:00 pool-pole-speakers-1 node[22054]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:00 pool-pole-speakers-1 node[22054]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:00 pool-pole-speakers-1 node[22054]: *** WARNING *** For more information see Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: Discovery: Started advertising with name: pool-pole-speakers-1 Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: Loading plugin "outputs"... Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: Loading plugin "albumart"... Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: Plugin example_plugin is not enabled Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: Loading plugin "inputs"... Jul 05 14:12:00 pool-pole-speakers-1 volumio[22054]: info: Loading plugin "updater_comm"... Jul 05 14:12:01 pool-pole-speakers-1 volumio[22054]: info: Plugin mpdemulation is not enabled Jul 05 14:12:01 pool-pole-speakers-1 volumio[22054]: info: Loading plugin "rest_api"... Jul 05 14:12:01 pool-pole-speakers-1 volumio[22054]: info: Loading plugin "websocket"... Jul 05 14:12:01 pool-pole-speakers-1 volumio[22054]: info: Starting Socket.io Server version 2.3.0 Jul 05 14:12:01 pool-pole-speakers-1 volumio[22054]: info: Loading plugin "fusiondsp"... Jul 05 14:12:01 pool-pole-speakers-1 volumio[22054]: Forking 3 albumart workers Jul 05 14:12:01 pool-pole-speakers-1 volumio[22054]: info: Applying required configuration parameters for plugin fusiondsp Jul 05 14:12:01 pool-pole-speakers-1 volumio[22054]: info: Loading i18n strings for locale en Jul 05 14:12:01 pool-pole-speakers-1 volumio[22054]: Updating browse sources language Jul 05 14:12:01 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::initPlayerControls Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: Express server listening on port 3000 Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: [Metrics] WebUI: 5s 748.73ms Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreStateMachine::resetVolumioState Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreStateMachine::getcurrentVolume Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreStateMachine::pushState Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: verbose: New Socket.io Connection to 10.100.100.173 from 172.16.100.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Engine version: 3 Transport: polling Total Clients: 1 Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: verbose: New Socket.io Connection to 10.100.100.173 from 172.16.100.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Engine version: 3 Transport: polling Total Clients: 2 Jul 05 14:12:02 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720203119 101 Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: 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: 3 Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: Starting albumart workers Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: Starting albumart workers Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: Starting albumart workers Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: Reloading queue from file Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreStateMachine::setRepeat null single undefined Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreStateMachine::pushState Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreStateMachine::setRandom null Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreStateMachine::pushState Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: Setting Device type: Raspberry PI Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: Completed loading Core Plugins Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: Preparing to generate the ALSA configuration file Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: Reading ALSA contributions from plugins. Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: Asound.conf file unchanged, so no further update is needed Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: Output device has changed, restarting MPD Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: Output device has changed, restarting Shairport Sync Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 sudo[22133]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:02 pool-pole-speakers-1 sudo[22131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:02 pool-pole-speakers-1 sudo[22133]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:02 pool-pole-speakers-1 sudo[22131]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:02 pool-pole-speakers-1 sudo[22131]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:02 pool-pole-speakers-1 systemd[1]: Stopping Music Player Daemon... Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:02 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:02 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: ___________ START PLUGINS ___________ Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: ControllerMpd::onStart: Initializing MPD Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: Creating MPD Configuration file Jul 05 14:12:02 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: [1720203122822] CoreMusicLibrary::Adding element Media Servers Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:02 pool-pole-speakers-1 sudo[22141]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:02 pool-pole-speakers-1 sudo[22141]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:02 pool-pole-speakers-1 sudo[22141]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:02 pool-pole-speakers-1 sudo[22143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:02 pool-pole-speakers-1 sudo[22143]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 05 14:12:02 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:02 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:02 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: [1720203123066] CoreMusicLibrary::Adding element Last_100 Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: [1720203123072] CoreMusicLibrary::Adding element Webradio Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: Initializing BBC Radios Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 sudo[22149]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 14:12:03 pool-pole-speakers-1 sudo[22149]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:03 pool-pole-speakers-1 sudo[22149]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: Loading i18n strings for locale en Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: FusionDsp - mixtype--------------------- None Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: Volumio Calling Home Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: Preparing to generate the ALSA configuration file Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: Discovery: adding 7239786d-2dc8-4f98-8f91-97b248242581 Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: Discovery: Found device pool-pole-speakers-1 Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: Reading ALSA contributions from plugins. Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: MPD Permissions set Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: MPD Permissions set Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: Volumio called home Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:03 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: Starting Shairport Sync Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: Starting Shairport Sync Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: Starting Shairport Sync Jul 05 14:12:04 pool-pole-speakers-1 sudo[22202]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:04 pool-pole-speakers-1 sudo[22202]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:04 pool-pole-speakers-1 sudo[22204]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:04 pool-pole-speakers-1 sudo[22204]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:04 pool-pole-speakers-1 sudo[22208]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:04 pool-pole-speakers-1 sudo[22208]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:04 pool-pole-speakers-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:04 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:04 pool-pole-speakers-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:04 pool-pole-speakers-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:04 pool-pole-speakers-1 sudo[22202]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:04 pool-pole-speakers-1 sudo[22204]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:04 pool-pole-speakers-1 sudo[22208]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: Asound.conf file unchanged, so no further update is needed Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: Output device has changed, restarting MPD Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: Output device has changed, restarting Shairport Sync Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:04 pool-pole-speakers-1 sudo[22214]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:04 pool-pole-speakers-1 sudo[22214]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:04 pool-pole-speakers-1 sudo[22214]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:04 pool-pole-speakers-1 sudo[22216]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:04 pool-pole-speakers-1 sudo[22216]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:04 pool-pole-speakers-1 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 14:12:04 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:04 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: Shairport-Sync Started Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: Error adding Membership: Error: addMembership EINVAL Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: Shairport-Sync Started Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: Shairport-Sync Started Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: MPD Permissions set Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:04 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: Starting Shairport Sync Jul 05 14:12:04 pool-pole-speakers-1 sudo[22228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:04 pool-pole-speakers-1 sudo[22228]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:04 pool-pole-speakers-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:04 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:04 pool-pole-speakers-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:04 pool-pole-speakers-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:04 pool-pole-speakers-1 sudo[22228]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:04 pool-pole-speakers-1 sudo[22222]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 14:12:04 pool-pole-speakers-1 sudo[22222]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:04 pool-pole-speakers-1 volumio[22054]: info: Shairport-Sync Started Jul 05 14:12:04 pool-pole-speakers-1 sudo[22222]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: verbose: New Socket.io Connection to 10.100.100.173 from 172.16.100.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Engine version: 3 Transport: polling Total Clients: 5 Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: Received Get System Info Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: Discovery: Getting this device information Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: Listing playlists Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: 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 Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: camilladsp service started and running in background, instance 1 Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: FusionDsp loaded Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 14:12:05 pool-pole-speakers-1 sudo[22246]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 05 14:12:05 pool-pole-speakers-1 sudo[22246]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: Adding Signal Path Element [object Object] Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: Adding fusiondspeq DSP Signal Path Element Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: info: FusionDsp - ---- installed callbackRead Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:12:05 pool-pole-speakers-1 sudo[22246]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: at onErrorNT (internal/child_process.js:465:16) Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: at runNextTicks (internal/process/task_queues.js:62:3) Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: at listOnTimeout (internal/timers.js:523:9) Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: at processTimers (internal/timers.js:497:7) { Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: errno: -2, Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: code: 'ENOENT', Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: spawnargs: [ Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: '-p', Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: 9876, Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: '-o', Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: '/tmp/camilladsp.log', Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: '-l', Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: 'warn', Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: ] Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: } Jul 05 14:12:05 pool-pole-speakers-1 volumio[22054]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:12:06 pool-pole-speakers-1 sudo[22257]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-05 14:11 Jul 05 14:12:06 pool-pole-speakers-1 sudo[22257]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:06 pool-pole-speakers-1 mpd[22232]: Jul 05 14:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 14:12:06 pool-pole-speakers-1 systemd[1]: Started Music Player Daemon. Jul 05 14:12:06 pool-pole-speakers-1 sudo[22133]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:06 pool-pole-speakers-1 sudo[22143]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:06 pool-pole-speakers-1 sudo[22216]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:06 pool-pole-speakers-1 sudo[22257]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:06 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:06] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 05 14:12:06 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:06] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 05 14:12:06 pool-pole-speakers-1 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 05 14:12:06 pool-pole-speakers-1 systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 05 14:12:06 pool-pole-speakers-1 systemd[1]: Started dynamicswap service. Jul 05 14:12:06 pool-pole-speakers-1 systemd[1]: dynamicswap.service: Succeeded. Jul 05 14:12:06 pool-pole-speakers-1 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 05 14:12:06 pool-pole-speakers-1 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 95. Jul 05 14:12:06 pool-pole-speakers-1 systemd[1]: Stopped Volumio Backend Module. Jul 05 14:12:06 pool-pole-speakers-1 systemd[1]: Started Volumio Backend Module. Jul 05 14:12:06 pool-pole-speakers-1 systemd[1]: Started dynamicswap service. Jul 05 14:12:06 pool-pole-speakers-1 systemd[1]: dynamicswap.service: Succeeded. Jul 05 14:12:08 pool-pole-speakers-1 volumio[22269]: info: ------------------------------------------- Jul 05 14:12:08 pool-pole-speakers-1 volumio[22269]: info: ----- Volumio3 ---- Jul 05 14:12:08 pool-pole-speakers-1 volumio[22269]: info: ------------------------------------------- Jul 05 14:12:08 pool-pole-speakers-1 volumio[22269]: info: ----- System startup ---- Jul 05 14:12:08 pool-pole-speakers-1 volumio[22269]: info: ------------------------------------------- Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: MYVOLUMIO Environment detected Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Plugin folders cleanup Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Scanning into folder /volumio/app/plugins/ Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Scanning category audio_interface Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Scanning category miscellanea Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Scanning category music_service Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Scanning category plugins.json Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Scanning category system_controller Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Scanning category user_interface Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Scanning into folder /data/plugins/ Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Scanning category audio_interface Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Plugin folders cleanup completed Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: ------------------------------------------- Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: ----- Core plugins startup ---- Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: ------------------------------------------- Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Adding plugin upnp to MyMusic Plugins Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Loading plugins from folder /data/plugins/ Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "system"... Jul 05 14:12:09 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "appearance"... Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "network"... Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Refreshing Cached IP Addresses Jul 05 14:12:11 pool-pole-speakers-1 sudo[22334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 14:12:11 pool-pole-speakers-1 sudo[22334]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "services"... Jul 05 14:12:11 pool-pole-speakers-1 sudo[22336]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 14:12:11 pool-pole-speakers-1 sudo[22336]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "alsa_controller"... Jul 05 14:12:11 pool-pole-speakers-1 sudo[22336]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:11 pool-pole-speakers-1 sudo[22339]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 14:12:11 pool-pole-speakers-1 sudo[22339]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:11 pool-pole-speakers-1 sudo[22334]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "wizard"... Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "networkfs"... Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Starting Udev Watcher for removable devices Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Ignoring mount for partition: boot Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Ignoring mount for partition: volumio Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Ignoring mount for partition: volumio_data Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "volumio_command_line_client"... Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "upnp"... Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: [1720203131336] Starting Upmpd Daemon Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "my_music"... Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "mpd"... Jul 05 14:12:11 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:11] [connect] Successful connection Jul 05 14:12:11 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "upnp_browser"... Jul 05 14:12:11 pool-pole-speakers-1 sudo[22339]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "alarm-clock"... Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "airplay_emulation"... Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Starting Shairport Sync Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "last_100"... Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "webradio"... Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "i2s_dacs"... Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "volumiodiscovery"... Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: *** WARNING *** For more information see Jul 05 14:12:12 pool-pole-speakers-1 node[22269]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: *** WARNING *** For more information see Jul 05 14:12:12 pool-pole-speakers-1 node[22269]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:12 pool-pole-speakers-1 node[22269]: *** WARNING *** For more information see Jul 05 14:12:12 pool-pole-speakers-1 node[22269]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:12 pool-pole-speakers-1 node[22269]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:12 pool-pole-speakers-1 node[22269]: *** WARNING *** For more information see Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Discovery: Started advertising with name: pool-pole-speakers-1 Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "outputs"... Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "albumart"... Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Plugin example_plugin is not enabled Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "inputs"... Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "updater_comm"... Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Plugin mpdemulation is not enabled Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "rest_api"... Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "websocket"... Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Starting Socket.io Server version 2.3.0 Jul 05 14:12:12 pool-pole-speakers-1 volumio[22269]: info: Loading plugin "fusiondsp"... Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: Forking 3 albumart workers Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: Applying required configuration parameters for plugin fusiondsp Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: Loading i18n strings for locale en Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: Updating browse sources language Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::initPlayerControls Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: Express server listening on port 3000 Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: [Metrics] WebUI: 5s 688.40ms Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreStateMachine::resetVolumioState Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreStateMachine::getcurrentVolume Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreStateMachine::pushState Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:13 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:14 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:14] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720203131 101 Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: 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: 1 Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Reloading queue from file Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreStateMachine::setRepeat null single undefined Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreStateMachine::pushState Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreStateMachine::setRandom null Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreStateMachine::pushState Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Setting Device type: Raspberry PI Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Completed loading Core Plugins Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Preparing to generate the ALSA configuration file Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Reading ALSA contributions from plugins. Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: Starting albumart workers Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Asound.conf file unchanged, so no further update is needed Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Output device has changed, restarting MPD Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: Starting albumart workers Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Output device has changed, restarting Shairport Sync Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:14 pool-pole-speakers-1 sudo[22386]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:14 pool-pole-speakers-1 sudo[22386]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:14 pool-pole-speakers-1 sudo[22387]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:14 pool-pole-speakers-1 sudo[22387]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:14 pool-pole-speakers-1 sudo[22386]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: ___________ START PLUGINS ___________ Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: ControllerMpd::onStart: Initializing MPD Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Creating MPD Configuration file Jul 05 14:12:14 pool-pole-speakers-1 systemd[1]: Stopping Music Player Daemon... Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: [1720203134501] CoreMusicLibrary::Adding element Media Servers Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:14 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: Starting albumart workers Jul 05 14:12:14 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:14 pool-pole-speakers-1 sudo[22395]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:14 pool-pole-speakers-1 sudo[22395]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:14 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:14 pool-pole-speakers-1 sudo[22396]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:14 pool-pole-speakers-1 sudo[22396]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:14 pool-pole-speakers-1 sudo[22395]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:14 pool-pole-speakers-1 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 05 14:12:14 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:14 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:14 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: [1720203134727] CoreMusicLibrary::Adding element Last_100 Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: [1720203134732] CoreMusicLibrary::Adding element Webradio Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Initializing BBC Radios Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:14 pool-pole-speakers-1 sudo[22406]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 14:12:14 pool-pole-speakers-1 sudo[22406]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:14 pool-pole-speakers-1 sudo[22406]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Loading i18n strings for locale en Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: FusionDsp - mixtype--------------------- None Jul 05 14:12:14 pool-pole-speakers-1 volumio[22269]: info: Volumio Calling Home Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: Preparing to generate the ALSA configuration file Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: Discovery: adding 7239786d-2dc8-4f98-8f91-97b248242581 Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: Discovery: Found device pool-pole-speakers-1 Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: Reading ALSA contributions from plugins. Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: verbose: New Socket.io Connection to 10.100.100.173 from 172.16.100.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Engine version: 3 Transport: polling Total Clients: 2 Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: MPD Permissions set Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: MPD Permissions set Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::volumioGetVisibleSources Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: Received Get System Info Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: Discovery: Getting this device information Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:15 pool-pole-speakers-1 volumio[22269]: info: Listing playlists Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: Volumio called home Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: Starting Shairport Sync Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: Starting Shairport Sync Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: Starting Shairport Sync Jul 05 14:12:16 pool-pole-speakers-1 sudo[22455]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:16 pool-pole-speakers-1 sudo[22455]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:16 pool-pole-speakers-1 sudo[22457]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:16 pool-pole-speakers-1 sudo[22457]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:16 pool-pole-speakers-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:16 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:16 pool-pole-speakers-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:16 pool-pole-speakers-1 sudo[22461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:16 pool-pole-speakers-1 sudo[22461]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::getUIConfigOnPlugin Jul 05 14:12:16 pool-pole-speakers-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:16 pool-pole-speakers-1 sudo[22455]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:16 pool-pole-speakers-1 sudo[22457]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: Asound.conf file unchanged, so no further update is needed Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: Output device has changed, restarting MPD Jul 05 14:12:16 pool-pole-speakers-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:16 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 05 14:12:16 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:16 pool-pole-speakers-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:16 pool-pole-speakers-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:16 pool-pole-speakers-1 sudo[22461]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:16 pool-pole-speakers-1 sudo[22467]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:16 pool-pole-speakers-1 sudo[22467]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:16 pool-pole-speakers-1 sudo[22467]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: Output device has changed, restarting Shairport Sync Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:16 pool-pole-speakers-1 sudo[22470]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:16 pool-pole-speakers-1 sudo[22470]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:16 pool-pole-speakers-1 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 14:12:16 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:16 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:16 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: Shairport-Sync Started Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: Error adding Membership: Error: addMembership EINVAL Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: Shairport-Sync Started Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: MPD Permissions set Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: Shairport-Sync Started Jul 05 14:12:16 pool-pole-speakers-1 sudo[22477]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 14:12:16 pool-pole-speakers-1 sudo[22477]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:16 pool-pole-speakers-1 sudo[22477]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: 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 Jul 05 14:12:16 pool-pole-speakers-1 volumio[22269]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: info: camilladsp service started and running in background, instance 1 Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: info: FusionDsp loaded Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 14:12:17 pool-pole-speakers-1 sudo[22495]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 05 14:12:17 pool-pole-speakers-1 sudo[22495]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: info: Adding Signal Path Element [object Object] Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: info: Adding fusiondspeq DSP Signal Path Element Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: info: FusionDsp - ---- installed callbackRead Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:12:17 pool-pole-speakers-1 sudo[22495]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: at onErrorNT (internal/child_process.js:465:16) Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: at runNextTicks (internal/process/task_queues.js:62:3) Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: at listOnTimeout (internal/timers.js:523:9) Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: at processTimers (internal/timers.js:497:7) { Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: errno: -2, Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: code: 'ENOENT', Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: spawnargs: [ Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: '-p', Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: 9876, Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: '-o', Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: '/tmp/camilladsp.log', Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: '-l', Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: 'warn', Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: ] Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: } Jul 05 14:12:17 pool-pole-speakers-1 volumio[22269]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:12:17 pool-pole-speakers-1 sudo[22506]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-05 14:11 Jul 05 14:12:17 pool-pole-speakers-1 sudo[22506]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:18 pool-pole-speakers-1 sudo[22506]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:18 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:18] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 05 14:12:18 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:18] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 05 14:12:18 pool-pole-speakers-1 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 05 14:12:18 pool-pole-speakers-1 sudo[22470]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:18 pool-pole-speakers-1 sudo[22396]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:18 pool-pole-speakers-1 sudo[22387]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:18 pool-pole-speakers-1 systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 05 14:12:18 pool-pole-speakers-1 systemd[1]: Started dynamicswap service. Jul 05 14:12:18 pool-pole-speakers-1 systemd[1]: dynamicswap.service: Succeeded. Jul 05 14:12:18 pool-pole-speakers-1 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 05 14:12:18 pool-pole-speakers-1 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 96. Jul 05 14:12:18 pool-pole-speakers-1 systemd[1]: Started dynamicswap service. Jul 05 14:12:18 pool-pole-speakers-1 systemd[1]: Stopped Volumio Backend Module. Jul 05 14:12:18 pool-pole-speakers-1 systemd[1]: Started Volumio Backend Module. Jul 05 14:12:18 pool-pole-speakers-1 systemd[1]: dynamicswap.service: Succeeded. Jul 05 14:12:18 pool-pole-speakers-1 mpd[22480]: decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 14:12:18 pool-pole-speakers-1 mpd[22480]: output: No 'audio_output' defined in config file Jul 05 14:12:18 pool-pole-speakers-1 mpd[22480]: output: Successfully detected a sndio audio device Jul 05 14:12:18 pool-pole-speakers-1 mpd[22480]: zeroconf: No global port, disabling zeroconf Jul 05 14:12:18 pool-pole-speakers-1 systemd[1]: Started Music Player Daemon. Jul 05 14:12:20 pool-pole-speakers-1 volumio[22517]: info: ------------------------------------------- Jul 05 14:12:20 pool-pole-speakers-1 volumio[22517]: info: ----- Volumio3 ---- Jul 05 14:12:20 pool-pole-speakers-1 volumio[22517]: info: ------------------------------------------- Jul 05 14:12:20 pool-pole-speakers-1 volumio[22517]: info: ----- System startup ---- Jul 05 14:12:20 pool-pole-speakers-1 volumio[22517]: info: ------------------------------------------- Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: MYVOLUMIO Environment detected Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Plugin folders cleanup Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Scanning into folder /volumio/app/plugins/ Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Scanning category audio_interface Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Scanning category miscellanea Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Scanning category music_service Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Scanning category plugins.json Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Scanning category system_controller Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Scanning category user_interface Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Scanning into folder /data/plugins/ Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Scanning category audio_interface Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Plugin folders cleanup completed Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: ------------------------------------------- Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: ----- Core plugins startup ---- Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: ------------------------------------------- Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Adding plugin upnp to MyMusic Plugins Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Loading plugins from folder /data/plugins/ Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "system"... Jul 05 14:12:21 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "appearance"... Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "network"... Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: Refreshing Cached IP Addresses Jul 05 14:12:22 pool-pole-speakers-1 sudo[22545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 14:12:22 pool-pole-speakers-1 sudo[22545]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:22 pool-pole-speakers-1 sudo[22547]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 14:12:22 pool-pole-speakers-1 sudo[22547]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "services"... Jul 05 14:12:22 pool-pole-speakers-1 sudo[22547]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "alsa_controller"... Jul 05 14:12:22 pool-pole-speakers-1 sudo[22545]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:22 pool-pole-speakers-1 sudo[22551]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 14:12:22 pool-pole-speakers-1 sudo[22551]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "wizard"... Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "networkfs"... Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: Starting Udev Watcher for removable devices Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: Ignoring mount for partition: boot Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: Ignoring mount for partition: volumio Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: Ignoring mount for partition: volumio_data Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "volumio_command_line_client"... Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "upnp"... Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: [1720203142987] Starting Upmpd Daemon Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "my_music"... Jul 05 14:12:22 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "mpd"... Jul 05 14:12:23 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:23] [connect] Successful connection Jul 05 14:12:23 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "upnp_browser"... Jul 05 14:12:23 pool-pole-speakers-1 sudo[22551]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:23 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "alarm-clock"... Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "airplay_emulation"... Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Starting Shairport Sync Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "last_100"... Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "webradio"... Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "i2s_dacs"... Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "volumiodiscovery"... Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:24 pool-pole-speakers-1 node[22517]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:24 pool-pole-speakers-1 node[22517]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: *** WARNING *** For more information see Jul 05 14:12:24 pool-pole-speakers-1 node[22517]: *** WARNING *** For more information see Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:24 pool-pole-speakers-1 node[22517]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:24 pool-pole-speakers-1 node[22517]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: *** WARNING *** For more information see Jul 05 14:12:24 pool-pole-speakers-1 node[22517]: *** WARNING *** For more information see Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Discovery: Started advertising with name: pool-pole-speakers-1 Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "outputs"... Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "albumart"... Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Plugin example_plugin is not enabled Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "inputs"... Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "updater_comm"... Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Plugin mpdemulation is not enabled Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "rest_api"... Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "websocket"... Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Starting Socket.io Server version 2.3.0 Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: info: Loading plugin "fusiondsp"... Jul 05 14:12:24 pool-pole-speakers-1 volumio[22517]: Forking 3 albumart workers Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: Applying required configuration parameters for plugin fusiondsp Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: Loading i18n strings for locale en Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: Updating browse sources language Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::initPlayerControls Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: Express server listening on port 3000 Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: [Metrics] WebUI: 5s 847.23ms Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreStateMachine::resetVolumioState Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreStateMachine::getcurrentVolume Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreStateMachine::pushState Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: verbose: New Socket.io Connection to 10.100.100.173 from 172.16.100.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Engine version: 3 Transport: polling Total Clients: 1 Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: verbose: New Socket.io Connection to 10.100.100.173 from 172.16.100.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Engine version: 3 Transport: polling Total Clients: 2 Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: Starting albumart workers Jul 05 14:12:25 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:25] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720203143 101 Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: Starting albumart workers Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: 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: 3 Jul 05 14:12:25 pool-pole-speakers-1 volumio[22517]: verbose: New Socket.io Connection to 10.100.100.173 from 172.16.100.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Engine version: 3 Transport: polling Total Clients: 4 Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: Reloading queue from file Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreStateMachine::setRepeat null single undefined Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreStateMachine::pushState Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: Starting albumart workers Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreStateMachine::setRandom null Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreStateMachine::pushState Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: Setting Device type: Raspberry PI Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: Completed loading Core Plugins Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: Preparing to generate the ALSA configuration file Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: Reading ALSA contributions from plugins. Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: Asound.conf file unchanged, so no further update is needed Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: Output device has changed, restarting MPD Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: Output device has changed, restarting Shairport Sync Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:26 pool-pole-speakers-1 sudo[22598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:26 pool-pole-speakers-1 sudo[22598]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:26 pool-pole-speakers-1 sudo[22598]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:26 pool-pole-speakers-1 sudo[22599]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:26 pool-pole-speakers-1 sudo[22599]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: ___________ START PLUGINS ___________ Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: ControllerMpd::onStart: Initializing MPD Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: Creating MPD Configuration file Jul 05 14:12:26 pool-pole-speakers-1 systemd[1]: Stopping Music Player Daemon... Jul 05 14:12:26 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:26 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: [1720203146453] CoreMusicLibrary::Adding element Media Servers Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:26 pool-pole-speakers-1 sudo[22609]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:26 pool-pole-speakers-1 sudo[22609]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:26 pool-pole-speakers-1 sudo[22607]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:26 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:26 pool-pole-speakers-1 sudo[22607]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:26 pool-pole-speakers-1 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 05 14:12:26 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:26 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:26 pool-pole-speakers-1 sudo[22607]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:26 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:26 pool-pole-speakers-1 sudo[22612]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 14:12:26 pool-pole-speakers-1 sudo[22612]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:26 pool-pole-speakers-1 sudo[22612]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: [1720203146850] CoreMusicLibrary::Adding element Last_100 Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: [1720203146857] CoreMusicLibrary::Adding element Webradio Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: Initializing BBC Radios Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 05 14:12:26 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: Loading i18n strings for locale en Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: FusionDsp - mixtype--------------------- None Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: Volumio Calling Home Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: Preparing to generate the ALSA configuration file Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: Discovery: adding 7239786d-2dc8-4f98-8f91-97b248242581 Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: Discovery: Found device pool-pole-speakers-1 Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: Reading ALSA contributions from plugins. Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: MPD Permissions set Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: MPD Permissions set Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: Volumio called home Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: Starting Shairport Sync Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: Starting Shairport Sync Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: Starting Shairport Sync Jul 05 14:12:27 pool-pole-speakers-1 sudo[22667]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:27 pool-pole-speakers-1 sudo[22667]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:27 pool-pole-speakers-1 sudo[22669]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:27 pool-pole-speakers-1 sudo[22669]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:27 pool-pole-speakers-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:27 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:27 pool-pole-speakers-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:27 pool-pole-speakers-1 sudo[22673]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:27 pool-pole-speakers-1 sudo[22673]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:27 pool-pole-speakers-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:27 pool-pole-speakers-1 sudo[22667]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:27 pool-pole-speakers-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:27 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 05 14:12:27 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:27 pool-pole-speakers-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: Asound.conf file unchanged, so no further update is needed Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: Output device has changed, restarting MPD Jul 05 14:12:27 pool-pole-speakers-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:27 pool-pole-speakers-1 sudo[22669]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:27 pool-pole-speakers-1 sudo[22673]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:27 pool-pole-speakers-1 sudo[22678]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:27 pool-pole-speakers-1 sudo[22678]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: Output device has changed, restarting Shairport Sync Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:27 pool-pole-speakers-1 sudo[22678]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:27 pool-pole-speakers-1 sudo[22680]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:27 pool-pole-speakers-1 sudo[22680]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:28 pool-pole-speakers-1 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 14:12:28 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:28 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: Shairport-Sync Started Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: Error adding Membership: Error: addMembership EINVAL Jul 05 14:12:28 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: MPD Permissions set Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: Shairport-Sync Started Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: Shairport-Sync Started Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:28 pool-pole-speakers-1 sudo[22688]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 14:12:28 pool-pole-speakers-1 sudo[22688]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: Starting Shairport Sync Jul 05 14:12:28 pool-pole-speakers-1 sudo[22688]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:28 pool-pole-speakers-1 sudo[22696]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:28 pool-pole-speakers-1 sudo[22696]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:28 pool-pole-speakers-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:28 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:28 pool-pole-speakers-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:28 pool-pole-speakers-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:28 pool-pole-speakers-1 sudo[22696]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:28 pool-pole-speakers-1 volumio[22517]: info: Shairport-Sync Started Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: 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 Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: info: camilladsp service started and running in background, instance 1 Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: info: FusionDsp loaded Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 14:12:29 pool-pole-speakers-1 sudo[22712]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 05 14:12:29 pool-pole-speakers-1 sudo[22712]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: info: Adding Signal Path Element [object Object] Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: info: Adding fusiondspeq DSP Signal Path Element Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: info: FusionDsp - ---- installed callbackRead Jul 05 14:12:29 pool-pole-speakers-1 sudo[22712]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: at onErrorNT (internal/child_process.js:465:16) Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: errno: -2, Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: code: 'ENOENT', Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: spawnargs: [ Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: '-p', Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: 9876, Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: '-o', Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: '/tmp/camilladsp.log', Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: '-l', Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: 'warn', Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: ] Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: } Jul 05 14:12:29 pool-pole-speakers-1 volumio[22517]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:12:29 pool-pole-speakers-1 sudo[22723]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-05 14:11 Jul 05 14:12:29 pool-pole-speakers-1 sudo[22723]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:30 pool-pole-speakers-1 mpd[22694]: Jul 05 14:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 14:12:30 pool-pole-speakers-1 systemd[1]: Started Music Player Daemon. Jul 05 14:12:30 pool-pole-speakers-1 sudo[22609]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:30 pool-pole-speakers-1 sudo[22680]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:30 pool-pole-speakers-1 sudo[22599]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:30 pool-pole-speakers-1 sudo[22723]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:30 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:30] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 05 14:12:30 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:30] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 05 14:12:30 pool-pole-speakers-1 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 05 14:12:30 pool-pole-speakers-1 systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 05 14:12:30 pool-pole-speakers-1 systemd[1]: Started dynamicswap service. Jul 05 14:12:30 pool-pole-speakers-1 systemd[1]: dynamicswap.service: Succeeded. Jul 05 14:12:30 pool-pole-speakers-1 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 05 14:12:30 pool-pole-speakers-1 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 97. Jul 05 14:12:30 pool-pole-speakers-1 systemd[1]: Started dynamicswap service. Jul 05 14:12:30 pool-pole-speakers-1 systemd[1]: Stopped Volumio Backend Module. Jul 05 14:12:30 pool-pole-speakers-1 systemd[1]: Started Volumio Backend Module. Jul 05 14:12:30 pool-pole-speakers-1 systemd[1]: dynamicswap.service: Succeeded. Jul 05 14:12:32 pool-pole-speakers-1 volumio[22736]: info: ------------------------------------------- Jul 05 14:12:32 pool-pole-speakers-1 volumio[22736]: info: ----- Volumio3 ---- Jul 05 14:12:32 pool-pole-speakers-1 volumio[22736]: info: ------------------------------------------- Jul 05 14:12:32 pool-pole-speakers-1 volumio[22736]: info: ----- System startup ---- Jul 05 14:12:32 pool-pole-speakers-1 volumio[22736]: info: ------------------------------------------- Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: MYVOLUMIO Environment detected Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Plugin folders cleanup Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Scanning into folder /volumio/app/plugins/ Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Scanning category audio_interface Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Scanning category miscellanea Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Scanning category music_service Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Scanning category plugins.json Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Scanning category system_controller Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Scanning category user_interface Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Scanning into folder /data/plugins/ Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Scanning category audio_interface Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Plugin folders cleanup completed Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: ------------------------------------------- Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: ----- Core plugins startup ---- Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: ------------------------------------------- Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Adding plugin upnp to MyMusic Plugins Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Loading plugins from folder /data/plugins/ Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "system"... Jul 05 14:12:33 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "appearance"... Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "network"... Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Refreshing Cached IP Addresses Jul 05 14:12:35 pool-pole-speakers-1 sudo[22762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 14:12:35 pool-pole-speakers-1 sudo[22762]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "services"... Jul 05 14:12:35 pool-pole-speakers-1 sudo[22764]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 14:12:35 pool-pole-speakers-1 sudo[22764]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "alsa_controller"... Jul 05 14:12:35 pool-pole-speakers-1 sudo[22764]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:35 pool-pole-speakers-1 sudo[22762]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:35 pool-pole-speakers-1 sudo[22767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 14:12:35 pool-pole-speakers-1 sudo[22767]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "wizard"... Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "networkfs"... Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Starting Udev Watcher for removable devices Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Ignoring mount for partition: boot Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Ignoring mount for partition: volumio Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Ignoring mount for partition: volumio_data Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "volumio_command_line_client"... Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "upnp"... Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: [1720203155273] Starting Upmpd Daemon Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "my_music"... Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "mpd"... Jul 05 14:12:35 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:35] [connect] Successful connection Jul 05 14:12:35 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "upnp_browser"... Jul 05 14:12:35 pool-pole-speakers-1 sudo[22767]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "alarm-clock"... Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "airplay_emulation"... Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Starting Shairport Sync Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "last_100"... Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "webradio"... Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "i2s_dacs"... Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "volumiodiscovery"... Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: *** WARNING *** For more information see Jul 05 14:12:36 pool-pole-speakers-1 node[22736]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: *** WARNING *** For more information see Jul 05 14:12:36 pool-pole-speakers-1 node[22736]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:36 pool-pole-speakers-1 node[22736]: *** WARNING *** For more information see Jul 05 14:12:36 pool-pole-speakers-1 node[22736]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:36 pool-pole-speakers-1 node[22736]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:36 pool-pole-speakers-1 node[22736]: *** WARNING *** For more information see Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Discovery: Started advertising with name: pool-pole-speakers-1 Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "outputs"... Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "albumart"... Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Plugin example_plugin is not enabled Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "inputs"... Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "updater_comm"... Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Plugin mpdemulation is not enabled Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "rest_api"... Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "websocket"... Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Starting Socket.io Server version 2.3.0 Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: info: Loading plugin "fusiondsp"... Jul 05 14:12:36 pool-pole-speakers-1 volumio[22736]: Forking 3 albumart workers Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: Applying required configuration parameters for plugin fusiondsp Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: Loading i18n strings for locale en Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: Updating browse sources language Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::initPlayerControls Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: Express server listening on port 3000 Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: [Metrics] WebUI: 5s 628.80ms Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreStateMachine::resetVolumioState Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreStateMachine::getcurrentVolume Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreStateMachine::pushState Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:37 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: verbose: New Socket.io Connection to 10.100.100.173 from 172.16.100.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Engine version: 3 Transport: polling Total Clients: 1 Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: verbose: New Socket.io Connection to 10.100.100.173 from 172.16.100.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Engine version: 3 Transport: polling Total Clients: 2 Jul 05 14:12:38 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:38] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720203155 101 Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: 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: 3 Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: Reloading queue from file Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: Starting albumart workers Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreStateMachine::setRepeat null single undefined Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreStateMachine::pushState Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreStateMachine::setRandom null Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreStateMachine::pushState Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: Setting Device type: Raspberry PI Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: Completed loading Core Plugins Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: Preparing to generate the ALSA configuration file Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: Starting albumart workers Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: Reading ALSA contributions from plugins. Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: Starting albumart workers Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: Asound.conf file unchanged, so no further update is needed Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: Output device has changed, restarting MPD Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: Output device has changed, restarting Shairport Sync Jul 05 14:12:38 pool-pole-speakers-1 sudo[22813]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:38 pool-pole-speakers-1 sudo[22813]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:38 pool-pole-speakers-1 sudo[22813]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:38 pool-pole-speakers-1 sudo[22817]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:38 pool-pole-speakers-1 sudo[22817]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:38 pool-pole-speakers-1 systemd[1]: Stopping Music Player Daemon... Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: ___________ START PLUGINS ___________ Jul 05 14:12:38 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:38 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: ControllerMpd::onStart: Initializing MPD Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: Creating MPD Configuration file Jul 05 14:12:38 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:38 pool-pole-speakers-1 sudo[22824]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:38 pool-pole-speakers-1 sudo[22824]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: [1720203158661] CoreMusicLibrary::Adding element Media Servers Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:38 pool-pole-speakers-1 sudo[22824]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:38 pool-pole-speakers-1 sudo[22826]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:38 pool-pole-speakers-1 sudo[22826]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:38 pool-pole-speakers-1 sudo[22821]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 14:12:38 pool-pole-speakers-1 sudo[22821]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:38 pool-pole-speakers-1 sudo[22821]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:38 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:38 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:38 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: [1720203158940] CoreMusicLibrary::Adding element Last_100 Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: [1720203158944] CoreMusicLibrary::Adding element Webradio Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:38 pool-pole-speakers-1 volumio[22736]: info: Initializing BBC Radios Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 sudo[22832]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 14:12:39 pool-pole-speakers-1 sudo[22832]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:39 pool-pole-speakers-1 sudo[22832]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: Loading i18n strings for locale en Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: FusionDsp - mixtype--------------------- None Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: Volumio Calling Home Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: Preparing to generate the ALSA configuration file Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: Discovery: adding 7239786d-2dc8-4f98-8f91-97b248242581 Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: Discovery: Found device pool-pole-speakers-1 Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: Reading ALSA contributions from plugins. Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: MPD Permissions set Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: MPD Permissions set Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: Volumio called home Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: Starting Shairport Sync Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: Starting Shairport Sync Jul 05 14:12:39 pool-pole-speakers-1 sudo[22884]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: Starting Shairport Sync Jul 05 14:12:39 pool-pole-speakers-1 sudo[22884]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:39 pool-pole-speakers-1 sudo[22886]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:39 pool-pole-speakers-1 sudo[22886]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:39 pool-pole-speakers-1 volumio[22736]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:39 pool-pole-speakers-1 sudo[22889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:39 pool-pole-speakers-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:39 pool-pole-speakers-1 sudo[22889]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:39 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:39 pool-pole-speakers-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:40 pool-pole-speakers-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:40 pool-pole-speakers-1 sudo[22884]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: Asound.conf file unchanged, so no further update is needed Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: Output device has changed, restarting MPD Jul 05 14:12:40 pool-pole-speakers-1 sudo[22886]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:40 pool-pole-speakers-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:40 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Main process exited, code=killed, status=15/TERM Jul 05 14:12:40 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:40 pool-pole-speakers-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:40 pool-pole-speakers-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: Output device has changed, restarting Shairport Sync Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:40 pool-pole-speakers-1 sudo[22889]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:40 pool-pole-speakers-1 sudo[22895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:40 pool-pole-speakers-1 sudo[22895]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:40 pool-pole-speakers-1 sudo[22895]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:40 pool-pole-speakers-1 sudo[22897]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:40 pool-pole-speakers-1 sudo[22897]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:40 pool-pole-speakers-1 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 14:12:40 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:40 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: Shairport-Sync Started Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: Error adding Membership: Error: addMembership EINVAL Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: MPD Permissions set Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: Shairport-Sync Started Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: Shairport-Sync Started Jul 05 14:12:40 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: Starting Shairport Sync Jul 05 14:12:40 pool-pole-speakers-1 sudo[22912]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:40 pool-pole-speakers-1 sudo[22912]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:40 pool-pole-speakers-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:40 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:40 pool-pole-speakers-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:40 pool-pole-speakers-1 sudo[22905]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 14:12:40 pool-pole-speakers-1 sudo[22905]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:40 pool-pole-speakers-1 sudo[22905]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:40 pool-pole-speakers-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:40 pool-pole-speakers-1 sudo[22912]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:40 pool-pole-speakers-1 volumio[22736]: info: Shairport-Sync Started Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: 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 Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: info: camilladsp service started and running in background, instance 1 Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: info: FusionDsp loaded Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 14:12:41 pool-pole-speakers-1 sudo[22930]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 05 14:12:41 pool-pole-speakers-1 sudo[22930]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: info: Adding Signal Path Element [object Object] Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: info: Adding fusiondspeq DSP Signal Path Element Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: info: FusionDsp - ---- installed callbackRead Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:12:41 pool-pole-speakers-1 sudo[22930]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: at onErrorNT (internal/child_process.js:465:16) Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: errno: -2, Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: code: 'ENOENT', Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: spawnargs: [ Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: '-p', Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: 9876, Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: '-o', Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: '/tmp/camilladsp.log', Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: '-l', Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: 'warn', Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: ] Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: } Jul 05 14:12:41 pool-pole-speakers-1 volumio[22736]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:12:42 pool-pole-speakers-1 sudo[22941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-05 14:11 Jul 05 14:12:42 pool-pole-speakers-1 sudo[22941]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:42 pool-pole-speakers-1 mpd[22917]: Jul 05 14:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 14:12:42 pool-pole-speakers-1 systemd[1]: Started Music Player Daemon. Jul 05 14:12:42 pool-pole-speakers-1 sudo[22897]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:42 pool-pole-speakers-1 sudo[22826]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:42 pool-pole-speakers-1 sudo[22817]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:42 pool-pole-speakers-1 sudo[22941]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:42 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:42] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 05 14:12:42 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:42] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 05 14:12:42 pool-pole-speakers-1 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 05 14:12:42 pool-pole-speakers-1 systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 05 14:12:42 pool-pole-speakers-1 systemd[1]: Started dynamicswap service. Jul 05 14:12:42 pool-pole-speakers-1 systemd[1]: dynamicswap.service: Succeeded. Jul 05 14:12:42 pool-pole-speakers-1 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 05 14:12:42 pool-pole-speakers-1 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 98. Jul 05 14:12:42 pool-pole-speakers-1 systemd[1]: Started dynamicswap service. Jul 05 14:12:42 pool-pole-speakers-1 systemd[1]: Stopped Volumio Backend Module. Jul 05 14:12:42 pool-pole-speakers-1 systemd[1]: Started Volumio Backend Module. Jul 05 14:12:42 pool-pole-speakers-1 systemd[1]: dynamicswap.service: Succeeded. Jul 05 14:12:44 pool-pole-speakers-1 volumio[22954]: info: ------------------------------------------- Jul 05 14:12:44 pool-pole-speakers-1 volumio[22954]: info: ----- Volumio3 ---- Jul 05 14:12:44 pool-pole-speakers-1 volumio[22954]: info: ------------------------------------------- Jul 05 14:12:44 pool-pole-speakers-1 volumio[22954]: info: ----- System startup ---- Jul 05 14:12:44 pool-pole-speakers-1 volumio[22954]: info: ------------------------------------------- Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: MYVOLUMIO Environment detected Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Plugin folders cleanup Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Scanning into folder /volumio/app/plugins/ Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Scanning category audio_interface Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Scanning category miscellanea Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Scanning category music_service Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Scanning category plugins.json Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Scanning category system_controller Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Scanning category user_interface Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Scanning into folder /data/plugins/ Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Scanning category audio_interface Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Plugin folders cleanup completed Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: ------------------------------------------- Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: ----- Core plugins startup ---- Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: ------------------------------------------- Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Adding plugin upnp to MyMusic Plugins Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Loading plugins from folder /data/plugins/ Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "system"... Jul 05 14:12:45 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "appearance"... Jul 05 14:12:46 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "network"... Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: Refreshing Cached IP Addresses Jul 05 14:12:47 pool-pole-speakers-1 sudo[22981]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 14:12:47 pool-pole-speakers-1 sudo[22981]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "services"... Jul 05 14:12:47 pool-pole-speakers-1 sudo[22983]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 14:12:47 pool-pole-speakers-1 sudo[22983]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "alsa_controller"... Jul 05 14:12:47 pool-pole-speakers-1 sudo[22983]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:47 pool-pole-speakers-1 sudo[22986]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 14:12:47 pool-pole-speakers-1 sudo[22986]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:47 pool-pole-speakers-1 sudo[22981]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "wizard"... Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "networkfs"... Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: Starting Udev Watcher for removable devices Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: Ignoring mount for partition: boot Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: Ignoring mount for partition: volumio Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: Ignoring mount for partition: volumio_data Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "volumio_command_line_client"... Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "upnp"... Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: [1720203167242] Starting Upmpd Daemon Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "my_music"... Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "mpd"... Jul 05 14:12:47 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:47] [connect] Successful connection Jul 05 14:12:47 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "upnp_browser"... Jul 05 14:12:47 pool-pole-speakers-1 sudo[22986]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "alarm-clock"... Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "airplay_emulation"... Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Starting Shairport Sync Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "last_100"... Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "webradio"... Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "i2s_dacs"... Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "volumiodiscovery"... Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: *** WARNING *** For more information see Jul 05 14:12:48 pool-pole-speakers-1 node[22954]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: *** WARNING *** For more information see Jul 05 14:12:48 pool-pole-speakers-1 node[22954]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:48 pool-pole-speakers-1 node[22954]: *** WARNING *** For more information see Jul 05 14:12:48 pool-pole-speakers-1 node[22954]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 14:12:48 pool-pole-speakers-1 node[22954]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:12:48 pool-pole-speakers-1 node[22954]: *** WARNING *** For more information see Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Discovery: Started advertising with name: pool-pole-speakers-1 Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "outputs"... Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "albumart"... Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Plugin example_plugin is not enabled Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "inputs"... Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "updater_comm"... Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Plugin mpdemulation is not enabled Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "rest_api"... Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "websocket"... Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Starting Socket.io Server version 2.3.0 Jul 05 14:12:48 pool-pole-speakers-1 volumio[22954]: info: Loading plugin "fusiondsp"... Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: Forking 3 albumart workers Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: Applying required configuration parameters for plugin fusiondsp Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: Loading i18n strings for locale en Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: Updating browse sources language Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::initPlayerControls Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: Express server listening on port 3000 Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: [Metrics] WebUI: 5s 654.04ms Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreStateMachine::resetVolumioState Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreStateMachine::getcurrentVolume Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreStateMachine::pushState Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:49 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: verbose: New Socket.io Connection to 10.100.100.173 from 172.16.100.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Engine version: 3 Transport: polling Total Clients: 1 Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: verbose: New Socket.io Connection to 10.100.100.173 from 172.16.100.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Engine version: 3 Transport: polling Total Clients: 2 Jul 05 14:12:50 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:50] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720203167 101 Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: 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: 3 Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: Reloading queue from file Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreStateMachine::setRepeat null single undefined Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreStateMachine::pushState Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreStateMachine::setRandom null Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreStateMachine::pushState Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::volumioPushState Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: Setting Device type: Raspberry PI Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: Completed loading Core Plugins Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: Preparing to generate the ALSA configuration file Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: Starting albumart workers Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: Starting albumart workers Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: Reading ALSA contributions from plugins. Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: Asound.conf file unchanged, so no further update is needed Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: Output device has changed, restarting MPD Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: Output device has changed, restarting Shairport Sync Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: Starting albumart workers Jul 05 14:12:50 pool-pole-speakers-1 sudo[23032]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:50 pool-pole-speakers-1 sudo[23032]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:50 pool-pole-speakers-1 sudo[23034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:50 pool-pole-speakers-1 sudo[23032]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:50 pool-pole-speakers-1 sudo[23034]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: ___________ START PLUGINS ___________ Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: ControllerMpd::onStart: Initializing MPD Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: Creating MPD Configuration file Jul 05 14:12:50 pool-pole-speakers-1 systemd[1]: Stopping Music Player Daemon... Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: [1720203170512] CoreMusicLibrary::Adding element Media Servers Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:50 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:50 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:50 pool-pole-speakers-1 sudo[23042]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:50 pool-pole-speakers-1 sudo[23042]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:50 pool-pole-speakers-1 sudo[23042]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:50 pool-pole-speakers-1 sudo[23043]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:50 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:50 pool-pole-speakers-1 sudo[23043]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:50 pool-pole-speakers-1 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 05 14:12:50 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:50 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:50 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:50 pool-pole-speakers-1 sudo[23048]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:50 pool-pole-speakers-1 sudo[23048]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:50 pool-pole-speakers-1 sudo[23048]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: [1720203170812] CoreMusicLibrary::Adding element Last_100 Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: [1720203170819] CoreMusicLibrary::Adding element Webradio Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: Initializing BBC Radios Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:50 pool-pole-speakers-1 volumio[22954]: info: Loading i18n strings for locale en Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: FusionDsp - mixtype--------------------- None Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: Volumio Calling Home Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: Preparing to generate the ALSA configuration file Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: Discovery: adding 7239786d-2dc8-4f98-8f91-97b248242581 Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: Discovery: Found device pool-pole-speakers-1 Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: Reading ALSA contributions from plugins. Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: MPD Permissions set Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: MPD Permissions set Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: Volumio called home Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: Starting Shairport Sync Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: Starting Shairport Sync Jul 05 14:12:51 pool-pole-speakers-1 volumio[22954]: info: Starting Shairport Sync Jul 05 14:12:51 pool-pole-speakers-1 sudo[23102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:51 pool-pole-speakers-1 sudo[23102]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:51 pool-pole-speakers-1 sudo[23104]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:51 pool-pole-speakers-1 sudo[23104]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:51 pool-pole-speakers-1 sudo[23107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:52 pool-pole-speakers-1 sudo[23107]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::volumioGetState Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: CorePlayQueue::getTrack 0 Jul 05 14:12:52 pool-pole-speakers-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:52 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:52 pool-pole-speakers-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: Asound.conf file unchanged, so no further update is needed Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: Output device has changed, restarting MPD Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: Output device has changed, restarting Shairport Sync Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:52 pool-pole-speakers-1 sudo[23112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:12:52 pool-pole-speakers-1 sudo[23112]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:52 pool-pole-speakers-1 sudo[23112]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:52 pool-pole-speakers-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:52 pool-pole-speakers-1 sudo[23104]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:52 pool-pole-speakers-1 sudo[23107]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:52 pool-pole-speakers-1 sudo[23114]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:12:52 pool-pole-speakers-1 sudo[23114]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:52 pool-pole-speakers-1 sudo[23102]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:12:52 pool-pole-speakers-1 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 14:12:52 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:12:52 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:12:52 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: MPD Permissions set Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: Shairport-Sync Started Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: Error adding Membership: Error: addMembership EINVAL Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: Shairport-Sync Started Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: Shairport-Sync Started Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: Starting Shairport Sync Jul 05 14:12:52 pool-pole-speakers-1 sudo[23128]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:12:52 pool-pole-speakers-1 sudo[23128]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:52 pool-pole-speakers-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:12:52 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:12:52 pool-pole-speakers-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:52 pool-pole-speakers-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:12:52 pool-pole-speakers-1 sudo[23122]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 14:12:52 pool-pole-speakers-1 sudo[23128]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:52 pool-pole-speakers-1 sudo[23122]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:52 pool-pole-speakers-1 sudo[23122]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: info: Shairport-Sync Started Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: verbose: New Socket.io Connection to 10.100.100.173 from 172.16.100.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Engine version: 3 Transport: polling Total Clients: 5 Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: TypeError: Cannot read property 'off' of undefined Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1537:29) Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1380:47) Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: at Socket.emit (events.js:315:20) Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Jul 05 14:12:52 pool-pole-speakers-1 volumio[22954]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:12:53 pool-pole-speakers-1 sudo[23145]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-05 14:11 Jul 05 14:12:53 pool-pole-speakers-1 sudo[23145]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:54 pool-pole-speakers-1 sudo[23145]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:54 pool-pole-speakers-1 mpd[23133]: Jul 05 14:12 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jul 05 14:12:54 pool-pole-speakers-1 systemd[1]: Started Music Player Daemon. Jul 05 14:12:54 pool-pole-speakers-1 sudo[23114]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:54 pool-pole-speakers-1 sudo[23034]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:54 pool-pole-speakers-1 sudo[23043]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:55 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:55] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Jul 05 14:12:55 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:12:55] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Jul 05 14:12:55 pool-pole-speakers-1 systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Jul 05 14:12:55 pool-pole-speakers-1 systemd[1]: volumio.service: Failed with result 'exit-code'. Jul 05 14:12:55 pool-pole-speakers-1 systemd[1]: Started dynamicswap service. Jul 05 14:12:55 pool-pole-speakers-1 systemd[1]: dynamicswap.service: Succeeded. Jul 05 14:12:55 pool-pole-speakers-1 systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Jul 05 14:12:55 pool-pole-speakers-1 systemd[1]: volumio.service: Scheduled restart job, restart counter is at 99. Jul 05 14:12:55 pool-pole-speakers-1 systemd[1]: Stopped Volumio Backend Module. Jul 05 14:12:55 pool-pole-speakers-1 systemd[1]: Started Volumio Backend Module. Jul 05 14:12:55 pool-pole-speakers-1 systemd[1]: Started dynamicswap service. Jul 05 14:12:55 pool-pole-speakers-1 systemd[1]: dynamicswap.service: Succeeded. Jul 05 14:12:57 pool-pole-speakers-1 volumio[23160]: info: ------------------------------------------- Jul 05 14:12:57 pool-pole-speakers-1 volumio[23160]: info: ----- Volumio3 ---- Jul 05 14:12:57 pool-pole-speakers-1 volumio[23160]: info: ------------------------------------------- Jul 05 14:12:57 pool-pole-speakers-1 volumio[23160]: info: ----- System startup ---- Jul 05 14:12:57 pool-pole-speakers-1 volumio[23160]: info: ------------------------------------------- Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: MYVOLUMIO Environment detected Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Plugin folders cleanup Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Scanning into folder /volumio/app/plugins/ Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Scanning category audio_interface Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Scanning category miscellanea Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Scanning category music_service Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Scanning category plugins.json Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Scanning category system_controller Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Scanning category user_interface Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Scanning into folder /data/plugins/ Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Scanning category audio_interface Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Plugin folders cleanup completed Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: ------------------------------------------- Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: ----- Core plugins startup ---- Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: ------------------------------------------- Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Loading plugins from folder /volumio/app/plugins/ Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Adding plugin upnp to MyMusic Plugins Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Adding plugin airplay_emulation to MyMusic Plugins Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Adding plugin upnp_browser to MyMusic Plugins Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Loading plugins from folder /data/plugins/ Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "system"... Jul 05 14:12:58 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "appearance"... Jul 05 14:12:59 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "network"... Jul 05 14:12:59 pool-pole-speakers-1 volumio[23160]: info: Refreshing Cached IP Addresses Jul 05 14:12:59 pool-pole-speakers-1 sudo[23187]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jul 05 14:12:59 pool-pole-speakers-1 sudo[23187]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:59 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "services"... Jul 05 14:12:59 pool-pole-speakers-1 sudo[23189]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jul 05 14:12:59 pool-pole-speakers-1 sudo[23189]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:59 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "alsa_controller"... Jul 05 14:12:59 pool-pole-speakers-1 sudo[23189]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:59 pool-pole-speakers-1 sudo[23187]: pam_unix(sudo:session): session closed for user root Jul 05 14:12:59 pool-pole-speakers-1 sudo[23193]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jul 05 14:12:59 pool-pole-speakers-1 sudo[23193]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:12:59 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:12:59 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "wizard"... Jul 05 14:12:59 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "networkfs"... Jul 05 14:13:00 pool-pole-speakers-1 volumio[23160]: info: Starting Udev Watcher for removable devices Jul 05 14:13:00 pool-pole-speakers-1 volumio[23160]: info: Ignoring mount for partition: boot Jul 05 14:13:00 pool-pole-speakers-1 volumio[23160]: info: Ignoring mount for partition: volumio Jul 05 14:13:00 pool-pole-speakers-1 volumio[23160]: info: Ignoring mount for partition: volumio_data Jul 05 14:13:00 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:13:00 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "volumio_command_line_client"... Jul 05 14:13:00 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "upnp"... Jul 05 14:13:00 pool-pole-speakers-1 volumio[23160]: info: [1720203180060] Starting Upmpd Daemon Jul 05 14:13:00 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:13:00 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "my_music"... Jul 05 14:13:00 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "mpd"... Jul 05 14:13:00 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:13:00] [connect] Successful connection Jul 05 14:13:00 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "upnp_browser"... Jul 05 14:13:00 pool-pole-speakers-1 sudo[23193]: pam_unix(sudo:session): session closed for user root Jul 05 14:13:00 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "alarm-clock"... Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "airplay_emulation"... Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Starting Shairport Sync Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "last_100"... Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "webradio"... Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "i2s_dacs"... Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "volumiodiscovery"... Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: *** WARNING *** For more information see Jul 05 14:13:01 pool-pole-speakers-1 node[23160]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: *** WARNING *** For more information see Jul 05 14:13:01 pool-pole-speakers-1 node[23160]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:13:01 pool-pole-speakers-1 node[23160]: *** WARNING *** For more information see Jul 05 14:13:01 pool-pole-speakers-1 node[23160]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Jul 05 14:13:01 pool-pole-speakers-1 node[23160]: *** WARNING *** Please fix your application to use the native API of Avahi! Jul 05 14:13:01 pool-pole-speakers-1 node[23160]: *** WARNING *** For more information see Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Applying required configuration parameters for plugin volumiodiscovery Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Discovery: Started advertising with name: pool-pole-speakers-1 Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "outputs"... Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "albumart"... Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Plugin example_plugin is not enabled Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "inputs"... Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "updater_comm"... Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Plugin mpdemulation is not enabled Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "rest_api"... Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "websocket"... Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Starting Socket.io Server version 2.3.0 Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: info: Loading plugin "fusiondsp"... Jul 05 14:13:01 pool-pole-speakers-1 volumio[23160]: Forking 3 albumart workers Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: Applying required configuration parameters for plugin fusiondsp Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: Loading i18n strings for locale en Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: Updating browse sources language Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::initPlayerControls Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: Express server listening on port 3000 Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: [Metrics] WebUI: 5s 704.46ms Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreStateMachine::resetVolumioState Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreStateMachine::getcurrentVolume Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::volumioRetrievevolume Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreStateMachine::pushState Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CorePlayQueue::getTrack 0 Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::volumioPushState Jul 05 14:13:02 pool-pole-speakers-1 volumio-remote-updater[520]: [2024-07-05 14:13:02] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1720203180 101 Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: 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: 1 Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: Reloading queue from file Jul 05 14:13:02 pool-pole-speakers-1 volumio[23160]: info: CoreStateMachine::setRepeat null single undefined Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreStateMachine::pushState Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CorePlayQueue::getTrack 0 Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::volumioPushState Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreStateMachine::setRandom null Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreStateMachine::pushState Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CorePlayQueue::getTrack 0 Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::volumioPushState Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: Setting Device type: Raspberry PI Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: Starting albumart workers Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: Starting albumart workers Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: Completed loading Core Plugins Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: Preparing to generate the ALSA configuration file Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: Starting albumart workers Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: Reading ALSA contributions from plugins. Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: Asound.conf file unchanged, so no further update is needed Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: Output device has changed, restarting MPD Jul 05 14:13:03 pool-pole-speakers-1 sudo[23239]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:13:03 pool-pole-speakers-1 sudo[23239]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:13:03 pool-pole-speakers-1 sudo[23239]: pam_unix(sudo:session): session closed for user root Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: Output device has changed, restarting Shairport Sync Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:13:03 pool-pole-speakers-1 sudo[23241]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:13:03 pool-pole-speakers-1 sudo[23241]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: ___________ START PLUGINS ___________ Jul 05 14:13:03 pool-pole-speakers-1 systemd[1]: Stopping Music Player Daemon... Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: ControllerMpd::onStart: Initializing MPD Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: Creating MPD Configuration file Jul 05 14:13:03 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:13:03 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: [1720203183339] CoreMusicLibrary::Adding element Media Servers Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:13:03 pool-pole-speakers-1 sudo[23248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:13:03 pool-pole-speakers-1 sudo[23248]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:13:03 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:13:03 pool-pole-speakers-1 sudo[23248]: pam_unix(sudo:session): session closed for user root Jul 05 14:13:03 pool-pole-speakers-1 sudo[23251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:13:03 pool-pole-speakers-1 sudo[23251]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:13:03 pool-pole-speakers-1 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Jul 05 14:13:03 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:13:03 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:13:03 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: [1720203183574] CoreMusicLibrary::Adding element Last_100 Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: [1720203183580] CoreMusicLibrary::Adding element Webradio Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: Initializing BBC Radios Jul 05 14:13:03 pool-pole-speakers-1 sudo[23256]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 14:13:03 pool-pole-speakers-1 sudo[23256]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:13:03 pool-pole-speakers-1 sudo[23256]: pam_unix(sudo:session): session closed for user root Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: Loading i18n strings for locale en Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: FusionDsp - mixtype--------------------- None Jul 05 14:13:03 pool-pole-speakers-1 volumio[23160]: info: Volumio Calling Home Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: Preparing to generate the ALSA configuration file Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: Discovery: adding 7239786d-2dc8-4f98-8f91-97b248242581 Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: Discovery: Found device pool-pole-speakers-1 Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::volumioGetState Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CorePlayQueue::getTrack 0 Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: Reading ALSA contributions from plugins. Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: verbose: New Socket.io Connection to 10.100.100.173 from 172.16.100.200 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:127.0) Gecko/20100101 Firefox/127.0 Engine version: 3 Transport: polling Total Clients: 2 Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: MPD Permissions set Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: MPD Permissions set Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: Volumio called home Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: Starting Shairport Sync Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: Starting Shairport Sync Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: Starting Shairport Sync Jul 05 14:13:04 pool-pole-speakers-1 sudo[23309]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:13:04 pool-pole-speakers-1 sudo[23309]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:13:04 pool-pole-speakers-1 sudo[23311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:13:04 pool-pole-speakers-1 sudo[23311]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:13:04 pool-pole-speakers-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:13:04 pool-pole-speakers-1 systemd[1]: shairport-sync.service: Succeeded. Jul 05 14:13:04 pool-pole-speakers-1 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::volumioGetState Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: CorePlayQueue::getTrack 0 Jul 05 14:13:04 pool-pole-speakers-1 sudo[23314]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:13:04 pool-pole-speakers-1 sudo[23314]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:13:04 pool-pole-speakers-1 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: Asound.conf file unchanged, so no further update is needed Jul 05 14:13:04 pool-pole-speakers-1 sudo[23309]: pam_unix(sudo:session): session closed for user root Jul 05 14:13:04 pool-pole-speakers-1 volumio[23160]: info: Output device has changed, restarting MPD Jul 05 14:13:05 pool-pole-speakers-1 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: Output device has changed, restarting Shairport Sync Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:13:05 pool-pole-speakers-1 sudo[23320]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jul 05 14:13:05 pool-pole-speakers-1 sudo[23320]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:13:05 pool-pole-speakers-1 sudo[23320]: pam_unix(sudo:session): session closed for user root Jul 05 14:13:05 pool-pole-speakers-1 sudo[23322]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jul 05 14:13:05 pool-pole-speakers-1 sudo[23322]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:13:05 pool-pole-speakers-1 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Jul 05 14:13:05 pool-pole-speakers-1 systemd[1]: mpd.service: Succeeded. Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jul 05 14:13:05 pool-pole-speakers-1 systemd[1]: Stopped Music Player Daemon. Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: MPD Permissions set Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: Shairport-Sync Started Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: Error adding Membership: Error: addMembership EINVAL Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:05 pool-pole-speakers-1 systemd[1]: Starting Music Player Daemon... Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: Starting Shairport Sync Jul 05 14:13:05 pool-pole-speakers-1 sudo[23334]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Jul 05 14:13:05 pool-pole-speakers-1 sudo[23334]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:13:05 pool-pole-speakers-1 sudo[23328]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jul 05 14:13:05 pool-pole-speakers-1 sudo[23328]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:13:05 pool-pole-speakers-1 sudo[23328]: pam_unix(sudo:session): session closed for user root Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: 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 Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: camilladsp service started and running in background, instance 1 Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: FusionDsp loaded Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jul 05 14:13:05 pool-pole-speakers-1 sudo[23349]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jul 05 14:13:05 pool-pole-speakers-1 sudo[23349]: pam_unix(sudo:session): session opened for user root by (uid=0) Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: FusionDsp - Reporting Fusion DSP Enabled Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: Adding Signal Path Element [object Object] Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: Adding fusiondspeq DSP Signal Path Element Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: info: FusionDsp - ---- installed callbackRead Jul 05 14:13:05 pool-pole-speakers-1 sudo[23349]: pam_unix(sudo:session): session closed for user root Jul 05 14:13:05 pool-pole-speakers-1 volumio[23160]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: at onErrorNT (internal/child_process.js:465:16) Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: errno: -2, Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: code: 'ENOENT', Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: spawnargs: [ Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: '-p', Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: 9876, Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: '-o', Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: '/tmp/camilladsp.log', Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: '-l', Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: 'warn', Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: ] Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: } Jul 05 14:13:06 pool-pole-speakers-1 volumio[23160]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 05 14:13:06 pool-pole-speakers-1 sudo[23360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-07-05 14:12 Jul 05 14:13:06 pool-pole-speakers-1 sudo[23360]: 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="4b122b2365bb8c09786cd63b50d1ea6db780eb4e" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="49f98390f2d84c6549f33dc85b694fbd99e4c720" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 04 Jun 2024 08:41:12 PM CEST" VOLUMIO_VERSION="3.703" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="8e78a6e3f56f1bcbbd1760812129a8e9"