-- Logs begin at Thu 2019-02-14 17:11:58 WIB, end at Wed 2024-10-02 16:55:18 WIB. -- Oct 02 16:54:00 primo sudo[7103]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 16:54:00 primo sudo[7103]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:54:00 primo sudo[7105]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 16:54:00 primo sudo[7103]: pam_unix(sudo:session): session closed for user root Oct 02 16:54:00 primo sudo[7105]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:54:00 primo sudo[7105]: pam_unix(sudo:session): session closed for user root Oct 02 16:54:00 primo volumio[3187]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.104 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 10 Oct 02 16:54:00 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 16:54:00 primo sudo[7113]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 16:54:00 primo sudo[7113]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:54:00 primo sudo[7113]: pam_unix(sudo:session): session closed for user root Oct 02 16:54:00 primo sudo[7116]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 16:54:00 primo sudo[7116]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:54:00 primo sudo[7116]: pam_unix(sudo:session): session closed for user root Oct 02 16:54:00 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 16:54:01 primo volumio[3187]: verbose: New Socket.io Connection to 192.168.1.103 from 192.168.1.104 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_0 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 11 Oct 02 16:54:01 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Oct 02 16:54:01 primo volumio[3187]: info: CoreCommandRouter::volumioGetVisibleSources Oct 02 16:54:01 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Oct 02 16:54:01 primo volumio[3187]: info: CoreCommandRouter::volumioGetState Oct 02 16:54:01 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Oct 02 16:54:01 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Oct 02 16:54:01 primo volumio[3187]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Oct 02 16:54:01 primo volumio[3187]: info: Received Get System Info Oct 02 16:54:01 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:54:01 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:54:01 primo volumio[3187]: info: Discovery: Getting this device information Oct 02 16:54:01 primo volumio[3187]: info: CoreCommandRouter::volumioGetState Oct 02 16:54:01 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:54:01 primo volumio[3187]: info: CoreCommandRouter::volumioGetState Oct 02 16:54:01 primo volumio[3187]: info: Listing playlists Oct 02 16:54:01 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Oct 02 16:54:01 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 16:54:01 primo volumio[3187]: info: Running setup.py bdist_wheel for camilladsp: finished with status 'done' Oct 02 16:54:01 primo volumio[3187]: info: Stored in directory: /tmp/pip-ephem-wheel-cache-4wa9d6ei/wheels/ca/47/7d/ee36beb671f74869a8e2ecb2c3fd4a28c1b7488a9c78e442b6 Oct 02 16:54:01 primo volumio[3187]: info: Running setup.py bdist_wheel for PyYAML: started Oct 02 16:54:01 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Oct 02 16:54:02 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 16:54:02 primo volumio[3187]: info: Received Get System Info Oct 02 16:54:02 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:54:02 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:54:02 primo volumio[3187]: info: Discovery: Getting this device information Oct 02 16:54:02 primo volumio[3187]: info: CoreCommandRouter::volumioGetState Oct 02 16:54:02 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:54:02 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 02 16:54:02 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Oct 02 16:54:02 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:54:02 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:54:02 primo volumio[3187]: info: Discovery: Getting this device information Oct 02 16:54:02 primo volumio[3187]: info: CoreCommandRouter::volumioGetState Oct 02 16:54:02 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:54:04 primo volumio[3187]: info: Running setup.py bdist_wheel for PyYAML: finished with status 'done' Oct 02 16:54:04 primo volumio[3187]: info: Stored in directory: /root/.cache/pip/wheels/e6/c6/ef/4e8ee93f1b79fc90562f1600d47189799f8213023d9dadafa2 Oct 02 16:54:04 primo volumio[3187]: info: Successfully built camilladsp PyYAML Oct 02 16:54:04 primo volumio[3187]: info: Installing collected packages: PyYAML, websocket-client, camilladsp Oct 02 16:54:05 primo volumio[3187]: info: Successfully installed PyYAML-6.0.1 camilladsp-1.0.0 websocket-client-1.6.1 Oct 02 16:54:05 primo volumio[3187]: info: CoreCommandRouter::volumioGetQueue Oct 02 16:54:05 primo volumio[3187]: info: CoreStateMachine::getQueue Oct 02 16:54:05 primo volumio[3187]: info: CorePlayQueue::getQueue Oct 02 16:54:05 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 16:54:05 primo volumio[3187]: info: Received Get System Info Oct 02 16:54:05 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:54:05 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:54:05 primo volumio[3187]: info: Discovery: Getting this device information Oct 02 16:54:05 primo volumio[3187]: info: CoreCommandRouter::volumioGetState Oct 02 16:54:05 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:54:06 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 16:54:06 primo volumio[3187]: info: Received Get System Info Oct 02 16:54:06 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:54:06 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:54:06 primo volumio[3187]: info: Discovery: Getting this device information Oct 02 16:54:06 primo volumio[3187]: info: CoreCommandRouter::volumioGetState Oct 02 16:54:06 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:54:06 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 16:54:06 primo volumio[3187]: info: Received Get System Info Oct 02 16:54:06 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:54:06 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:54:06 primo volumio[3187]: info: Discovery: Getting this device information Oct 02 16:54:06 primo volumio[3187]: info: CoreCommandRouter::volumioGetState Oct 02 16:54:06 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:54:11 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Oct 02 16:54:11 primo volumio[3187]: info: Collecting git+https://github.com/HEnquist/pycamilladsp-plot.git@v1.0.2 Oct 02 16:54:11 primo volumio[3187]: info: Cloning https://github.com/HEnquist/pycamilladsp-plot.git (to revision v1.0.2) to /tmp/pip-req-build-yenuwdi7 Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:54:15 primo volumio[3187]: info: Retrieving Cloud Streaming UI Oct 02 16:54:15 primo volumio[3187]: info: Getting Tidal Cloud Configuration Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:54:15 primo volumio[3187]: info: Getting Qobuz Cloud Configuration Oct 02 16:54:15 primo volumio[3187]: info: Asking plugin for UI Config Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:54:15 primo volumio[3187]: info: Getting Spotify Cloud Configuration Oct 02 16:54:15 primo volumio[3187]: info: Asking plugin for UI Config Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:54:15 primo volumio[3187]: info: Saving Spotify Acccount Oct 02 16:54:15 primo volumio[3187]: info: Got it Oct 02 16:54:15 primo volumio[3187]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Oct 02 16:54:15 primo volumio[3187]: info: Got Tidal Cloud Configuration Oct 02 16:54:15 primo volumio[3187]: info: Got it Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::volumioGetBrowseSources Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 16:54:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Oct 02 16:54:19 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Oct 02 16:54:19 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Oct 02 16:54:19 primo volumio[3187]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Oct 02 16:54:19 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Oct 02 16:54:19 primo volumio[3187]: info: Received Get System Version Oct 02 16:54:19 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Oct 02 16:54:19 primo volumio[3187]: info: Received Get System Info Oct 02 16:54:19 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:54:19 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:54:19 primo volumio[3187]: info: Discovery: Getting this device information Oct 02 16:54:19 primo volumio[3187]: info: CoreCommandRouter::volumioGetState Oct 02 16:54:19 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:54:23 primo volumio[3187]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:54:23 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 16:54:23 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Oct 02 16:54:23 primo sudo[7138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Oct 02 16:54:23 primo sudo[7138]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:54:23 primo sudo[7138]: pam_unix(sudo:session): session closed for user root Oct 02 16:54:23 primo sudo[7143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 02 16:54:23 primo sudo[7143]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:54:23 primo kernel: wl_iw_get_wireless_stats: WLC_GET_RSSI error=-22 Oct 02 16:54:23 primo sudo[7151]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 02 16:54:23 primo sudo[7151]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:54:23 primo sudo[7143]: pam_unix(sudo:session): session closed for user root Oct 02 16:54:23 primo kernel: wl_iw_get_wireless_stats: WLC_GET_RSSI error=-22 Oct 02 16:54:23 primo sudo[7151]: pam_unix(sudo:session): session closed for user root Oct 02 16:54:23 primo sudo[7158]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Oct 02 16:54:23 primo sudo[7158]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:54:23 primo kernel: wl_iw_get_wireless_stats: WLC_GET_RSSI error=-22 Oct 02 16:54:23 primo sudo[7158]: pam_unix(sudo:session): session closed for user root Oct 02 16:54:23 primo sudo[7165]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Oct 02 16:54:23 primo sudo[7165]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:54:23 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Oct 02 16:54:23 primo sudo[7165]: pam_unix(sudo:session): session closed for user root Oct 02 16:54:23 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Oct 02 16:54:23 primo sudo[7169]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Oct 02 16:54:23 primo sudo[7169]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:54:23 primo sudo[7169]: pam_unix(sudo:session): session closed for user root Oct 02 16:54:23 primo sudo[7173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Oct 02 16:54:23 primo sudo[7173]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:23 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:24 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 8 Oct 02 16:54:26 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc04f548000 drv_status 0x0 e_type 69 e_states 0 Oct 02 16:54:26 primo sudo[7173]: pam_unix(sudo:session): session closed for user root Oct 02 16:54:27 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:54:27 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:54:27 primo volumio[3187]: info: Discovery: Getting this device information Oct 02 16:54:27 primo volumio[3187]: info: CoreCommandRouter::volumioGetState Oct 02 16:54:27 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:54:49 primo volumio[3187]: No protocol specified Oct 02 16:54:49 primo volumio[3187]: xcb_connection_has_error() returned true Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sOptions Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Oct 02 16:54:49 primo volumio[3187]: No protocol specified Oct 02 16:54:49 primo volumio[3187]: xcb_connection_has_error() returned true Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getPlaybackMode Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: raat , getAdditionalUiSection Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: inputs , getAdditionalUiSection Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:54:49 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:54:57 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 02 16:54:57 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 02 16:54:57 primo volumio[3187]: info: Discovery: Getting this device information Oct 02 16:54:57 primo volumio[3187]: info: CoreCommandRouter::volumioGetState Oct 02 16:54:57 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:55:09 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Oct 02 16:55:09 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Oct 02 16:55:14 primo volumio[3187]: info: Enabling plugin fusiondsp Oct 02 16:55:14 primo volumio[3187]: info: Loading plugin "fusiondsp"... Oct 02 16:55:15 primo volumio[3187]: info: Preparing to generate the ALSA configuration file Oct 02 16:55:15 primo volumio[3187]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 16:55:15 primo volumio[3187]: info: Reading ALSA contributions from plugins. Oct 02 16:55:15 primo volumio[3187]: info: Asound.conf file unchanged, so no further update is needed Oct 02 16:55:15 primo volumio[3187]: info: Output device has changed, restarting MPD Oct 02 16:55:15 primo sudo[7248]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 16:55:15 primo sudo[7248]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:15 primo volumio[3187]: info: Output device has changed, restarting Shairport Sync Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:55:15 primo sudo[7248]: pam_unix(sudo:session): session closed for user root Oct 02 16:55:15 primo sudo[7251]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 16:55:15 primo sudo[7251]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:15 primo systemd[1]: Stopping Music Player Daemon... Oct 02 16:55:15 primo volumio[3187]: No protocol specified Oct 02 16:55:15 primo volumio[3187]: xcb_connection_has_error() returned true Oct 02 16:55:15 primo volumio[3187]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 16:55:15 primo volumio[3187]: No protocol specified Oct 02 16:55:15 primo volumio[3187]: xcb_connection_has_error() returned true Oct 02 16:55:15 primo volumio[3187]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:15 primo systemd[1]: mpd.service: Succeeded. Oct 02 16:55:15 primo systemd[1]: Stopped Music Player Daemon. Oct 02 16:55:15 primo systemd[1]: Starting Music Player Daemon... Oct 02 16:55:15 primo volumio[3187]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:15 primo vtcs[5143]: [2024-10-02 16:55:15.350] [tisoc] [warning] [SessionManagerImpl.cpp:243] Illegal State: IDLE Oct 02 16:55:15 primo vtcs[5143]: [2024-10-02 16:55:15.354] [tisoc] [error] [SpkconServer.cpp:376] recv error. socket disconnected Oct 02 16:55:15 primo volumio[3187]: info: PLUGIN START: fusiondsp Oct 02 16:55:15 primo volumio[3187]: info: Loading i18n strings for locale en Oct 02 16:55:15 primo kernel: aml_tdm_open Oct 02 16:55:15 primo kernel: Not init audio effects Oct 02 16:55:15 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 02 16:55:15 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 02 16:55:15 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Oct 02 16:55:15 primo sudo[7270]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:15 primo volumio[3187]: info: FusionDsp - mixtype--------------------- Hardware Oct 02 16:55:15 primo volumio[3187]: info: Preparing to generate the ALSA configuration file Oct 02 16:55:15 primo sudo[7270]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:15 primo volumio[3187]: info: Done. Oct 02 16:55:15 primo systemd[1]: Stopping Volumio Tidal Connect Service... Oct 02 16:55:15 primo systemd[1]: vtcs.service: Main process exited, code=killed, status=15/TERM Oct 02 16:55:15 primo systemd[1]: vtcs.service: Succeeded. Oct 02 16:55:15 primo systemd[1]: Stopped Volumio Tidal Connect Service. Oct 02 16:55:15 primo sudo[7270]: pam_unix(sudo:session): session closed for user root Oct 02 16:55:15 primo volumio[3187]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Oct 02 16:55:15 primo volumio[3187]: info: Reading ALSA contributions from plugins. Oct 02 16:55:15 primo sudo[7257]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 16:55:15 primo sudo[7257]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:15 primo volumio[3187]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 02 16:55:15 primo sudo[7257]: pam_unix(sudo:session): session closed for user root Oct 02 16:55:15 primo volumio[3187]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 02 16:55:15 primo sudo[7274]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:55:15 primo sudo[7274]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:15 primo volumio[3187]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 02 16:55:15 primo volumio[3187]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:55:15 primo volumio[3187]: info: MPD Permissions set Oct 02 16:55:15 primo volumio[3187]: info: CoreStateMachine::pushState Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::volumioPushState Oct 02 16:55:15 primo volumio[3187]: info: MRS: Pushing multiroomSync output update for this device Oct 02 16:55:15 primo volumio[3187]: info: MRS: Pushing multiroomSync output Oct 02 16:55:15 primo volumio[3187]: info: CoreStateMachine::pushState Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::volumioPushState Oct 02 16:55:15 primo volumio[3187]: info: MRS: Pushing multiroomSync output update for this device Oct 02 16:55:15 primo volumio[3187]: info: MRS: Pushing multiroomSync output Oct 02 16:55:15 primo sudo[7274]: pam_unix(sudo:session): session closed for user root Oct 02 16:55:15 primo volumio[3187]: info: Signalling Playback active due to playback status change Oct 02 16:55:15 primo volumio[3187]: info: Signalling Playback active due to playback status change Oct 02 16:55:15 primo volumio[3187]: info: Updating RAAT Signal Path Oct 02 16:55:15 primo volumio[3187]: info: Updating RAAT Signal Path Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 16:55:15 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: Not Reporting Auto name since its the default one Oct 02 16:55:16 primo volumio[3187]: info: Starting Shairport Sync Oct 02 16:55:16 primo volumio[3187]: info: Asound.conf file unchanged, so no further update is needed Oct 02 16:55:16 primo volumio[3187]: info: Output device has changed, restarting MPD Oct 02 16:55:16 primo sudo[7292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 16:55:16 primo sudo[7292]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:16 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 02 16:55:16 primo volumio[3187]: info: Output device has changed, restarting Shairport Sync Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:55:16 primo sudo[7295]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Oct 02 16:55:16 primo sudo[7292]: pam_unix(sudo:session): session closed for user root Oct 02 16:55:16 primo sudo[7295]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:16 primo sudo[7295]: pam_unix(sudo:session): session closed for user root Oct 02 16:55:16 primo sudo[7300]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Oct 02 16:55:16 primo sudo[7300]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:16 primo systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Oct 02 16:55:16 primo systemd[1]: mpd.service: Succeeded. Oct 02 16:55:16 primo systemd[1]: Stopped Music Player Daemon. Oct 02 16:55:16 primo systemd[1]: Starting Music Player Daemon... Oct 02 16:55:16 primo volumio[3187]: No protocol specified Oct 02 16:55:16 primo volumio[3187]: xcb_connection_has_error() returned true Oct 02 16:55:16 primo volumio[3187]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Oct 02 16:55:16 primo volumio[3187]: No protocol specified Oct 02 16:55:16 primo volumio[3187]: xcb_connection_has_error() returned true Oct 02 16:55:16 primo volumio[3187]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: Reconfiguring and Restarting RAAT Plugin due to audio device changes Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo kernel: aml_tdm_open Oct 02 16:55:16 primo kernel: Not init audio effects Oct 02 16:55:16 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 02 16:55:16 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 02 16:55:16 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 02 16:55:16 primo sudo[7312]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:55:16 primo sudo[7312]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkCurrentAudioDeviceAvailable Oct 02 16:55:16 primo sudo[7312]: pam_unix(sudo:session): session closed for user root Oct 02 16:55:16 primo sudo[7317]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Oct 02 16:55:16 primo sudo[7317]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:16 primo sudo[7308]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Oct 02 16:55:16 primo sudo[7308]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:16 primo sudo[7308]: pam_unix(sudo:session): session closed for user root Oct 02 16:55:16 primo sudo[7317]: pam_unix(sudo:session): session closed for user root Oct 02 16:55:16 primo volumio[3187]: No protocol specified Oct 02 16:55:16 primo volumio[3187]: xcb_connection_has_error() returned true Oct 02 16:55:16 primo volumio[3187]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 02 16:55:16 primo sudo[7326]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed raat-daemon.service Oct 02 16:55:16 primo volumio[3187]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 02 16:55:16 primo sudo[7326]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:16 primo volumio[3187]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 02 16:55:16 primo volumio[3187]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 02 16:55:16 primo sudo[7326]: pam_unix(sudo:session): session closed for user root Oct 02 16:55:16 primo volumio[3187]: info: MPD Permissions set Oct 02 16:55:16 primo volumio[3187]: info: Shairport-Sync Started Oct 02 16:55:16 primo volumio[3187]: info: CoreStateMachine::pushState Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::volumioPushState Oct 02 16:55:16 primo volumio[3187]: info: MRS: Pushing multiroomSync output update for this device Oct 02 16:55:16 primo volumio[3187]: info: MRS: Pushing multiroomSync output Oct 02 16:55:16 primo volumio[3187]: info: CoreStateMachine::pushState Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::volumioPushState Oct 02 16:55:16 primo volumio[3187]: info: MRS: Pushing multiroomSync output update for this device Oct 02 16:55:16 primo volumio[3187]: info: MRS: Pushing multiroomSync output Oct 02 16:55:16 primo sudo[7328]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart raat-daemon.service Oct 02 16:55:16 primo sudo[7328]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:16 primo volumio[3187]: info: Signalling Playback active due to playback status change Oct 02 16:55:16 primo volumio[3187]: info: Signalling Playback active due to playback status change Oct 02 16:55:16 primo volumio[3187]: info: Updating RAAT Signal Path Oct 02 16:55:16 primo volumio[3187]: info: Updating RAAT Signal Path Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:16 primo systemd[1]: Stopping RAAT DAEMON... Oct 02 16:55:16 primo systemd[1]: raat-daemon.service: Main process exited, code=killed, status=15/TERM Oct 02 16:55:16 primo systemd[1]: raat-daemon.service: Succeeded. Oct 02 16:55:16 primo systemd[1]: Stopped RAAT DAEMON. Oct 02 16:55:16 primo systemd[1]: Started RAAT DAEMON. Oct 02 16:55:16 primo sudo[7328]: pam_unix(sudo:session): session closed for user root Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Oct 02 16:55:16 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Oct 02 16:55:17 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:17 primo volumio[3187]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 02 16:55:17 primo volumio[3187]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 02 16:55:17 primo kernel: aml_tdm_open Oct 02 16:55:17 primo kernel: Not init audio effects Oct 02 16:55:17 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 02 16:55:17 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 02 16:55:17 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 02 16:55:17 primo kernel: aml_tdm_open Oct 02 16:55:17 primo kernel: Not init audio effects Oct 02 16:55:17 primo kernel: audio_ddr_mngr: frddrs[0] registered by device ff660000.audiobus:tdm@1 Oct 02 16:55:17 primo kernel: tdm playback mute: 1, lane_cnt = 8 Oct 02 16:55:17 primo kernel: audio_ddr_mngr: frddrs[0] released by device ff660000.audiobus:tdm@1 Oct 02 16:55:17 primo volumio[3187]: info: CoreCommandRouter::getUIConfigOnPlugin Oct 02 16:55:17 primo volumio[3187]: info: Raat Daemon started successfully Oct 02 16:55:17 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:17 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:17 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:17 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:17 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:17 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:17 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Oct 02 16:55:17 primo volumio[3187]: info: Not Reporting Auto name since its the default one Oct 02 16:55:17 primo volumio[3187]: info: Starting Shairport Sync Oct 02 16:55:17 primo volumio[3187]: info: Executing endpoint restartRAATSocket Oct 02 16:55:17 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: raat , establishDaemonConnection Oct 02 16:55:17 primo systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Oct 02 16:55:17 primo sudo[7353]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Oct 02 16:55:17 primo systemd[1]: shairport-sync.service: Succeeded. Oct 02 16:55:17 primo sudo[7353]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:17 primo systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Oct 02 16:55:17 primo sudo[7353]: pam_unix(sudo:session): session closed for user root Oct 02 16:55:17 primo systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Oct 02 16:55:17 primo volumio[3187]: info: camilladsp spawned new process with pid undefined, instance 1, run: true Oct 02 16:55:17 primo volumio[3187]: info: camilladsp service started and running in background, instance 1 Oct 02 16:55:17 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:17 primo volumio[3187]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 02 16:55:17 primo volumio[3187]: error: FusionDsp - ----Hw detection failed :Error: Command failed: /data/plugins/audio_interface/fusiondsp/hw_params volumioHw >/data/configuration/audio_interface/fusiondsp/hwinfo.json Oct 02 16:55:17 primo volumio[3187]: /bin/sh: 1: /data/plugins/audio_interface/fusiondsp/hw_params: not found Oct 02 16:55:17 primo volumio[3187]: info: FusionDsp loaded Oct 02 16:55:17 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 02 16:55:17 primo sudo[7369]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Oct 02 16:55:17 primo sudo[7369]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 02 16:55:17 primo volumio[3187]: info: FusionDsp - Reporting Fusion DSP Enabled Oct 02 16:55:17 primo volumio[3187]: info: Adding Signal Path Element [object Object] Oct 02 16:55:17 primo volumio[3187]: info: Adding fusiondspeq DSP Signal Path Element Oct 02 16:55:17 primo volumio[3187]: info: Additional DSP elements updated Oct 02 16:55:17 primo volumio[3187]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Oct 02 16:55:17 primo volumio[3187]: info: Updating RAAT Signal Path Oct 02 16:55:17 primo volumio[3187]: info: FusionDsp - ---- installed callbackRead Oct 02 16:55:17 primo volumio[3187]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 16:55:17 primo volumio[3187]: Error: spawn /data/plugins/audio_interface/fusiondsp/camilladsp ENOENT Oct 02 16:55:17 primo volumio[3187]: at Process.ChildProcess._handle.onexit (internal/child_process.js:277:19) Oct 02 16:55:17 primo volumio[3187]: at onErrorNT (internal/child_process.js:472:16) Oct 02 16:55:17 primo volumio[3187]: at processTicksAndRejections (internal/process/task_queues.js:82:21) Oct 02 16:55:17 primo volumio[3187]: at runNextTicks (internal/process/task_queues.js:64:3) Oct 02 16:55:17 primo volumio[3187]: at listOnTimeout (internal/timers.js:526:9) Oct 02 16:55:17 primo volumio[3187]: at processTimers (internal/timers.js:500:7) { Oct 02 16:55:17 primo volumio[3187]: errno: -2, Oct 02 16:55:17 primo volumio[3187]: code: 'ENOENT', Oct 02 16:55:17 primo volumio[3187]: syscall: 'spawn /data/plugins/audio_interface/fusiondsp/camilladsp', Oct 02 16:55:17 primo volumio[3187]: path: '/data/plugins/audio_interface/fusiondsp/camilladsp', Oct 02 16:55:17 primo volumio[3187]: spawnargs: [ Oct 02 16:55:17 primo volumio[3187]: '-p', Oct 02 16:55:17 primo volumio[3187]: 9876, Oct 02 16:55:17 primo volumio[3187]: '-o', Oct 02 16:55:17 primo volumio[3187]: '/tmp/camilladsp.log', Oct 02 16:55:17 primo volumio[3187]: '-l', Oct 02 16:55:17 primo volumio[3187]: 'warn', Oct 02 16:55:17 primo volumio[3187]: '/data/configuration/audio_interface/fusiondsp/camilladsp.yml' Oct 02 16:55:17 primo volumio[3187]: ] Oct 02 16:55:17 primo volumio[3187]: } Oct 02 16:55:17 primo volumio[3187]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 02 16:55:17 primo systemd[1]: Started FusionDsp Daemon. Oct 02 16:55:17 primo sudo[7369]: pam_unix(sudo:session): session closed for user root Oct 02 16:55:18 primo sudo[7382]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-10-02 16:54 Oct 02 16:55:18 primo sudo[7382]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Debian GNU/Linux 10 (buster)" NAME="Debian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="bba9e3185e150dd0995f9a49355b34fc6218d5a2" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="8aa57e3ae5b742ea1069404608336a9884ccfb4e" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue 17 Sep 2024 06:34:13 PM CEST" VOLUMIO_VERSION="3.757" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="4afef88585183bd857d5aee3dd000abc"