-- Logs begin at Thu 2019-02-14 05:11:59 EST, end at Mon 2025-03-17 16:36:33 EDT. -- Mar 17 16:35:01 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:01 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:02 volumio volumio[788]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin multiroom Mar 17 16:35:02 volumio sudo[1642]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -rf /tmp/multiroom Mar 17 16:35:02 volumio sudo[1642]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:02 volumio sudo[1642]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:03 volumio volumio[788]: info: MRS: MultiRoom plugin initialized Mar 17 16:35:03 volumio volumio[788]: info: MRS: STOPPING SNAPCLIENT Mar 17 16:35:03 volumio volumio[788]: info: MRS: Snap server stop Mar 17 16:35:03 volumio sudo[1658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapclient Mar 17 16:35:03 volumio sudo[1658]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:03 volumio volumio[788]: info: MRS: STOPPING volumioStreaming Mar 17 16:35:03 volumio sudo[1658]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:03 volumio sudo[1661]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioSnapserver Mar 17 16:35:03 volumio sudo[1661]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:03 volumio sudo[1661]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:03 volumio volumio[788]: info: [MyVolumio PluginManager] Loading plugin "metavolumio"... Mar 17 16:35:03 volumio sudo[1663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumioStreaming Mar 17 16:35:03 volumio sudo[1663]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:03 volumio sudo[1666]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/rm -f /tmp/hls/* Mar 17 16:35:03 volumio sudo[1666]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:03 volumio sudo[1666]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:03 volumio sudo[1663]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:03 volumio volumio[788]: info: [MyVolumio PluginManager] Loading plugin "manifestui"... Mar 17 16:35:03 volumio volumio[788]: info: [MyVolumio PluginManager] Loading plugin "cd_controller"... Mar 17 16:35:03 volumio volumio[788]: info: [MyVolumio PluginManager] Loading plugin "smart_inputs"... Mar 17 16:35:03 volumio volumio[788]: info: [MyVolumio PluginManager] Loading plugin "hi_res_audio"... Mar 17 16:35:03 volumio volumio[788]: info: [MyVolumio PluginManager] Applying required configuration parameters for plugin hi_res_audio Mar 17 16:35:03 volumio volumio[788]: info: [MyVolumio PluginManager] Loading plugin "tidal"... Mar 17 16:35:03 volumio volumio[788]: info: [MyVolumio PluginManager] Loading plugin "qobuz"... Mar 17 16:35:03 volumio volumio[788]: info: [MyVolumio PluginManager] Loading plugin "tidalconnect"... Mar 17 16:35:03 volumio volumio[788]: info: Preparing to generate the ALSA configuration file Mar 17 16:35:03 volumio volumio[788]: info: Updating MyVolumio device info Mar 17 16:35:03 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:03 volumio volumio[788]: info: Updating MyVolumio device info Mar 17 16:35:03 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:03 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:03 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:03 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 17 16:35:03 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:03 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Mar 17 16:35:04 volumio volumio[788]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 17 16:35:04 volumio volumio[788]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 17 16:35:04 volumio volumio[788]: info: Reading ALSA contributions from plugins. Mar 17 16:35:04 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:04 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:04 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:04 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:04 volumio volumio[788]: info: Successfully Added MyVolumio device Mar 17 16:35:04 volumio volumio[788]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 16:35:04 volumio volumio[788]: info: MRS: Removed streaming files Mar 17 16:35:04 volumio volumio[788]: info: MRS: volumioStreaming STOPPED Mar 17 16:35:04 volumio volumio[788]: info: MRS: SNAPSERVER STOPPED Mar 17 16:35:04 volumio volumio[788]: info: MRS: SNAPCLIENT STOPPED Mar 17 16:35:04 volumio volumio[788]: info: camilladsp spawned new process with pid 1671, instance 1, run: true Mar 17 16:35:04 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled Mar 17 16:35:04 volumio volumio-remote-updater[474]: No test mode Mar 17 16:35:04 volumio volumio-remote-updater[474]: No alpha test mode Mar 17 16:35:04 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:04 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 17 16:35:04 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:04 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 17 16:35:04 volumio volumio[788]: info: Getting Alsa Cards List without I2S DAC Mar 17 16:35:04 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 17 16:35:04 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:05 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:05 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:05 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:05 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:05 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:05 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 17 16:35:05 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 17 16:35:05 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:05 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:05 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:05 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:05 volumio volumio[788]: info: Successfully Added MyVolumio device Mar 17 16:35:05 volumio volumio[788]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Mar 17 16:35:05 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Mar 17 16:35:05 volumio sudo[1693]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart sshtunnel.service Mar 17 16:35:05 volumio sudo[1693]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:05 volumio volumio[788]: info: Asound.conf file unchanged, so no further update is needed Mar 17 16:35:05 volumio volumio[788]: info: Output device has changed, restarting MPD Mar 17 16:35:05 volumio sudo[1697]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 16:35:05 volumio systemd[1]: Started MyVolumio SSH Tunnel. Mar 17 16:35:05 volumio sudo[1697]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:05 volumio sudo[1693]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:05 volumio volumio[788]: info: Output device has changed, restarting Shairport Sync Mar 17 16:35:05 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:05 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:05 volumio sudo[1697]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:05 volumio sudo[1699]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 16:35:05 volumio sudo[1699]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:05 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 17 16:35:05 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 17 16:35:05 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 17 16:35:05 volumio systemd[1]: Stopping Music Player Daemon... Mar 17 16:35:05 volumio autossh[1702]: port set to 0, monitoring disabled Mar 17 16:35:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:05 volumio autossh[1702]: starting ssh (count 1) Mar 17 16:35:05 volumio autossh[1702]: ssh child pid is 1708 Mar 17 16:35:05 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:05 volumio volumio[788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 16:35:05 volumio volumio[788]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 17 16:35:05 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:05 volumio volumio[788]: info: [MyVolumio PluginManager] Starting plugin audio_interface.bluetooth Mar 17 16:35:05 volumio volumio[788]: info: [MyVolumio PluginManager] Plugin bluetooth disabled by user. Not starting Mar 17 16:35:05 volumio volumio[788]: info: [MyVolumio PluginManager] Starting plugin audio_interface.multiroom Mar 17 16:35:05 volumio volumio[788]: info: [MyVolumio PluginManager] Plugin multiroom disabled by user. Not starting Mar 17 16:35:05 volumio volumio[788]: info: [MyVolumio PluginManager] Starting plugin miscellanea.metavolumio Mar 17 16:35:05 volumio volumio[788]: info: Adding METAVOLUMIO REST API Endpoints Mar 17 16:35:05 volumio volumio[788]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Mar 17 16:35:05 volumio volumio[788]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Mar 17 16:35:05 volumio volumio[788]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Mar 17 16:35:05 volumio volumio[788]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Mar 17 16:35:05 volumio volumio[788]: info: [MyVolumio PluginManager] Starting plugin miscellanea.manifestui Mar 17 16:35:05 volumio volumio[788]: info: [MyVolumio PluginManager] Starting plugin music_service.cd_controller Mar 17 16:35:05 volumio volumio[788]: info: Preparing CD Folders Mar 17 16:35:05 volumio volumio[788]: info: Adding CD REST API Endpoints Mar 17 16:35:05 volumio volumio[788]: info: Adding cdPostRip REST Endpoint for plugin: music_service/cd_controller Mar 17 16:35:05 volumio volumio[788]: info: Starting UDEV Watcher for CD Mar 17 16:35:05 volumio volumio[788]: info: Detecting CD presence with UDEV Mar 17 16:35:05 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: networkfs , getUdevDevices Mar 17 16:35:05 volumio volumio[788]: info: [MyVolumio PluginManager] Starting plugin music_service.smart_inputs Mar 17 16:35:05 volumio volumio[788]: info: Adding inputs REST Endpoints Mar 17 16:35:05 volumio volumio[788]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Mar 17 16:35:05 volumio volumio[788]: info: Scanning Audio Inputs Mar 17 16:35:06 volumio volumio[788]: info: Checking against Known Cards name Mar 17 16:35:06 volumio volumio[788]: info: Adding Server instance for streaming Mar 17 16:35:06 volumio volumio[788]: info: [MyVolumio PluginManager] Starting plugin music_service.hi_res_audio Mar 17 16:35:06 volumio volumio[788]: error: Hi Res Audio Failed Login: Missing Login Data Mar 17 16:35:06 volumio volumio[788]: info: Adding HIGHRESAUDIO REST API Endpoints Mar 17 16:35:06 volumio volumio[788]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Mar 17 16:35:06 volumio volumio[788]: info: [MyVolumio PluginManager] Starting plugin music_service.tidal Mar 17 16:35:06 volumio volumio[788]: info: [MyVolumio PluginManager] Starting plugin music_service.qobuz Mar 17 16:35:06 volumio volumio[788]: info: [MyVolumio PluginManager] Starting plugin music_service.tidalconnect Mar 17 16:35:06 volumio volumio[788]: info: [MyVolumio PluginManager] Plugin tidalconnect disabled by user. Not starting Mar 17 16:35:06 volumio volumio[788]: info: Stopping AccessToken refresher cron Mar 17 16:35:06 volumio volumio[788]: info: AccessToken refresher cron started Mar 17 16:35:06 volumio volumio[788]: info: Stopping AccessToken refresher cron for QOBUZ Mar 17 16:35:06 volumio systemd[1]: mpd.service: Succeeded. Mar 17 16:35:06 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 16:35:06 volumio volumio[788]: info: AccessToken refresher cron started for QOBUZ Mar 17 16:35:06 volumio volumio[788]: info: Adding TIDAL REST API Endpoints Mar 17 16:35:06 volumio volumio[788]: info: Adding getStreamUrltidal REST Endpoint for plugin: music_service/tidal Mar 17 16:35:06 volumio volumio[788]: info: Adding QOBUZ REST API Endpoints Mar 17 16:35:06 volumio volumio[788]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Mar 17 16:35:06 volumio volumio[788]: [Metrics] CommandRouter: 81s 993.10ms Mar 17 16:35:06 volumio volumio[788]: info: CoreCommandRouter::volumiosetStartupVolume Mar 17 16:35:06 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:06 volumio volumio[788]: info: VolumeController:: Setting startup Volume 100 Mar 17 16:35:06 volumio volumio[788]: info: VolumeController::SetAlsaVolume100 Mar 17 16:35:06 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:06 volumio systemd[1]: Starting Music Player Daemon... Mar 17 16:35:06 volumio volumiossh-tunnel[1696]: Warning: Permanently added '[us1.myvolumio.org]:2222,[162.243.167.188]:2222' (RSA) to the list of known hosts. Mar 17 16:35:06 volumio volumio[788]: info: CoreCommandRouter::Close All Modals sent Mar 17 16:35:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:06 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:06 volumio volumio[788]: info: CoreCommandRouter::Close All Modals sent Mar 17 16:35:06 volumio volumio[788]: info: CoreStateMachine::pushState Mar 17 16:35:06 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 16:35:06 volumio volumio[788]: info: CoreCommandRouter::volumioPushState Mar 17 16:35:06 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Mar 17 16:35:06 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:06 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:06 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:06 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:06 volumio sudo[1713]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 16:35:06 volumio volumio[788]: info: Successfully Updated MyVolumio device Mar 17 16:35:06 volumio sudo[1713]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:06 volumio volumio[788]: info: Successfully Updated MyVolumio device Mar 17 16:35:06 volumio sudo[1713]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:06 volumio volumio[788]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 17 16:35:06 volumio volumio[788]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 17 16:35:06 volumio volumio[788]: info: camilladsp stopping service pid 1671... Mar 17 16:35:06 volumio volumio[788]: info: camilladsp service terminated, instance 1 Mar 17 16:35:06 volumio volumio[788]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 17 16:35:06 volumio volumio[788]: info: camilladsp service started and running in background, instance 1 Mar 17 16:35:06 volumio volumiossh-tunnel[1696]: Warning: remote port forwarding failed for listen port 8091 Mar 17 16:35:06 volumio volumio[788]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 17 16:35:06 volumio volumio[788]: info: MPD Permissions set Mar 17 16:35:06 volumio volumio[788]: info: Remote SSH Started Mar 17 16:35:07 volumio volumio[788]: info: camilladsp spawned new process with pid 1722, instance 1, run: true Mar 17 16:35:07 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:07 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:07 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:07 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:07 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Mar 17 16:35:07 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 17 16:35:07 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Mar 17 16:35:07 volumio volumio[788]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Mar 17 16:35:07 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:07 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:07 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:07 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:07 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:07 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:07 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:07 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:08 volumio volumio[788]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Mar 17 16:35:08 volumio volumio[788]: info: Starting Shairport Sync Mar 17 16:35:08 volumio sudo[1734]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 16:35:08 volumio sudo[1734]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:08 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 17 16:35:08 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 17 16:35:08 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:08 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:08 volumio volumio[788]: info: Preparing to generate the ALSA configuration file Mar 17 16:35:08 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:08 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:08 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:08 volumio sudo[1734]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:08 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:08 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:08 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:08 volumio volumio[788]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 17 16:35:08 volumio volumio[788]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 17 16:35:08 volumio volumio[788]: info: Reading ALSA contributions from plugins. Mar 17 16:35:08 volumio volumio[788]: info: Shairport-Sync Started Mar 17 16:35:08 volumio volumio[788]: info: Asound.conf file unchanged, so no further update is needed Mar 17 16:35:08 volumio volumio[788]: info: Output device has changed, restarting MPD Mar 17 16:35:08 volumio sudo[1740]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 16:35:08 volumio volumio[788]: info: Output device has changed, restarting Shairport Sync Mar 17 16:35:08 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:08 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:08 volumio sudo[1740]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:08 volumio sudo[1740]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:08 volumio sudo[1742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 16:35:08 volumio sudo[1742]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:09 volumio volumio[788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 16:35:09 volumio volumio[788]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 17 16:35:09 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:09 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 17 16:35:09 volumio systemd[1]: mpd.service: Succeeded. Mar 17 16:35:09 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 16:35:09 volumio systemd[1]: Starting Music Player Daemon... Mar 17 16:35:09 volumio sudo[1749]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 16:35:09 volumio sudo[1749]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:09 volumio sudo[1749]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:09 volumio volumio[788]: info: MPD Permissions set Mar 17 16:35:09 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:09 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:09 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:09 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:09 volumio volumio[788]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 17 16:35:09 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:09 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:09 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:09 volumio volumio[788]: info: Starting Shairport Sync Mar 17 16:35:09 volumio volumio[788]: info: Preparing to generate the ALSA configuration file Mar 17 16:35:09 volumio sudo[1758]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 16:35:09 volumio sudo[1758]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:09 volumio volumio[788]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 17 16:35:09 volumio volumio[788]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 17 16:35:09 volumio volumio[788]: info: Reading ALSA contributions from plugins. Mar 17 16:35:09 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 17 16:35:09 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 17 16:35:09 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:09 volumio volumio[788]: info: Asound.conf file unchanged, so no further update is needed Mar 17 16:35:09 volumio volumio[788]: info: Output device has changed, restarting MPD Mar 17 16:35:09 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:10 volumio sudo[1758]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:10 volumio sudo[1762]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 16:35:10 volumio sudo[1762]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:10 volumio volumio[788]: info: Output device has changed, restarting Shairport Sync Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:10 volumio sudo[1762]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:10 volumio sudo[1767]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 16:35:10 volumio sudo[1767]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:10 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 17 16:35:10 volumio systemd[1]: mpd.service: Succeeded. Mar 17 16:35:10 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 16:35:10 volumio volumio[788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 16:35:10 volumio volumio[788]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:10 volumio systemd[1]: Starting Music Player Daemon... Mar 17 16:35:10 volumio volumio[788]: info: MPD Permissions set Mar 17 16:35:10 volumio volumio[788]: info: Shairport-Sync Started Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:10 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:10 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:10 volumio sudo[1772]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 16:35:10 volumio volumio[788]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 17 16:35:10 volumio sudo[1772]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:10 volumio sudo[1772]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::volumioGetBrowseSources Mar 17 16:35:10 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Mar 17 16:35:10 volumio volumio[788]: info: Starting Shairport Sync Mar 17 16:35:10 volumio sudo[1796]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 16:35:10 volumio sudo[1796]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:11 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 17 16:35:11 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 17 16:35:11 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:11 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:11 volumio sudo[1796]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:11 volumio volumio[788]: info: Shairport-Sync Started Mar 17 16:35:11 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:11 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:11 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:11 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:12 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:12 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:12 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:12 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:13 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:13 volumio volumio[788]: info: camilladsp spawned new process with pid 1801, instance 1, run: true Mar 17 16:35:13 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:13 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:13 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:13 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:13 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:13 volumio volumio[788]: info: BOOT COMPLETED Mar 17 16:35:13 volumio mpd[1783]: Mar 17 16:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 17 16:35:13 volumio systemd[1]: Started Music Player Daemon. Mar 17 16:35:13 volumio sudo[1767]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:13 volumio sudo[1742]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:13 volumio sudo[1699]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:13 volumio volumio[788]: error: MPD error: The expression evaluated to a falsy value: Mar 17 16:35:13 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:13 volumio volumio[788]: error: The expression evaluated to a falsy value: Mar 17 16:35:13 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:13 volumio volumio[788]: error: MPD error: The expression evaluated to a falsy value: Mar 17 16:35:13 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:13 volumio volumio[788]: error: The expression evaluated to a falsy value: Mar 17 16:35:13 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:13 volumio volumio[788]: error: updateQueue error: null Mar 17 16:35:13 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 17 16:35:14 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:14 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:14 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:14 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:14 volumio volumio[1810]: cset: --> shielding system active with Mar 17 16:35:14 volumio volumio[1810]: cset: "system" cpuset of CPUSPEC(0,3) with 145 tasks running Mar 17 16:35:14 volumio volumio[1810]: cset: "user" cpuset of CPUSPEC(1-2) with 3 tasks running Mar 17 16:35:14 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 17 16:35:14 volumio volumio[788]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Mar 17 16:35:14 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Mar 17 16:35:14 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:14 volumio volumio[788]: info: Disable softmixer device for audio device Mar 17 16:35:14 volumio volumio[788]: info: Output device has changed, restarting MPD Mar 17 16:35:15 volumio sudo[1832]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 16:35:15 volumio sudo[1832]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:15 volumio sudo[1832]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:15 volumio volumio[788]: info: Output device has changed, restarting Shairport Sync Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:15 volumio sudo[1835]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 16:35:15 volumio sudo[1835]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:15 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 17 16:35:15 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 17 16:35:15 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 17 16:35:15 volumio systemd[1]: Stopping Music Player Daemon... Mar 17 16:35:15 volumio systemd[1]: mpd.service: Succeeded. Mar 17 16:35:15 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 16:35:15 volumio volumio[788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 16:35:15 volumio volumio[788]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:15 volumio systemd[1]: Starting Music Player Daemon... Mar 17 16:35:15 volumio volumio[788]: info: Restoring Previous Volume level: 100 false false Mar 17 16:35:15 volumio volumio[788]: info: Volume configurations have been set Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 17 16:35:15 volumio volumio[788]: info: Updating Volume Controller Parameters: Device: 2 Name: Audiophonics I-Sabre ES9028Q2M Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 17 16:35:15 volumio volumio[788]: info: Disabling external Volume Control Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 16:35:15 volumio volumio[788]: info: Soft Volume ALSA configuration file deleted Mar 17 16:35:15 volumio volumio[788]: info: Preparing to generate the ALSA configuration file Mar 17 16:35:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:15 volumio volumio[788]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 17 16:35:15 volumio volumio[788]: info: MPD Permissions set Mar 17 16:35:15 volumio volumio[788]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 17 16:35:15 volumio volumio[788]: info: Reading ALSA contributions from plugins. Mar 17 16:35:15 volumio volumio[788]: error: Cannot get ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:15 volumio sudo[1840]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 16:35:15 volumio sudo[1840]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:15 volumio sudo[1840]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 17 16:35:15 volumio volumio[788]: info: Getting Alsa Cards List without I2S DAC Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:15 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:15 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:15 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:15 volumio volumio[788]: info: VolumeController:: Volume=undefined Mute =false Mar 17 16:35:15 volumio volumio[788]: info: CoreStateMachine::pushState Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 16:35:15 volumio volumio[788]: info: CoreCommandRouter::volumioPushState Mar 17 16:35:16 volumio volumio[788]: info: Starting Shairport Sync Mar 17 16:35:16 volumio volumio[788]: info: Asound.conf file written Mar 17 16:35:16 volumio sudo[1862]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 16:35:16 volumio sudo[1862]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:16 volumio sudo[1865]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 17 16:35:16 volumio sudo[1865]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 17 16:35:16 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 17 16:35:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:16 volumio sudo[1865]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:16 volumio sudo[1862]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:16 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:16 volumio volumio[788]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error Mar 17 16:35:16 volumio volumio[788]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:FIR Filter Type:0' : Invalid argument Mar 17 16:35:16 volumio volumio[788]: info: Output device has changed, restarting MPD Mar 17 16:35:16 volumio volumio[788]: info: Output device has changed, restarting Shairport Sync Mar 17 16:35:16 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:16 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:16 volumio sudo[1871]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 16:35:16 volumio sudo[1871]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:16 volumio sudo[1871]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:16 volumio sudo[1873]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 16:35:16 volumio sudo[1873]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:16 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 17 16:35:16 volumio systemd[1]: mpd.service: Succeeded. Mar 17 16:35:16 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 16:35:16 volumio volumio[788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 16:35:16 volumio volumio[788]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 17 16:35:16 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:16 volumio systemd[1]: Starting Music Player Daemon... Mar 17 16:35:16 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:16 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:16 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:16 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:16 volumio volumio[788]: info: MPD Permissions set Mar 17 16:35:16 volumio volumio[788]: info: Shairport-Sync Started Mar 17 16:35:16 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:16 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:16 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:16 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:16 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:16 volumio volumio[788]: info: Starting Shairport Sync Mar 17 16:35:16 volumio sudo[1889]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 16:35:16 volumio volumio[788]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 17 16:35:16 volumio sudo[1889]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:16 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 17 16:35:16 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 17 16:35:16 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:16 volumio sudo[1881]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 16:35:16 volumio sudo[1881]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:16 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:16 volumio sudo[1881]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:16 volumio sudo[1889]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:16 volumio volumio[788]: info: Shairport-Sync Started Mar 17 16:35:17 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:17 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:17 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:17 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:18 volumio mpd[1893]: Mar 17 16:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 17 16:35:18 volumio systemd[1]: Started Music Player Daemon. Mar 17 16:35:18 volumio sudo[1835]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:18 volumio sudo[1873]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:18 volumio volumio[788]: error: MPD error: The expression evaluated to a falsy value: Mar 17 16:35:18 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:18 volumio volumio[788]: error: The expression evaluated to a falsy value: Mar 17 16:35:18 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:18 volumio volumio[788]: error: updateQueue error: null Mar 17 16:35:18 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:18 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:18 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:18 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:18 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 17 16:35:19 volumio volumio[1898]: cset: --> shielding system active with Mar 17 16:35:19 volumio volumio[1898]: cset: "system" cpuset of CPUSPEC(0,3) with 145 tasks running Mar 17 16:35:19 volumio volumio[1898]: cset: "user" cpuset of CPUSPEC(1-2) with 3 tasks running Mar 17 16:35:19 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 17 16:35:19 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:19 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:19 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:19 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:19 volumio volumio[788]: info: VolumeController::SetAlsaVolume100 Mar 17 16:35:19 volumio volumio[788]: info: CoreStateMachine::pushState Mar 17 16:35:19 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 16:35:19 volumio volumio[788]: info: CoreCommandRouter::volumioPushState Mar 17 16:35:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:19 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:19 volumio volumio[788]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 17 16:35:20 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:20 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:20 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:20 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:21 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:21 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:21 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:21 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:22 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:22 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:22 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:22 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:23 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:23 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:23 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:23 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:24 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:24 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:24 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:24 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:25 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:25 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:25 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:25 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:26 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:26 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:26 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:26 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:26 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:27 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:27 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:27 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:27 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:28 volumio volumio[788]: info: Executing endpoint metavolumio Mar 17 16:35:28 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Mar 17 16:35:28 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:28 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:28 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:28 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:29 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:29 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:29 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:29 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:30 volumio volumio[788]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 16:35:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 17 16:35:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 17 16:35:30 volumio volumio[788]: info: Getting Alsa Cards List without I2S DAC Mar 17 16:35:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 17 16:35:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:30 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 17 16:35:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 17 16:35:30 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:30 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:30 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:30 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:31 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:31 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:31 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:31 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:32 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:32 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:32 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:32 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:33 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:33 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:33 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:33 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:34 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:34 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:34 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:34 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:35 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:35 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:35 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:35 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:36 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:36 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:36 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:36 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:37 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:37 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:37 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:37 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:38 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:38 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:38 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:38 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:39 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:39 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:39 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:39 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:40 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:40 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:40 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:40 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:41 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:41 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:41 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:41 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:42 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:42 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:42 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:42 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:43 volumio volumio[788]: info: CALLMETHOD: audio_interface alsa_controller saveVolumeOptions [object Object] Mar 17 16:35:43 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveVolumeOptions Mar 17 16:35:43 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:43 volumio volumio[788]: info: Restoring Previous Volume level: 100 false true Mar 17 16:35:43 volumio volumio[788]: info: VolumeController::SetAlsaVolume100 Mar 17 16:35:43 volumio volumio[788]: info: Enable softmixer device for audio device number 2 Mar 17 16:35:43 volumio volumio[788]: info: CoreCommandRouter::volumioStop Mar 17 16:35:43 volumio volumio[788]: info: CoreStateMachine::stop Mar 17 16:35:43 volumio volumio[788]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 17 16:35:43 volumio volumio[788]: info: CoreStateMachine::stPlaybackTimer Mar 17 16:35:43 volumio volumio[788]: info: CoreStateMachine::updateTrackBlock Mar 17 16:35:43 volumio volumio[788]: info: CorePlayQueue::getTrackBlock Mar 17 16:35:43 volumio volumio[788]: info: CoreStateMachine::pushState Mar 17 16:35:43 volumio volumio[788]: info: CorePlayQueue::getTrack 0 Mar 17 16:35:43 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 16:35:43 volumio volumio[788]: info: CoreCommandRouter::volumioPushState Mar 17 16:35:43 volumio volumio[788]: info: CoreStateMachine::serviceStop Mar 17 16:35:43 volumio volumio[788]: info: CorePlayQueue::getTrack 0 Mar 17 16:35:43 volumio volumio[788]: info: CoreCommandRouter::serviceStop Mar 17 16:35:43 volumio volumio[788]: info: [1742243743507] ControllerWebradio::stop Mar 17 16:35:43 volumio volumio[788]: verbose: ControllerMpd::sendMpdCommand stop Mar 17 16:35:43 volumio volumio[788]: info: Enable softmixer device for audio device undefined Mar 17 16:35:43 volumio volumio[788]: info: Output device has changed, restarting MPD Mar 17 16:35:43 volumio sudo[2016]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 16:35:43 volumio sudo[2016]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:43 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:43 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:43 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:43 volumio volumio[788]: info: Output device has changed, restarting Shairport Sync Mar 17 16:35:43 volumio sudo[2016]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:43 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:43 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:43 volumio sudo[2019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 16:35:43 volumio sudo[2019]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:43 volumio volumio[788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 16:35:43 volumio volumio[788]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 17 16:35:43 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:43 volumio volumio[788]: info: Volume configurations have been set Mar 17 16:35:43 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 17 16:35:43 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 17 16:35:43 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 17 16:35:43 volumio systemd[1]: Stopping Music Player Daemon... Mar 17 16:35:43 volumio systemd[1]: mpd.service: Succeeded. Mar 17 16:35:43 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 16:35:43 volumio volumio[788]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 17 16:35:43 volumio volumio[788]: info: Updating Volume Controller Parameters: Device: 2 Name: Audiophonics I-Sabre ES9028Q2M Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 17 16:35:43 volumio systemd[1]: Starting Music Player Daemon... Mar 17 16:35:43 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 17 16:35:43 volumio volumio[788]: info: Disabling external Volume Control Mar 17 16:35:43 volumio volumio[788]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 16:35:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:44 volumio volumio[788]: info: CoreStateMachine::pushState Mar 17 16:35:44 volumio volumio[788]: info: CorePlayQueue::getTrack 0 Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::volumioPushState Mar 17 16:35:44 volumio sudo[2028]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 16:35:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:44 volumio sudo[2028]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:44 volumio sudo[2028]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:44 volumio volumio[788]: error: MPD error: Error: write EPIPE Mar 17 16:35:44 volumio volumio[788]: error: write EPIPE {"errno":-32,"code":"EPIPE","syscall":"write"} Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:35:44 volumio volumio[788]: info: CorePlayQueue::getTrack 0 Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::volumioGetQueue Mar 17 16:35:44 volumio volumio[788]: info: CoreStateMachine::getQueue Mar 17 16:35:44 volumio volumio[788]: info: CorePlayQueue::getQueue Mar 17 16:35:44 volumio volumio[788]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 17 16:35:44 volumio volumio[788]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 17 16:35:44 volumio volumio[788]: info: MPD Permissions set Mar 17 16:35:44 volumio volumio[788]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 17 16:35:44 volumio volumio[788]: info: CoreStateMachine::pushState Mar 17 16:35:44 volumio volumio[788]: info: CorePlayQueue::getTrack 0 Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::volumioPushState Mar 17 16:35:44 volumio volumio[788]: info: Software Volume ALSA configuration written Mar 17 16:35:44 volumio volumio[788]: info: Preparing to generate the ALSA configuration file Mar 17 16:35:44 volumio volumio[788]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 17 16:35:44 volumio volumio[788]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 17 16:35:44 volumio volumio[788]: info: Reading ALSA contributions from plugins. Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 17 16:35:44 volumio volumio[788]: info: Getting Alsa Cards List without I2S DAC Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:44 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 17 16:35:44 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 17 16:35:44 volumio volumio[788]: info: Starting Shairport Sync Mar 17 16:35:44 volumio volumio[788]: info: Asound.conf file written Mar 17 16:35:44 volumio sudo[2051]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 16:35:44 volumio sudo[2051]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:44 volumio sudo[2054]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 17 16:35:44 volumio sudo[2054]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:44 volumio sudo[2054]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:44 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 17 16:35:44 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 17 16:35:44 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:45 volumio volumio[788]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error Mar 17 16:35:45 volumio volumio[788]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:FIR Filter Type:0' : Invalid argument Mar 17 16:35:45 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:45 volumio volumio[788]: info: Output device has changed, restarting MPD Mar 17 16:35:45 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:45 volumio sudo[2051]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:45 volumio sudo[2060]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 16:35:45 volumio volumio[788]: info: Output device has changed, restarting Shairport Sync Mar 17 16:35:45 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:45 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:45 volumio sudo[2060]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:45 volumio sudo[2060]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:45 volumio sudo[2065]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 16:35:45 volumio sudo[2065]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:45 volumio volumio[788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 16:35:45 volumio volumio[788]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 17 16:35:45 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:45 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 17 16:35:45 volumio systemd[1]: mpd.service: Succeeded. Mar 17 16:35:45 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 16:35:45 volumio systemd[1]: Starting Music Player Daemon... Mar 17 16:35:45 volumio sudo[2074]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 16:35:45 volumio sudo[2074]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:45 volumio sudo[2074]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:46 volumio volumio[788]: Playing WAVE '/volumio/app/silence.wav' : Signed 16 bit Little Endian, Rate 44100 Hz, Stereo Mar 17 16:35:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:46 volumio volumio[788]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error Mar 17 16:35:46 volumio volumio[788]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:FIR Filter Type:0' : Invalid argument Mar 17 16:35:46 volumio volumio[788]: info: Output device has changed, restarting MPD Mar 17 16:35:46 volumio sudo[2081]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 16:35:46 volumio sudo[2081]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:46 volumio sudo[2081]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:46 volumio volumio[788]: info: Output device has changed, restarting Shairport Sync Mar 17 16:35:46 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:46 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:46 volumio sudo[2084]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 16:35:46 volumio sudo[2084]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:46 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 17 16:35:46 volumio systemd[1]: mpd.service: Succeeded. Mar 17 16:35:46 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 16:35:46 volumio volumio[788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 16:35:46 volumio volumio[788]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 17 16:35:46 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:46 volumio systemd[1]: Starting Music Player Daemon... Mar 17 16:35:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:46 volumio sudo[2089]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 16:35:46 volumio sudo[2089]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:46 volumio volumio[788]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 17 16:35:46 volumio volumio[788]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 17 16:35:46 volumio sudo[2089]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:46 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 17 16:35:46 volumio volumio[788]: info: Disabling external Volume Control Mar 17 16:35:46 volumio volumio[788]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Mar 17 16:35:46 volumio volumio[788]: info: FusionDsp - ---- read samplerate from file: 44100 Mar 17 16:35:46 volumio volumio[788]: info: FusionDsp - If filter freq >samplerate/2 then disable it Mar 17 16:35:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:46 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:47 volumio volumio[788]: info: MPD Permissions set Mar 17 16:35:47 volumio volumio[788]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 17 16:35:47 volumio volumio[788]: info: MPD Permissions set Mar 17 16:35:47 volumio volumio[788]: info: Shairport-Sync Started Mar 17 16:35:47 volumio volumio[788]: info: camilladsp spawned new process with pid 2103, instance 1, run: true Mar 17 16:35:47 volumio volumio[788]: info: CoreStateMachine::pushState Mar 17 16:35:47 volumio volumio[788]: info: CorePlayQueue::getTrack 0 Mar 17 16:35:47 volumio volumio[788]: info: CoreCommandRouter::volumioPushState Mar 17 16:35:47 volumio volumio[788]: error: Cannot set ALSA Volume: Error: Alsa Mixer Error: amixer: Unable to find simple control '',0 Mar 17 16:35:47 volumio volumio[788]: error: FusionDsp - WebSocket error: [object Object] Mar 17 16:35:47 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:47 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:47 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:47 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:47 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:47 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:47 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:47 volumio volumio[788]: info: Starting Shairport Sync Mar 17 16:35:47 volumio volumio[788]: info: Starting Shairport Sync Mar 17 16:35:47 volumio sudo[2119]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 16:35:47 volumio sudo[2119]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:47 volumio volumio[788]: info: Preparing to generate the ALSA configuration file Mar 17 16:35:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 17 16:35:47 volumio sudo[2122]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 16:35:47 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 17 16:35:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:47 volumio sudo[2122]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:47 volumio sudo[2126]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 16:35:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:47 volumio volumio[788]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 17 16:35:47 volumio volumio[788]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 17 16:35:47 volumio volumio[788]: info: Reading ALSA contributions from plugins. Mar 17 16:35:47 volumio volumio[788]: info: Asound.conf file unchanged, so no further update is needed Mar 17 16:35:47 volumio volumio[788]: info: Output device has changed, restarting MPD Mar 17 16:35:47 volumio sudo[2126]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:47 volumio sudo[2119]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:47 volumio sudo[2126]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:47 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 17 16:35:47 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 17 16:35:47 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:47 volumio volumio[788]: info: Output device has changed, restarting Shairport Sync Mar 17 16:35:47 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:47 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:47 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:47 volumio sudo[2131]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 16:35:47 volumio sudo[2131]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:47 volumio sudo[2122]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:47 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 17 16:35:47 volumio systemd[1]: mpd.service: Succeeded. Mar 17 16:35:47 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 16:35:47 volumio volumio[788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 16:35:47 volumio volumio[788]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 17 16:35:47 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:48 volumio volumio[788]: info: VolumeController::SetAlsaVolume100 Mar 17 16:35:48 volumio systemd[1]: Starting Music Player Daemon... Mar 17 16:35:48 volumio volumio[788]: info: CoreStateMachine::pushState Mar 17 16:35:48 volumio volumio[788]: info: CorePlayQueue::getTrack 0 Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::volumioPushState Mar 17 16:35:48 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:48 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:35:48 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:35:48 volumio volumio[788]: info: MPD Permissions set Mar 17 16:35:48 volumio volumio[788]: info: Shairport-Sync Started Mar 17 16:35:48 volumio volumio[788]: (node:788) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 17 16:35:48 volumio volumio[788]: (Use `node --trace-warnings ...` to show where the warning was created) Mar 17 16:35:48 volumio volumio[788]: (node:788) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 17 16:35:48 volumio volumio[788]: (node:788) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 17 16:35:48 volumio volumio[788]: (node:788) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 17 16:35:48 volumio volumio[788]: (node:788) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Mar 17 16:35:48 volumio volumio[788]: info: Shairport-Sync Started Mar 17 16:35:48 volumio volumio[788]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:48 volumio volumio[788]: info: Starting Shairport Sync Mar 17 16:35:48 volumio volumio[788]: info: Preparing to generate the ALSA configuration file Mar 17 16:35:48 volumio sudo[2148]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 16:35:48 volumio sudo[2148]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:48 volumio volumio[788]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 17 16:35:48 volumio volumio[788]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 17 16:35:48 volumio volumio[788]: info: Reading ALSA contributions from plugins. Mar 17 16:35:48 volumio volumio[788]: info: Asound.conf file unchanged, so no further update is needed Mar 17 16:35:48 volumio volumio[788]: info: Output device has changed, restarting MPD Mar 17 16:35:48 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 17 16:35:48 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 17 16:35:48 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:48 volumio sudo[2138]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 16:35:48 volumio sudo[2138]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:48 volumio sudo[2138]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:48 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:48 volumio sudo[2153]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 16:35:48 volumio sudo[2148]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:48 volumio volumio[788]: info: Output device has changed, restarting Shairport Sync Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:48 volumio sudo[2153]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:48 volumio sudo[2153]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:48 volumio sudo[2157]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 16:35:48 volumio sudo[2157]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:48 volumio systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Mar 17 16:35:48 volumio systemd[1]: mpd.service: Succeeded. Mar 17 16:35:48 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 16:35:48 volumio volumio[788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 16:35:48 volumio volumio[788]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:48 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Mar 17 16:35:48 volumio systemd[1]: Starting Music Player Daemon... Mar 17 16:35:49 volumio volumio[788]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 17 16:35:49 volumio volumio[788]: info: MPD Permissions set Mar 17 16:35:49 volumio volumio[788]: info: Shairport-Sync Started Mar 17 16:35:49 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:49 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:49 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:49 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:49 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:49 volumio sudo[2168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 16:35:49 volumio sudo[2168]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:49 volumio sudo[2164]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 16:35:49 volumio sudo[2164]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:49 volumio sudo[2164]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:49 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:35:49 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:35:49 volumio systemd[1]: mpd.service: Succeeded. Mar 17 16:35:49 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 16:35:49 volumio volumio[788]: info: Starting Shairport Sync Mar 17 16:35:49 volumio systemd[1]: Starting Music Player Daemon... Mar 17 16:35:49 volumio sudo[2177]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 16:35:49 volumio sudo[2177]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:49 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 17 16:35:49 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 17 16:35:49 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:49 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 17 16:35:49 volumio sudo[2177]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:49 volumio volumio[788]: info: Shairport-Sync Started Mar 17 16:35:49 volumio sudo[2175]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 16:35:49 volumio sudo[2175]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:35:49 volumio sudo[2175]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:51 volumio volumio[788]: info: CoreCommandRouter::volumioPlay Mar 17 16:35:51 volumio volumio[788]: info: CoreStateMachine::play index undefined Mar 17 16:35:51 volumio volumio[788]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 17 16:35:51 volumio volumio[788]: info: CorePlayQueue::getTrack 0 Mar 17 16:35:51 volumio volumio[788]: info: CoreStateMachine::startPlaybackTimer Mar 17 16:35:51 volumio volumio[788]: info: CorePlayQueue::getTrack 0 Mar 17 16:35:51 volumio volumio[788]: info: CoreCommandRouter::volumioGetVisibleSources Mar 17 16:35:51 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Mar 17 16:35:51 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Mar 17 16:35:51 volumio volumio[788]: info: [1742243751106] ControllerWebradio::clearAddPlayTrack Mar 17 16:35:51 volumio volumio[788]: verbose: ControllerMpd::sendMpdCommand stop Mar 17 16:35:51 volumio mpd[2184]: Mar 17 16:35 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 17 16:35:51 volumio systemd[1]: Started Music Player Daemon. Mar 17 16:35:51 volumio sudo[2131]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:51 volumio sudo[2019]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:51 volumio sudo[2157]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:51 volumio sudo[2065]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:51 volumio sudo[2084]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:51 volumio sudo[2168]: pam_unix(sudo:session): session closed for user root Mar 17 16:35:51 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 17 16:35:51 volumio volumio[788]: error: MPD error: The expression evaluated to a falsy value: Mar 17 16:35:51 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:51 volumio volumio[788]: error: The expression evaluated to a falsy value: Mar 17 16:35:51 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:51 volumio volumio[788]: error: MPD error: The expression evaluated to a falsy value: Mar 17 16:35:51 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:51 volumio volumio[788]: error: The expression evaluated to a falsy value: Mar 17 16:35:51 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:51 volumio volumio[788]: error: MPD error: The expression evaluated to a falsy value: Mar 17 16:35:51 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:51 volumio volumio[788]: error: The expression evaluated to a falsy value: Mar 17 16:35:51 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:51 volumio volumio[788]: error: MPD error: The expression evaluated to a falsy value: Mar 17 16:35:51 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:51 volumio volumio[788]: error: The expression evaluated to a falsy value: Mar 17 16:35:51 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:51 volumio volumio[788]: error: MPD error: The expression evaluated to a falsy value: Mar 17 16:35:51 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:51 volumio volumio[788]: error: The expression evaluated to a falsy value: Mar 17 16:35:51 volumio volumio[788]: assert.ok(self.idling) Mar 17 16:35:51 volumio volumio[788]: error: updateQueue error: null Mar 17 16:35:51 volumio volumio[2201]: cset: --> shielding system active with Mar 17 16:35:51 volumio volumio[2201]: cset: "system" cpuset of CPUSPEC(0,3) with 145 tasks running Mar 17 16:35:51 volumio volumio[2201]: cset: "user" cpuset of CPUSPEC(1-2) with 3 tasks running Mar 17 16:35:51 volumio systemd[1]: Started Shield Volumio Music Services in the User CPU Set. Mar 17 16:36:28 volumio volumio[788]: info: CALLMETHOD: audio_interface alsa_controller saveAlsaOptions [object Object] Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , saveAlsaOptions Mar 17 16:36:28 volumio volumio[788]: info: Preparing to save Alsa Options, stopping services first Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::volumioGetState Mar 17 16:36:28 volumio volumio[788]: info: CorePlayQueue::getTrack 0 Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::volumioStop Mar 17 16:36:28 volumio volumio[788]: info: CoreStateMachine::stop Mar 17 16:36:28 volumio volumio[788]: info: CoreStateMachine::setConsumeUpdateService undefined Mar 17 16:36:28 volumio volumio[788]: info: Saving Audio Output to: {"output_device":{"value":"0","label":"HDMI Out"},"i2s":true,"i2sid":{"value":"generic-dac","label":"Generic I2S DAC"}} Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:36:28 volumio volumio[788]: info: Enabling I2S DAC: Generic I2S DAC Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , enableI2SDAC Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:36:28 volumio sudo[2273]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay -l Mar 17 16:36:28 volumio sudo[2273]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:36:28 volumio sudo[2273]: pam_unix(sudo:session): session closed for user root Mar 17 16:36:28 volumio volumio[788]: info: No Overlays Loaded Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SAlsaName Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:36:28 volumio sudo[2276]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/dtoverlay hifiberry-dac Mar 17 16:36:28 volumio sudo[2276]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:36:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/i2s@7e203000/status Mar 17 16:36:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/compatible Mar 17 16:36:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/i2s-controller Mar 17 16:36:28 volumio kernel: OF: overlay: WARNING: memory leak will occur if overlay removed, property: /soc/sound/status Mar 17 16:36:28 volumio sudo[2276]: pam_unix(sudo:session): session closed for user root Mar 17 16:36:28 volumio volumio[788]: warn: Unable to locate the device HDMI Out a reboot may be required. Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SMixer Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:36:28 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:36:28 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:36:28 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:36:28 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:36:28 volumio volumio[788]: info: Setting default mixerSoftMaster for Softvolume device Mar 17 16:36:28 volumio volumio[788]: info: CoreCommandRouter::volumioUpdateVolumeSettings Mar 17 16:36:28 volumio volumio[788]: info: Updating Volume Controller Parameters: Device: 2 Name: softvolume Mixer: SoftMaster Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Mar 17 16:36:29 volumio volumio[788]: info: Disabling external Volume Control Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 17 16:36:29 volumio volumio[788]: info: Enable softmixer device for audio device undefined Mar 17 16:36:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:36:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:36:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: wizard , setWizardAction Mar 17 16:36:29 volumio volumio[788]: info: I2S Param [object Object] successfully enabled Mar 17 16:36:29 volumio volumio[788]: info: Software Volume ALSA configuration written Mar 17 16:36:29 volumio volumio[788]: info: Preparing to generate the ALSA configuration file Mar 17 16:36:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:36:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:36:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Mar 17 16:36:29 volumio volumio[788]: info: Getting Alsa Cards List without I2S DAC Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2SNumber Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:36:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:36:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:36:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:36:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at soc_component_read_no_lock on i-sabre-codec-i2c.1-0048 for register: [0x00000022] -121 Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 17 16:36:29 volumio volumio[788]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Mar 17 16:36:29 volumio volumio[788]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Mar 17 16:36:29 volumio volumio[788]: info: Reading ALSA contributions from plugins. Mar 17 16:36:29 volumio volumio[788]: info: CoreStateMachine::pushState Mar 17 16:36:29 volumio volumio[788]: info: CorePlayQueue::getTrack 0 Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::volumioPushState Mar 17 16:36:29 volumio volumio[788]: info: Asound.conf file written Mar 17 16:36:29 volumio sudo[2311]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Mar 17 16:36:29 volumio sudo[2311]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:36:29 volumio sudo[2311]: pam_unix(sudo:session): session closed for user root Mar 17 16:36:29 volumio kernel: i-sabre-codec-i2c 1-0048: ASoC: error at snd_soc_component_update_bits on i-sabre-codec-i2c.1-0048 for register: [0x00000021] -121 Mar 17 16:36:29 volumio volumio[788]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:Digital Playback Switch:0' : Remote I/O error Mar 17 16:36:29 volumio volumio[788]: /usr/sbin/alsactl: set_control:1461: Cannot write control '2:0:0:FIR Filter Type:0' : Invalid argument Mar 17 16:36:29 volumio volumio[788]: info: Output device has changed, restarting MPD Mar 17 16:36:29 volumio sudo[2316]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 16:36:29 volumio sudo[2316]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:36:29 volumio sudo[2316]: pam_unix(sudo:session): session closed for user root Mar 17 16:36:29 volumio volumio[788]: info: Output device has changed, restarting Shairport Sync Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:36:29 volumio sudo[2319]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 16:36:29 volumio sudo[2319]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:36:29 volumio volumio[788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 16:36:29 volumio volumio[788]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:29 volumio systemd[1]: musicservicesshield.service: Succeeded. Mar 17 16:36:29 volumio systemd[1]: Stopped Shield Volumio Music Services in the User CPU Set. Mar 17 16:36:29 volumio systemd[1]: Stopping Shield Volumio Music Services in the User CPU Set... Mar 17 16:36:29 volumio volumio[788]: info: Output device has changed, restarting MPD Mar 17 16:36:29 volumio systemd[1]: Stopping Music Player Daemon... Mar 17 16:36:29 volumio systemd[1]: mpd.service: Succeeded. Mar 17 16:36:29 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 16:36:29 volumio systemd[1]: Starting Music Player Daemon... Mar 17 16:36:29 volumio volumio[788]: info: Output device has changed, restarting Shairport Sync Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:29 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:36:29 volumio sudo[2329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Mar 17 16:36:29 volumio sudo[2329]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:36:29 volumio sudo[2329]: pam_unix(sudo:session): session closed for user root Mar 17 16:36:29 volumio sudo[2330]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Mar 17 16:36:29 volumio sudo[2330]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:36:30 volumio sudo[2327]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 16:36:30 volumio sudo[2327]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:36:30 volumio sudo[2327]: pam_unix(sudo:session): session closed for user root Mar 17 16:36:30 volumio systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Mar 17 16:36:30 volumio systemd[1]: mpd.service: Succeeded. Mar 17 16:36:30 volumio systemd[1]: Stopped Music Player Daemon. Mar 17 16:36:30 volumio volumio[788]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Mar 17 16:36:30 volumio volumio[788]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio systemd[1]: Starting Music Player Daemon... Mar 17 16:36:30 volumio volumio[788]: info: MPD Permissions set Mar 17 16:36:30 volumio volumio[788]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 17 16:36:30 volumio volumio[788]: info: MPD Permissions set Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio sudo[2336]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Mar 17 16:36:30 volumio sudo[2336]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:36:30 volumio sudo[2336]: pam_unix(sudo:session): session closed for user root Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Mar 17 16:36:30 volumio volumio[788]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Mar 17 16:36:30 volumio volumio[788]: error: Failed to parse min max audio card capabilities, sending default vales: TypeError: Cannot read property 'split' of undefined Mar 17 16:36:30 volumio volumio[788]: info: Starting Shairport Sync Mar 17 16:36:30 volumio volumio[788]: info: Starting Shairport Sync Mar 17 16:36:30 volumio sudo[2350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 16:36:30 volumio sudo[2350]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:36:30 volumio sudo[2353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Mar 17 16:36:30 volumio sudo[2353]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:36:30 volumio systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Mar 17 16:36:30 volumio systemd[1]: shairport-sync.service: Succeeded. Mar 17 16:36:30 volumio systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Mar 17 16:36:30 volumio systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Mar 17 16:36:30 volumio sudo[2353]: pam_unix(sudo:session): session closed for user root Mar 17 16:36:30 volumio sudo[2350]: pam_unix(sudo:session): session closed for user root Mar 17 16:36:30 volumio volumio[788]: info: Shairport-Sync Started Mar 17 16:36:30 volumio volumio[788]: info: Shairport-Sync Started Mar 17 16:36:31 volumio volumio[788]: info: ___________ PLUGINS: Run onVolumioReboot Tasks ___________ Mar 17 16:36:31 volumio volumio[788]: info: PLUGIN onReboot : networkfs Mar 17 16:36:31 volumio sudo[2373]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /mnt/NAS/Lenovo Mar 17 16:36:31 volumio sudo[2373]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 17 16:36:31 volumio sudo[2373]: pam_unix(sudo:session): session closed for user root Mar 17 16:36:31 volumio volumio[788]: umount: /mnt/NAS/Lenovo: not mounted. Mar 17 16:36:31 volumio volumio[788]: error: Cannot umount share Lenovo : Error: Command failed: /usr/bin/sudo /bin/umount -f /mnt/NAS/Lenovo Mar 17 16:36:31 volumio volumio[788]: umount: /mnt/NAS/Lenovo: not mounted. Mar 17 16:36:31 volumio volumio[788]: info: PLUGIN onReboot : audiophonicsonoff Mar 17 16:36:31 volumio volumio[788]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 16:36:31 volumio volumio[788]: TypeError: Cannot read property 'writeSync' of undefined Mar 17 16:36:31 volumio volumio[788]: at ControllerAudiophonicsOnOff.onVolumioReboot (/data/plugins/system_hardware/audiophonicsonoff/index.js:40:25) Mar 17 16:36:31 volumio volumio[788]: at PluginManager.onVolumioRebootPlugin (/volumio/app/pluginmanager.js:684:30) Mar 17 16:36:31 volumio volumio[788]: at HashMap. (/volumio/app/pluginmanager.js:668:31) Mar 17 16:36:31 volumio volumio[788]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Mar 17 16:36:31 volumio volumio[788]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Mar 17 16:36:31 volumio volumio[788]: at PluginManager.onVolumioReboot (/volumio/app/pluginmanager.js:666:20) Mar 17 16:36:31 volumio volumio[788]: at CoreCommandRouter.reboot (/volumio/app/index.js:1350:22) Mar 17 16:36:31 volumio volumio[788]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:870:33) Mar 17 16:36:31 volumio volumio[788]: at Socket.emit (events.js:315:20) Mar 17 16:36:31 volumio volumio[788]: at /volumio/node_modules/socket.io/lib/socket.js:528:12 Mar 17 16:36:31 volumio volumio[788]: at processTicksAndRejections (internal/process/task_queues.js:75:11) Mar 17 16:36:31 volumio volumio[788]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 17 16:36:32 volumio mpd[2345]: Mar 17 16:36 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Mar 17 16:36:32 volumio systemd[1]: Started Music Player Daemon. Mar 17 16:36:32 volumio sudo[2330]: pam_unix(sudo:session): session closed for user root Mar 17 16:36:32 volumio sudo[2319]: pam_unix(sudo:session): session closed for user root Mar 17 16:36:32 volumio systemd[1]: Starting Shield Volumio Music Services in the User CPU Set... Mar 17 16:36:33 volumio sudo[2398]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-17 16:35 Mar 17 16:36:33 volumio sudo[2398]: 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="147953990fca4aa103a8f90538ef56fb0454638c" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 11:42:54 AM CET" VOLUMIO_VERSION="3.795" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="4049a67f1c09d4a94e033ba35a80a144"