-- Logs begin at Thu 2019-02-14 15:41:58 IST, end at Thu 2024-08-29 14:10:42 IST. -- Aug 29 14:10:23 volumio ntpd[1027]: receive: Unexpected origin timestamp 0xea7ab1c4.39dc1f67 does not match aorg 0000000000.00000000 from server@162.159.200.1 xmt 0xea7ab277.0dcacb64 Aug 29 14:10:23 volumio ntpd[1027]: receive: Unexpected origin timestamp 0xea7ab1c4.39d9d90b does not match aorg 0000000000.00000000 from server@139.84.142.141 xmt 0xea7ab277.0fb1cc14 Aug 29 14:10:23 volumio ntpd[1027]: receive: Unexpected origin timestamp 0xea7ab1c4.39db8fec does not match aorg 0000000000.00000000 from server@192.46.210.39 xmt 0xea7ab277.14f6dcf0 Aug 29 14:10:23 volumio ntpd[1027]: receive: Unexpected origin timestamp 0xea7ab1c4.39da9829 does not match aorg 0000000000.00000000 from server@15.207.248.194 xmt 0xea7ab277.11c0f3f2 Aug 29 14:10:23 volumio ntpd[1027]: receive: Unexpected origin timestamp 0xea7ab1c4.39dd1622 does not match aorg 0000000000.00000000 from server@192.46.211.253 xmt 0xea7ab277.164493a2 Aug 29 14:10:23 volumio volumio[4616]: info: MYVOLUMIO Environment detected Aug 29 14:10:23 volumio volumio[4616]: info: Plugin folders cleanup Aug 29 14:10:23 volumio volumio[4616]: info: Scanning into folder /volumio/app/plugins/ Aug 29 14:10:23 volumio volumio[4616]: info: Scanning category audio_interface Aug 29 14:10:23 volumio volumio[4616]: info: Scanning category miscellanea Aug 29 14:10:23 volumio volumio[4616]: info: Scanning category music_service Aug 29 14:10:23 volumio volumio[4616]: info: Scanning category plugins.json Aug 29 14:10:23 volumio volumio[4616]: info: Scanning category system_controller Aug 29 14:10:23 volumio volumio[4616]: info: Scanning category user_interface Aug 29 14:10:23 volumio volumio[4616]: info: Scanning into folder /data/plugins/ Aug 29 14:10:23 volumio volumio[4616]: info: Scanning category audio_interface Aug 29 14:10:23 volumio volumio[4616]: info: Scanning category music_service Aug 29 14:10:23 volumio volumio[4616]: info: Scanning category user_interface Aug 29 14:10:23 volumio volumio[4616]: info: Plugin folders cleanup completed Aug 29 14:10:23 volumio volumio[4616]: info: ------------------------------------------- Aug 29 14:10:23 volumio volumio[4616]: info: ----- Core plugins startup ---- Aug 29 14:10:23 volumio volumio[4616]: info: ------------------------------------------- Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 14:10:23 volumio volumio[4616]: info: Adding plugin upnp to MyMusic Plugins Aug 29 14:10:23 volumio volumio[4616]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 14:10:23 volumio volumio[4616]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugins from folder /data/plugins/ Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "system"... Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "appearance"... Aug 29 14:10:23 volumio ntpd[1027]: receive: Unexpected origin timestamp 0xea7ab1c4.39d6a157 does not match aorg 0000000000.00000000 from server@95.216.144.226 xmt 0xea7ab277.2333feec Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "network"... Aug 29 14:10:23 volumio volumio[4616]: info: Refreshing Cached IP Addresses Aug 29 14:10:23 volumio sudo[4643]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 14:10:23 volumio sudo[4643]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:23 volumio sudo[4645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 14:10:23 volumio sudo[4645]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:23 volumio sudo[4643]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:23 volumio sudo[4645]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "services"... Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "alsa_controller"... Aug 29 14:10:23 volumio sudo[4649]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 14:10:23 volumio sudo[4649]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:23 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "wizard"... Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "networkfs"... Aug 29 14:10:23 volumio volumio[4616]: info: Starting Udev Watcher for removable devices Aug 29 14:10:23 volumio volumio[4616]: info: Mounting Device 7A6D-519E Aug 29 14:10:23 volumio sudo[4657]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/7A6D-519E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:10:23 volumio sudo[4657]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:23 volumio sudo[4657]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:23 volumio volumio[4616]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:10:23 volumio volumio[4616]: error: Failed to mount 7A6D-519E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/7A6D-519E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:10:23 volumio volumio[4616]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:10:23 volumio volumio[4616]: info: Ignoring mount for partition: boot Aug 29 14:10:23 volumio volumio[4616]: info: Ignoring mount for partition: volumio Aug 29 14:10:23 volumio volumio[4616]: info: Ignoring mount for partition: volumio_data Aug 29 14:10:23 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "volumio_command_line_client"... Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "upnp"... Aug 29 14:10:23 volumio volumio[4616]: info: [1724920823480] Starting Upmpd Daemon Aug 29 14:10:23 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "my_music"... Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "mpd"... Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "upnp_browser"... Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "alarm-clock"... Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "airplay_emulation"... Aug 29 14:10:23 volumio volumio[4616]: info: Starting Shairport Sync Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "last_100"... Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "webradio"... Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "i2s_dacs"... Aug 29 14:10:23 volumio volumio[4616]: info: I2S DAC not set, start Auto-detection Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "volumiodiscovery"... Aug 29 14:10:23 volumio volumio[4616]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:23 volumio volumio[4616]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:23 volumio volumio[4616]: *** WARNING *** For more information see Aug 29 14:10:23 volumio node[4616]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:23 volumio volumio[4616]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:23 volumio volumio[4616]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:23 volumio volumio[4616]: *** WARNING *** For more information see Aug 29 14:10:23 volumio node[4616]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:23 volumio node[4616]: *** WARNING *** For more information see Aug 29 14:10:23 volumio node[4616]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:23 volumio node[4616]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:23 volumio node[4616]: *** WARNING *** For more information see Aug 29 14:10:23 volumio volumio[4616]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 14:10:23 volumio volumio[4616]: info: Discovery: Started advertising with name: Volumio Aug 29 14:10:23 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "youtube2"... Aug 29 14:10:23 volumio volumio[4616]: info: Loading plugin "ytmusic"... Aug 29 14:10:24 volumio ntpd[1027]: Soliciting pool server 17.253.18.131 Aug 29 14:10:24 volumio volumio[4616]: info: Loading plugin "outputs"... Aug 29 14:10:24 volumio volumio[4616]: info: Loading plugin "albumart"... Aug 29 14:10:24 volumio volumio[4616]: info: Plugin example_plugin is not enabled Aug 29 14:10:24 volumio volumio[4616]: info: Loading plugin "inputs"... Aug 29 14:10:24 volumio volumio[4616]: info: Loading plugin "updater_comm"... Aug 29 14:10:24 volumio volumio[4616]: info: Plugin mpdemulation is not enabled Aug 29 14:10:24 volumio volumio[4616]: info: Loading plugin "rest_api"... Aug 29 14:10:24 volumio volumio[4616]: info: Loading plugin "websocket"... Aug 29 14:10:24 volumio volumio[4616]: info: Starting Socket.io Server version 2.3.0 Aug 29 14:10:24 volumio volumio[4616]: info: Loading plugin "fusiondsp"... Aug 29 14:10:24 volumio volumio[4616]: Forking 3 albumart workers Aug 29 14:10:24 volumio volumio[4616]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 14:10:24 volumio volumio[4616]: info: Loading plugin "touch_display"... Aug 29 14:10:24 volumio volumio[4616]: info: Applying required configuration parameters for plugin touch_display Aug 29 14:10:24 volumio volumio[4616]: info: Loading i18n strings for locale en Aug 29 14:10:24 volumio volumio[4616]: Updating browse sources language Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::initPlayerControls Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: Express server listening on port 3000 Aug 29 14:10:24 volumio volumio[4616]: [Metrics] WebUI: 1s 685.55ms Aug 29 14:10:24 volumio volumio[4616]: info: CoreStateMachine::resetVolumioState Aug 29 14:10:24 volumio volumio[4616]: info: CoreStateMachine::getcurrentVolume Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:10:24 volumio volumio[4616]: Starting albumart workers Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: Starting albumart workers Aug 29 14:10:24 volumio volumio[4616]: Starting albumart workers Aug 29 14:10:24 volumio volumio[4616]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 14:10:24 volumio volumio[4616]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 29 14:10:24 volumio volumio[4616]: info: Reloading queue from file Aug 29 14:10:24 volumio volumio[4616]: info: CoreStateMachine::pushState Aug 29 14:10:24 volumio volumio[4616]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:24 volumio volumio[4616]: info: CoreStateMachine::updateTrackBlock Aug 29 14:10:24 volumio volumio[4616]: info: CorePlayQueue::getTrackBlock Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:10:24 volumio volumio[4616]: info: CoreStateMachine::setRepeat null single undefined Aug 29 14:10:24 volumio volumio[4616]: info: CoreStateMachine::pushState Aug 29 14:10:24 volumio volumio[4616]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:24 volumio volumio[4616]: info: CoreStateMachine::setRandom null Aug 29 14:10:24 volumio volumio[4616]: info: CoreStateMachine::pushState Aug 29 14:10:24 volumio volumio[4616]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:24 volumio volumio[4616]: info: Setting Device type: Raspberry PI Aug 29 14:10:24 volumio volumio[4616]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 14:10:24 volumio volumio[4616]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Aug 29 14:10:24 volumio volumio[4616]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 14:10:24 volumio volumio[4616]: info: Completed loading Core Plugins Aug 29 14:10:24 volumio volumio[4616]: info: Preparing to generate the ALSA configuration file Aug 29 14:10:24 volumio volumio[4616]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:10:24 volumio volumio[4616]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:10:24 volumio volumio[4616]: info: Reading ALSA contributions from plugins. Aug 29 14:10:24 volumio volumio[4616]: info: Discovery: adding 32308897-683e-4edd-a3a1-38bd95fb29e7 Aug 29 14:10:24 volumio volumio[4616]: info: Discovery: Found device Volumio Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:24 volumio volumio[4616]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:24 volumio volumio[4616]: info: CoreStateMachine::pushState Aug 29 14:10:24 volumio volumio[4616]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:24 volumio volumio[4616]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:10:24 volumio volumio[4616]: info: Output device has changed, restarting MPD Aug 29 14:10:24 volumio volumio[4616]: info: Output device has changed, restarting Shairport Sync Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:24 volumio sudo[4731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:10:24 volumio sudo[4724]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:10:24 volumio sudo[4724]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:24 volumio sudo[4731]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:24 volumio sudo[4724]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:24 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:10:24 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:10:24 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:10:24 volumio volumio[4616]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:24 volumio volumio[4616]: info: ___________ START PLUGINS ___________ Aug 29 14:10:24 volumio volumio[4616]: info: ControllerMpd::onStart: Initializing MPD Aug 29 14:10:24 volumio volumio[4616]: info: Creating MPD Configuration file Aug 29 14:10:24 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:10:24 volumio sudo[4746]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:10:24 volumio sudo[4746]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:24 volumio volumio[4616]: info: [1724920824808] CoreMusicLibrary::Adding element Media Servers Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:24 volumio sudo[4746]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:24 volumio sudo[4749]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:10:24 volumio sudo[4749]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:24 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 14:10:24 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:10:24 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:10:24 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:10:24 volumio volumio[4616]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:24 volumio volumio[4616]: info: [1724920824852] CoreMusicLibrary::Adding element Last_100 Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:24 volumio volumio[4616]: info: [1724920824853] CoreMusicLibrary::Adding element Webradio Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:24 volumio sudo[4754]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:10:24 volumio sudo[4754]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:24 volumio volumio[4616]: info: Initializing BBC Radios Aug 29 14:10:24 volumio sudo[4754]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:24 volumio volumio[4616]: info: [1724920824885] CoreMusicLibrary::Adding element YouTube2 Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:24 volumio volumio[4616]: Cannot find translation for source YouTube2 Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:24 volumio volumio[4616]: info: [1724920824887] CoreMusicLibrary::Adding element YouTube Music Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:24 volumio volumio[4616]: Cannot find translation for source YouTube2 Aug 29 14:10:24 volumio volumio[4616]: Cannot find translation for source YouTube Music Aug 29 14:10:24 volumio volumio[4616]: info: Loading i18n strings for locale en Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:24 volumio volumio[4616]: info: FusionDsp - mixtype--------------------- Software Aug 29 14:10:24 volumio volumio[4616]: info: Loading i18n strings for locale en Aug 29 14:10:24 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:24 volumio volumio[4616]: info: Volumio Calling Home Aug 29 14:10:24 volumio volumio[4616]: info: Preparing to generate the ALSA configuration file Aug 29 14:10:24 volumio sudo[4774]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 29 14:10:24 volumio sudo[4774]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:24 volumio sudo[4776]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 29 14:10:24 volumio sudo[4776]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:24 volumio sudo[4780]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 14:10:24 volumio sudo[4780]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:24 volumio sudo[4774]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:24 volumio systemd[1]: Reloading. Aug 29 14:10:25 volumio volumio[4616]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:10:25 volumio volumio[4616]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:10:25 volumio volumio[4616]: info: Reading ALSA contributions from plugins. Aug 29 14:10:25 volumio volumio[4616]: info: touch_display: No backlight interface detected. Aug 29 14:10:25 volumio volumio[4616]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 29 14:10:25 volumio volumio[4616]: info: MPD Permissions set Aug 29 14:10:25 volumio volumio[4616]: info: MPD Permissions set Aug 29 14:10:25 volumio volumio[4616]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 14:10:25 volumio sudo[4804]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 29 14:10:25 volumio sudo[4804]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:25 volumio volumio[4616]: info: Starting Shairport Sync Aug 29 14:10:25 volumio sudo[4804]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:25 volumio volumio[4616]: info: Starting Shairport Sync Aug 29 14:10:25 volumio sudo[4806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 29 14:10:25 volumio sudo[4806]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:25 volumio volumio[4616]: info: Starting Shairport Sync Aug 29 14:10:25 volumio sudo[4812]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:25 volumio sudo[4812]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:25 volumio sudo[4809]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:25 volumio sudo[4809]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:25 volumio sudo[4806]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:25 volumio volumio[4616]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:25 volumio sudo[4816]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:25 volumio sudo[4816]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:25 volumio volumio[4616]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 29 14:10:25 volumio volumio[4616]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 29 14:10:25 volumio volumio[4616]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:10:25 volumio volumio[4616]: info: Output device has changed, restarting MPD Aug 29 14:10:25 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:10:25 volumio volumio[4616]: info: Output device has changed, restarting Shairport Sync Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:25 volumio sudo[4819]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:10:25 volumio sudo[4819]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:25 volumio sudo[4819]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:25 volumio sudo[4823]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:10:25 volumio sudo[4823]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:25 volumio mpd[4759]: Aug 29 14:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:10:25 volumio volumio[4616]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:25 volumio volumio[4616]: info: MPD Permissions set Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:25 volumio volumio[4616]: info: Starting Shairport Sync Aug 29 14:10:25 volumio sudo[4834]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:25 volumio sudo[4834]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:25 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:10:25 volumio sudo[4749]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:25 volumio sudo[4731]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:25 volumio sudo[4776]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:25 volumio volumio[4616]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 29 14:10:25 volumio systemd[1]: Reloading. Aug 29 14:10:25 volumio volumio[4616]: error: MPD error: The expression evaluated to a falsy value: Aug 29 14:10:25 volumio volumio[4616]: assert.ok(self.idling) Aug 29 14:10:25 volumio volumio[4616]: error: The expression evaluated to a falsy value: Aug 29 14:10:25 volumio volumio[4616]: assert.ok(self.idling) Aug 29 14:10:25 volumio volumio[4616]: error: updateQueue error: null Aug 29 14:10:25 volumio volumio[4616]: info: MPD running with PID4759 Aug 29 14:10:25 volumio volumio[4616]: ,establishing connection Aug 29 14:10:25 volumio volumio[4616]: error: updateQueue error: null Aug 29 14:10:25 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:10:25 volumio volumio[4616]: info: Volumio called home Aug 29 14:10:25 volumio sudo[4780]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:25 volumio volumio[4616]: info: touch_display: systemctl daemon-reload succeeded. Aug 29 14:10:25 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:10:25 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 14:10:25 volumio sudo[4852]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 29 14:10:25 volumio sudo[4852]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:25 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 14:10:25 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 14:10:25 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:10:25 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:10:25 volumio sudo[4816]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:25 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:10:25 volumio sudo[4834]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:25 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 14:10:25 volumio sudo[4812]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:25 volumio sudo[4809]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:25 volumio volumio[4616]: info: Shairport-Sync Started Aug 29 14:10:25 volumio volumio[4616]: Error adding Membership: Error: addMembership EINVAL Aug 29 14:10:25 volumio volumio[4616]: info: Shairport-Sync Started Aug 29 14:10:25 volumio volumio[4616]: info: Shairport-Sync Started Aug 29 14:10:25 volumio volumio[4616]: info: Shairport-Sync Started Aug 29 14:10:25 volumio sudo[4852]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:25 volumio volumio[4616]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 29 14:10:25 volumio volumio[4616]: info: touch_display: Volumio Kiosk started. Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:25 volumio volumio[4616]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:25 volumio volumio[4616]: info: Completed starting Core Plugins Aug 29 14:10:25 volumio volumio[4616]: info: ------------------------------------------- Aug 29 14:10:25 volumio volumio[4616]: info: ----- MyVolumio plugins startup ---- Aug 29 14:10:25 volumio volumio[4616]: info: ------------------------------------------- Aug 29 14:10:25 volumio volumio[4616]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 29 14:10:25 volumio sudo[4854]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:10:25 volumio sudo[4854]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:25 volumio volumio[4616]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:25 volumio sudo[4854]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:25 volumio volumio[4616]: info: touch_display: X display number found: 0 Aug 29 14:10:25 volumio volumio[4616]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::Close All Modals sent Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:25 volumio volumio[4616]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:25 volumio volumio[4616]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:10:25 volumio volumio[4616]: info: Received Get System Info Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:10:25 volumio volumio[4616]: info: Discovery: Getting this device information Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:25 volumio volumio[4616]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:25 volumio volumio[4616]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:25 volumio volumio[4616]: info: Listing playlists Aug 29 14:10:25 volumio volumio[4616]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 29 14:10:25 volumio volumio[4616]: info: touch_display: X display number found: 0 Aug 29 14:10:25 volumio volumio[4616]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:25 volumio volumio[4616]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:26 volumio mpd[4861]: Aug 29 14:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:10:26 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:10:26 volumio sudo[4823]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:26 volumio volumio[4616]: error: updateQueue error: null Aug 29 14:10:26 volumio volumio[4616]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 14:10:26 volumio volumio[4616]: 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 Aug 29 14:10:26 volumio volumio[4616]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 14:10:26 volumio volumio[4616]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Aug 29 14:10:26 volumio volumio[4616]: info: camilladsp service started and running in background, instance 1 Aug 29 14:10:26 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:26 volumio volumio[4616]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 14:10:26 volumio volumio[4616]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 29 14:10:26 volumio volumio[4616]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 14:10:26 volumio volumio[4616]: info: FusionDsp loaded Aug 29 14:10:26 volumio volumio[4616]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:10:26 volumio sudo[4883]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 14:10:26 volumio sudo[4883]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:26 volumio volumio[4616]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 14:10:26 volumio volumio[4616]: info: Adding Signal Path Element [object Object] Aug 29 14:10:26 volumio volumio[4616]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 14:10:26 volumio volumio[4616]: info: FusionDsp - ---- installed callbackRead Aug 29 14:10:26 volumio volumio[4616]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:10:26 volumio volumio[4616]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 14:10:26 volumio volumio[4616]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 14:10:26 volumio volumio[4616]: at onErrorNT (internal/child_process.js:465:16) Aug 29 14:10:26 volumio volumio[4616]: at processTicksAndRejections (internal/process/task_queues.js:80:21) { Aug 29 14:10:26 volumio volumio[4616]: errno: -2, Aug 29 14:10:26 volumio volumio[4616]: code: 'ENOENT', Aug 29 14:10:26 volumio volumio[4616]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 14:10:26 volumio volumio[4616]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 14:10:26 volumio volumio[4616]: spawnargs: [ Aug 29 14:10:26 volumio volumio[4616]: '-p', Aug 29 14:10:26 volumio volumio[4616]: 9876, Aug 29 14:10:26 volumio volumio[4616]: '-o', Aug 29 14:10:26 volumio volumio[4616]: '/tmp/camilladsp.log', Aug 29 14:10:26 volumio volumio[4616]: '-l', Aug 29 14:10:26 volumio volumio[4616]: 'warn', Aug 29 14:10:26 volumio volumio[4616]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 14:10:26 volumio volumio[4616]: ] Aug 29 14:10:26 volumio volumio[4616]: } Aug 29 14:10:26 volumio volumio[4616]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:10:26 volumio sudo[4883]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:27 volumio ntpd[1027]: Soliciting pool server 64:ff9b::5fd8:90e2 Aug 29 14:10:27 volumio sudo[4649]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:27 volumio sudo[4894]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 14:09 Aug 29 14:10:27 volumio sudo[4894]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:27 volumio sudo[4894]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:27 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 14:10:27 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 14:10:27 volumio systemd[1]: Started dynamicswap service. Aug 29 14:10:27 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:10:27 volumio volumio-remote-updater[857]: [2024-08-29 14:10:27] [info] asio async_connect error: system:111 (Connection refused) Aug 29 14:10:27 volumio volumio-remote-updater[857]: [2024-08-29 14:10:27] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Aug 29 14:10:27 volumio volumio-remote-updater[857]: [2024-08-29 14:10:27] [error] handle_connect error: Underlying Transport Error Aug 29 14:10:27 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 14:10:27 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 11. Aug 29 14:10:27 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 14:10:27 volumio systemd[1]: Started Volumio Backend Module. Aug 29 14:10:27 volumio systemd[1]: Started dynamicswap service. Aug 29 14:10:27 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:10:27 volumio volumio[4904]: info: ------------------------------------------- Aug 29 14:10:27 volumio volumio[4904]: info: ----- Volumio3 ---- Aug 29 14:10:27 volumio volumio[4904]: info: ------------------------------------------- Aug 29 14:10:27 volumio volumio[4904]: info: ----- System startup ---- Aug 29 14:10:27 volumio volumio[4904]: info: ------------------------------------------- Aug 29 14:10:28 volumio volumio[4904]: info: MYVOLUMIO Environment detected Aug 29 14:10:28 volumio volumio[4904]: info: Plugin folders cleanup Aug 29 14:10:28 volumio volumio[4904]: info: Scanning into folder /volumio/app/plugins/ Aug 29 14:10:28 volumio volumio[4904]: info: Scanning category audio_interface Aug 29 14:10:28 volumio volumio[4904]: info: Scanning category miscellanea Aug 29 14:10:28 volumio volumio[4904]: info: Scanning category music_service Aug 29 14:10:28 volumio volumio[4904]: info: Scanning category plugins.json Aug 29 14:10:28 volumio volumio[4904]: info: Scanning category system_controller Aug 29 14:10:28 volumio volumio[4904]: info: Scanning category user_interface Aug 29 14:10:28 volumio volumio[4904]: info: Scanning into folder /data/plugins/ Aug 29 14:10:28 volumio volumio[4904]: info: Scanning category audio_interface Aug 29 14:10:28 volumio volumio[4904]: info: Scanning category music_service Aug 29 14:10:28 volumio volumio[4904]: info: Scanning category user_interface Aug 29 14:10:28 volumio volumio[4904]: info: Plugin folders cleanup completed Aug 29 14:10:28 volumio volumio[4904]: info: ------------------------------------------- Aug 29 14:10:28 volumio volumio[4904]: info: ----- Core plugins startup ---- Aug 29 14:10:28 volumio volumio[4904]: info: ------------------------------------------- Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 14:10:28 volumio volumio[4904]: info: Adding plugin upnp to MyMusic Plugins Aug 29 14:10:28 volumio volumio[4904]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 14:10:28 volumio volumio[4904]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugins from folder /data/plugins/ Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "system"... Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "appearance"... Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "network"... Aug 29 14:10:28 volumio volumio[4904]: info: Refreshing Cached IP Addresses Aug 29 14:10:28 volumio sudo[4931]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 14:10:28 volumio sudo[4931]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:28 volumio sudo[4933]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 14:10:28 volumio sudo[4933]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "services"... Aug 29 14:10:28 volumio sudo[4931]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "alsa_controller"... Aug 29 14:10:28 volumio sudo[4933]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:28 volumio sudo[4936]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 14:10:28 volumio sudo[4936]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:28 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "wizard"... Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "networkfs"... Aug 29 14:10:28 volumio volumio[4904]: info: Starting Udev Watcher for removable devices Aug 29 14:10:28 volumio volumio[4904]: info: Mounting Device 7A6D-519E Aug 29 14:10:28 volumio sudo[4945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/7A6D-519E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:10:28 volumio sudo[4945]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:28 volumio sudo[4945]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:28 volumio volumio[4904]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:10:28 volumio volumio[4904]: error: Failed to mount 7A6D-519E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/7A6D-519E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:10:28 volumio volumio[4904]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:10:28 volumio volumio[4904]: info: Ignoring mount for partition: boot Aug 29 14:10:28 volumio volumio[4904]: info: Ignoring mount for partition: volumio Aug 29 14:10:28 volumio volumio[4904]: info: Ignoring mount for partition: volumio_data Aug 29 14:10:28 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "volumio_command_line_client"... Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "upnp"... Aug 29 14:10:28 volumio volumio[4904]: info: [1724920828428] Starting Upmpd Daemon Aug 29 14:10:28 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "my_music"... Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "mpd"... Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "upnp_browser"... Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "alarm-clock"... Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "airplay_emulation"... Aug 29 14:10:28 volumio volumio[4904]: info: Starting Shairport Sync Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "last_100"... Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "webradio"... Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "i2s_dacs"... Aug 29 14:10:28 volumio volumio[4904]: info: I2S DAC not set, start Auto-detection Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "volumiodiscovery"... Aug 29 14:10:28 volumio volumio[4904]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:28 volumio volumio[4904]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:28 volumio volumio[4904]: *** WARNING *** For more information see Aug 29 14:10:28 volumio node[4904]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:28 volumio volumio[4904]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:28 volumio volumio[4904]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:28 volumio volumio[4904]: *** WARNING *** For more information see Aug 29 14:10:28 volumio node[4904]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:28 volumio node[4904]: *** WARNING *** For more information see Aug 29 14:10:28 volumio node[4904]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:28 volumio node[4904]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:28 volumio node[4904]: *** WARNING *** For more information see Aug 29 14:10:28 volumio volumio[4904]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 14:10:28 volumio volumio[4904]: info: Discovery: Started advertising with name: Volumio Aug 29 14:10:28 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "youtube2"... Aug 29 14:10:28 volumio volumio[4904]: info: Loading plugin "ytmusic"... Aug 29 14:10:29 volumio volumio[4904]: info: Loading plugin "outputs"... Aug 29 14:10:29 volumio volumio[4904]: info: Loading plugin "albumart"... Aug 29 14:10:29 volumio volumio[4904]: info: Plugin example_plugin is not enabled Aug 29 14:10:29 volumio volumio[4904]: info: Loading plugin "inputs"... Aug 29 14:10:29 volumio volumio[4904]: info: Loading plugin "updater_comm"... Aug 29 14:10:29 volumio volumio[4904]: info: Plugin mpdemulation is not enabled Aug 29 14:10:29 volumio volumio[4904]: info: Loading plugin "rest_api"... Aug 29 14:10:29 volumio volumio[4904]: info: Loading plugin "websocket"... Aug 29 14:10:29 volumio volumio[4904]: info: Starting Socket.io Server version 2.3.0 Aug 29 14:10:29 volumio volumio[4904]: info: Loading plugin "fusiondsp"... Aug 29 14:10:29 volumio volumio[4904]: Forking 3 albumart workers Aug 29 14:10:29 volumio volumio[4904]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 14:10:29 volumio volumio[4904]: info: Loading plugin "touch_display"... Aug 29 14:10:29 volumio volumio[4904]: info: Applying required configuration parameters for plugin touch_display Aug 29 14:10:29 volumio volumio[4904]: info: Loading i18n strings for locale en Aug 29 14:10:29 volumio volumio[4904]: Updating browse sources language Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:29 volumio volumio[4904]: Starting albumart workers Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::initPlayerControls Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: Express server listening on port 3000 Aug 29 14:10:29 volumio volumio[4904]: [Metrics] WebUI: 1s 649.18ms Aug 29 14:10:29 volumio volumio[4904]: info: CoreStateMachine::resetVolumioState Aug 29 14:10:29 volumio volumio[4904]: info: CoreStateMachine::getcurrentVolume Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:10:29 volumio volumio[4904]: Starting albumart workers Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: Starting albumart workers Aug 29 14:10:29 volumio volumio[4904]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 14:10:29 volumio volumio[4904]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 29 14:10:29 volumio volumio[4904]: info: Reloading queue from file Aug 29 14:10:29 volumio volumio[4904]: info: CoreStateMachine::setRepeat null single undefined Aug 29 14:10:29 volumio volumio[4904]: info: CoreStateMachine::pushState Aug 29 14:10:29 volumio volumio[4904]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:29 volumio volumio[4904]: info: CoreStateMachine::setRandom null Aug 29 14:10:29 volumio volumio[4904]: info: CoreStateMachine::pushState Aug 29 14:10:29 volumio volumio[4904]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:29 volumio volumio[4904]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 14:10:29 volumio volumio[4904]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Aug 29 14:10:29 volumio volumio[4904]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 14:10:29 volumio volumio[4904]: info: CoreStateMachine::pushState Aug 29 14:10:29 volumio volumio[4904]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:29 volumio volumio[4904]: info: CoreStateMachine::updateTrackBlock Aug 29 14:10:29 volumio volumio[4904]: info: CorePlayQueue::getTrackBlock Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:10:29 volumio volumio[4904]: info: Setting Device type: Raspberry PI Aug 29 14:10:29 volumio volumio[4904]: info: Completed loading Core Plugins Aug 29 14:10:29 volumio volumio[4904]: info: Preparing to generate the ALSA configuration file Aug 29 14:10:29 volumio volumio[4904]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:10:29 volumio volumio[4904]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:10:29 volumio volumio[4904]: info: Reading ALSA contributions from plugins. Aug 29 14:10:29 volumio volumio[4904]: info: CoreStateMachine::pushState Aug 29 14:10:29 volumio volumio[4904]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:29 volumio volumio[4904]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:10:29 volumio volumio[4904]: info: Output device has changed, restarting MPD Aug 29 14:10:29 volumio sudo[5008]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:10:29 volumio sudo[5008]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:29 volumio volumio[4904]: info: Output device has changed, restarting Shairport Sync Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:29 volumio sudo[5008]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:29 volumio sudo[5024]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:10:29 volumio sudo[5024]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:29 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:10:29 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:10:29 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:10:29 volumio volumio[4904]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:29 volumio volumio[4904]: info: ___________ START PLUGINS ___________ Aug 29 14:10:29 volumio volumio[4904]: info: ControllerMpd::onStart: Initializing MPD Aug 29 14:10:29 volumio volumio[4904]: info: Creating MPD Configuration file Aug 29 14:10:29 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:10:29 volumio sudo[5034]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:29 volumio sudo[5034]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:29 volumio volumio[4904]: info: [1724920829738] CoreMusicLibrary::Adding element Media Servers Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:29 volumio sudo[5036]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:29 volumio sudo[5036]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:29 volumio sudo[5034]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:29 volumio sudo[5032]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:10:29 volumio sudo[5032]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:29 volumio sudo[5032]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:29 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 14:10:29 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:10:29 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:10:29 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:10:29 volumio volumio[4904]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:29 volumio volumio[4904]: info: [1724920829780] CoreMusicLibrary::Adding element Last_100 Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:29 volumio volumio[4904]: info: [1724920829782] CoreMusicLibrary::Adding element Webradio Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:29 volumio sudo[5042]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:10:29 volumio sudo[5042]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:29 volumio volumio[4904]: info: Initializing BBC Radios Aug 29 14:10:29 volumio sudo[5042]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:29 volumio volumio[4904]: info: [1724920829813] CoreMusicLibrary::Adding element YouTube2 Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:29 volumio volumio[4904]: Cannot find translation for source YouTube2 Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:29 volumio volumio[4904]: info: [1724920829815] CoreMusicLibrary::Adding element YouTube Music Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:29 volumio volumio[4904]: Cannot find translation for source YouTube2 Aug 29 14:10:29 volumio volumio[4904]: Cannot find translation for source YouTube Music Aug 29 14:10:29 volumio volumio[4904]: info: Loading i18n strings for locale en Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: FusionDsp - mixtype--------------------- Software Aug 29 14:10:29 volumio volumio[4904]: info: Loading i18n strings for locale en Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:29 volumio volumio[4904]: info: Volumio Calling Home Aug 29 14:10:29 volumio volumio[4904]: info: Preparing to generate the ALSA configuration file Aug 29 14:10:29 volumio sudo[5062]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 29 14:10:29 volumio sudo[5062]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:29 volumio sudo[5065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 29 14:10:29 volumio sudo[5065]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:29 volumio sudo[5062]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:29 volumio systemd[1]: Reloading. Aug 29 14:10:29 volumio sudo[5068]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 14:10:29 volumio sudo[5068]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:29 volumio volumio[4904]: info: Discovery: adding 32308897-683e-4edd-a3a1-38bd95fb29e7 Aug 29 14:10:29 volumio volumio[4904]: info: Discovery: Found device Volumio Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:29 volumio volumio[4904]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:29 volumio volumio[4904]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:10:29 volumio volumio[4904]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:10:29 volumio volumio[4904]: info: Reading ALSA contributions from plugins. Aug 29 14:10:29 volumio volumio[4904]: info: touch_display: No backlight interface detected. Aug 29 14:10:29 volumio volumio[4904]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 29 14:10:29 volumio volumio[4904]: info: MPD Permissions set Aug 29 14:10:29 volumio volumio[4904]: info: MPD Permissions set Aug 29 14:10:29 volumio volumio[4904]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:29 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:30 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:30 volumio volumio[4904]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 14:10:30 volumio sudo[5092]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 29 14:10:30 volumio sudo[5092]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:30 volumio sudo[5092]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:30 volumio volumio[4904]: info: Starting Shairport Sync Aug 29 14:10:30 volumio sudo[5095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 29 14:10:30 volumio sudo[5095]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:30 volumio sudo[5095]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:30 volumio volumio[4904]: info: Starting Shairport Sync Aug 29 14:10:30 volumio sudo[5098]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:30 volumio sudo[5098]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:30 volumio volumio[4904]: info: Starting Shairport Sync Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:30 volumio volumio[4904]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:30 volumio sudo[5103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:30 volumio sudo[5103]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:30 volumio volumio[4904]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 29 14:10:30 volumio sudo[5102]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:30 volumio sudo[5102]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:30 volumio volumio[4904]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 29 14:10:30 volumio volumio[4904]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:10:30 volumio volumio[4904]: info: Output device has changed, restarting MPD Aug 29 14:10:30 volumio sudo[5107]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:10:30 volumio sudo[5107]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:30 volumio mpd[5048]: Aug 29 14:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:10:30 volumio volumio[4904]: info: Output device has changed, restarting Shairport Sync Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:30 volumio sudo[5107]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:30 volumio sudo[5112]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:10:30 volumio sudo[5112]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:30 volumio volumio[4904]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:30 volumio volumio[4904]: info: MPD Permissions set Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:30 volumio volumio[4904]: info: Starting Shairport Sync Aug 29 14:10:30 volumio sudo[5122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:30 volumio sudo[5122]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:30 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:10:30 volumio sudo[5036]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:30 volumio sudo[5065]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:30 volumio sudo[5024]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:30 volumio volumio[4904]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 29 14:10:30 volumio systemd[1]: Reloading. Aug 29 14:10:30 volumio volumio[4904]: error: MPD error: The expression evaluated to a falsy value: Aug 29 14:10:30 volumio volumio[4904]: assert.ok(self.idling) Aug 29 14:10:30 volumio volumio[4904]: error: The expression evaluated to a falsy value: Aug 29 14:10:30 volumio volumio[4904]: assert.ok(self.idling) Aug 29 14:10:30 volumio volumio[4904]: error: updateQueue error: null Aug 29 14:10:30 volumio volumio[4904]: info: MPD running with PID5048 Aug 29 14:10:30 volumio volumio[4904]: ,establishing connection Aug 29 14:10:30 volumio volumio[4904]: error: updateQueue error: null Aug 29 14:10:30 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:10:30 volumio sudo[5068]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:30 volumio volumio[4904]: info: touch_display: systemctl daemon-reload succeeded. Aug 29 14:10:30 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:10:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 14:10:30 volumio sudo[5140]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 29 14:10:30 volumio sudo[5140]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:30 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 14:10:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 14:10:30 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:10:30 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:10:30 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:10:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 14:10:30 volumio sudo[5122]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:30 volumio sudo[5102]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:30 volumio sudo[5103]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:30 volumio sudo[5098]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:30 volumio sudo[5140]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:30 volumio volumio[4904]: info: Shairport-Sync Started Aug 29 14:10:30 volumio volumio[4904]: Error adding Membership: Error: addMembership EINVAL Aug 29 14:10:30 volumio volumio[4904]: info: Shairport-Sync Started Aug 29 14:10:30 volumio volumio[4904]: info: Shairport-Sync Started Aug 29 14:10:30 volumio volumio[4904]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 29 14:10:30 volumio volumio[4904]: info: touch_display: Volumio Kiosk started. Aug 29 14:10:30 volumio sudo[5142]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:10:30 volumio sudo[5142]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:30 volumio volumio[4904]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:30 volumio volumio[4904]: info: Completed starting Core Plugins Aug 29 14:10:30 volumio volumio[4904]: info: ------------------------------------------- Aug 29 14:10:30 volumio volumio[4904]: info: ----- MyVolumio plugins startup ---- Aug 29 14:10:30 volumio sudo[5142]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:30 volumio volumio[4904]: info: ------------------------------------------- Aug 29 14:10:30 volumio volumio[4904]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 29 14:10:30 volumio volumio[4904]: info: Shairport-Sync Started Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:30 volumio volumio[4904]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:30 volumio volumio[4904]: info: touch_display: X display number found: 0 Aug 29 14:10:30 volumio volumio[4904]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:10:30 volumio volumio[4904]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:30 volumio volumio[4904]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:30 volumio volumio[4904]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:10:30 volumio volumio[4904]: info: Received Get System Info Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:10:30 volumio volumio[4904]: info: Discovery: Getting this device information Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:30 volumio volumio[4904]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:30 volumio volumio[4904]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:30 volumio volumio[4904]: info: Listing playlists Aug 29 14:10:30 volumio volumio[4904]: info: touch_display: X display number found: 0 Aug 29 14:10:30 volumio volumio[4904]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:30 volumio volumio[4904]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:30 volumio mpd[5149]: Aug 29 14:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:10:30 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:10:30 volumio sudo[5112]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:31 volumio volumio[4904]: error: updateQueue error: null Aug 29 14:10:31 volumio volumio[4904]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:10:31 volumio volumio[4904]: TypeError: Cannot read property 'off' of undefined Aug 29 14:10:31 volumio volumio[4904]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Aug 29 14:10:31 volumio volumio[4904]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Aug 29 14:10:31 volumio volumio[4904]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Aug 29 14:10:31 volumio volumio[4904]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1537:29) Aug 29 14:10:31 volumio volumio[4904]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1387:47) Aug 29 14:10:31 volumio volumio[4904]: at Socket.emit (events.js:315:20) Aug 29 14:10:31 volumio volumio[4904]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 29 14:10:31 volumio volumio[4904]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 29 14:10:31 volumio volumio[4904]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:10:31 volumio sudo[5170]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 14:09 Aug 29 14:10:31 volumio sudo[5170]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:31 volumio sudo[5170]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:32 volumio sudo[4936]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:32 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 14:10:32 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 14:10:32 volumio systemd[1]: Started dynamicswap service. Aug 29 14:10:32 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:10:33 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 14:10:33 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 12. Aug 29 14:10:33 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 14:10:33 volumio systemd[1]: Started Volumio Backend Module. Aug 29 14:10:33 volumio systemd[1]: Started dynamicswap service. Aug 29 14:10:33 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:10:33 volumio volumio[5183]: info: ------------------------------------------- Aug 29 14:10:33 volumio volumio[5183]: info: ----- Volumio3 ---- Aug 29 14:10:33 volumio volumio[5183]: info: ------------------------------------------- Aug 29 14:10:33 volumio volumio[5183]: info: ----- System startup ---- Aug 29 14:10:33 volumio volumio[5183]: info: ------------------------------------------- Aug 29 14:10:33 volumio volumio[5183]: info: MYVOLUMIO Environment detected Aug 29 14:10:33 volumio volumio[5183]: info: Plugin folders cleanup Aug 29 14:10:33 volumio volumio[5183]: info: Scanning into folder /volumio/app/plugins/ Aug 29 14:10:33 volumio volumio[5183]: info: Scanning category audio_interface Aug 29 14:10:33 volumio volumio[5183]: info: Scanning category miscellanea Aug 29 14:10:33 volumio volumio[5183]: info: Scanning category music_service Aug 29 14:10:33 volumio volumio[5183]: info: Scanning category plugins.json Aug 29 14:10:33 volumio volumio[5183]: info: Scanning category system_controller Aug 29 14:10:33 volumio volumio[5183]: info: Scanning category user_interface Aug 29 14:10:33 volumio volumio[5183]: info: Scanning into folder /data/plugins/ Aug 29 14:10:33 volumio volumio[5183]: info: Scanning category audio_interface Aug 29 14:10:33 volumio volumio[5183]: info: Scanning category music_service Aug 29 14:10:33 volumio volumio[5183]: info: Scanning category user_interface Aug 29 14:10:33 volumio volumio[5183]: info: Plugin folders cleanup completed Aug 29 14:10:33 volumio volumio[5183]: info: ------------------------------------------- Aug 29 14:10:33 volumio volumio[5183]: info: ----- Core plugins startup ---- Aug 29 14:10:33 volumio volumio[5183]: info: ------------------------------------------- Aug 29 14:10:33 volumio volumio[5183]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 14:10:33 volumio volumio[5183]: info: Adding plugin upnp to MyMusic Plugins Aug 29 14:10:33 volumio volumio[5183]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 14:10:33 volumio volumio[5183]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 14:10:33 volumio volumio[5183]: info: Loading plugins from folder /data/plugins/ Aug 29 14:10:33 volumio volumio[5183]: info: Loading plugin "system"... Aug 29 14:10:33 volumio volumio[5183]: info: Loading plugin "appearance"... Aug 29 14:10:33 volumio volumio[5183]: info: Loading plugin "network"... Aug 29 14:10:33 volumio volumio[5183]: info: Refreshing Cached IP Addresses Aug 29 14:10:33 volumio sudo[5210]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 14:10:33 volumio sudo[5210]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:33 volumio volumio[5183]: info: Loading plugin "services"... Aug 29 14:10:33 volumio sudo[5212]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 14:10:33 volumio sudo[5212]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:33 volumio sudo[5210]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:33 volumio volumio[5183]: info: Loading plugin "alsa_controller"... Aug 29 14:10:33 volumio sudo[5212]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:33 volumio sudo[5215]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 14:10:33 volumio sudo[5215]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:33 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:33 volumio volumio[5183]: info: Loading plugin "wizard"... Aug 29 14:10:33 volumio volumio[5183]: info: Loading plugin "networkfs"... Aug 29 14:10:33 volumio volumio[5183]: info: Starting Udev Watcher for removable devices Aug 29 14:10:33 volumio volumio[5183]: info: Mounting Device 7A6D-519E Aug 29 14:10:33 volumio sudo[5224]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/7A6D-519E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:10:33 volumio sudo[5224]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:33 volumio sudo[5224]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:33 volumio volumio[5183]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:10:33 volumio volumio[5183]: error: Failed to mount 7A6D-519E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/7A6D-519E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:10:33 volumio volumio[5183]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:10:33 volumio volumio[5183]: info: Ignoring mount for partition: boot Aug 29 14:10:33 volumio volumio[5183]: info: Ignoring mount for partition: volumio Aug 29 14:10:33 volumio volumio[5183]: info: Ignoring mount for partition: volumio_data Aug 29 14:10:33 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:10:33 volumio volumio[5183]: info: Loading plugin "volumio_command_line_client"... Aug 29 14:10:33 volumio volumio[5183]: info: Loading plugin "upnp"... Aug 29 14:10:33 volumio volumio[5183]: info: [1724920833938] Starting Upmpd Daemon Aug 29 14:10:33 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:10:33 volumio volumio[5183]: info: Loading plugin "my_music"... Aug 29 14:10:33 volumio volumio[5183]: info: Loading plugin "mpd"... Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "upnp_browser"... Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "alarm-clock"... Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "airplay_emulation"... Aug 29 14:10:34 volumio volumio[5183]: info: Starting Shairport Sync Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "last_100"... Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "webradio"... Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "i2s_dacs"... Aug 29 14:10:34 volumio volumio[5183]: info: I2S DAC not set, start Auto-detection Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "volumiodiscovery"... Aug 29 14:10:34 volumio volumio[5183]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:34 volumio node[5183]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:34 volumio volumio[5183]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:34 volumio node[5183]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:34 volumio volumio[5183]: *** WARNING *** For more information see Aug 29 14:10:34 volumio node[5183]: *** WARNING *** For more information see Aug 29 14:10:34 volumio volumio[5183]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:34 volumio node[5183]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:34 volumio volumio[5183]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:34 volumio node[5183]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:34 volumio volumio[5183]: *** WARNING *** For more information see Aug 29 14:10:34 volumio node[5183]: *** WARNING *** For more information see Aug 29 14:10:34 volumio volumio[5183]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 14:10:34 volumio volumio[5183]: info: Discovery: Started advertising with name: Volumio Aug 29 14:10:34 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "youtube2"... Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "ytmusic"... Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "outputs"... Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "albumart"... Aug 29 14:10:34 volumio volumio[5183]: info: Plugin example_plugin is not enabled Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "inputs"... Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "updater_comm"... Aug 29 14:10:34 volumio volumio[5183]: info: Plugin mpdemulation is not enabled Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "rest_api"... Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "websocket"... Aug 29 14:10:34 volumio volumio[5183]: info: Starting Socket.io Server version 2.3.0 Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "fusiondsp"... Aug 29 14:10:34 volumio volumio[5183]: Forking 3 albumart workers Aug 29 14:10:34 volumio volumio-remote-updater[857]: [2024-08-29 14:10:34] [connect] Successful connection Aug 29 14:10:34 volumio volumio[5183]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 14:10:34 volumio volumio[5183]: info: Loading plugin "touch_display"... Aug 29 14:10:34 volumio volumio[5183]: info: Applying required configuration parameters for plugin touch_display Aug 29 14:10:34 volumio volumio[5183]: info: Loading i18n strings for locale en Aug 29 14:10:34 volumio volumio[5183]: Updating browse sources language Aug 29 14:10:34 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:34 volumio volumio[5183]: Starting albumart workers Aug 29 14:10:34 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:10:34 volumio volumio[5183]: info: CoreCommandRouter::initPlayerControls Aug 29 14:10:34 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:34 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:34 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:34 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:34 volumio volumio[5183]: Starting albumart workers Aug 29 14:10:34 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:34 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:34 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:34 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:34 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:10:34 volumio volumio[5183]: Express server listening on port 3000 Aug 29 14:10:34 volumio volumio[5183]: [Metrics] WebUI: 1s 655.94ms Aug 29 14:10:34 volumio volumio[5183]: info: CoreStateMachine::resetVolumioState Aug 29 14:10:34 volumio volumio[5183]: info: CoreStateMachine::getcurrentVolume Aug 29 14:10:34 volumio volumio[5183]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:35 volumio volumio-remote-updater[857]: [2024-08-29 14:10:35] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1724920834 101 Aug 29 14:10:35 volumio volumio[5183]: 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 Aug 29 14:10:35 volumio volumio[5183]: Starting albumart workers Aug 29 14:10:35 volumio volumio[5183]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 29 14:10:35 volumio volumio[5183]: info: CoreStateMachine::pushState Aug 29 14:10:35 volumio volumio[5183]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:35 volumio volumio[5183]: info: CoreStateMachine::updateTrackBlock Aug 29 14:10:35 volumio volumio[5183]: info: CorePlayQueue::getTrackBlock Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:10:35 volumio volumio[5183]: info: Reloading queue from file Aug 29 14:10:35 volumio volumio[5183]: info: CoreStateMachine::setRepeat null single undefined Aug 29 14:10:35 volumio volumio[5183]: info: CoreStateMachine::pushState Aug 29 14:10:35 volumio volumio[5183]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:35 volumio volumio[5183]: info: CoreStateMachine::setRandom null Aug 29 14:10:35 volumio volumio[5183]: info: CoreStateMachine::pushState Aug 29 14:10:35 volumio volumio[5183]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:35 volumio volumio[5183]: info: Setting Device type: Raspberry PI Aug 29 14:10:35 volumio volumio[5183]: info: CoreStateMachine::pushState Aug 29 14:10:35 volumio volumio[5183]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:35 volumio volumio[5183]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 14:10:35 volumio volumio[5183]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Aug 29 14:10:35 volumio volumio[5183]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 14:10:35 volumio volumio[5183]: info: Completed loading Core Plugins Aug 29 14:10:35 volumio volumio[5183]: info: Preparing to generate the ALSA configuration file Aug 29 14:10:35 volumio volumio[5183]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:10:35 volumio volumio[5183]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:10:35 volumio volumio[5183]: info: Reading ALSA contributions from plugins. Aug 29 14:10:35 volumio volumio[5183]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:10:35 volumio volumio[5183]: info: Output device has changed, restarting MPD Aug 29 14:10:35 volumio sudo[5295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:10:35 volumio sudo[5295]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio sudo[5295]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:35 volumio volumio[5183]: info: Output device has changed, restarting Shairport Sync Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:35 volumio sudo[5298]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:10:35 volumio sudo[5298]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:10:35 volumio volumio[5183]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:35 volumio volumio[5183]: info: ___________ START PLUGINS ___________ Aug 29 14:10:35 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:10:35 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:10:35 volumio volumio[5183]: info: ControllerMpd::onStart: Initializing MPD Aug 29 14:10:35 volumio volumio[5183]: info: Creating MPD Configuration file Aug 29 14:10:35 volumio sudo[5305]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:10:35 volumio sudo[5305]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:35 volumio volumio[5183]: info: [1724920835208] CoreMusicLibrary::Adding element Media Servers Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:35 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:35 volumio sudo[5308]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:10:35 volumio sudo[5308]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio sudo[5305]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:35 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 14:10:35 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:10:35 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:10:35 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:10:35 volumio volumio[5183]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:35 volumio volumio[5183]: info: [1724920835257] CoreMusicLibrary::Adding element Last_100 Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:35 volumio volumio[5183]: info: [1724920835258] CoreMusicLibrary::Adding element Webradio Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:35 volumio volumio[5183]: info: Initializing BBC Radios Aug 29 14:10:35 volumio sudo[5321]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:10:35 volumio sudo[5321]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio sudo[5321]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:35 volumio volumio[5183]: info: [1724920835292] CoreMusicLibrary::Adding element YouTube2 Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:35 volumio volumio[5183]: Cannot find translation for source YouTube2 Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:35 volumio volumio[5183]: info: [1724920835294] CoreMusicLibrary::Adding element YouTube Music Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:35 volumio volumio[5183]: Cannot find translation for source YouTube2 Aug 29 14:10:35 volumio volumio[5183]: Cannot find translation for source YouTube Music Aug 29 14:10:35 volumio volumio[5183]: info: Loading i18n strings for locale en Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: FusionDsp - mixtype--------------------- Software Aug 29 14:10:35 volumio volumio[5183]: info: Loading i18n strings for locale en Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:35 volumio volumio[5183]: info: Volumio Calling Home Aug 29 14:10:35 volumio volumio[5183]: info: Preparing to generate the ALSA configuration file Aug 29 14:10:35 volumio sudo[5341]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 29 14:10:35 volumio sudo[5341]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio sudo[5343]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 29 14:10:35 volumio sudo[5343]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio sudo[5347]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 14:10:35 volumio sudo[5347]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio sudo[5341]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:35 volumio systemd[1]: Reloading. Aug 29 14:10:35 volumio volumio[5183]: info: Discovery: adding 32308897-683e-4edd-a3a1-38bd95fb29e7 Aug 29 14:10:35 volumio volumio[5183]: info: Discovery: Found device Volumio Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:35 volumio volumio[5183]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:35 volumio volumio[5183]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:10:35 volumio volumio[5183]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:10:35 volumio volumio[5183]: info: Reading ALSA contributions from plugins. Aug 29 14:10:35 volumio volumio[5183]: info: touch_display: No backlight interface detected. Aug 29 14:10:35 volumio volumio[5183]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 29 14:10:35 volumio volumio[5183]: info: MPD Permissions set Aug 29 14:10:35 volumio volumio[5183]: info: MPD Permissions set Aug 29 14:10:35 volumio volumio[5183]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 14:10:35 volumio sudo[5372]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 29 14:10:35 volumio mpd[5327]: Aug 29 14:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:10:35 volumio volumio[5183]: info: Starting Shairport Sync Aug 29 14:10:35 volumio sudo[5373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 29 14:10:35 volumio sudo[5373]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:10:35 volumio sudo[5373]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:35 volumio sudo[5372]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio sudo[5372]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:35 volumio volumio[5183]: info: Starting Shairport Sync Aug 29 14:10:35 volumio sudo[5379]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:35 volumio sudo[5379]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio volumio[5183]: info: Starting Shairport Sync Aug 29 14:10:35 volumio sudo[5382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:35 volumio sudo[5382]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:35 volumio volumio[5183]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:35 volumio sudo[5385]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:35 volumio sudo[5385]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio volumio[5183]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 29 14:10:35 volumio volumio[5183]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 29 14:10:35 volumio volumio[5183]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:10:35 volumio volumio[5183]: info: Output device has changed, restarting MPD Aug 29 14:10:35 volumio sudo[5388]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:10:35 volumio sudo[5388]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio volumio[5183]: info: Output device has changed, restarting Shairport Sync Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio sudo[5388]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:35 volumio sudo[5391]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:10:35 volumio sudo[5391]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio volumio[5183]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:35 volumio volumio[5183]: info: MPD Permissions set Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:35 volumio volumio[5183]: info: Starting Shairport Sync Aug 29 14:10:35 volumio sudo[5401]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:35 volumio sudo[5401]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:35 volumio volumio[5183]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:10:35 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:10:35 volumio sudo[5298]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:35 volumio sudo[5308]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:35 volumio sudo[5343]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:35 volumio volumio[5183]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:35 volumio volumio[5183]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:10:35 volumio volumio[5183]: info: Received Get System Info Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:10:35 volumio volumio[5183]: info: Discovery: Getting this device information Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:35 volumio systemd[1]: Reloading. Aug 29 14:10:35 volumio volumio[5183]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:35 volumio volumio[5183]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:35 volumio volumio[5183]: info: Listing playlists Aug 29 14:10:35 volumio volumio[5183]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 29 14:10:35 volumio volumio[5183]: error: MPD error: The expression evaluated to a falsy value: Aug 29 14:10:35 volumio volumio[5183]: assert.ok(self.idling) Aug 29 14:10:35 volumio volumio[5183]: error: The expression evaluated to a falsy value: Aug 29 14:10:35 volumio volumio[5183]: assert.ok(self.idling) Aug 29 14:10:35 volumio volumio[5183]: info: MPD running with PID5327 Aug 29 14:10:35 volumio volumio[5183]: ,establishing connection Aug 29 14:10:35 volumio volumio[5183]: error: updateQueue error: null Aug 29 14:10:35 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:10:35 volumio volumio[5183]: info: Volumio called home Aug 29 14:10:35 volumio volumio[5183]: error: updateQueue error: null Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:35 volumio volumio[5183]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:35 volumio sudo[5347]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:36 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:10:36 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 14:10:36 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 14:10:36 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 14:10:36 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:10:36 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:10:36 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:10:36 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 14:10:36 volumio sudo[5385]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:36 volumio sudo[5401]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:36 volumio sudo[5382]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:36 volumio sudo[5379]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:36 volumio sudo[5418]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:10:36 volumio sudo[5418]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:36 volumio sudo[5418]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:36 volumio mpd[5424]: Aug 29 14:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:10:36 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:10:36 volumio sudo[5391]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:36 volumio volumio[5183]: info: Shairport-Sync Started Aug 29 14:10:36 volumio volumio[5183]: Error adding Membership: Error: addMembership EINVAL Aug 29 14:10:36 volumio volumio[5183]: info: Shairport-Sync Started Aug 29 14:10:36 volumio volumio[5183]: info: Shairport-Sync Started Aug 29 14:10:36 volumio volumio[5183]: info: Shairport-Sync Started Aug 29 14:10:36 volumio volumio[5183]: info: touch_display: systemctl daemon-reload succeeded. Aug 29 14:10:36 volumio volumio[5183]: error: updateQueue error: null Aug 29 14:10:36 volumio sudo[5430]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 29 14:10:36 volumio sudo[5430]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:36 volumio sudo[5430]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:36 volumio volumio[5183]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 29 14:10:36 volumio volumio[5183]: info: touch_display: Volumio Kiosk started. Aug 29 14:10:36 volumio volumio[5183]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:36 volumio volumio[5183]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:36 volumio volumio[5183]: info: Completed starting Core Plugins Aug 29 14:10:36 volumio volumio[5183]: info: ------------------------------------------- Aug 29 14:10:36 volumio volumio[5183]: info: ----- MyVolumio plugins startup ---- Aug 29 14:10:36 volumio volumio[5183]: info: ------------------------------------------- Aug 29 14:10:36 volumio volumio[5183]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 29 14:10:36 volumio volumio[5183]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:36 volumio volumio[5183]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:36 volumio volumio[5183]: info: touch_display: X display number found: 0 Aug 29 14:10:36 volumio volumio[5183]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:10:36 volumio volumio[5183]: TypeError: Cannot read property 'off' of undefined Aug 29 14:10:36 volumio volumio[5183]: at FusionDsp.onStop (/data/plugins/audio_interface/fusiondsp/index.js:107:15) Aug 29 14:10:36 volumio volumio[5183]: at PluginManager.stopPlugin (/volumio/app/pluginmanager.js:460:30) Aug 29 14:10:36 volumio volumio[5183]: at PluginManager.disableAndStopPlugin (/volumio/app/pluginmanager.js:1883:8) Aug 29 14:10:36 volumio volumio[5183]: at CoreCommandRouter.disableAndStopPlugin (/volumio/app/index.js:1537:29) Aug 29 14:10:36 volumio volumio[5183]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:1387:47) Aug 29 14:10:36 volumio volumio[5183]: at Socket.emit (events.js:315:20) Aug 29 14:10:36 volumio volumio[5183]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Aug 29 14:10:36 volumio volumio[5183]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Aug 29 14:10:36 volumio volumio[5183]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:10:36 volumio sudo[5443]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 14:09 Aug 29 14:10:36 volumio sudo[5443]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:36 volumio sudo[5443]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:36 volumio volumio-remote-updater[857]: [2024-08-29 14:10:36] [error] handle_read_frame error: websocketpp.transport:7 (End of File) Aug 29 14:10:36 volumio volumio-remote-updater[857]: [2024-08-29 14:10:36] [disconnect] Disconnect close local:[1006,End of File] remote:[1006] Aug 29 14:10:36 volumio systemd[1]: volumio.service: Main process exited, code=exited, status=1/FAILURE Aug 29 14:10:36 volumio sudo[5215]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:36 volumio systemd[1]: volumio.service: Failed with result 'exit-code'. Aug 29 14:10:36 volumio systemd[1]: Started dynamicswap service. Aug 29 14:10:36 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:10:37 volumio systemd[1]: volumio.service: Service RestartSec=100ms expired, scheduling restart. Aug 29 14:10:37 volumio systemd[1]: volumio.service: Scheduled restart job, restart counter is at 13. Aug 29 14:10:37 volumio systemd[1]: Started dynamicswap service. Aug 29 14:10:37 volumio systemd[1]: Stopped Volumio Backend Module. Aug 29 14:10:37 volumio systemd[1]: Started Volumio Backend Module. Aug 29 14:10:37 volumio systemd[1]: dynamicswap.service: Succeeded. Aug 29 14:10:37 volumio volumio[5454]: info: ------------------------------------------- Aug 29 14:10:37 volumio volumio[5454]: info: ----- Volumio3 ---- Aug 29 14:10:37 volumio volumio[5454]: info: ------------------------------------------- Aug 29 14:10:37 volumio volumio[5454]: info: ----- System startup ---- Aug 29 14:10:37 volumio volumio[5454]: info: ------------------------------------------- Aug 29 14:10:37 volumio volumio[5454]: info: MYVOLUMIO Environment detected Aug 29 14:10:37 volumio volumio[5454]: info: Plugin folders cleanup Aug 29 14:10:37 volumio volumio[5454]: info: Scanning into folder /volumio/app/plugins/ Aug 29 14:10:37 volumio volumio[5454]: info: Scanning category audio_interface Aug 29 14:10:37 volumio volumio[5454]: info: Scanning category miscellanea Aug 29 14:10:37 volumio volumio[5454]: info: Scanning category music_service Aug 29 14:10:37 volumio volumio[5454]: info: Scanning category plugins.json Aug 29 14:10:37 volumio volumio[5454]: info: Scanning category system_controller Aug 29 14:10:37 volumio volumio[5454]: info: Scanning category user_interface Aug 29 14:10:37 volumio volumio[5454]: info: Scanning into folder /data/plugins/ Aug 29 14:10:37 volumio volumio[5454]: info: Scanning category audio_interface Aug 29 14:10:37 volumio volumio[5454]: info: Scanning category music_service Aug 29 14:10:37 volumio volumio[5454]: info: Scanning category user_interface Aug 29 14:10:37 volumio volumio[5454]: info: Plugin folders cleanup completed Aug 29 14:10:37 volumio volumio[5454]: info: ------------------------------------------- Aug 29 14:10:37 volumio volumio[5454]: info: ----- Core plugins startup ---- Aug 29 14:10:37 volumio volumio[5454]: info: ------------------------------------------- Aug 29 14:10:37 volumio volumio[5454]: info: Loading plugins from folder /volumio/app/plugins/ Aug 29 14:10:37 volumio volumio[5454]: info: Adding plugin upnp to MyMusic Plugins Aug 29 14:10:37 volumio volumio[5454]: info: Adding plugin airplay_emulation to MyMusic Plugins Aug 29 14:10:37 volumio volumio[5454]: info: Adding plugin upnp_browser to MyMusic Plugins Aug 29 14:10:37 volumio volumio[5454]: info: Loading plugins from folder /data/plugins/ Aug 29 14:10:37 volumio volumio[5454]: info: Loading plugin "system"... Aug 29 14:10:37 volumio volumio[5454]: info: Loading plugin "appearance"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "network"... Aug 29 14:10:38 volumio volumio[5454]: info: Refreshing Cached IP Addresses Aug 29 14:10:38 volumio sudo[5480]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 14:10:38 volumio sudo[5480]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:38 volumio sudo[5482]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 14:10:38 volumio sudo[5482]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "services"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "alsa_controller"... Aug 29 14:10:38 volumio sudo[5482]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:38 volumio sudo[5480]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:38 volumio sudo[5485]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 14:10:38 volumio sudo[5485]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:38 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "wizard"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "networkfs"... Aug 29 14:10:38 volumio volumio[5454]: info: Starting Udev Watcher for removable devices Aug 29 14:10:38 volumio volumio[5454]: info: Mounting Device 7A6D-519E Aug 29 14:10:38 volumio sudo[5494]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/7A6D-519E -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:10:38 volumio sudo[5494]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:38 volumio sudo[5494]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:38 volumio volumio[5454]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:10:38 volumio volumio[5454]: error: Failed to mount 7A6D-519E: Error: Command failed: /usr/bin/sudo /bin/mount "/dev/sda1" "/mnt/USB/7A6D-519E" -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Aug 29 14:10:38 volumio volumio[5454]: mount: /media/7A6D-519E: /dev/sda1 already mounted on /media/7A6D-519E. Aug 29 14:10:38 volumio volumio[5454]: info: Ignoring mount for partition: boot Aug 29 14:10:38 volumio volumio[5454]: info: Ignoring mount for partition: volumio Aug 29 14:10:38 volumio volumio[5454]: info: Ignoring mount for partition: volumio_data Aug 29 14:10:38 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "volumio_command_line_client"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "upnp"... Aug 29 14:10:38 volumio volumio[5454]: info: [1724920838195] Starting Upmpd Daemon Aug 29 14:10:38 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "my_music"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "mpd"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "upnp_browser"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "alarm-clock"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "airplay_emulation"... Aug 29 14:10:38 volumio volumio[5454]: info: Starting Shairport Sync Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "last_100"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "webradio"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "i2s_dacs"... Aug 29 14:10:38 volumio volumio[5454]: info: I2S DAC not set, start Auto-detection Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "volumiodiscovery"... Aug 29 14:10:38 volumio volumio[5454]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:38 volumio node[5454]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:38 volumio volumio[5454]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:38 volumio volumio[5454]: *** WARNING *** For more information see Aug 29 14:10:38 volumio volumio[5454]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:38 volumio volumio[5454]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:38 volumio volumio[5454]: *** WARNING *** For more information see Aug 29 14:10:38 volumio node[5454]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:38 volumio node[5454]: *** WARNING *** For more information see Aug 29 14:10:38 volumio node[5454]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Aug 29 14:10:38 volumio node[5454]: *** WARNING *** Please fix your application to use the native API of Avahi! Aug 29 14:10:38 volumio node[5454]: *** WARNING *** For more information see Aug 29 14:10:38 volumio volumio[5454]: info: Applying required configuration parameters for plugin volumiodiscovery Aug 29 14:10:38 volumio volumio[5454]: info: Discovery: Started advertising with name: Volumio Aug 29 14:10:38 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "youtube2"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "ytmusic"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "outputs"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "albumart"... Aug 29 14:10:38 volumio volumio[5454]: info: Plugin example_plugin is not enabled Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "inputs"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "updater_comm"... Aug 29 14:10:38 volumio volumio[5454]: info: Plugin mpdemulation is not enabled Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "rest_api"... Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "websocket"... Aug 29 14:10:38 volumio volumio[5454]: info: Starting Socket.io Server version 2.3.0 Aug 29 14:10:38 volumio volumio[5454]: info: Loading plugin "fusiondsp"... Aug 29 14:10:39 volumio volumio[5454]: Forking 3 albumart workers Aug 29 14:10:39 volumio volumio[5454]: info: Applying required configuration parameters for plugin fusiondsp Aug 29 14:10:39 volumio volumio[5454]: info: Loading plugin "touch_display"... Aug 29 14:10:39 volumio volumio[5454]: info: Applying required configuration parameters for plugin touch_display Aug 29 14:10:39 volumio volumio[5454]: info: Loading i18n strings for locale en Aug 29 14:10:39 volumio volumio[5454]: Starting albumart workers Aug 29 14:10:39 volumio volumio[5454]: Updating browse sources language Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:39 volumio volumio[5454]: Starting albumart workers Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::initPlayerControls Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: Express server listening on port 3000 Aug 29 14:10:39 volumio volumio[5454]: [Metrics] WebUI: 1s 707.37ms Aug 29 14:10:39 volumio volumio[5454]: Starting albumart workers Aug 29 14:10:39 volumio volumio[5454]: info: CoreStateMachine::resetVolumioState Aug 29 14:10:39 volumio volumio[5454]: info: CoreStateMachine::getcurrentVolume Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Aug 29 14:10:39 volumio volumio[5454]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Aug 29 14:10:39 volumio volumio[5454]: info: Reloading queue from file Aug 29 14:10:39 volumio volumio[5454]: info: CoreStateMachine::pushState Aug 29 14:10:39 volumio volumio[5454]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:39 volumio volumio[5454]: info: CoreStateMachine::updateTrackBlock Aug 29 14:10:39 volumio volumio[5454]: info: CorePlayQueue::getTrackBlock Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::volumioRetrievevolume Aug 29 14:10:39 volumio volumio[5454]: info: CoreStateMachine::setRepeat null single undefined Aug 29 14:10:39 volumio volumio[5454]: info: CoreStateMachine::pushState Aug 29 14:10:39 volumio volumio[5454]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:39 volumio volumio[5454]: info: CoreStateMachine::setRandom null Aug 29 14:10:39 volumio volumio[5454]: info: CoreStateMachine::pushState Aug 29 14:10:39 volumio volumio[5454]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:39 volumio volumio[5454]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Aug 29 14:10:39 volumio volumio[5454]: info: USB Boot Capable - System SBC Revision found in cpuinfo: c04170 Aug 29 14:10:39 volumio volumio[5454]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Aug 29 14:10:39 volumio volumio[5454]: info: CoreStateMachine::pushState Aug 29 14:10:39 volumio volumio[5454]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::volumioPushState Aug 29 14:10:39 volumio volumio[5454]: info: Setting Device type: Raspberry PI Aug 29 14:10:39 volumio volumio[5454]: info: Completed loading Core Plugins Aug 29 14:10:39 volumio volumio[5454]: info: Preparing to generate the ALSA configuration file Aug 29 14:10:39 volumio volumio[5454]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:10:39 volumio volumio[5454]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:10:39 volumio volumio[5454]: info: Reading ALSA contributions from plugins. Aug 29 14:10:39 volumio volumio[5454]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:10:39 volumio volumio[5454]: info: Output device has changed, restarting MPD Aug 29 14:10:39 volumio sudo[5573]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:10:39 volumio sudo[5573]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio volumio[5454]: info: Output device has changed, restarting Shairport Sync Aug 29 14:10:39 volumio sudo[5573]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:39 volumio sudo[5576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:10:39 volumio sudo[5576]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:10:39 volumio volumio[5454]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:39 volumio volumio[5454]: info: ___________ START PLUGINS ___________ Aug 29 14:10:39 volumio volumio[5454]: info: ControllerMpd::onStart: Initializing MPD Aug 29 14:10:39 volumio volumio[5454]: info: Creating MPD Configuration file Aug 29 14:10:39 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:10:39 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:10:39 volumio sudo[5583]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:10:39 volumio sudo[5583]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:39 volumio volumio[5454]: info: [1724920839533] CoreMusicLibrary::Adding element Media Servers Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:39 volumio sudo[5583]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:39 volumio sudo[5586]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:10:39 volumio sudo[5586]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Aug 29 14:10:39 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:10:39 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:10:39 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:10:39 volumio volumio[5454]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:39 volumio sudo[5591]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:10:39 volumio sudo[5591]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio sudo[5591]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:39 volumio volumio[5454]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:39 volumio volumio[5454]: info: [1724920839580] CoreMusicLibrary::Adding element Last_100 Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:39 volumio volumio[5454]: info: [1724920839582] CoreMusicLibrary::Adding element Webradio Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:39 volumio volumio[5454]: info: Initializing BBC Radios Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:39 volumio volumio[5454]: info: [1724920839614] CoreMusicLibrary::Adding element YouTube2 Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:39 volumio volumio[5454]: Cannot find translation for source YouTube2 Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Aug 29 14:10:39 volumio volumio[5454]: info: [1724920839618] CoreMusicLibrary::Adding element YouTube Music Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:39 volumio volumio[5454]: Cannot find translation for source YouTube2 Aug 29 14:10:39 volumio volumio[5454]: Cannot find translation for source YouTube Music Aug 29 14:10:39 volumio volumio[5454]: info: Loading i18n strings for locale en Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: FusionDsp - mixtype--------------------- Software Aug 29 14:10:39 volumio volumio[5454]: info: Loading i18n strings for locale en Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 14:10:39 volumio volumio[5454]: info: Volumio Calling Home Aug 29 14:10:39 volumio volumio[5454]: info: Preparing to generate the ALSA configuration file Aug 29 14:10:39 volumio sudo[5611]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Aug 29 14:10:39 volumio sudo[5611]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio sudo[5613]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Aug 29 14:10:39 volumio sudo[5616]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Aug 29 14:10:39 volumio sudo[5613]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio sudo[5616]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio sudo[5611]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:39 volumio systemd[1]: Reloading. Aug 29 14:10:39 volumio volumio[5454]: info: Discovery: adding 32308897-683e-4edd-a3a1-38bd95fb29e7 Aug 29 14:10:39 volumio volumio[5454]: info: Discovery: Found device Volumio Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:39 volumio volumio[5454]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:39 volumio volumio[5454]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Aug 29 14:10:39 volumio volumio[5454]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Aug 29 14:10:39 volumio volumio[5454]: info: Reading ALSA contributions from plugins. Aug 29 14:10:39 volumio volumio[5454]: info: touch_display: No backlight interface detected. Aug 29 14:10:39 volumio volumio[5454]: info: touch_display: systemctl stop getty@tty1.service succeeded. Aug 29 14:10:39 volumio volumio[5454]: info: MPD Permissions set Aug 29 14:10:39 volumio volumio[5454]: info: MPD Permissions set Aug 29 14:10:39 volumio volumio[5454]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: touch_display: No Raspberry Pi Foundation touch screen detected. Aug 29 14:10:39 volumio sudo[5641]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/99-vc4.conf Aug 29 14:10:39 volumio sudo[5641]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio volumio[5454]: info: Starting Shairport Sync Aug 29 14:10:39 volumio sudo[5641]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:39 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:10:39 volumio volumio[5454]: info: Starting Shairport Sync Aug 29 14:10:39 volumio sudo[5645]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Aug 29 14:10:39 volumio sudo[5645]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio sudo[5646]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:39 volumio sudo[5646]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio sudo[5645]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:39 volumio volumio[5454]: info: Starting Shairport Sync Aug 29 14:10:39 volumio sudo[5650]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:39 volumio sudo[5650]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio mpd[5596]: Aug 29 14:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:39 volumio volumio[5454]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:39 volumio volumio[5454]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Aug 29 14:10:39 volumio volumio[5454]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/99-vc4.conf set. Aug 29 14:10:39 volumio volumio[5454]: info: Asound.conf file unchanged, so no further update is needed Aug 29 14:10:39 volumio volumio[5454]: info: Output device has changed, restarting MPD Aug 29 14:10:39 volumio sudo[5655]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:39 volumio sudo[5655]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio volumio[5454]: info: Output device has changed, restarting Shairport Sync Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:39 volumio sudo[5659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 14:10:39 volumio sudo[5659]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio sudo[5659]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:39 volumio sudo[5660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 14:10:39 volumio sudo[5660]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio volumio[5454]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 14:10:39 volumio volumio[5454]: info: MPD Permissions set Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:39 volumio volumio[5454]: info: Starting Shairport Sync Aug 29 14:10:39 volumio sudo[5671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 14:10:39 volumio sudo[5671]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:39 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:10:39 volumio sudo[5576]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:40 volumio sudo[5616]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:40 volumio sudo[5586]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:40 volumio systemd[1]: Reloading. Aug 29 14:10:40 volumio volumio[5454]: info: touch_display: systemctl daemon-reload succeeded. Aug 29 14:10:40 volumio sudo[5674]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Aug 29 14:10:40 volumio sudo[5674]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:40 volumio volumio[5454]: error: MPD error: The expression evaluated to a falsy value: Aug 29 14:10:40 volumio volumio[5454]: assert.ok(self.idling) Aug 29 14:10:40 volumio volumio[5454]: error: The expression evaluated to a falsy value: Aug 29 14:10:40 volumio volumio[5454]: assert.ok(self.idling) Aug 29 14:10:40 volumio volumio[5454]: info: Volumio called home Aug 29 14:10:40 volumio volumio[5454]: info: MPD running with PID5596 Aug 29 14:10:40 volumio volumio[5454]: ,establishing connection Aug 29 14:10:40 volumio volumio[5454]: error: updateQueue error: null Aug 29 14:10:40 volumio volumio[5454]: error: updateQueue error: null Aug 29 14:10:40 volumio systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Aug 29 14:10:40 volumio sudo[5613]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:40 volumio volumio[5454]: info: touch_display: systemctl disable getty@tty1.service succeeded. Aug 29 14:10:40 volumio sudo[5674]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:40 volumio systemd[1]: Stopping Music Player Daemon... Aug 29 14:10:40 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Aug 29 14:10:40 volumio volumio[5454]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Aug 29 14:10:40 volumio volumio[5454]: info: touch_display: Volumio Kiosk started. Aug 29 14:10:40 volumio systemd[1]: shairport-sync.service: Succeeded. Aug 29 14:10:40 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Aug 29 14:10:40 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Aug 29 14:10:40 volumio sudo[5650]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:40 volumio sudo[5655]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:40 volumio sudo[5646]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:40 volumio sudo[5671]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:40 volumio systemd[1]: mpd.service: Succeeded. Aug 29 14:10:40 volumio systemd[1]: Stopped Music Player Daemon. Aug 29 14:10:40 volumio volumio[5454]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:40 volumio volumio[5454]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:40 volumio volumio[5454]: info: Completed starting Core Plugins Aug 29 14:10:40 volumio volumio[5454]: info: ------------------------------------------- Aug 29 14:10:40 volumio volumio[5454]: info: ----- MyVolumio plugins startup ---- Aug 29 14:10:40 volumio volumio[5454]: info: ------------------------------------------- Aug 29 14:10:40 volumio volumio[5454]: info: [MyVolumio PluginManager] Fetching plans data.... Aug 29 14:10:40 volumio systemd[1]: Starting Music Player Daemon... Aug 29 14:10:40 volumio volumio[5454]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:40 volumio volumio[5454]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:40 volumio volumio[5454]: info: Shairport-Sync Started Aug 29 14:10:40 volumio volumio[5454]: Error adding Membership: Error: addMembership EINVAL Aug 29 14:10:40 volumio volumio[5454]: info: Shairport-Sync Started Aug 29 14:10:40 volumio volumio[5454]: info: Shairport-Sync Started Aug 29 14:10:40 volumio volumio[5454]: info: Shairport-Sync Started Aug 29 14:10:40 volumio volumio[5454]: info: touch_display: X display number found: 0 Aug 29 14:10:40 volumio sudo[5696]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 14:10:40 volumio sudo[5696]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:40 volumio sudo[5696]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:40 volumio volumio[5454]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Aug 29 14:10:40 volumio volumio[5454]: info: touch_display: X display number found: 0 Aug 29 14:10:40 volumio volumio[5454]: info: touch_display: Setting screensaver timeout to 0 seconds. Aug 29 14:10:40 volumio mpd[5698]: Aug 29 14:10 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 14:10:40 volumio systemd[1]: Started Music Player Daemon. Aug 29 14:10:40 volumio sudo[5660]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:40 volumio volumio[5454]: error: updateQueue error: null Aug 29 14:10:41 volumio volumio[5454]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: Mozilla/5.0 (X11; CrOS aarch64 13597.84.0) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/92.0.4515.98 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:41 volumio volumio[5454]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:41 volumio volumio[5454]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 14:10:41 volumio volumio[5454]: info: Received Get System Info Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 14:10:41 volumio volumio[5454]: info: Discovery: Getting this device information Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:41 volumio volumio[5454]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::volumioGetState Aug 29 14:10:41 volumio volumio[5454]: info: CorePlayQueue::getTrack 0 Aug 29 14:10:41 volumio volumio[5454]: info: Listing playlists Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 14:10:41 volumio sudo[5485]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:41 volumio volumio[5454]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 14:10:41 volumio volumio[5454]: 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 Aug 29 14:10:41 volumio volumio[5454]: /usr/bin/mkfifo: cannot create fifo '/tmp/fusiondspfifo': File exists Aug 29 14:10:41 volumio volumio[5454]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Aug 29 14:10:41 volumio volumio[5454]: info: camilladsp service started and running in background, instance 1 Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 14:10:41 volumio volumio-remote-updater[857]: [2024-08-29 14:10:41] [connect] Successful connection Aug 29 14:10:41 volumio volumio[5454]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 14:10:41 volumio volumio[5454]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Aug 29 14:10:41 volumio volumio[5454]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Aug 29 14:10:41 volumio volumio[5454]: info: FusionDsp loaded Aug 29 14:10:41 volumio volumio[5454]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 14:10:41 volumio volumio[5454]: info: FusionDsp - Reporting Fusion DSP Enabled Aug 29 14:10:41 volumio volumio[5454]: info: Adding Signal Path Element [object Object] Aug 29 14:10:41 volumio volumio[5454]: info: Adding fusiondspeq DSP Signal Path Element Aug 29 14:10:41 volumio volumio[5454]: info: FusionDsp - ---- installed callbackRead Aug 29 14:10:41 volumio volumio[5454]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:10:41 volumio sudo[5720]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Aug 29 14:10:41 volumio sudo[5720]: pam_unix(sudo:session): session opened for user root by (uid=0) Aug 29 14:10:41 volumio volumio[5454]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Aug 29 14:10:41 volumio volumio[5454]: at Process.ChildProcess._handle.onexit (internal/child_process.js:269:19) Aug 29 14:10:41 volumio volumio[5454]: at onErrorNT (internal/child_process.js:465:16) Aug 29 14:10:41 volumio volumio[5454]: at processTicksAndRejections (internal/process/task_queues.js:80:21) Aug 29 14:10:41 volumio volumio[5454]: at runNextTicks (internal/process/task_queues.js:62:3) Aug 29 14:10:41 volumio volumio[5454]: at listOnTimeout (internal/timers.js:523:9) Aug 29 14:10:41 volumio volumio[5454]: at processTimers (internal/timers.js:497:7) { Aug 29 14:10:41 volumio volumio[5454]: errno: -2, Aug 29 14:10:41 volumio volumio[5454]: code: 'ENOENT', Aug 29 14:10:41 volumio volumio[5454]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 14:10:41 volumio volumio[5454]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Aug 29 14:10:41 volumio volumio[5454]: spawnargs: [ Aug 29 14:10:41 volumio volumio[5454]: '-p', Aug 29 14:10:41 volumio volumio[5454]: 9876, Aug 29 14:10:41 volumio volumio[5454]: '-o', Aug 29 14:10:41 volumio volumio[5454]: '/tmp/camilladsp.log', Aug 29 14:10:41 volumio volumio[5454]: '-l', Aug 29 14:10:41 volumio volumio[5454]: 'warn', Aug 29 14:10:41 volumio volumio[5454]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Aug 29 14:10:41 volumio volumio[5454]: ] Aug 29 14:10:41 volumio volumio[5454]: } Aug 29 14:10:41 volumio volumio[5454]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 14:10:41 volumio sudo[5720]: pam_unix(sudo:session): session closed for user root Aug 29 14:10:42 volumio sudo[5731]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-08-29 14:09 Aug 29 14:10:42 volumio sudo[5731]: 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="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:17:01 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="2782e52360f70dfab079c82951ebbbb1"