-- Logs begin at Sun 2024-02-18 12:01:04 CET, end at Mon 2024-04-15 12:15:33 CEST. -- Apr 15 12:14:01 primo sudo[9939]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 15 12:14:01 primo sudo[9939]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:14:01 primo sudo[9939]: pam_unix(sudo:session): session closed for user root Apr 15 12:14:01 primo sudo[9941]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 15 12:14:01 primo sudo[9941]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:14:01 primo sudo[9941]: pam_unix(sudo:session): session closed for user root Apr 15 12:14:01 primo volumio[5490]: verbose: New Socket.io Connection to 192.168.178.30 from 192.168.178.52 UA: okhttp/4.9.2 Total Clients: 9 Apr 15 12:14:01 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 15 12:14:01 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 15 12:14:01 primo volumio[5490]: info: CoreCommandRouter::volumioGetVisibleSources Apr 15 12:14:01 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 12:14:01 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:01 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:01 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 15 12:14:01 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 15 12:14:01 primo volumio[5490]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 15 12:14:01 primo volumio[5490]: info: Received Get System Info Apr 15 12:14:01 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 12:14:01 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 12:14:01 primo volumio[5490]: info: Discovery: Getting this device information Apr 15 12:14:01 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:01 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:01 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 12:14:01 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:01 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:01 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 15 12:14:01 primo volumio[5490]: info: Listing playlists Apr 15 12:14:01 primo sudo[9948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 15 12:14:01 primo sudo[9948]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:14:01 primo sudo[9948]: pam_unix(sudo:session): session closed for user root Apr 15 12:14:02 primo sudo[9951]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 15 12:14:02 primo sudo[9951]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:14:02 primo sudo[9951]: pam_unix(sudo:session): session closed for user root Apr 15 12:14:02 primo volumio[5490]: verbose: New Socket.io Connection to 192.168.178.30 from 192.168.178.52 UA: okhttp/4.9.2 Total Clients: 9 Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::volumioGetVisibleSources Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:02 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 15 12:14:02 primo volumio[5490]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 15 12:14:02 primo volumio[5490]: info: Received Get System Info Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 12:14:02 primo volumio[5490]: info: Discovery: Getting this device information Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:02 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:02 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:02 primo volumio[5490]: info: Listing playlists Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 15 12:14:02 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 15 12:14:02 primo volumio[5490]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 9 Apr 15 12:14:03 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:03 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:03 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 15 12:14:03 primo volumio[5490]: info: Received Get System Info Apr 15 12:14:03 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 12:14:03 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 12:14:03 primo volumio[5490]: info: Discovery: Getting this device information Apr 15 12:14:03 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:03 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:03 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 12:14:04 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:14:04 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:14:04 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 15 12:14:04 primo volumio[5490]: info: Received Get System Info Apr 15 12:14:04 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 12:14:04 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 12:14:04 primo volumio[5490]: info: Discovery: Getting this device information Apr 15 12:14:04 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:04 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:04 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 12:14:05 primo volumio[5490]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 10 Apr 15 12:14:07 primo volumio[5490]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: volumiokiosk-memorysave-touch Total Clients: 11 Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::volumioGetVisibleSources Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:07 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 15 12:14:07 primo volumio[5490]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 15 12:14:07 primo volumio[5490]: info: Received Get System Info Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 12:14:07 primo volumio[5490]: info: Discovery: Getting this device information Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:07 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:07 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:07 primo volumio[5490]: info: Listing playlists Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:07 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::volumioGetVisibleSources Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:07 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:07 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Apr 15 12:14:07 primo volumio[5490]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Apr 15 12:14:07 primo volumio[5490]: info: Received Get System Info Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 12:14:07 primo volumio[5490]: info: Discovery: Getting this device information Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:07 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:07 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:07 primo volumio[5490]: info: Listing playlists Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 15 12:14:07 primo volumio[5490]: info: Received Get System Info Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 12:14:07 primo volumio[5490]: info: Discovery: Getting this device information Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:07 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:07 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 12:14:09 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Apr 15 12:14:11 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 15 12:14:11 primo volumio[5490]: info: Received Get System Info Apr 15 12:14:11 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 12:14:11 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 12:14:11 primo volumio[5490]: info: Discovery: Getting this device information Apr 15 12:14:11 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:11 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:11 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 12:14:12 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:14:12 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:14:12 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Apr 15 12:14:12 primo volumio[5490]: info: Received Get System Info Apr 15 12:14:12 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 12:14:12 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 12:14:12 primo volumio[5490]: info: Discovery: Getting this device information Apr 15 12:14:12 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:12 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:12 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 12:14:20 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:14:20 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 15 12:14:20 primo volumio[5490]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 15 12:14:20 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 15 12:14:20 primo volumio[5490]: info: Received Get System Version Apr 15 12:14:20 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 15 12:14:20 primo volumio[5490]: info: Received Get System Info Apr 15 12:14:20 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 12:14:20 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 12:14:20 primo volumio[5490]: info: Discovery: Getting this device information Apr 15 12:14:20 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:20 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:20 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 12:14:23 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Apr 15 12:14:23 primo volumio[5490]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Apr 15 12:14:23 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Apr 15 12:14:23 primo volumio[5490]: info: Received Get System Version Apr 15 12:14:23 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Apr 15 12:14:23 primo volumio[5490]: info: Received Get System Info Apr 15 12:14:23 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 12:14:23 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 12:14:23 primo volumio[5490]: info: Discovery: Getting this device information Apr 15 12:14:23 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:23 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:23 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 12:14:24 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 12:14:24 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 12:14:24 primo volumio[5490]: info: Discovery: Getting this device information Apr 15 12:14:24 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:24 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:24 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 12:14:24 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Apr 15 12:14:24 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Apr 15 12:14:24 primo volumio[5490]: info: Discovery: Getting this device information Apr 15 12:14:24 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:14:24 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:14:24 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Apr 15 12:14:25 primo volumio[5490]: info: Discovery: A device disappeared from network Apr 15 12:14:28 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:14:31 primo volumio[5490]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 12:14:31 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 15 12:14:32 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Apr 15 12:14:32 primo sudo[10138]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Apr 15 12:14:32 primo sudo[10138]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:14:32 primo sudo[10143]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 15 12:14:32 primo sudo[10143]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:14:32 primo sudo[10138]: pam_unix(sudo:session): session closed for user root Apr 15 12:14:32 primo sudo[10149]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 15 12:14:32 primo sudo[10149]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:14:32 primo sudo[10143]: pam_unix(sudo:session): session closed for user root Apr 15 12:14:32 primo sudo[10149]: pam_unix(sudo:session): session closed for user root Apr 15 12:14:32 primo sudo[10162]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 15 12:14:32 primo sudo[10162]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:14:32 primo sudo[10155]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 15 12:14:32 primo sudo[10155]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:14:32 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Apr 15 12:14:32 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Apr 15 12:14:32 primo sudo[10162]: pam_unix(sudo:session): session closed for user root Apr 15 12:14:32 primo sudo[10155]: pam_unix(sudo:session): session closed for user root Apr 15 12:14:32 primo sudo[10168]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 15 12:14:32 primo sudo[10168]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:14:32 primo sudo[10168]: pam_unix(sudo:session): session closed for user root Apr 15 12:14:32 primo sudo[10173]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Apr 15 12:14:32 primo sudo[10173]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:14:32 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:32 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:32 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:32 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:33 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:34 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:34 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:34 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:34 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:34 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:34 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:34 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:34 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:34 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:34 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:34 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:35 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:35 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:35 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:36 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:36 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:36 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:36 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 8 Apr 15 12:14:36 primo kernel: CFG80211-ERROR) wl_escan_handler : escan is not ready ndev ffffffc002835000 drv_status 0x0 e_type 69 e_states 0 Apr 15 12:14:36 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:14:36 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:14:36 primo sudo[10173]: pam_unix(sudo:session): session closed for user root Apr 15 12:14:42 primo ntpd[9320]: 75.119.140.230 local addr 192.168.178.30 -> Apr 15 12:14:44 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:14:44 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:14:52 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:14:52 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:15:00 primo volumio[5490]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Apr 15 12:15:00 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Apr 15 12:15:00 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:15:00 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:15:01 primo sudo[10228]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Apr 15 12:15:01 primo sudo[10228]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:01 primo sudo[10228]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:01 primo volumio[5490]: info: Permissions for /etc/hostapd/hostapd.conf Apr 15 12:15:01 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Apr 15 12:15:01 primo volumio[5490]: info: Discovery: Restarting Advertising due to device name change Apr 15 12:15:01 primo sudo[10231]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Apr 15 12:15:01 primo sudo[10231]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:01 primo wpa_supplicant[9219]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Apr 15 12:15:01 primo wpa_supplicant[9219]: nl80211: Failed to set IPv4 unicast in multicast filter Apr 15 12:15:01 primo systemd[1]: Stopping Wireless Services... Apr 15 12:15:01 primo wpa_supplicant[9219]: nl80211: Failed to open /proc/sys/net/ipv4/conf/p2p-dev-wlan0/drop_unicast_in_l2_multicast: No such file or directory Apr 15 12:15:01 primo wpa_supplicant[9219]: nl80211: Failed to set IPv4 unicast in multicast filter Apr 15 12:15:01 primo wpa_supplicant[9219]: nl80211: deinit ifname=p2p-dev-wlan0 disabled_11b_rates=0 Apr 15 12:15:01 primo volumio[5490]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 12:15:01 primo wpa_supplicant[9219]: p2p-dev-wlan0: CTRL-EVENT-TERMINATING Apr 15 12:15:01 primo kernel: [35B blob data] Apr 15 12:15:01 primo kernel: Exit. P2P interface stopped Apr 15 12:15:01 primo kernel: CFG80211-ERROR) wl_cfg80211_disconnect : Reason 3 Apr 15 12:15:01 primo kernel: dhd_dbg_stop_pkt_monitor, 1963 Apr 15 12:15:01 primo kernel: wl_iw_event: Link Down with b0:f2:08:ac:4d:09, reason=2 Apr 15 12:15:01 primo kernel: CFG80211-ERROR) wl_is_linkdown : Link down Reason : WLC_E_LINK Apr 15 12:15:01 primo kernel: link down if wlan0 may call cfg80211_disconnected. event : 16, reason=2 from b0:f2:08:ac:4d:09 Apr 15 12:15:01 primo kernel: dhd_dbg_stop_pkt_monitor, 1963 Apr 15 12:15:01 primo kernel: CFG80211-ERROR) wl_cfg80211_tdls_config : No change in tdls state. Do nothing. tdls_enable:1 Apr 15 12:15:01 primo wpa_supplicant[9219]: wlan0: CTRL-EVENT-DISCONNECTED bssid=b0:f2:08:ac:4d:09 reason=3 locally_generated=1 Apr 15 12:15:01 primo dhcpcd[5833]: wlan0: carrier lost Apr 15 12:15:01 primo wpa_supplicant[9219]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Apr 15 12:15:01 primo avahi-daemon[4532]: Withdrawing address record for 192.168.178.36 on wlan0. Apr 15 12:15:01 primo avahi-daemon[4532]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.36. Apr 15 12:15:01 primo avahi-daemon[4532]: Interface wlan0.IPv4 no longer relevant for mDNS. Apr 15 12:15:01 primo volumio[5490]: info: Discovery: A device disappeared from network Apr 15 12:15:01 primo volumio[5490]: info: Discovery: Device primo disappeared from network Apr 15 12:15:01 primo kernel: dhd_stop: Enter ffffffc002835000 Apr 15 12:15:01 primo kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Apr 15 12:15:01 primo kernel: CFG80211-ERROR) wl_cfgp2p_down : Clean up the p2p discovery IF Apr 15 12:15:01 primo kernel: wl_cfgp2p_del_p2p_disc_if: wdev: ffffffc04de1dc00, wdev->net: (null) Apr 15 12:15:01 primo dhcpcd[5833]: wlan0: deleting route to 192.168.178.0/24 Apr 15 12:15:01 primo kernel: P2P interface unregistered Apr 15 12:15:01 primo kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In Apr 15 12:15:01 primo kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 1 -> mode 0 Apr 15 12:15:01 primo kernel: wl_android_wifi_off in 1 Apr 15 12:15:01 primo kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 Apr 15 12:15:01 primo kernel: dhd_dbg_detach_pkt_monitor, 2204 Apr 15 12:15:01 primo kernel: dhd_bus_devreset: == Power OFF == Apr 15 12:15:01 primo kernel: bcmsdh_oob_intr_unregister: Enter Apr 15 12:15:01 primo kernel: dhd_txglom_enable: enable 0 Apr 15 12:15:01 primo kernel: dhd_conf_set_txglom_params: txglom_mode=copy Apr 15 12:15:01 primo kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0 Apr 15 12:15:01 primo kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Apr 15 12:15:01 primo kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Apr 15 12:15:01 primo kernel: dhd_bus_devreset: WLAN OFF DONE Apr 15 12:15:01 primo kernel: wifi_platform_set_power = 0 Apr 15 12:15:01 primo kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Apr 15 12:15:01 primo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Apr 15 12:15:02 primo kernel: wl_android_wifi_off out Apr 15 12:15:02 primo kernel: wl_ext_iapsta_dettach_netdev: Enter Apr 15 12:15:02 primo kernel: dhd_stop: Exit Apr 15 12:15:02 primo dhcpcd[5833]: wlan0: deleting default route via 192.168.178.1 Apr 15 12:15:02 primo wpa_supplicant[9219]: wlan0: CTRL-EVENT-TERMINATING Apr 15 12:15:02 primo systemd[1]: wireless.service: Succeeded. Apr 15 12:15:02 primo systemd[1]: Stopped Wireless Services. Apr 15 12:15:02 primo volumio[5490]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 12:15:02 primo systemd[1]: Starting Wireless Services... Apr 15 12:15:02 primo volumio[5490]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 12:15:02 primo dhcpcd[5833]: wlan0: carrier acquired Apr 15 12:15:02 primo dhcpcd[5833]: wlan0: IAID 0e:ab:bf:fe Apr 15 12:15:02 primo dhcpcd[5833]: wlan0: carrier lost Apr 15 12:15:02 primo volumio[5490]: info: Discovery: A device disappeared from network Apr 15 12:15:02 primo wireless.js[10268]: Cleaning previous... Apr 15 12:15:02 primo sudo[10334]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 15 12:15:02 primo sudo[10334]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:02 primo sudo[10334]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:02 primo sudo[10337]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 15 12:15:02 primo sudo[10337]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:02 primo sudo[10337]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:03 primo wireless.js[10268]: Stopped aP Apr 15 12:15:03 primo sudo[10345]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 15 12:15:03 primo sudo[10345]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:03 primo kernel: dhd_open: Enter ffffffc002835000 Apr 15 12:15:03 primo kernel: dhd_open : no mutex held. set lock Apr 15 12:15:03 primo kernel: Dongle Host Driver, version 1.579.77.41.10 (r) Apr 15 12:15:03 primo kernel: wl_ext_iapsta_attach_netdev: ifidx=0, bssidx=0 Apr 15 12:15:03 primo kernel: wl_android_wifi_on in 1 Apr 15 12:15:03 primo kernel: wl_android_wifi_on in 2: g_wifi_on=0 Apr 15 12:15:03 primo kernel: wifi_platform_set_power = 1 Apr 15 12:15:03 primo kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Apr 15 12:15:03 primo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Apr 15 12:15:03 primo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Enable! 482 Apr 15 12:15:03 primo kernel: sdio_reset_comm(): Apr 15 12:15:03 primo kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Apr 15 12:15:03 primo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Apr 15 12:15:03 primo kernel: meson-mmc: actual_clock :0, HHI_nand: 0x80 Apr 15 12:15:03 primo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Apr 15 12:15:03 primo kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Apr 15 12:15:03 primo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Apr 15 12:15:03 primo kernel: sdio_read_cis: 2 callbacks suppressed Apr 15 12:15:03 primo kernel: sdio: queuing unknown CIS tuple 0x80 (2 bytes) Apr 15 12:15:03 primo kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Apr 15 12:15:03 primo kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Apr 15 12:15:03 primo kernel: sdio: queuing unknown CIS tuple 0x80 (7 bytes) Apr 15 12:15:03 primo kernel: sdio: queuing unknown CIS tuple 0x81 (9 bytes) Apr 15 12:15:03 primo kernel: sdio: queuing unknown CIS tuple 0x8e (9 bytes) Apr 15 12:15:04 primo kernel: meson-aml-mmc ffe03000.sdio: divider requested rate 200000000 != actual rate 199999997: ret=0 Apr 15 12:15:04 primo kernel: meson-mmc: actual_clock :199999997, HHI_nand: 0x80 Apr 15 12:15:04 primo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x10000245 Apr 15 12:15:04 primo kernel: meson-mmc: Data 1 aligned delay is 0 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: clk 199999997 tuning start Apr 15 12:15:04 primo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: [prev_map] fixed_adj_win_map:30 Apr 15 12:15:04 primo kernel: meson-mmc: [0]=0 Apr 15 12:15:04 primo kernel: meson-mmc: [1]=1 Apr 15 12:15:04 primo kernel: meson-mmc: [2]=1 Apr 15 12:15:04 primo kernel: meson-mmc: [3]=1 Apr 15 12:15:04 primo kernel: meson-mmc: [4]=1 Apr 15 12:15:04 primo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Apr 15 12:15:04 primo kernel: meson-mmc: step:4, delay1:0x4104104, delay2:0x4004104 Apr 15 12:15:04 primo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Apr 15 12:15:04 primo kernel: meson-mmc: step:8, delay1:0x8208208, delay2:0x8008208 Apr 15 12:15:04 primo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Apr 15 12:15:04 primo kernel: meson-mmc: step:12, delay1:0xc30c30c, delay2:0xc00c30c Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Apr 15 12:15:04 primo kernel: meson-mmc: step:16, delay1:0x10410410, delay2:0x10010410 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Apr 15 12:15:04 primo kernel: meson-mmc: [>>>>>>>>bitmap_xor_dst] fixed_adj_win_map:2 Apr 15 12:15:04 primo kernel: meson-mmc: [0]=0 Apr 15 12:15:04 primo kernel: meson-mmc: [1]=1 Apr 15 12:15:04 primo kernel: meson-mmc: [2]=0 Apr 15 12:15:04 primo kernel: meson-mmc: [3]=0 Apr 15 12:15:04 primo kernel: meson-mmc: [4]=0 Apr 15 12:15:04 primo kernel: meson-mmc: [_find_fixed_adj_valid_win] fir_adj:1 Apr 15 12:15:04 primo kernel: meson-mmc: [>>>>>>>>prev_map_range] fixed_adj_win_map:495 Apr 15 12:15:04 primo kernel: meson-mmc: [1]=1 Apr 15 12:15:04 primo kernel: meson-mmc: [2]=1 Apr 15 12:15:04 primo kernel: meson-mmc: [3]=1 Apr 15 12:15:04 primo kernel: meson-mmc: [4]=1 Apr 15 12:15:04 primo kernel: meson-mmc: [0]=0 Apr 15 12:15:04 primo kernel: meson-mmc: left:3, right:0, mid:4, size:4 Apr 15 12:15:04 primo kernel: meson-mmc: step:0, delay1:0x0, delay2:0x0 Apr 15 12:15:04 primo kernel: meson-mmc: sdio: sd_emmc_regs->gclock=0x10000245,sd_emmc_regs->gadjust=0x32000 Apr 15 12:15:04 primo kernel: meson-mmc: delay1:0x0, delay2:0x0 Apr 15 12:15:04 primo kernel: sdioh_start: set sd_f2_blocksize 256 Apr 15 12:15:04 primo kernel: dhd_bus_devreset: == WLAN ON == Apr 15 12:15:04 primo kernel: F1 signature read @0x18000000=0x17294359 Apr 15 12:15:04 primo kernel: F1 signature OK, socitype:0x1 chip:0x4359 rev:0x9 pkg:0x2 Apr 15 12:15:04 primo kernel: DHD: dongle ram size is set to 917504(orig 917504) at 0x160000 Apr 15 12:15:04 primo kernel: dhd_bus_set_default_min_res_mask: Unhandled chip id Apr 15 12:15:04 primo kernel: dhd_os_open_image: /lib/firmware/brcm/config_bcm4359c0_ag.txt (185 bytes) open success Apr 15 12:15:04 primo kernel: dhd_conf_read_sdio_params: dhd_slpauto = 0 Apr 15 12:15:04 primo kernel: dhd_conf_read_others: ccode = ALL Apr 15 12:15:04 primo kernel: dhd_conf_read_others: regrev = 38 Apr 15 12:15:04 primo kernel: dhd_conf_read_nv_by_chip: nv_by_chip_count=5 Apr 15 12:15:04 primo kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=0, name=nvram_ap6212.txt Apr 15 12:15:04 primo kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=1, name=nvram_ap6212a.txt Apr 15 12:15:04 primo kernel: dhd_conf_read_nv_by_chip: chip=0x4345, chiprev=6, name=nvram_ap6255.txt Apr 15 12:15:04 primo kernel: dhd_conf_read_nv_by_chip: chip=0x4354, chiprev=2, name=nvram_ap6356.txt Apr 15 12:15:04 primo kernel: dhd_conf_read_nv_by_chip: chip=0x4359, chiprev=9, name=nvram_ap6359sa.txt Apr 15 12:15:04 primo kernel: Final fw_path=/lib/firmware/brcm/fw_bcm4359c0_ag.bin Apr 15 12:15:04 primo kernel: Final nv_path=/lib/firmware/brcm/nvram_ap6359sa.txt Apr 15 12:15:04 primo kernel: Final clm_path=/lib/firmware/brcm/clm.blob Apr 15 12:15:04 primo kernel: Final conf_path=/lib/firmware/brcm/config_bcm4359c0_ag.txt Apr 15 12:15:04 primo kernel: dhd_os_open_image: /lib/firmware/brcm/fw_bcm4359c0_ag.bin (595758 bytes) open success Apr 15 12:15:04 primo kernel: dhd_os_open_image: /lib/firmware/brcm/nvram_ap6359sa.txt (5861 bytes) open success Apr 15 12:15:04 primo kernel: [43B blob data] Apr 15 12:15:04 primo kernel: dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. Apr 15 12:15:04 primo kernel: dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) Apr 15 12:15:04 primo kernel: si_get_pmu_reg_addr: addrRET: 18000670 Apr 15 12:15:04 primo kernel: bcmsdh_oob_intr_register: HW_OOB irq=70 flags=0x8 Apr 15 12:15:04 primo kernel: dhd_get_memdump_info: File [/data/misc/wifi/.memdump.info] doesn't exist Apr 15 12:15:04 primo kernel: dhd_get_memdump_info: MEMDUMP ENABLED = 2 Apr 15 12:15:04 primo kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 0 -> mode 1 Apr 15 12:15:04 primo kernel: dhd_apply_default_clm: Ignore clm file /lib/firmware/brcm/clm.blob Apr 15 12:15:04 primo kernel: Firmware up: op_mode=0x0405, MAC=70:4a:0e:ab:bf:fe Apr 15 12:15:04 primo kernel: Driver: 1.579.77.41.10 (r) Firmware: wl0: Jan 19 2018 12:14:30 version 9.87.51.11.8 (a85e25e@shgit) (r) FWID 01-cb5aa0a5 CLM: 9.7.5 Apr 15 12:15:04 primo kernel: dhd_txglom_enable: enable 1 Apr 15 12:15:04 primo kernel: dhd_conf_set_txglom_params: txglom_mode=copy Apr 15 12:15:04 primo kernel: dhd_conf_set_txglom_params: txglomsize=36, deferred_tx_len=0 Apr 15 12:15:04 primo kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Apr 15 12:15:04 primo kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Apr 15 12:15:04 primo kernel: dhd_conf_get_disable_proptx: fw_proptx=0, disable_proptx=1 Apr 15 12:15:04 primo kernel: dhd_pno_init: Support Android Location Service Apr 15 12:15:04 primo kernel: rtt_do_get_ioctl: failed to send getbuf proxd iovar (CMD ID : 1), status=-23 Apr 15 12:15:04 primo kernel: dhd_rtt_init : FTM is not supported Apr 15 12:15:04 primo kernel: dhd_preinit_ioctls: SensorHub diabled 0 Apr 15 12:15:04 primo kernel: dhd_preinit_ioctls failed to set ShubHub disable Apr 15 12:15:04 primo kernel: failed to set WNM capabilities Apr 15 12:15:04 primo kernel: dhd_conf_set_country: set country ALL, revision 38 Apr 15 12:15:04 primo kernel: CONFIG-ERROR) dhd_conf_set_bufiovar: country setting failed -2 Apr 15 12:15:04 primo kernel: Country code: US (US/0) Apr 15 12:15:04 primo kernel: CONFIG-ERROR) dhd_conf_set_intiovar: txbf setting failed -23 Apr 15 12:15:04 primo kernel: wl_android_wifi_on: Success Apr 15 12:15:04 primo kernel: dhd_open : the lock is released. Apr 15 12:15:04 primo kernel: dhd_open: Exit ret=0 Apr 15 12:15:04 primo dhcpcd[5833]: wlan0: carrier acquired Apr 15 12:15:04 primo ntpd[9320]: Deleting interface #4 wlan0, 192.168.178.36#123, interface stats: received=0, sent=0, dropped=0, active_time=638 secs Apr 15 12:15:04 primo sudo[10345]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:04 primo dhcpcd[5833]: wlan0: IAID 0e:ab:bf:fe Apr 15 12:15:04 primo sudo[10350]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Apr 15 12:15:04 primo sudo[10350]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:04 primo sudo[10350]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:04 primo sudo[10357]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Apr 15 12:15:04 primo sudo[10357]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:04 primo sudo[10357]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:04 primo sudo[10360]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Apr 15 12:15:04 primo sudo[10360]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:04 primo dhcpcd[5833]: wlan0: soliciting an IPv6 router Apr 15 12:15:04 primo dhcpcd[5833]: wlan0: rebinding lease of 192.168.178.36 Apr 15 12:15:06 primo volumio[5490]: info: Discovery: Started advertising with name: Primo Apr 15 12:15:06 primo sudo[10360]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:06 primo wireless.js[10268]: Start wireless flow Apr 15 12:15:06 primo wireless.js[10268]: Stopped hotspot (if there).. Apr 15 12:15:06 primo sudo[10371]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Apr 15 12:15:06 primo sudo[10371]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:06 primo sudo[10371]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:06 primo sudo[10374]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Apr 15 12:15:06 primo sudo[10374]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:06 primo kernel: dhd_stop: Enter ffffffc002835000 Apr 15 12:15:06 primo kernel: CFGP2P-ERROR) wl_cfgp2p_disable_discovery : do nothing, not initialized Apr 15 12:15:06 primo kernel: CFGP2P-ERROR) wl_cfgp2p_deinit_priv : In Apr 15 12:15:06 primo kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 1 -> mode 0 Apr 15 12:15:06 primo kernel: wl_android_wifi_off in 1 Apr 15 12:15:06 primo kernel: wl_android_wifi_off in 2: g_wifi_on=1, on_failure=1 Apr 15 12:15:06 primo kernel: dhd_dbg_detach_pkt_monitor, 2204 Apr 15 12:15:06 primo kernel: dhd_bus_devreset: == Power OFF == Apr 15 12:15:06 primo kernel: bcmsdh_oob_intr_unregister: Enter Apr 15 12:15:06 primo kernel: dhd_txglom_enable: enable 0 Apr 15 12:15:06 primo kernel: dhd_conf_set_txglom_params: txglom_mode=copy Apr 15 12:15:06 primo kernel: dhd_conf_set_txglom_params: txglomsize=0, deferred_tx_len=0 Apr 15 12:15:06 primo kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Apr 15 12:15:06 primo kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Apr 15 12:15:06 primo kernel: dhd_bus_devreset: WLAN OFF DONE Apr 15 12:15:06 primo kernel: wifi_platform_set_power = 0 Apr 15 12:15:06 primo kernel: ======== PULL WL_REG_ON(-1) LOW! ======== Apr 15 12:15:06 primo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Apr 15 12:15:07 primo dhcpcd[5833]: wlan0: carrier lost Apr 15 12:15:07 primo kernel: wl_android_wifi_off out Apr 15 12:15:07 primo kernel: wl_ext_iapsta_dettach_netdev: Enter Apr 15 12:15:07 primo kernel: dhd_stop: Exit Apr 15 12:15:07 primo sudo[10374]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:07 primo wireless.js[10268]: DHCP IP Apr 15 12:15:07 primo wireless.js[10268]: Start ap Apr 15 12:15:07 primo wpa_supplicant[10383]: Successfully initialized wpa_supplicant Apr 15 12:15:07 primo kernel: dhd_open: Enter ffffffc002835000 Apr 15 12:15:07 primo kernel: dhd_open : no mutex held. set lock Apr 15 12:15:07 primo kernel: Dongle Host Driver, version 1.579.77.41.10 (r) Apr 15 12:15:07 primo kernel: wl_ext_iapsta_attach_netdev: ifidx=0, bssidx=0 Apr 15 12:15:07 primo kernel: wl_android_wifi_on in 1 Apr 15 12:15:07 primo kernel: wl_android_wifi_on in 2: g_wifi_on=0 Apr 15 12:15:07 primo kernel: wifi_platform_set_power = 1 Apr 15 12:15:07 primo kernel: ======== PULL WL_REG_ON(-1) HIGH! ======== Apr 15 12:15:07 primo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Disable! 482 Apr 15 12:15:07 primo kernel: aml_wifi wifi: [extern_wifi_set_enable] WIFI Enable! 482 Apr 15 12:15:07 primo volumio[5490]: info: Discovery: adding undefined Apr 15 12:15:07 primo volumio[5490]: info: Discovery: Found device Primo Apr 15 12:15:07 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:15:07 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:07 primo kernel: sdio_reset_comm(): Apr 15 12:15:07 primo kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Apr 15 12:15:07 primo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Apr 15 12:15:07 primo kernel: meson-mmc: actual_clock :0, HHI_nand: 0x80 Apr 15 12:15:07 primo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Apr 15 12:15:07 primo kernel: meson-mmc: actual_clock :400000, HHI_nand: 0x80 Apr 15 12:15:07 primo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x1000023c Apr 15 12:15:07 primo kernel: sdio: queuing unknown CIS tuple 0x80 (2 bytes) Apr 15 12:15:07 primo kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Apr 15 12:15:07 primo kernel: sdio: queuing unknown CIS tuple 0x80 (3 bytes) Apr 15 12:15:07 primo kernel: sdio: queuing unknown CIS tuple 0x80 (7 bytes) Apr 15 12:15:08 primo kernel: meson-aml-mmc ffe03000.sdio: divider requested rate 200000000 != actual rate 199999997: ret=0 Apr 15 12:15:08 primo kernel: meson-mmc: actual_clock :199999997, HHI_nand: 0x80 Apr 15 12:15:08 primo kernel: meson-mmc: [meson_mmc_clk_set_rate_v3] after clock: 0x10000245 Apr 15 12:15:08 primo kernel: meson-mmc: Data 1 aligned delay is 0 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: clk 199999997 tuning start Apr 15 12:15:08 primo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: [prev_map] fixed_adj_win_map:30 Apr 15 12:15:08 primo kernel: meson-mmc: [0]=0 Apr 15 12:15:08 primo kernel: meson-mmc: [1]=1 Apr 15 12:15:08 primo kernel: meson-mmc: [2]=1 Apr 15 12:15:08 primo kernel: meson-mmc: [3]=1 Apr 15 12:15:08 primo kernel: meson-mmc: [4]=1 Apr 15 12:15:08 primo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Apr 15 12:15:08 primo kernel: meson-mmc: step:4, delay1:0x4104104, delay2:0x4004104 Apr 15 12:15:08 primo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Apr 15 12:15:08 primo kernel: meson-mmc: step:8, delay1:0x8208208, delay2:0x8008208 Apr 15 12:15:08 primo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Apr 15 12:15:08 primo kernel: meson-mmc: step:12, delay1:0xc30c30c, delay2:0xc00c30c Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[1] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: [_find_fixed_adj_valid_win]retry test fixed adj... Apr 15 12:15:08 primo kernel: meson-mmc: step:16, delay1:0x10410410, delay2:0x10010410 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[0] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: Tuning transfer error: nmatch=0 tuning_err:0xffffffac Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[2] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[3] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: rx_tuning_result[4] = 40 Apr 15 12:15:08 primo kernel: meson-mmc: [>>>>>>>>bitmap_xor_dst] fixed_adj_win_map:2 Apr 15 12:15:08 primo kernel: meson-mmc: [0]=0 Apr 15 12:15:08 primo kernel: meson-mmc: [1]=1 Apr 15 12:15:08 primo kernel: meson-mmc: [2]=0 Apr 15 12:15:08 primo kernel: meson-mmc: [3]=0 Apr 15 12:15:08 primo kernel: meson-mmc: [4]=0 Apr 15 12:15:08 primo kernel: meson-mmc: [_find_fixed_adj_valid_win] fir_adj:1 Apr 15 12:15:08 primo kernel: meson-mmc: [>>>>>>>>prev_map_range] fixed_adj_win_map:495 Apr 15 12:15:08 primo kernel: meson-mmc: [1]=1 Apr 15 12:15:08 primo kernel: meson-mmc: [2]=1 Apr 15 12:15:08 primo kernel: meson-mmc: [3]=1 Apr 15 12:15:08 primo kernel: meson-mmc: [4]=1 Apr 15 12:15:08 primo kernel: meson-mmc: [0]=0 Apr 15 12:15:08 primo kernel: meson-mmc: left:3, right:0, mid:4, size:4 Apr 15 12:15:08 primo kernel: meson-mmc: step:0, delay1:0x0, delay2:0x0 Apr 15 12:15:08 primo kernel: meson-mmc: sdio: sd_emmc_regs->gclock=0x10000245,sd_emmc_regs->gadjust=0x32000 Apr 15 12:15:08 primo kernel: meson-mmc: delay1:0x0, delay2:0x0 Apr 15 12:15:08 primo kernel: sdioh_start: set sd_f2_blocksize 256 Apr 15 12:15:08 primo kernel: dhd_bus_devreset: == WLAN ON == Apr 15 12:15:08 primo kernel: F1 signature read @0x18000000=0x17294359 Apr 15 12:15:08 primo kernel: F1 signature OK, socitype:0x1 chip:0x4359 rev:0x9 pkg:0x2 Apr 15 12:15:08 primo kernel: DHD: dongle ram size is set to 917504(orig 917504) at 0x160000 Apr 15 12:15:08 primo kernel: dhd_bus_set_default_min_res_mask: Unhandled chip id Apr 15 12:15:08 primo kernel: dhd_os_open_image: /lib/firmware/brcm/config_bcm4359c0_ag.txt (185 bytes) open success Apr 15 12:15:08 primo kernel: dhd_conf_read_sdio_params: dhd_slpauto = 0 Apr 15 12:15:08 primo kernel: dhd_conf_read_others: ccode = ALL Apr 15 12:15:08 primo kernel: dhd_conf_read_others: regrev = 38 Apr 15 12:15:08 primo kernel: dhd_conf_read_nv_by_chip: nv_by_chip_count=5 Apr 15 12:15:08 primo kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=0, name=nvram_ap6212.txt Apr 15 12:15:08 primo kernel: dhd_conf_read_nv_by_chip: chip=0xa9a6, chiprev=1, name=nvram_ap6212a.txt Apr 15 12:15:08 primo kernel: dhd_conf_read_nv_by_chip: chip=0x4345, chiprev=6, name=nvram_ap6255.txt Apr 15 12:15:08 primo kernel: dhd_conf_read_nv_by_chip: chip=0x4354, chiprev=2, name=nvram_ap6356.txt Apr 15 12:15:08 primo kernel: dhd_conf_read_nv_by_chip: chip=0x4359, chiprev=9, name=nvram_ap6359sa.txt Apr 15 12:15:08 primo kernel: Final fw_path=/lib/firmware/brcm/fw_bcm4359c0_ag.bin Apr 15 12:15:08 primo kernel: Final nv_path=/lib/firmware/brcm/nvram_ap6359sa.txt Apr 15 12:15:08 primo kernel: Final clm_path=/lib/firmware/brcm/clm.blob Apr 15 12:15:08 primo kernel: Final conf_path=/lib/firmware/brcm/config_bcm4359c0_ag.txt Apr 15 12:15:08 primo kernel: dhd_os_open_image: /lib/firmware/brcm/fw_bcm4359c0_ag.bin (595758 bytes) open success Apr 15 12:15:08 primo wireless.js[10268]: trying... Apr 15 12:15:08 primo kernel: dhd_os_open_image: /lib/firmware/brcm/nvram_ap6359sa.txt (5861 bytes) open success Apr 15 12:15:08 primo kernel: [43B blob data] Apr 15 12:15:08 primo kernel: dhdsdio_write_vars: Download, Upload and compare of NVRAM succeeded. Apr 15 12:15:08 primo kernel: dhd_bus_init: enable 0x06, ready 0x06 (waited 0us) Apr 15 12:15:08 primo kernel: si_get_pmu_reg_addr: addrRET: 18000670 Apr 15 12:15:08 primo kernel: bcmsdh_oob_intr_register: HW_OOB irq=70 flags=0x8 Apr 15 12:15:08 primo kernel: dhd_get_memdump_info: File [/data/misc/wifi/.memdump.info] doesn't exist Apr 15 12:15:08 primo kernel: dhd_get_memdump_info: MEMDUMP ENABLED = 2 Apr 15 12:15:08 primo kernel: dhd_tcpack_suppress_set: TCP ACK Suppress mode 0 -> mode 1 Apr 15 12:15:08 primo kernel: dhd_apply_default_clm: Ignore clm file /lib/firmware/brcm/clm.blob Apr 15 12:15:08 primo kernel: Firmware up: op_mode=0x0405, MAC=70:4a:0e:ab:bf:fe Apr 15 12:15:08 primo kernel: Driver: 1.579.77.41.10 (r) Firmware: wl0: Jan 19 2018 12:14:30 version 9.87.51.11.8 (a85e25e@shgit) (r) FWID 01-cb5aa0a5 CLM: 9.7.5 Apr 15 12:15:08 primo kernel: dhd_txglom_enable: enable 1 Apr 15 12:15:08 primo kernel: dhd_conf_set_txglom_params: txglom_mode=copy Apr 15 12:15:08 primo kernel: dhd_conf_set_txglom_params: txglomsize=36, deferred_tx_len=0 Apr 15 12:15:08 primo kernel: dhd_conf_set_txglom_params: txinrx_thres=128, dhd_txminmax=-1 Apr 15 12:15:08 primo kernel: dhd_conf_set_txglom_params: tx_max_offset=0, txctl_tmo_fix=300 Apr 15 12:15:08 primo kernel: dhd_conf_get_disable_proptx: fw_proptx=0, disable_proptx=1 Apr 15 12:15:08 primo kernel: dhd_pno_init: Support Android Location Service Apr 15 12:15:08 primo kernel: rtt_do_get_ioctl: failed to send getbuf proxd iovar (CMD ID : 1), status=-23 Apr 15 12:15:08 primo kernel: dhd_rtt_init : FTM is not supported Apr 15 12:15:08 primo kernel: dhd_preinit_ioctls: SensorHub diabled 0 Apr 15 12:15:08 primo kernel: dhd_preinit_ioctls failed to set ShubHub disable Apr 15 12:15:08 primo kernel: failed to set WNM capabilities Apr 15 12:15:08 primo kernel: dhd_conf_set_country: set country ALL, revision 38 Apr 15 12:15:08 primo kernel: CONFIG-ERROR) dhd_conf_set_bufiovar: country setting failed -2 Apr 15 12:15:08 primo kernel: Country code: US (US/0) Apr 15 12:15:08 primo kernel: CONFIG-ERROR) dhd_conf_set_intiovar: txbf setting failed -23 Apr 15 12:15:08 primo kernel: wl_android_wifi_on: Success Apr 15 12:15:08 primo kernel: dhd_open : the lock is released. Apr 15 12:15:08 primo kernel: dhd_open: Exit ret=0 Apr 15 12:15:08 primo dhcpcd[5833]: wlan0: carrier acquired Apr 15 12:15:08 primo sudo[10387]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Apr 15 12:15:08 primo dhcpcd[5833]: wlan0: IAID 0e:ab:bf:fe Apr 15 12:15:08 primo dhcpcd[5833]: wlan0: carrier lost Apr 15 12:15:08 primo sudo[10387]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:08 primo sudo[10413]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:15:08 primo sudo[10413]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:08 primo dhcpcd[10418]: sending commands to master dhcpcd process Apr 15 12:15:08 primo sudo[10413]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:08 primo sudo[10387]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:08 primo kernel: P2P interface registered Apr 15 12:15:08 primo kernel: wl_cfgp2p_add_p2p_disc_if: wdev: ffffffc03f821000, wdev->net: (null) Apr 15 12:15:08 primo dhcpcd[5833]: control command: /sbin/dhcpcd Apr 15 12:15:08 primo kernel: [35B blob data] Apr 15 12:15:08 primo kernel: P2P interface started Apr 15 12:15:08 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:15:09 primo wireless.js[10268]: trying... Apr 15 12:15:09 primo sudo[10439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:15:09 primo sudo[10439]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:09 primo sudo[10439]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:10 primo wireless.js[10268]: trying... Apr 15 12:15:10 primo sudo[10442]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:15:10 primo sudo[10442]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:10 primo sudo[10442]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:10 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:15:10 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:10 primo wpa_supplicant[10437]: wlan0: Trying to associate with b0:f2:08:ac:4d:09 (SSID='sigaletho1' freq=5580 MHz) Apr 15 12:15:10 primo kernel: Connecting with b0:f2:08:ac:4d:09 ssid "sigaletho1", len (10) channel=116 Apr 15 12:15:10 primo kernel: dhd_dbg_start_pkt_monitor, 1724 Apr 15 12:15:10 primo kernel: wl_iw_event: Link UP with b0:f2:08:ac:4d:09 Apr 15 12:15:10 primo kernel: wl_bss_connect_done succeeded with b0:f2:08:ac:4d:09 Apr 15 12:15:10 primo kernel: CFG80211-ERROR) wl_cfg80211_scan_abort : scan abort failed Apr 15 12:15:10 primo wpa_supplicant[10437]: wlan0: Associated with b0:f2:08:ac:4d:09 Apr 15 12:15:10 primo wpa_supplicant[10437]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Apr 15 12:15:10 primo wpa_supplicant[10437]: wlan0: WPA: Key negotiation completed with b0:f2:08:ac:4d:09 [PTK=CCMP GTK=CCMP] Apr 15 12:15:10 primo wpa_supplicant[10437]: wlan0: CTRL-EVENT-CONNECTED - Connection to b0:f2:08:ac:4d:09 completed [id=0 id_str=] Apr 15 12:15:10 primo dhcpcd[5833]: wlan0: carrier acquired Apr 15 12:15:10 primo wpa_supplicant[10437]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=DE Apr 15 12:15:10 primo dhcpcd[5833]: wlan0: IAID 0e:ab:bf:fe Apr 15 12:15:10 primo kernel: wl_bss_connect_done succeeded with b0:f2:08:ac:4d:09 vndr_oui: 8C-FD-F0 Apr 15 12:15:11 primo wireless.js[10268]: trying... Apr 15 12:15:11 primo sudo[10448]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:15:11 primo sudo[10448]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:11 primo sudo[10448]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:11 primo dhcpcd[5833]: wlan0: soliciting an IPv6 router Apr 15 12:15:11 primo dhcpcd[5833]: wlan0: rebinding lease of 192.168.178.36 Apr 15 12:15:11 primo dhcpcd[5833]: wlan0: probing address 192.168.178.36/24 Apr 15 12:15:12 primo wireless.js[10268]: trying... Apr 15 12:15:12 primo sudo[10451]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:15:12 primo sudo[10451]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:12 primo sudo[10451]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:13 primo wireless.js[10268]: trying... Apr 15 12:15:13 primo sudo[10454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:15:13 primo sudo[10454]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:13 primo sudo[10454]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 12:15:13 primo volumio[5490]: info: Retrieving Cloud Streaming UI Apr 15 12:15:13 primo volumio[5490]: info: Getting Tidal Cloud Configuration Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 12:15:13 primo volumio[5490]: info: Getting Qobuz Cloud Configuration Apr 15 12:15:13 primo volumio[5490]: info: Asking plugin for UI Config Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 12:15:13 primo volumio[5490]: info: Getting Spotify Cloud Configuration Apr 15 12:15:13 primo volumio[5490]: info: Asking plugin for UI Config Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 12:15:13 primo volumio[5490]: info: Saving Spotify Acccount Apr 15 12:15:13 primo volumio[5490]: info: Got it Apr 15 12:15:13 primo volumio[5490]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Apr 15 12:15:13 primo volumio[5490]: info: Got Tidal Cloud Configuration Apr 15 12:15:13 primo volumio[5490]: info: Got it Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::getUIConfigOnPlugin Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::volumioGetBrowseSources Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::volumioGetBrowseSources Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::volumioGetBrowseSources Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 12:15:13 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Apr 15 12:15:14 primo wireless.js[10268]: trying... Apr 15 12:15:14 primo sudo[10458]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:15:14 primo sudo[10458]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:14 primo sudo[10458]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:15 primo wireless.js[10268]: trying... Apr 15 12:15:15 primo sudo[10461]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:15:15 primo sudo[10461]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:15 primo sudo[10461]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:16 primo wireless.js[10268]: trying... Apr 15 12:15:16 primo sudo[10464]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:15:16 primo sudo[10464]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:16 primo sudo[10464]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:16 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:15:16 primo dhcpcd[5833]: wlan0: leased 192.168.178.36 for 34560000 seconds Apr 15 12:15:16 primo avahi-daemon[4532]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.178.36. Apr 15 12:15:16 primo dhcpcd[5833]: wlan0: adding route to 192.168.178.0/24 Apr 15 12:15:16 primo dhcpcd[5833]: wlan0: adding default route via 192.168.178.1 Apr 15 12:15:16 primo avahi-daemon[4532]: New relevant interface wlan0.IPv4 for mDNS. Apr 15 12:15:16 primo avahi-daemon[4532]: Registering new address record for 192.168.178.36 on wlan0.IPv4. Apr 15 12:15:17 primo ntpd[9320]: ntpd exiting on signal 15 (Terminated) Apr 15 12:15:17 primo ntpd[9320]: 185.252.140.125 local addr 192.168.178.30 -> Apr 15 12:15:17 primo ntpd[9320]: 176.9.157.155 local addr 192.168.178.30 -> Apr 15 12:15:17 primo systemd[1]: Stopping Network Time Service... Apr 15 12:15:17 primo ntpd[9320]: 178.215.228.24 local addr 192.168.178.30 -> Apr 15 12:15:17 primo ntpd[9320]: 141.98.136.83 local addr 192.168.178.30 -> Apr 15 12:15:17 primo ntpd[9320]: 131.188.3.220 local addr 192.168.178.30 -> Apr 15 12:15:17 primo ntpd[9320]: 178.63.166.157 local addr 192.168.178.30 -> Apr 15 12:15:17 primo ntpd[9320]: 49.12.199.148 local addr 192.168.178.30 -> Apr 15 12:15:17 primo ntpd[9320]: 144.76.76.107 local addr 192.168.178.30 -> Apr 15 12:15:17 primo ntpd[9320]: 144.76.0.164 local addr 192.168.178.30 -> Apr 15 12:15:17 primo ntpd[9320]: 128.127.67.142 local addr 192.168.178.30 -> Apr 15 12:15:17 primo ntpd[9320]: 185.232.69.65 local addr 192.168.178.30 -> Apr 15 12:15:17 primo ntpd[9320]: 90.187.112.137 local addr 192.168.178.30 -> Apr 15 12:15:17 primo ntpd[9320]: 81.169.204.195 local addr 192.168.178.30 -> Apr 15 12:15:17 primo ntpd[9320]: 85.214.38.116 local addr 192.168.178.30 -> Apr 15 12:15:17 primo wireless.js[10268]: trying... Apr 15 12:15:17 primo volumio[5490]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 12:15:17 primo ntpd[9320]: 49.12.125.53 local addr 192.168.178.30 -> Apr 15 12:15:17 primo systemd[1]: ntp.service: Succeeded. Apr 15 12:15:17 primo systemd[1]: Stopped Network Time Service. Apr 15 12:15:17 primo volumio[5490]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Apr 15 12:15:17 primo sudo[10499]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Apr 15 12:15:17 primo sudo[10499]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:17 primo systemd[1]: Starting Network Time Service... Apr 15 12:15:17 primo volumio[5490]: ------------------------------------ BT MESSAGE: BT STATUS: running Apr 15 12:15:17 primo sudo[10499]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:17 primo wireless.js[10268]: Connected to: ----sigaletho1 Apr 15 12:15:17 primo wireless.js[10268]: ---- Apr 15 12:15:17 primo sudo[10533]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 15 12:15:17 primo sudo[10533]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:17 primo sudo[10533]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:17 primo ntpd[10502]: ntpd 4.2.8p12@1.3728-o (1): Starting Apr 15 12:15:17 primo ntpd[10502]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Apr 15 12:15:17 primo systemd[1]: Started Network Time Service. Apr 15 12:15:17 primo wireless.js[10268]: ... joined AP, wlan0 IPv4 is 192.168.178.36, ipV6 is undefined Apr 15 12:15:17 primo wireless.js[10268]: It's done! AP Apr 15 12:15:17 primo ntpd[10541]: proto: precision = 1.250 usec (-20) Apr 15 12:15:17 primo ntpd[10541]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Apr 15 12:15:17 primo ntpd[10541]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Apr 15 12:15:17 primo ntpd[10541]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 475 days ago Apr 15 12:15:17 primo ntpd[10541]: Listen and drop on 0 v6wildcard [::]:123 Apr 15 12:15:17 primo ntpd[10541]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Apr 15 12:15:17 primo ntpd[10541]: Listen normally on 2 lo 127.0.0.1:123 Apr 15 12:15:17 primo ntpd[10541]: Listen normally on 3 eth0 192.168.178.30:123 Apr 15 12:15:17 primo ntpd[10541]: Listen normally on 4 wlan0 192.168.178.36:123 Apr 15 12:15:17 primo ntpd[10541]: Listening on routing socket on fd #21 for interface updates Apr 15 12:15:17 primo ntpd[10541]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 15 12:15:17 primo ntpd[10541]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Apr 15 12:15:17 primo systemd[1]: Started Wireless Services. Apr 15 12:15:17 primo sudo[10231]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:17 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 12:15:17 primo volumio[5490]: info: Discovery: this is already registered, undefined Apr 15 12:15:17 primo volumio[5490]: info: Discovery: Found device Primo Apr 15 12:15:17 primo volumio[5490]: info: CoreCommandRouter::volumioGetState Apr 15 12:15:17 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:18 primo ntpd[10541]: Soliciting pool server 168.119.238.107 Apr 15 12:15:19 primo ntpd[10541]: Soliciting pool server 144.91.116.85 Apr 15 12:15:19 primo ntpd[10541]: Soliciting pool server 129.250.35.251 Apr 15 12:15:20 primo ntpd[10541]: Soliciting pool server 176.9.44.212 Apr 15 12:15:20 primo ntpd[10541]: Soliciting pool server 79.133.44.138 Apr 15 12:15:20 primo ntpd[10541]: Soliciting pool server 144.76.66.156 Apr 15 12:15:20 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: mpd , rescanDb Apr 15 12:15:20 primo volumio[5490]: verbose: ControllerMpd::sendMpdCommand rescan Apr 15 12:15:20 primo volumio[5490]: verbose: ControllerMpd::sendMpdCommand status Apr 15 12:15:20 primo volumio[5490]: info: Apr 15 12:15:20 primo volumio[5490]: ---------------------------- MPD announces state update: update Apr 15 12:15:20 primo volumio[5490]: info: sendMpdCommand rescan took 28 milliseconds Apr 15 12:15:20 primo volumio[5490]: info: ControllerMpd::getState Apr 15 12:15:20 primo volumio[5490]: verbose: ControllerMpd::sendMpdCommand status Apr 15 12:15:20 primo volumio[5490]: info: sendMpdCommand status took 34 milliseconds Apr 15 12:15:20 primo volumio[5490]: verbose: ControllerMpd::parseState Apr 15 12:15:20 primo volumio[5490]: info: Command Router : Notfying DB Updatetrue Apr 15 12:15:20 primo volumio[5490]: info: sendMpdCommand status took 101 milliseconds Apr 15 12:15:20 primo volumio[5490]: verbose: ControllerMpd::parseState Apr 15 12:15:20 primo volumio[5490]: info: ControllerMpd::pushState Apr 15 12:15:20 primo volumio[5490]: info: CoreCommandRouter::servicePushState Apr 15 12:15:20 primo volumio[5490]: info: CoreStateMachine::pushState Apr 15 12:15:20 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:20 primo volumio[5490]: info: CoreCommandRouter::volumioPushState Apr 15 12:15:20 primo volumio[5490]: info: MRS: Pushing multiroomSync output update for this device Apr 15 12:15:20 primo volumio[5490]: info: MRS: Pushing multiroomSync output Apr 15 12:15:20 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:20 primo volumio[5490]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":true,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 15 12:15:20 primo volumio[5490]: verbose: CURRENT POSITION 0 Apr 15 12:15:20 primo volumio[5490]: info: CoreStateMachine::syncState stateService stop Apr 15 12:15:20 primo volumio[5490]: info: CoreStateMachine::syncState currentStatus stop Apr 15 12:15:20 primo volumio[5490]: info: CoreStateMachine::pushState Apr 15 12:15:20 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:20 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 12:15:20 primo volumio[5490]: info: CoreCommandRouter::volumioPushState Apr 15 12:15:20 primo volumio[5490]: info: MRS: Pushing multiroomSync output update for this device Apr 15 12:15:20 primo volumio[5490]: info: MRS: Pushing multiroomSync output Apr 15 12:15:20 primo volumio[5490]: info: No code Apr 15 12:15:20 primo volumio[5490]: info: CoreStateMachine::pushState Apr 15 12:15:20 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:20 primo volumio[5490]: info: CoreCommandRouter::volumioPushState Apr 15 12:15:20 primo volumio[5490]: info: MRS: Pushing multiroomSync output update for this device Apr 15 12:15:20 primo volumio[5490]: info: MRS: Pushing multiroomSync output Apr 15 12:15:20 primo volumio[5490]: info: ------------------------------ 166ms Apr 15 12:15:20 primo volumio[5490]: info: Updating RAAT Signal Path Apr 15 12:15:20 primo volumio[5490]: info: Updating RAAT Signal Path Apr 15 12:15:20 primo volumio[5490]: info: Updating RAAT Signal Path Apr 15 12:15:21 primo nmbd[5191]: [2024/04/15 12:15:21.030505, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 15 12:15:21 primo nmbd[5191]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Apr 15 12:15:21 primo nmbd[5191]: [2024/04/15 12:15:21.030785, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Apr 15 12:15:21 primo nmbd[5191]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Apr 15 12:15:21 primo nmbd[5191]: [2024/04/15 12:15:21.030930, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 15 12:15:21 primo nmbd[5191]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Apr 15 12:15:21 primo nmbd[5191]: [2024/04/15 12:15:21.031050, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Apr 15 12:15:21 primo nmbd[5191]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Apr 15 12:15:21 primo nmbd[5191]: [2024/04/15 12:15:21.031175, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 15 12:15:21 primo nmbd[5191]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Apr 15 12:15:21 primo nmbd[5191]: [2024/04/15 12:15:21.031312, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Apr 15 12:15:21 primo nmbd[5191]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Apr 15 12:15:21 primo nmbd[5191]: [2024/04/15 12:15:21.031437, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 15 12:15:21 primo nmbd[5191]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Apr 15 12:15:21 primo nmbd[5191]: [2024/04/15 12:15:21.031557, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Apr 15 12:15:21 primo nmbd[5191]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Apr 15 12:15:21 primo nmbd[5191]: [2024/04/15 12:15:21.031688, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 15 12:15:21 primo nmbd[5191]: Packet send failed to 127.0.0.1(137) ERRNO=Network is unreachable Apr 15 12:15:21 primo nmbd[5191]: [2024/04/15 12:15:21.031828, 0] ../source3/nmbd/nmbd_packets.c:180(send_netbios_packet) Apr 15 12:15:21 primo nmbd[5191]: send_netbios_packet: send_packet() to IP 127.0.0.1 port 137 failed Apr 15 12:15:21 primo ntpd[10541]: Soliciting pool server 178.63.9.212 Apr 15 12:15:21 primo ntpd[10541]: Soliciting pool server 178.215.228.24 Apr 15 12:15:21 primo ntpd[10541]: Soliciting pool server 51.75.67.47 Apr 15 12:15:21 primo ntpd[10541]: Soliciting pool server 128.140.109.119 Apr 15 12:15:21 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 12:15:22 primo volumio[5490]: info: CoreCommandRouter::Close All Modals sent Apr 15 12:15:22 primo ntpd[10541]: Soliciting pool server 90.187.112.137 Apr 15 12:15:22 primo ntpd[10541]: Soliciting pool server 167.235.246.170 Apr 15 12:15:22 primo ntpd[10541]: Soliciting pool server 78.46.204.247 Apr 15 12:15:22 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: wizard , reportWirelessConnection Apr 15 12:15:22 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessInfo Apr 15 12:15:22 primo sudo[10562]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 15 12:15:22 primo sudo[10562]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:22 primo sudo[10562]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:22 primo sudo[10566]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Apr 15 12:15:22 primo sudo[10566]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:22 primo sudo[10566]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:23 primo ntpd[10541]: Soliciting pool server 85.220.190.246 Apr 15 12:15:23 primo ntpd[10541]: Soliciting pool server 78.47.249.55 Apr 15 12:15:23 primo volumio[5490]: info: MPD Database updated - AlbumList cache refreshed Apr 15 12:15:23 primo volumio[5490]: info: Apr 15 12:15:23 primo volumio[5490]: ---------------------------- MPD announces state update: database Apr 15 12:15:23 primo volumio[5490]: verbose: ControllerMpd::sendMpdCommand status Apr 15 12:15:23 primo volumio[5490]: info: Apr 15 12:15:23 primo volumio[5490]: ---------------------------- MPD announces state update: update Apr 15 12:15:23 primo volumio[5490]: info: ControllerMpd::getState Apr 15 12:15:23 primo volumio[5490]: verbose: ControllerMpd::sendMpdCommand status Apr 15 12:15:23 primo volumio[5490]: info: ControllerMpd::getState Apr 15 12:15:23 primo volumio[5490]: verbose: ControllerMpd::sendMpdCommand status Apr 15 12:15:23 primo volumio[5490]: info: sendMpdCommand status took 18 milliseconds Apr 15 12:15:23 primo volumio[5490]: info: sendMpdCommand status took 17 milliseconds Apr 15 12:15:23 primo volumio[5490]: info: sendMpdCommand status took 16 milliseconds Apr 15 12:15:23 primo volumio[5490]: verbose: ControllerMpd::parseState Apr 15 12:15:23 primo volumio[5490]: info: Command Router : Notfying DB Updatefalse Apr 15 12:15:23 primo volumio[5490]: info: CoreCommandRouter::Close All Modals sent Apr 15 12:15:23 primo volumio[5490]: verbose: ControllerMpd::parseState Apr 15 12:15:23 primo volumio[5490]: verbose: ControllerMpd::parseState Apr 15 12:15:23 primo volumio[5490]: info: ControllerMpd::pushState Apr 15 12:15:23 primo volumio[5490]: info: CoreCommandRouter::servicePushState Apr 15 12:15:23 primo volumio[5490]: info: CoreStateMachine::pushState Apr 15 12:15:23 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:23 primo volumio[5490]: info: CoreCommandRouter::volumioPushState Apr 15 12:15:23 primo volumio[5490]: info: MRS: Pushing multiroomSync output update for this device Apr 15 12:15:23 primo volumio[5490]: info: MRS: Pushing multiroomSync output Apr 15 12:15:23 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:23 primo volumio[5490]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 15 12:15:23 primo volumio[5490]: verbose: CURRENT POSITION 0 Apr 15 12:15:23 primo volumio[5490]: info: CoreStateMachine::syncState stateService stop Apr 15 12:15:23 primo volumio[5490]: info: CoreStateMachine::syncState currentStatus stop Apr 15 12:15:23 primo volumio[5490]: info: CoreStateMachine::pushState Apr 15 12:15:23 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:23 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Apr 15 12:15:23 primo volumio[5490]: info: CoreCommandRouter::volumioPushState Apr 15 12:15:23 primo volumio[5490]: info: MRS: Pushing multiroomSync output update for this device Apr 15 12:15:23 primo volumio[5490]: info: MRS: Pushing multiroomSync output Apr 15 12:15:23 primo volumio[5490]: info: No code Apr 15 12:15:23 primo volumio[5490]: info: CoreStateMachine::pushState Apr 15 12:15:23 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:23 primo volumio[5490]: info: CoreCommandRouter::volumioPushState Apr 15 12:15:23 primo volumio[5490]: info: MRS: Pushing multiroomSync output update for this device Apr 15 12:15:23 primo volumio[5490]: info: MRS: Pushing multiroomSync output Apr 15 12:15:23 primo volumio[5490]: info: ControllerMpd::pushState Apr 15 12:15:23 primo volumio[5490]: info: CoreCommandRouter::servicePushState Apr 15 12:15:23 primo volumio[5490]: info: CoreStateMachine::pushState Apr 15 12:15:23 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:23 primo volumio[5490]: info: CoreCommandRouter::volumioPushState Apr 15 12:15:23 primo volumio[5490]: info: MRS: Pushing multiroomSync output update for this device Apr 15 12:15:23 primo volumio[5490]: info: MRS: Pushing multiroomSync output Apr 15 12:15:23 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:23 primo volumio[5490]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Apr 15 12:15:23 primo volumio[5490]: verbose: CURRENT POSITION 0 Apr 15 12:15:23 primo volumio[5490]: info: CoreStateMachine::syncState stateService stop Apr 15 12:15:23 primo volumio[5490]: info: CoreStateMachine::syncState currentStatus stop Apr 15 12:15:23 primo volumio[5490]: info: CoreStateMachine::pushState Apr 15 12:15:23 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:23 primo volumio[5490]: info: CoreCommandRouter::volumioPushState Apr 15 12:15:23 primo volumio[5490]: info: MRS: Pushing multiroomSync output update for this device Apr 15 12:15:23 primo volumio[5490]: info: MRS: Pushing multiroomSync output Apr 15 12:15:23 primo volumio[5490]: info: No code Apr 15 12:15:23 primo volumio[5490]: info: CoreStateMachine::pushState Apr 15 12:15:23 primo volumio[5490]: info: CorePlayQueue::getTrack 0 Apr 15 12:15:23 primo volumio[5490]: info: CoreCommandRouter::volumioPushState Apr 15 12:15:23 primo volumio[5490]: info: MRS: Pushing multiroomSync output update for this device Apr 15 12:15:23 primo volumio[5490]: info: MRS: Pushing multiroomSync output Apr 15 12:15:23 primo volumio[5490]: info: ------------------------------ 153ms Apr 15 12:15:23 primo volumio[5490]: info: ------------------------------ 154ms Apr 15 12:15:23 primo volumio[5490]: info: Updating RAAT Signal Path Apr 15 12:15:23 primo volumio[5490]: info: Updating RAAT Signal Path Apr 15 12:15:23 primo volumio[5490]: info: Updating RAAT Signal Path Apr 15 12:15:23 primo volumio[5490]: info: Updating RAAT Signal Path Apr 15 12:15:23 primo volumio[5490]: info: Updating RAAT Signal Path Apr 15 12:15:23 primo volumio[5490]: info: Updating RAAT Signal Path Apr 15 12:15:24 primo nmbd[5191]: [2024/04/15 12:15:24.036026, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 15 12:15:24 primo nmbd[5191]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Apr 15 12:15:24 primo ntpd[10541]: Soliciting pool server 2a02:a60:0:122:5652::16 Apr 15 12:15:24 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:15:25 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 12:15:27 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: upnp , onRestart Apr 15 12:15:27 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: network , onNetworkingRestart Apr 15 12:15:27 primo volumio[5490]: info: Refreshing Cached IP Addresses Apr 15 12:15:27 primo sudo[10576]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/killall upmpdcli Apr 15 12:15:27 primo sudo[10576]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:27 primo sudo[10577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Apr 15 12:15:27 primo sudo[10577]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:27 primo sudo[10576]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:27 primo sudo[10577]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:27 primo volumio[5490]: error: Upnp client error: Error: This socket has been ended by the other party Apr 15 12:15:27 primo sudo[10582]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Apr 15 12:15:27 primo sudo[10582]: pam_unix(sudo:session): session opened for user root by (uid=0) Apr 15 12:15:27 primo sudo[10582]: pam_unix(sudo:session): session closed for user root Apr 15 12:15:28 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: networkfs , discoverShares Apr 15 12:15:28 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , browseForService Apr 15 12:15:28 primo nmbd[5191]: [2024/04/15 12:15:28.328504, 0] ../source3/libsmb/nmblib.c:917(send_udp) Apr 15 12:15:28 primo nmbd[5191]: Packet send failed to 192.168.211.255(138) ERRNO=Network is unreachable Apr 15 12:15:29 primo volumio[5490]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Apr 15 12:15:32 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:15:32 primo volumio[5490]: info: Discovery: Remote connection error: 192.168.178.51 Apr 15 12:15:33 primo volumio[5490]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 12:15:33 primo volumio[5490]: Error: dns service error: unknown Apr 15 12:15:33 primo volumio[5490]: at MDNSService.on_resolver_done (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:19:30) Apr 15 12:15:33 primo volumio[5490]: at SocketWatcher.MDNSService.self.watcher.callback (/volumio/node_modules/mdns/lib/mdns_service.js:18:40) { Apr 15 12:15:33 primo volumio[5490]: errorCode: -65537 Apr 15 12:15:33 primo volumio[5490]: } Apr 15 12:15:33 primo volumio[5490]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 12:15:33 primo sudo[10598]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2024-04-15 12:14 Apr 15 12:15:33 primo sudo[10598]: 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="7f9f1bf001add7a8b19a4516e3c74ef598b2730d" VOLUMIO_FE_VERSION="614d7b13ffdcb0cc5a6072e7d06f59d52e323e71" VOLUMIO_FE3_VERSION="570aaed971c1346d45d1172dc445b086b258c6d1" VOLUMIO_BE_VERSION="e831866afe2db61aee9333a8f5ba9d9fd36609c7" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="primo2rev2" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sun 18 Feb 2024 12:01:04 PM CET" VOLUMIO_VERSION="3.629" VOLUMIO_HARDWARE="mp1" VOLUMIO_DEVICENAME="Volumio MP1" VOLUMIO_VENDOR_MODEL="Volumio Primo" VOLUMIO_VENDOR="Volumio" VOLUMIO_MODEL="Primo" VOLUMIO_HASH="728189e850980f21ef569472434ec23d"