-- Logs begin at Thu 2024-09-05 22:22:23 +0330, end at Thu 2024-09-05 22:31:45 +0330. -- Sep 05 22:30:13 volumio ntpd[1028]: Soliciting pool server 2a02:180:1:1::517:1034 Sep 05 22:30:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 22:30:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 22:30:15 volumio volumio[1039]: info: Discovery: Getting this device information Sep 05 22:30:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 05 22:30:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 22:30:15 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.1.104 from 192.168.1.103 UA: unknown Engine version: 3 Transport: websocket Total Clients: 5 Sep 05 22:30:15 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 05 22:30:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 05 22:30:15 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 05 22:30:17 volumio sudo[3026]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 22:30:17 volumio sudo[3026]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:30:17 volumio sudo[3026]: pam_unix(sudo:session): session closed for user root Sep 05 22:30:17 volumio sudo[3029]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 22:30:17 volumio sudo[3029]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:30:17 volumio sudo[3029]: pam_unix(sudo:session): session closed for user root Sep 05 22:30:17 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.1.104 from 192.168.1.103 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Sep 05 22:30:17 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 05 22:30:17 volumio volumio[1039]: info: CoreCommandRouter::volumioGetVisibleSources Sep 05 22:30:17 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 22:30:17 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 05 22:30:17 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 05 22:30:17 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 05 22:30:17 volumio volumio[1039]: info: Received Get System Info Sep 05 22:30:17 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 22:30:17 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 22:30:17 volumio volumio[1039]: info: Discovery: Getting this device information Sep 05 22:30:17 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 05 22:30:17 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 22:30:17 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 05 22:30:17 volumio volumio[1039]: info: Listing playlists Sep 05 22:30:17 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 05 22:30:17 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 05 22:30:18 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 05 22:30:19 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 05 22:30:19 volumio volumio[1039]: info: Received Get System Info Sep 05 22:30:19 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 22:30:19 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 22:30:19 volumio volumio[1039]: info: Discovery: Getting this device information Sep 05 22:30:19 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 05 22:30:19 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 22:30:19 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 05 22:30:19 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 05 22:30:19 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 05 22:30:19 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 05 22:30:19 volumio ntpd[1028]: Soliciting pool server 49.12.35.6 Sep 05 22:30:20 volumio ntpd[1028]: Soliciting pool server 168.119.211.223 Sep 05 22:30:21 volumio ntpd[1028]: Soliciting pool server 49.13.14.46 Sep 05 22:30:24 volumio volumio[1039]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Sep 05 22:30:27 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 05 22:30:34 volumio volumio[1039]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/fusiondsp/volumio/buster/amd64 Sep 05 22:30:34 volumio volumio[1039]: info: Folder /tmp/plugins removed Sep 05 22:30:34 volumio volumio[1039]: info: Check plugin dependencies Sep 05 22:30:34 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Sep 05 22:30:34 volumio volumio[1039]: info: Checking if plugin already exists Sep 05 22:30:34 volumio volumio[1039]: info: Rename folder Sep 05 22:30:34 volumio volumio[1039]: info: Folder /tmp/downloaded_plugin.zip removed Sep 05 22:30:34 volumio volumio[1039]: info: Move to category Sep 05 22:30:38 volumio volumio[1039]: info: Checking if install.sh is present Sep 05 22:30:38 volumio volumio[1039]: info: Executing install.sh Sep 05 22:30:38 volumio volumio[1039]: Upnp client error: Error: This socket has been ended by the other party Sep 05 22:30:38 volumio sudo[3124]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/sh /data/plugins/audio_interface/fusiondsp/install.sh Sep 05 22:30:38 volumio sudo[3124]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:30:38 volumio volumio[1039]: info: creating filters folder and copying demo filters Sep 05 22:30:39 volumio volumio[1039]: info: copying demo flters Sep 05 22:30:39 volumio volumio[1039]: info: Installing/fusiondsp dependencies Sep 05 22:30:39 volumio sudo[3147]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt update Sep 05 22:30:39 volumio sudo[3147]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:30:39 volumio volumio[1039]: info: Err:1 https://deb.nodesource.com/node_14.x buster InRelease Sep 05 22:30:39 volumio volumio[1039]: info: Something wicked happened resolving 'deb.nodesource.com:https' (-5 - No address associated with hostname) Sep 05 22:30:51 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 22:30:51 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 22:30:51 volumio volumio[1039]: info: Discovery: Getting this device information Sep 05 22:30:51 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 05 22:30:51 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 22:30:51 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.1.104 from 192.168.1.103 UA: unknown Engine version: 3 Transport: websocket Total Clients: 5 Sep 05 22:30:51 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 05 22:30:51 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Sep 05 22:30:51 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Sep 05 22:30:53 volumio sudo[3183]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Sep 05 22:30:53 volumio sudo[3183]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:30:53 volumio sudo[3183]: pam_unix(sudo:session): session closed for user root Sep 05 22:30:53 volumio sudo[3185]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Sep 05 22:30:53 volumio sudo[3185]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:30:53 volumio sudo[3185]: pam_unix(sudo:session): session closed for user root Sep 05 22:30:53 volumio volumio[1039]: verbose: New Socket.io Connection to 192.168.1.104 from 192.168.1.103 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 17_6_1 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 6 Sep 05 22:30:53 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Sep 05 22:30:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetVisibleSources Sep 05 22:30:53 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Sep 05 22:30:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 05 22:30:53 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Sep 05 22:30:53 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Sep 05 22:30:53 volumio volumio[1039]: info: Received Get System Info Sep 05 22:30:53 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 22:30:53 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 22:30:53 volumio volumio[1039]: info: Discovery: Getting this device information Sep 05 22:30:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 05 22:30:53 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 22:30:53 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 05 22:30:53 volumio volumio[1039]: info: Listing playlists Sep 05 22:30:53 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Sep 05 22:30:53 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Sep 05 22:30:53 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Sep 05 22:30:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Sep 05 22:30:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 05 22:30:54 volumio volumio[1039]: info: Received Get System Info Sep 05 22:30:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 22:30:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 22:30:54 volumio volumio[1039]: info: Discovery: Getting this device information Sep 05 22:30:54 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 05 22:30:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 22:30:54 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 05 22:30:56 volumio volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 22:30:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Sep 05 22:30:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 22:30:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Sep 05 22:30:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 22:30:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 22:30:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 22:30:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 22:30:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 22:30:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 22:30:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Sep 05 22:30:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Sep 05 22:30:56 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Sep 05 22:31:03 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Sep 05 22:31:08 volumio volumio[1039]: info: CALLMETHOD: audio_interface alsa_controller saveResamplingOpts [object Object] Sep 05 22:31:08 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveResamplingOpts Sep 05 22:31:08 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: mpd , saveResampleOptions Sep 05 22:31:09 volumio sudo[3226]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 22:31:09 volumio sudo[3226]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:31:09 volumio sudo[3226]: pam_unix(sudo:session): session closed for user root Sep 05 22:31:09 volumio volumio[1039]: info: MPD Permissions set Sep 05 22:31:09 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:09 volumio sudo[3228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 22:31:09 volumio sudo[3228]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:31:09 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 22:31:09 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 22:31:09 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 05 22:31:09 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:09 volumio systemd[1]: mpd.service: Succeeded. Sep 05 22:31:09 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 22:31:09 volumio systemd[1]: Starting Music Player Daemon... Sep 05 22:31:09 volumio sudo[3238]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 22:31:09 volumio sudo[3238]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:31:09 volumio sudo[3238]: pam_unix(sudo:session): session closed for user root Sep 05 22:31:09 volumio volumio[1039]: error: Upnp client error: Error: This socket has been ended by the other party Sep 05 22:31:09 volumio volumio[1039]: info: Get:2 http://deb.debian.org/debian buster InRelease [122 kB] Sep 05 22:31:09 volumio mpd[3242]: Sep 05 22:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 22:31:09 volumio systemd[1]: Started Music Player Daemon. Sep 05 22:31:09 volumio sudo[3228]: pam_unix(sudo:session): session closed for user root Sep 05 22:31:09 volumio volumio[1039]: error: updateQueue error: null Sep 05 22:31:10 volumio volumio[1039]: info: Get:3 http://deb.debian.org/debian buster/main i386 Packages [7866 kB] Sep 05 22:31:10 volumio volumio[1039]: info: CoreCommandRouter::volumioStop Sep 05 22:31:10 volumio volumio[1039]: info: CoreStateMachine::stop Sep 05 22:31:10 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 22:31:10 volumio volumio[1039]: info: CoreStateMachine::stPlaybackTimer Sep 05 22:31:10 volumio volumio[1039]: info: CoreStateMachine::updateTrackBlock Sep 05 22:31:10 volumio volumio[1039]: info: CorePlayQueue::getTrackBlock Sep 05 22:31:10 volumio volumio[1039]: info: CoreStateMachine::pushState Sep 05 22:31:10 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Sep 05 22:31:10 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:31:10 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 05 22:31:10 volumio volumio[1039]: info: CoreStateMachine::serviceStop Sep 05 22:31:10 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Sep 05 22:31:10 volumio volumio[1039]: info: CoreCommandRouter::serviceStop Sep 05 22:31:10 volumio volumio[1039]: info: [1725562870483] ControllerWebradio::stop Sep 05 22:31:10 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 22:31:10 volumio volumio[1039]: info: sendMpdCommand stop took 7 milliseconds Sep 05 22:31:11 volumio volumio[1039]: info: CoreCommandRouter::volumioPlay Sep 05 22:31:11 volumio volumio[1039]: info: CoreStateMachine::play index undefined Sep 05 22:31:11 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 22:31:11 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Sep 05 22:31:11 volumio volumio[1039]: info: CoreStateMachine::startPlaybackTimer Sep 05 22:31:11 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Sep 05 22:31:11 volumio volumio[1039]: info: [1725562871551] ControllerWebradio::clearAddPlayTrack Sep 05 22:31:11 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 22:31:11 volumio volumio[1039]: info: sendMpdCommand stop took 0 milliseconds Sep 05 22:31:11 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 22:31:11 volumio volumio[1039]: info: Sep 05 22:31:11 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Sep 05 22:31:11 volumio volumio[1039]: info: Ignoring MPD Status Update Sep 05 22:31:11 volumio volumio[1039]: info: sendMpdCommand clear took 1 milliseconds Sep 05 22:31:11 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99445847" Sep 05 22:31:11 volumio volumio[1039]: error: updateQueue error: null Sep 05 22:31:11 volumio volumio[1039]: info: ------------------------------ 2ms Sep 05 22:31:12 volumio volumio[1039]: info: CoreCommandRouter::volumioPlay Sep 05 22:31:12 volumio volumio[1039]: info: CoreStateMachine::play index undefined Sep 05 22:31:12 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService undefined Sep 05 22:31:12 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Sep 05 22:31:12 volumio volumio[1039]: info: CoreStateMachine::startPlaybackTimer Sep 05 22:31:12 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Sep 05 22:31:12 volumio volumio[1039]: info: [1725562872336] ControllerWebradio::clearAddPlayTrack Sep 05 22:31:12 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand stop Sep 05 22:31:12 volumio volumio[1039]: info: Sep 05 22:31:12 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Sep 05 22:31:12 volumio volumio[1039]: info: Ignoring MPD Status Update Sep 05 22:31:12 volumio volumio[1039]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99445847" took 1418 milliseconds Sep 05 22:31:12 volumio volumio[1039]: info: sendMpdCommand stop took 637 milliseconds Sep 05 22:31:12 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 05 22:31:12 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand play Sep 05 22:31:12 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand clear Sep 05 22:31:12 volumio volumio[1039]: info: ------------------------------ 2ms Sep 05 22:31:12 volumio volumio[1039]: info: sendMpdCommand play took 2 milliseconds Sep 05 22:31:13 volumio volumio[1039]: info: Sep 05 22:31:13 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Sep 05 22:31:13 volumio volumio[1039]: info: Ignoring MPD Status Update Sep 05 22:31:13 volumio volumio[1039]: info: Sep 05 22:31:13 volumio volumio[1039]: ---------------------------- MPD announces state update: player Sep 05 22:31:13 volumio volumio[1039]: info: sendMpdCommand clear took 116 milliseconds Sep 05 22:31:13 volumio volumio[1039]: info: ControllerMpd::getState Sep 05 22:31:13 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Sep 05 22:31:13 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99445847" Sep 05 22:31:13 volumio volumio[1039]: error: updateQueue error: null Sep 05 22:31:13 volumio volumio[1039]: info: ------------------------------ 2ms Sep 05 22:31:13 volumio volumio[1039]: info: sendMpdCommand status took 1 milliseconds Sep 05 22:31:13 volumio volumio[1039]: verbose: ControllerMpd::parseState Sep 05 22:31:13 volumio volumio[1039]: info: ControllerMpd::pushState Sep 05 22:31:13 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Sep 05 22:31:13 volumio volumio[1039]: info: CoreStateMachine::pushState Sep 05 22:31:13 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:31:13 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 05 22:31:13 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Sep 05 22:31:13 volumio volumio[1039]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Sep 05 22:31:13 volumio volumio[1039]: verbose: CURRENT POSITION 0 Sep 05 22:31:13 volumio volumio[1039]: info: CoreStateMachine::syncState stateService stop Sep 05 22:31:13 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Sep 05 22:31:13 volumio volumio[1039]: info: CoreStateMachine::pushState Sep 05 22:31:13 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 05 22:31:13 volumio volumio[1039]: info: No code Sep 05 22:31:13 volumio volumio[1039]: info: CoreStateMachine::pushState Sep 05 22:31:13 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 05 22:31:13 volumio volumio[1039]: info: ------------------------------ 31ms Sep 05 22:31:13 volumio volumio[1039]: info: Sep 05 22:31:13 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Sep 05 22:31:13 volumio volumio[1039]: info: Ignoring MPD Status Update Sep 05 22:31:13 volumio volumio[1039]: info: sendMpdCommand load "http://yp.shoutcast.com/sbin/tunein-station.m3u?id=99445847" took 183 milliseconds Sep 05 22:31:13 volumio volumio[1039]: info: CoreStateMachine::setConsumeUpdateService mpd Sep 05 22:31:13 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand play Sep 05 22:31:13 volumio volumio[1039]: info: ------------------------------ 9ms Sep 05 22:31:13 volumio volumio[1039]: info: sendMpdCommand play took 9 milliseconds Sep 05 22:31:14 volumio volumio[1039]: info: Sep 05 22:31:14 volumio volumio[1039]: ---------------------------- MPD announces state update: player Sep 05 22:31:14 volumio volumio[1039]: info: ControllerMpd::getState Sep 05 22:31:14 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Sep 05 22:31:14 volumio volumio[1039]: info: Sep 05 22:31:14 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Sep 05 22:31:14 volumio volumio[1039]: info: Ignoring MPD Status Update Sep 05 22:31:14 volumio volumio[1039]: info: Sep 05 22:31:14 volumio volumio[1039]: ---------------------------- MPD announces state update: player Sep 05 22:31:14 volumio volumio[1039]: info: ControllerMpd::getState Sep 05 22:31:14 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Sep 05 22:31:14 volumio volumio[1039]: info: sendMpdCommand status took 4 milliseconds Sep 05 22:31:14 volumio volumio[1039]: verbose: ControllerMpd::parseState Sep 05 22:31:14 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 22:31:14 volumio volumio[1039]: info: ------------------------------ 6ms Sep 05 22:31:14 volumio volumio[1039]: info: sendMpdCommand status took 5 milliseconds Sep 05 22:31:14 volumio volumio[1039]: verbose: ControllerMpd::parseState Sep 05 22:31:14 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 22:31:14 volumio volumio[1039]: info: Sep 05 22:31:14 volumio volumio[1039]: ---------------------------- MPD announces system playlist update Sep 05 22:31:14 volumio volumio[1039]: info: Ignoring MPD Status Update Sep 05 22:31:14 volumio volumio[1039]: info: Sep 05 22:31:14 volumio volumio[1039]: ---------------------------- MPD announces state update: player Sep 05 22:31:14 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 05 22:31:14 volumio volumio[1039]: info: ControllerMpd::getState Sep 05 22:31:14 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand status Sep 05 22:31:14 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 2 milliseconds Sep 05 22:31:14 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Sep 05 22:31:14 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Sep 05 22:31:14 volumio volumio[1039]: info: ControllerMpd::pushState Sep 05 22:31:14 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Sep 05 22:31:14 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Sep 05 22:31:14 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Netsu - Back To The Source","artist":"Hirschmilch Chillout","album":null,"uri":"https://hirschmilch.de:7000/chillout.aac","trackType":"aac"} Sep 05 22:31:14 volumio volumio[1039]: verbose: CURRENT POSITION 0 Sep 05 22:31:14 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Sep 05 22:31:14 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus stop Sep 05 22:31:14 volumio volumio[1039]: info: ControllerMpd::pushState Sep 05 22:31:14 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Sep 05 22:31:14 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Sep 05 22:31:14 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Netsu - Back To The Source","artist":"Hirschmilch Chillout","album":null,"uri":"https://hirschmilch.de:7000/chillout.aac","trackType":"aac"} Sep 05 22:31:14 volumio volumio[1039]: verbose: CURRENT POSITION 0 Sep 05 22:31:14 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Sep 05 22:31:14 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Sep 05 22:31:14 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Sep 05 22:31:14 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 22:31:14 volumio volumio[1039]: info: CoreStateMachine::pushState Sep 05 22:31:14 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 05 22:31:14 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 05 22:31:14 volumio volumio[1039]: info: CoreStateMachine::pushState Sep 05 22:31:14 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 05 22:31:14 volumio volumio[1039]: info: ------------------------------ 23ms Sep 05 22:31:14 volumio volumio[1039]: info: ------------------------------ 29ms Sep 05 22:31:14 volumio volumio[1039]: info: ------------------------------ 24ms Sep 05 22:31:14 volumio volumio[1039]: info: sendMpdCommand status took 23 milliseconds Sep 05 22:31:14 volumio volumio[1039]: verbose: ControllerMpd::parseState Sep 05 22:31:14 volumio volumio[1039]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 05 22:31:14 volumio volumio[1039]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 05 22:31:14 volumio volumio[1039]: verbose: ControllerMpd::parseTrackInfo Sep 05 22:31:14 volumio volumio[1039]: info: ControllerMpd::pushState Sep 05 22:31:14 volumio volumio[1039]: info: CoreCommandRouter::servicePushState Sep 05 22:31:14 volumio volumio[1039]: info: CorePlayQueue::getTrack 0 Sep 05 22:31:14 volumio volumio[1039]: verbose: STATE SERVICE {"status":"play","position":0,"seek":853,"duration":0,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"96 Kbps","isStreaming":false,"title":"Netsu - Back To The Source","artist":"Hirschmilch Chillout","album":null,"uri":"https://hirschmilch.de:7000/chillout.aac","trackType":"aac"} Sep 05 22:31:14 volumio volumio[1039]: verbose: CURRENT POSITION 0 Sep 05 22:31:14 volumio volumio[1039]: info: CoreStateMachine::syncState stateService play Sep 05 22:31:14 volumio volumio[1039]: info: CoreStateMachine::syncState currentStatus play Sep 05 22:31:14 volumio volumio[1039]: info: Received an update from plugin. extracting info from payload Sep 05 22:31:14 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: albumart , getAlbumArt Sep 05 22:31:14 volumio volumio[1039]: info: CoreStateMachine::pushState Sep 05 22:31:14 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 05 22:31:14 volumio volumio[1039]: info: CoreStateMachine::pushState Sep 05 22:31:14 volumio volumio[1039]: info: CoreCommandRouter::volumioPushState Sep 05 22:31:14 volumio volumio[1039]: info: ------------------------------ 60ms Sep 05 22:31:18 volumio ntpd[1028]: Soliciting pool server 213.239.234.28 Sep 05 22:31:19 volumio volumio[1039]: info: Get:4 http://deb.debian.org/debian buster/main amd64 Packages [7909 kB] Sep 05 22:31:25 volumio ntpd[1028]: Soliciting pool server 217.91.44.17 Sep 05 22:31:27 volumio ntpd[1028]: Soliciting pool server 31.209.85.243 Sep 05 22:31:27 volumio ntpd[1028]: Soliciting pool server 62.169.23.198 Sep 05 22:31:28 volumio volumio[1039]: info: Get:5 http://deb.debian.org/debian buster/main Translation-en [5969 kB] Sep 05 22:31:34 volumio volumio[1039]: info: Get:6 http://deb.debian.org/debian buster/contrib i386 Packages [46.0 kB] Sep 05 22:31:35 volumio volumio[1039]: info: Get:7 http://deb.debian.org/debian buster/contrib amd64 Packages [50.1 kB] Sep 05 22:31:35 volumio volumio[1039]: info: Get:8 http://deb.debian.org/debian buster/contrib Translation-en [44.2 kB] Sep 05 22:31:35 volumio volumio[1039]: info: Get:9 http://deb.debian.org/debian buster/non-free i386 Packages [76.3 kB] Sep 05 22:31:35 volumio volumio[1039]: info: Get:10 http://deb.debian.org/debian buster/non-free amd64 Packages [87.8 kB] Sep 05 22:31:35 volumio volumio[1039]: info: Get:11 http://deb.debian.org/debian buster/non-free Translation-en [88.9 kB] Sep 05 22:31:36 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Sep 05 22:31:36 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Sep 05 22:31:36 volumio volumio[1039]: info: Discovery: Getting this device information Sep 05 22:31:36 volumio volumio[1039]: info: CoreCommandRouter::volumioGetState Sep 05 22:31:36 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 22:31:38 volumio volumio[1039]: info: Fetched 22.3 MB in 59s (376 kB/s) Sep 05 22:31:40 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Sep 05 22:31:40 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Sep 05 22:31:41 volumio volumio[1039]: info: Reading package lists... Sep 05 22:31:42 volumio volumio[1039]: info: Building dependency tree... Sep 05 22:31:42 volumio volumio[1039]: info: Reading state information... Sep 05 22:31:42 volumio volumio[1039]: info: All packages are up to date. Sep 05 22:31:42 volumio sudo[3147]: pam_unix(sudo:session): session closed for user root Sep 05 22:31:42 volumio sudo[3461]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/apt -y install python3-aiohttp python3-pip Sep 05 22:31:42 volumio sudo[3461]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:31:42 volumio volumio[1039]: info: Reading package lists... Sep 05 22:31:42 volumio volumio[1039]: info: Enabling plugin fusiondsp Sep 05 22:31:42 volumio volumio[1039]: info: Loading plugin "fusiondsp"... Sep 05 22:31:43 volumio volumio[1039]: info: Preparing to generate the ALSA configuration file Sep 05 22:31:43 volumio volumio[1039]: info: Asound.conf file unchanged, so no further update is needed Sep 05 22:31:43 volumio volumio[1039]: info: Output device has changed, restarting MPD Sep 05 22:31:43 volumio volumio[1039]: info: Output device has changed, restarting Shairport Sync Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 22:31:43 volumio sudo[3469]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 22:31:43 volumio sudo[3469]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:31:43 volumio sudo[3469]: pam_unix(sudo:session): session closed for user root Sep 05 22:31:43 volumio sudo[3471]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 22:31:43 volumio sudo[3471]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:31:43 volumio systemd[1]: Stopping Music Player Daemon... Sep 05 22:31:43 volumio volumio[1039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 05 22:31:43 volumio volumio[1039]: info: PLUGIN START: fusiondsp Sep 05 22:31:43 volumio volumio[1039]: info: Loading i18n strings for locale en Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Sep 05 22:31:43 volumio systemd[1]: mpd.service: Succeeded. Sep 05 22:31:43 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:43 volumio volumio[1039]: info: FusionDsp - mixtype--------------------- Hardware Sep 05 22:31:43 volumio volumio[1039]: info: Preparing to generate the ALSA configuration file Sep 05 22:31:43 volumio volumio[1039]: info: Done. Sep 05 22:31:43 volumio systemd[1]: Starting Music Player Daemon... Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::getUIConfigOnPlugin Sep 05 22:31:43 volumio volumio[1039]: info: MPD Permissions set Sep 05 22:31:43 volumio volumio[1039]: info: Building dependency tree... Sep 05 22:31:43 volumio volumio[1039]: info: Reading state information... Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:43 volumio sudo[3477]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 22:31:43 volumio sudo[3477]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:31:43 volumio sudo[3477]: pam_unix(sudo:session): session closed for user root Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:43 volumio volumio[1039]: info: Asound.conf file unchanged, so no further update is needed Sep 05 22:31:43 volumio volumio[1039]: info: Output device has changed, restarting MPD Sep 05 22:31:43 volumio volumio[1039]: info: Output device has changed, restarting Shairport Sync Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 22:31:43 volumio sudo[3488]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Sep 05 22:31:43 volumio sudo[3488]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:31:43 volumio sudo[3488]: pam_unix(sudo:session): session closed for user root Sep 05 22:31:43 volumio sudo[3491]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Sep 05 22:31:43 volumio sudo[3491]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:31:43 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Sep 05 22:31:43 volumio systemd[1]: mpd.service: Succeeded. Sep 05 22:31:43 volumio volumio[1039]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Sep 05 22:31:43 volumio systemd[1]: Stopped Music Player Daemon. Sep 05 22:31:43 volumio volumio[1039]: info: MPD Permissions set Sep 05 22:31:43 volumio systemd[1]: Starting Music Player Daemon... Sep 05 22:31:43 volumio sudo[3497]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Sep 05 22:31:43 volumio sudo[3497]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:31:43 volumio sudo[3497]: pam_unix(sudo:session): session closed for user root Sep 05 22:31:43 volumio volumio[1039]: info: Starting Shairport Sync Sep 05 22:31:43 volumio sudo[3501]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 05 22:31:43 volumio sudo[3501]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:31:43 volumio volumio[1039]: error: Upnp client error: Error: This socket has been ended by the other party Sep 05 22:31:43 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:43 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 05 22:31:43 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 05 22:31:43 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 05 22:31:44 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 05 22:31:44 volumio sudo[3501]: pam_unix(sudo:session): session closed for user root Sep 05 22:31:44 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Sep 05 22:31:44 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Sep 05 22:31:44 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:44 volumio volumio[1039]: info: Shairport-Sync Started Sep 05 22:31:44 volumio volumio[1039]: info: Starting Shairport Sync Sep 05 22:31:44 volumio sudo[3513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Sep 05 22:31:44 volumio sudo[3513]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:31:44 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Sep 05 22:31:44 volumio systemd[1]: shairport-sync.service: Succeeded. Sep 05 22:31:44 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Sep 05 22:31:44 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Sep 05 22:31:44 volumio sudo[3513]: pam_unix(sudo:session): session closed for user root Sep 05 22:31:44 volumio volumio[1039]: info: Shairport-Sync Started Sep 05 22:31:44 volumio mpd[3499]: Sep 05 22:31 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Sep 05 22:31:44 volumio systemd[1]: Started Music Player Daemon. Sep 05 22:31:44 volumio sudo[3471]: pam_unix(sudo:session): session closed for user root Sep 05 22:31:44 volumio sudo[3491]: pam_unix(sudo:session): session closed for user root Sep 05 22:31:44 volumio volumio[1039]: error: MPD error: The expression evaluated to a falsy value: Sep 05 22:31:44 volumio volumio[1039]: assert.ok(self.idling) Sep 05 22:31:44 volumio volumio[1039]: error: The expression evaluated to a falsy value: Sep 05 22:31:44 volumio volumio[1039]: assert.ok(self.idling) Sep 05 22:31:44 volumio volumio[1039]: error: updateQueue error: null Sep 05 22:31:44 volumio volumio[1039]: info: The following additional packages will be installed: Sep 05 22:31:44 volumio volumio[1039]: info: binutils binutils-common binutils-x86-64-linux-gnu build-essential bzip2 Sep 05 22:31:44 volumio volumio[1039]: info: dh-python dpkg-dev fakeroot g++ g++-8 gcc gcc-8 libalgorithm-diff-perl Sep 05 22:31:44 volumio volumio[1039]: info: libalgorithm-diff-xs-perl libalgorithm-merge-perl libasan5 libbinutils Sep 05 22:31:44 volumio volumio[1039]: info: libcc1-0 libexpat1-dev libfakeroot libgcc-8-dev libitm1 liblsan0 libmpx2 Sep 05 22:31:44 volumio volumio[1039]: info: libpython3-dev libpython3.7 libpython3.7-dev libquadmath0 libstdc++-8-dev Sep 05 22:31:44 volumio volumio[1039]: info: libtsan0 libubsan1 make patch python-pip-whl python3-asn1crypto Sep 05 22:31:44 volumio volumio[1039]: info: python3-async-timeout python3-attr python3-cffi-backend python3-crypto Sep 05 22:31:44 volumio volumio[1039]: info: python3-cryptography python3-dbus python3-dev python3-entrypoints python3-gi Sep 05 22:31:44 volumio volumio[1039]: info: python3-keyring python3-keyrings.alt python3-multidict python3-secretstorage Sep 05 22:31:44 volumio volumio[1039]: info: python3-setuptools python3-wheel python3-xdg python3-yarl python3.7-dev Sep 05 22:31:44 volumio volumio[1039]: info: Suggested packages: Sep 05 22:31:44 volumio volumio[1039]: info: binutils-doc bzip2-doc debian-keyring g++-multilib g++-8-multilib gcc-8-doc Sep 05 22:31:44 volumio volumio[1039]: info: libstdc++6-8-dbg gcc-multilib autoconf automake libtool flex bison gdb Sep 05 22:31:44 volumio volumio[1039]: info: gcc-doc gcc-8-multilib gcc-8-locales libgcc1-dbg libgomp1-dbg libitm1-dbg Sep 05 22:31:44 volumio volumio[1039]: info: libatomic1-dbg libasan5-dbg liblsan0-dbg libtsan0-dbg libubsan1-dbg Sep 05 22:31:44 volumio volumio[1039]: info: libmpx2-dbg libquadmath0-dbg libstdc++-8-doc make-doc ed diffutils-doc Sep 05 22:31:44 volumio volumio[1039]: info: python-attr-doc python-crypto-doc python-cryptography-doc Sep 05 22:31:44 volumio volumio[1039]: info: python3-cryptography-vectors python-dbus-doc python3-dbus-dbg gnome-keyring Sep 05 22:31:44 volumio volumio[1039]: info: libkf5wallet-bin gir1.2-gnomekeyring-1.0 python-secretstorage-doc Sep 05 22:31:44 volumio volumio[1039]: info: python-setuptools-doc Sep 05 22:31:44 volumio volumio[1039]: info: The following NEW packages will be installed: Sep 05 22:31:44 volumio volumio[1039]: info: binutils binutils-common binutils-x86-64-linux-gnu build-essential bzip2 Sep 05 22:31:44 volumio volumio[1039]: info: dh-python dpkg-dev fakeroot g++ g++-8 gcc gcc-8 libalgorithm-diff-perl Sep 05 22:31:44 volumio volumio[1039]: info: libalgorithm-diff-xs-perl libalgorithm-merge-perl libasan5 libbinutils Sep 05 22:31:44 volumio volumio[1039]: info: libcc1-0 libexpat1-dev libfakeroot libgcc-8-dev libitm1 liblsan0 libmpx2 Sep 05 22:31:44 volumio volumio[1039]: info: libpython3-dev libpython3.7 libpython3.7-dev libquadmath0 libstdc++-8-dev Sep 05 22:31:44 volumio volumio[1039]: info: libtsan0 libubsan1 make patch python-pip-whl python3-aiohttp Sep 05 22:31:44 volumio volumio[1039]: info: python3-asn1crypto python3-async-timeout python3-attr python3-cffi-backend Sep 05 22:31:44 volumio volumio[1039]: info: python3-crypto python3-cryptography python3-dbus python3-dev Sep 05 22:31:44 volumio volumio[1039]: info: python3-entrypoints python3-gi python3-keyring python3-keyrings.alt Sep 05 22:31:44 volumio volumio[1039]: info: python3-multidict python3-pip python3-secretstorage python3-setuptools Sep 05 22:31:44 volumio volumio[1039]: info: python3-wheel python3-xdg python3-yarl python3.7-dev Sep 05 22:31:45 volumio volumio[1039]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Sep 05 22:31:45 volumio volumio[1039]: info: camilladsp service started and running in background, instance 1 Sep 05 22:31:45 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Sep 05 22:31:45 volumio volumio[1039]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 22:31:45 volumio volumio[1039]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Sep 05 22:31:45 volumio volumio[1039]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Sep 05 22:31:45 volumio volumio[1039]: info: FusionDsp loaded Sep 05 22:31:45 volumio volumio[1039]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Sep 05 22:31:45 volumio sudo[3532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Sep 05 22:31:45 volumio sudo[3532]: pam_unix(sudo:session): session opened for user root by (uid=0) Sep 05 22:31:45 volumio volumio[1039]: info: FusionDsp - Reporting Fusion DSP Enabled Sep 05 22:31:45 volumio volumio[1039]: info: Adding Signal Path Element [object Object] Sep 05 22:31:45 volumio volumio[1039]: info: Adding fusiondspeq DSP Signal Path Element Sep 05 22:31:45 volumio volumio[1039]: info: FusionDsp - ---- installed callbackRead Sep 05 22:31:45 volumio volumio[1039]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 22:31:45 volumio volumio[1039]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Sep 05 22:31:45 volumio volumio[1039]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Sep 05 22:31:45 volumio volumio[1039]: at onErrorNT (internal/child_process.js:472:16) Sep 05 22:31:45 volumio volumio[1039]: at processTicksAndRejections (internal/process/task_queues.js:82:21) { Sep 05 22:31:45 volumio volumio[1039]: errno: -2, Sep 05 22:31:45 volumio volumio[1039]: code: 'ENOENT', Sep 05 22:31:45 volumio volumio[1039]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 22:31:45 volumio volumio[1039]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Sep 05 22:31:45 volumio volumio[1039]: spawnargs: [ Sep 05 22:31:45 volumio volumio[1039]: '-p', Sep 05 22:31:45 volumio volumio[1039]: 9876, Sep 05 22:31:45 volumio volumio[1039]: '-o', Sep 05 22:31:45 volumio volumio[1039]: '/tmp/camilladsp.log', Sep 05 22:31:45 volumio volumio[1039]: '-l', Sep 05 22:31:45 volumio volumio[1039]: 'warn', Sep 05 22:31:45 volumio volumio[1039]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Sep 05 22:31:45 volumio volumio[1039]: ] Sep 05 22:31:45 volumio volumio[1039]: } Sep 05 22:31:45 volumio volumio[1039]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 05 22:31:45 volumio sudo[3532]: pam_unix(sudo:session): session closed for user root Sep 05 22:31:45 volumio sudo[3543]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-09-05 22:30 Sep 05 22:31:45 volumio sudo[3543]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="5fd3886148e72a9cecd88a772d1f6079fb64d11a" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8bb23b009e91ae4c7ecd678f193e7d3b281fca0c" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Mon 22 Jul 2024 07:48:50 PM CEST" VOLUMIO_VERSION="3.742" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="1049be5fd382035818795b7f5c38e10d"