-- Logs begin at Sat 2025-06-21 20:26:33 MSK, end at Sat 2025-06-21 20:43:53 MSK. -- Jun 21 20:42:18 smsl-n100 volumio[958]: verbose: New Socket.io Connection to 192.168.80.227 from 192.168.80.206 UA: Mozilla/5.0 (Linux; Android 15; CPH2653 Build/AP3A.240617.008; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/137.0.7151.89 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Jun 21 20:42:18 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioGetState Jun 21 20:42:18 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 21 20:42:18 smsl-n100 volumio[958]: info: Listing playlists Jun 21 20:42:18 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioGetVisibleSources Jun 21 20:42:18 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 21 20:42:18 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioGetQueue Jun 21 20:42:18 smsl-n100 volumio[958]: info: CoreStateMachine::getQueue Jun 21 20:42:18 smsl-n100 volumio[958]: info: CorePlayQueue::getQueue Jun 21 20:42:20 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 21 20:42:20 smsl-n100 volumio[958]: info: Received Get System Info Jun 21 20:42:20 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 21 20:42:20 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 21 20:42:20 smsl-n100 volumio[958]: info: Discovery: Getting this device information Jun 21 20:42:20 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioGetState Jun 21 20:42:20 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioNext Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreStateMachine::next Jun 21 20:42:26 smsl-n100 volumio[958]: info: [1750527746339] ControllerQobuz::next Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreStateMachine::next Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreStateMachine::stop Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreStateMachine::stPlaybackTimer Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreStateMachine::updateTrackBlock Jun 21 20:42:26 smsl-n100 volumio[958]: info: CorePlayQueue::getTrackBlock Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreStateMachine::pushState Jun 21 20:42:26 smsl-n100 volumio[958]: info: CorePlayQueue::getTrack 9 Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreStateMachine::serviceStop Jun 21 20:42:26 smsl-n100 volumio[958]: info: CorePlayQueue::getTrack 9 Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreCommandRouter::serviceStop Jun 21 20:42:26 smsl-n100 volumio[958]: info: [1750527746381] ControllerQobuz::stop Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreStateMachine::setConsumeUpdateService mpd Jun 21 20:42:26 smsl-n100 volumio[958]: info: ControllerMpd::stop Jun 21 20:42:26 smsl-n100 volumio[958]: verbose: ControllerMpd::sendMpdCommand stop Jun 21 20:42:26 smsl-n100 volumio[958]: info: Jun 21 20:42:26 smsl-n100 volumio[958]: ---------------------------- MPD announces state update: player Jun 21 20:42:26 smsl-n100 volumio[958]: info: sendMpdCommand stop took 55 milliseconds Jun 21 20:42:26 smsl-n100 volumio[958]: info: ControllerMpd::getState Jun 21 20:42:26 smsl-n100 volumio[958]: verbose: ControllerMpd::sendMpdCommand status Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreStateMachine::play index undefined Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreStateMachine::setConsumeUpdateService undefined Jun 21 20:42:26 smsl-n100 volumio[958]: info: CorePlayQueue::getTrack 10 Jun 21 20:42:26 smsl-n100 volumio[958]: info: sendMpdCommand status took 8 milliseconds Jun 21 20:42:26 smsl-n100 volumio[958]: verbose: ControllerMpd::parseState Jun 21 20:42:26 smsl-n100 volumio[958]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jun 21 20:42:26 smsl-n100 volumio[958]: info: sendMpdCommand playlistinfo took 2 milliseconds Jun 21 20:42:26 smsl-n100 volumio[958]: verbose: ControllerMpd::parseTrackInfo Jun 21 20:42:26 smsl-n100 volumio[958]: info: ControllerMpd::pushState Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreCommandRouter::servicePushState Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreStateMachine::pushState Jun 21 20:42:26 smsl-n100 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 21 20:42:26 smsl-n100 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 21 20:42:26 smsl-n100 volumio[958]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current qobuz Received mpd Jun 21 20:42:26 smsl-n100 volumio[958]: info: ------------------------------ 45ms Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreStateMachine::pushState Jun 21 20:42:26 smsl-n100 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 21 20:42:26 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 21 20:42:26 smsl-n100 volumio[958]: error: Serial API: Failed to decode command: 100, message: undefined Jun 21 20:42:26 smsl-n100 volumio[958]: error: Serial API: Failed to decode command: MAXVOL, message: Jun 21 20:42:26 smsl-n100 volumio[958]: error: Serial API: Failed to decode command: 100, message: undefined Jun 21 20:42:26 smsl-n100 volumio[958]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 21 20:42:38 smsl-n100 volumio[958]: info: VolumeController::SetAlsaVolume10 Jun 21 20:42:38 smsl-n100 volumio[958]: info: CoreStateMachine::pushState Jun 21 20:42:38 smsl-n100 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 21 20:42:38 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 20:42:38 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 21 20:42:38 smsl-n100 volumio[958]: info: Updating RAAT Signal Path Jun 21 20:42:38 smsl-n100 volumio[958]: info: VolumeController::SetAlsaVolume50 Jun 21 20:42:38 smsl-n100 volumio[958]: info: CoreStateMachine::pushState Jun 21 20:42:38 smsl-n100 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 21 20:42:38 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 20:42:38 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 21 20:42:38 smsl-n100 volumio[958]: info: Updating RAAT Signal Path Jun 21 20:42:39 smsl-n100 volumio[958]: info: VolumeController::SetAlsaVolume90 Jun 21 20:42:39 smsl-n100 volumio[958]: info: CoreStateMachine::pushState Jun 21 20:42:39 smsl-n100 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 21 20:42:39 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 20:42:39 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 21 20:42:39 smsl-n100 volumio[958]: info: Updating RAAT Signal Path Jun 21 20:42:40 smsl-n100 volumio[958]: info: VolumeController::SetAlsaVolume100 Jun 21 20:42:40 smsl-n100 volumio[958]: info: CoreStateMachine::pushState Jun 21 20:42:40 smsl-n100 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 21 20:42:40 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 20:42:40 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 21 20:42:40 smsl-n100 volumio[958]: info: Updating RAAT Signal Path Jun 21 20:42:51 smsl-n100 volumio[958]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 21 20:42:51 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Jun 21 20:42:51 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Jun 21 20:42:51 smsl-n100 sudo[3513]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Jun 21 20:42:51 smsl-n100 sudo[3513]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:42:51 smsl-n100 sudo[3513]: pam_unix(sudo:session): session closed for user root Jun 21 20:42:51 smsl-n100 sudo[3518]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 21 20:42:51 smsl-n100 sudo[3518]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:42:51 smsl-n100 sudo[3518]: pam_unix(sudo:session): session closed for user root Jun 21 20:42:51 smsl-n100 sudo[3526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 21 20:42:51 smsl-n100 sudo[3526]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:42:51 smsl-n100 sudo[3526]: pam_unix(sudo:session): session closed for user root Jun 21 20:42:51 smsl-n100 sudo[3532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Jun 21 20:42:51 smsl-n100 sudo[3532]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:42:51 smsl-n100 sudo[3532]: pam_unix(sudo:session): session closed for user root Jun 21 20:42:51 smsl-n100 sudo[3538]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jun 21 20:42:51 smsl-n100 sudo[3538]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:42:51 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Jun 21 20:42:51 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Jun 21 20:42:51 smsl-n100 sudo[3538]: pam_unix(sudo:session): session closed for user root Jun 21 20:42:52 smsl-n100 sudo[3541]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 21 20:42:52 smsl-n100 sudo[3541]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:42:52 smsl-n100 sudo[3541]: pam_unix(sudo:session): session closed for user root Jun 21 20:42:52 smsl-n100 sudo[3545]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Jun 21 20:42:52 smsl-n100 sudo[3545]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:42:56 smsl-n100 sudo[3545]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:18 smsl-n100 systemd[1]: Starting Cleanup of Temporary Directories... Jun 21 20:43:18 smsl-n100 volumio[958]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 21 20:43:18 smsl-n100 systemd[1]: systemd-tmpfiles-clean.service: Succeeded. Jun 21 20:43:18 smsl-n100 systemd[1]: Started Cleanup of Temporary Directories. Jun 21 20:43:34 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: network , saveWirelessNetworkSettings Jun 21 20:43:34 smsl-n100 volumio[958]: info: Saving new wireless network Jun 21 20:43:34 smsl-n100 sudo[3660]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/wpa_supplicant/wpa_supplicant.conf Jun 21 20:43:34 smsl-n100 sudo[3660]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:34 smsl-n100 sudo[3660]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:34 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Jun 21 20:43:34 smsl-n100 volumio[958]: info: Discovery: Restarting Advertising due to device name change Jun 21 20:43:34 smsl-n100 sudo[3663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Jun 21 20:43:34 smsl-n100 sudo[3663]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:34 smsl-n100 systemd[1]: Stopping Wireless Services... Jun 21 20:43:34 smsl-n100 wpa_supplicant[847]: wlan0: CTRL-EVENT-DISCONNECTED bssid=90:fb:5d:94:17:cb reason=3 locally_generated=1 Jun 21 20:43:34 smsl-n100 dhcpcd[949]: wlan0: carrier lost Jun 21 20:43:34 smsl-n100 volumio[958]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 21 20:43:34 smsl-n100 wpa_supplicant[847]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Jun 21 20:43:34 smsl-n100 avahi-daemon[550]: Interface wlan0.IPv4 no longer relevant for mDNS. Jun 21 20:43:34 smsl-n100 avahi-daemon[550]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.80.227. Jun 21 20:43:34 smsl-n100 wpa_supplicant[847]: wlan0: CTRL-EVENT-TERMINATING Jun 21 20:43:34 smsl-n100 avahi-daemon[550]: Withdrawing address record for 192.168.80.227 on wlan0. Jun 21 20:43:34 smsl-n100 volumio[958]: info: Discovery: A device disappeared from network Jun 21 20:43:34 smsl-n100 systemd[1]: wireless.service: Succeeded. Jun 21 20:43:34 smsl-n100 systemd[1]: Stopped Wireless Services. Jun 21 20:43:34 smsl-n100 systemd[1]: Starting Wireless Services... Jun 21 20:43:34 smsl-n100 volumio[958]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 21 20:43:34 smsl-n100 volumio[958]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 21 20:43:34 smsl-n100 dhcpcd[949]: wlan0: deleting route to 192.168.80.0/24 Jun 21 20:43:34 smsl-n100 dhcpcd[949]: wlan0: deleting default route via 192.168.80.1 Jun 21 20:43:35 smsl-n100 wireless.js[3679]: Cleaning previous... Jun 21 20:43:35 smsl-n100 sudo[3707]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 21 20:43:35 smsl-n100 sudo[3707]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:35 smsl-n100 sudo[3707]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:35 smsl-n100 sudo[3709]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 21 20:43:35 smsl-n100 sudo[3709]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:35 smsl-n100 sudo[3709]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:36 smsl-n100 wireless.js[3679]: Stopped aP Jun 21 20:43:36 smsl-n100 ntpd[948]: Deleting interface #3 wlan0, 192.168.80.227#123, interface stats: received=222, sent=265, dropped=0, active_time=900 secs Jun 21 20:43:36 smsl-n100 ntpd[948]: 5.1.56.123 local addr 192.168.80.227 -> Jun 21 20:43:36 smsl-n100 ntpd[948]: 91.206.16.3 local addr 192.168.80.227 -> Jun 21 20:43:36 smsl-n100 ntpd[948]: 162.159.200.1 local addr 192.168.80.227 -> Jun 21 20:43:36 smsl-n100 ntpd[948]: 195.58.34.65 local addr 192.168.80.227 -> Jun 21 20:43:36 smsl-n100 ntpd[948]: 92.255.126.18 local addr 192.168.80.227 -> Jun 21 20:43:36 smsl-n100 ntpd[948]: 77.37.240.196 local addr 192.168.80.227 -> Jun 21 20:43:36 smsl-n100 ntpd[948]: 212.113.99.6 local addr 192.168.80.227 -> Jun 21 20:43:36 smsl-n100 ntpd[948]: 93.95.100.85 local addr 192.168.80.227 -> Jun 21 20:43:36 smsl-n100 ntpd[948]: 162.159.200.123 local addr 192.168.80.227 -> Jun 21 20:43:36 smsl-n100 ntpd[948]: 151.0.2.53 local addr 192.168.80.227 -> Jun 21 20:43:36 smsl-n100 ntpd[948]: 89.179.240.219 local addr 192.168.80.227 -> Jun 21 20:43:36 smsl-n100 ntpd[948]: 89.109.251.26 local addr 192.168.80.227 -> Jun 21 20:43:36 smsl-n100 sudo[3716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 21 20:43:36 smsl-n100 sudo[3716]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:36 smsl-n100 dhcpcd[949]: wlan0: carrier acquired Jun 21 20:43:36 smsl-n100 sudo[3716]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:36 smsl-n100 dhcpcd[949]: wlan0: IAID 2b:ee:c8:83 Jun 21 20:43:36 smsl-n100 sudo[3719]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Jun 21 20:43:36 smsl-n100 sudo[3719]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:36 smsl-n100 sudo[3719]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:36 smsl-n100 sudo[3726]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Jun 21 20:43:36 smsl-n100 sudo[3726]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:36 smsl-n100 sudo[3726]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:36 smsl-n100 sudo[3728]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Jun 21 20:43:36 smsl-n100 sudo[3728]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:36 smsl-n100 dhcpcd[949]: wlan0: rebinding lease of 192.168.80.227 Jun 21 20:43:37 smsl-n100 dhcpcd[949]: wlan0: soliciting an IPv6 router Jun 21 20:43:39 smsl-n100 volumio[958]: info: Discovery: Started advertising with name: SMSL N100 Jun 21 20:43:40 smsl-n100 sudo[3728]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:40 smsl-n100 wireless.js[3679]: Start wireless flow Jun 21 20:43:40 smsl-n100 wireless.js[3679]: Stopped hotspot (if there).. Jun 21 20:43:40 smsl-n100 sudo[3738]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Jun 21 20:43:40 smsl-n100 sudo[3738]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:40 smsl-n100 sudo[3738]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:40 smsl-n100 sudo[3740]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Jun 21 20:43:40 smsl-n100 sudo[3740]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:40 smsl-n100 dhcpcd[949]: wlan0: carrier lost Jun 21 20:43:40 smsl-n100 sudo[3740]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:40 smsl-n100 wireless.js[3679]: DHCP IP Jun 21 20:43:40 smsl-n100 wireless.js[3679]: Start ap Jun 21 20:43:40 smsl-n100 wpa_supplicant[3745]: Successfully initialized wpa_supplicant Jun 21 20:43:40 smsl-n100 sudo[3749]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/dhcpcd Jun 21 20:43:40 smsl-n100 sudo[3749]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:40 smsl-n100 dhcpcd[949]: wlan0: carrier acquired Jun 21 20:43:40 smsl-n100 dhcpcd[3758]: sending commands to master dhcpcd process Jun 21 20:43:40 smsl-n100 sudo[3749]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:40 smsl-n100 dhcpcd[949]: wlan0: IAID 2b:ee:c8:83 Jun 21 20:43:40 smsl-n100 dhcpcd[949]: wlan0: carrier lost Jun 21 20:43:41 smsl-n100 dhcpcd[949]: control command: /sbin/dhcpcd Jun 21 20:43:41 smsl-n100 wireless.js[3679]: trying... Jun 21 20:43:42 smsl-n100 sudo[3773]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 20:43:42 smsl-n100 sudo[3773]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:42 smsl-n100 sudo[3773]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:42 smsl-n100 volumio[958]: info: Reporting MCU Network Status: 0 Jun 21 20:43:42 smsl-n100 volumio[958]: info: Volumio Network Manager: Network status updated: 0 Jun 21 20:43:42 smsl-n100 volumio[958]: info: CoreStateMachine::pushState Jun 21 20:43:42 smsl-n100 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 21 20:43:42 smsl-n100 volumio[958]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jun 21 20:43:42 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 21 20:43:42 smsl-n100 volumio[958]: error: Serial API: Failed to decode command: 100, message: undefined Jun 21 20:43:42 smsl-n100 volumio[958]: error: Serial API: Failed to decode command: MAXVOL, message: Jun 21 20:43:42 smsl-n100 volumio[958]: error: Serial API: Failed to decode command: 100, message: undefined Jun 21 20:43:42 smsl-n100 volumio[958]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 21 20:43:42 smsl-n100 volumio[958]: error: Serial API: Failed to decode command: 0, message: undefined Jun 21 20:43:42 smsl-n100 wireless.js[3679]: trying... Jun 21 20:43:42 smsl-n100 sudo[3786]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 20:43:42 smsl-n100 sudo[3786]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:42 smsl-n100 sudo[3786]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:43 smsl-n100 wireless.js[3679]: trying... Jun 21 20:43:43 smsl-n100 sudo[3789]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 20:43:43 smsl-n100 sudo[3789]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:43 smsl-n100 sudo[3789]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:44 smsl-n100 wireless.js[3679]: trying... Jun 21 20:43:44 smsl-n100 sudo[3792]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 20:43:44 smsl-n100 sudo[3792]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:45 smsl-n100 sudo[3792]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:45 smsl-n100 wireless.js[3679]: trying... Jun 21 20:43:45 smsl-n100 sudo[3795]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 20:43:45 smsl-n100 sudo[3795]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:46 smsl-n100 sudo[3795]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:46 smsl-n100 wpa_supplicant[3760]: wlan0: Trying to associate with 38:6b:1c:d7:80:dc (SSID='UDALTCHOV!!!_5G' freq=5180 MHz) Jun 21 20:43:46 smsl-n100 wpa_supplicant[3760]: wlan0: Associated with 38:6b:1c:d7:80:dc Jun 21 20:43:46 smsl-n100 wpa_supplicant[3760]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 Jun 21 20:43:46 smsl-n100 wpa_supplicant[3760]: wlan0: WPA: Key negotiation completed with 38:6b:1c:d7:80:dc [PTK=CCMP GTK=CCMP] Jun 21 20:43:46 smsl-n100 wpa_supplicant[3760]: wlan0: CTRL-EVENT-CONNECTED - Connection to 38:6b:1c:d7:80:dc completed [id=0 id_str=] Jun 21 20:43:46 smsl-n100 dhcpcd[949]: wlan0: carrier acquired Jun 21 20:43:46 smsl-n100 dhcpcd[949]: wlan0: IAID 2b:ee:c8:83 Jun 21 20:43:46 smsl-n100 wireless.js[3679]: trying... Jun 21 20:43:46 smsl-n100 sudo[3799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 20:43:46 smsl-n100 sudo[3799]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:46 smsl-n100 sudo[3799]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:47 smsl-n100 dhcpcd[949]: wlan0: soliciting a DHCP lease Jun 21 20:43:47 smsl-n100 dhcpcd[949]: wlan0: offered 192.168.1.106 from 192.168.1.1 Jun 21 20:43:47 smsl-n100 dhcpcd[949]: wlan0: probing address 192.168.1.106/24 Jun 21 20:43:47 smsl-n100 dhcpcd[949]: wlan0: soliciting an IPv6 router Jun 21 20:43:47 smsl-n100 wireless.js[3679]: trying... Jun 21 20:43:47 smsl-n100 sudo[3802]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 20:43:47 smsl-n100 sudo[3802]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:48 smsl-n100 sudo[3802]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:48 smsl-n100 wireless.js[3679]: trying... Jun 21 20:43:48 smsl-n100 sudo[3805]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 20:43:48 smsl-n100 sudo[3805]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:49 smsl-n100 sudo[3805]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:49 smsl-n100 wireless.js[3679]: trying... Jun 21 20:43:49 smsl-n100 sudo[3811]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 20:43:49 smsl-n100 sudo[3811]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:50 smsl-n100 sudo[3811]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:50 smsl-n100 wireless.js[3679]: trying... Jun 21 20:43:50 smsl-n100 sudo[3814]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 20:43:50 smsl-n100 sudo[3814]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:51 smsl-n100 sudo[3814]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:51 smsl-n100 dhcpcd[949]: wlan0: leased 192.168.1.106 for 7200 seconds Jun 21 20:43:51 smsl-n100 dhcpcd[949]: wlan0: adding route to 192.168.1.0/24 Jun 21 20:43:51 smsl-n100 dhcpcd[949]: wlan0: adding default route via 192.168.1.1 Jun 21 20:43:51 smsl-n100 avahi-daemon[550]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.1.106. Jun 21 20:43:51 smsl-n100 avahi-daemon[550]: New relevant interface wlan0.IPv4 for mDNS. Jun 21 20:43:51 smsl-n100 avahi-daemon[550]: Registering new address record for 192.168.1.106 on wlan0.IPv4. Jun 21 20:43:51 smsl-n100 wireless.js[3679]: trying... Jun 21 20:43:52 smsl-n100 sudo[3843]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Jun 21 20:43:52 smsl-n100 sudo[3843]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:52 smsl-n100 sudo[3843]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:52 smsl-n100 wireless.js[3679]: Connected to: ----UDALTCHOV!!!_5G Jun 21 20:43:52 smsl-n100 wireless.js[3679]: ---- Jun 21 20:43:52 smsl-n100 sudo[3848]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jun 21 20:43:52 smsl-n100 sudo[3848]: pam_unix(sudo:session): session opened for user root by (uid=0) Jun 21 20:43:52 smsl-n100 sudo[3848]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:52 smsl-n100 ntpd[948]: ntpd exiting on signal 15 (Terminated) Jun 21 20:43:52 smsl-n100 systemd[1]: Stopping Network Time Service... Jun 21 20:43:52 smsl-n100 wireless.js[3679]: ... joined AP, wlan0 IPv4 is 192.168.1.106, ipV6 is undefined Jun 21 20:43:52 smsl-n100 systemd[1]: ntp.service: Succeeded. Jun 21 20:43:52 smsl-n100 wireless.js[3679]: It's done! AP Jun 21 20:43:52 smsl-n100 systemd[1]: Stopped Network Time Service. Jun 21 20:43:52 smsl-n100 volumio[958]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 21 20:43:52 smsl-n100 volumio[958]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Jun 21 20:43:52 smsl-n100 systemd[1]: Starting Network Time Service... Jun 21 20:43:52 smsl-n100 volumio[958]: ------------------------------------ BT MESSAGE: BT STATUS: running Jun 21 20:43:52 smsl-n100 volumio[958]: info: Reporting MCU Network Status: 2 Jun 21 20:43:52 smsl-n100 volumio[958]: info: Volumio Network Manager: Network status updated: 2 Jun 21 20:43:52 smsl-n100 volumio[958]: info: CoreStateMachine::pushState Jun 21 20:43:52 smsl-n100 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 21 20:43:52 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioPushState Jun 21 20:43:52 smsl-n100 volumio[958]: error: Serial API: Failed to decode command: 100, message: undefined Jun 21 20:43:52 smsl-n100 volumio[958]: error: Serial API: Failed to decode command: MAXVOL, message: Jun 21 20:43:52 smsl-n100 volumio[958]: error: Serial API: Failed to decode command: 100, message: undefined Jun 21 20:43:52 smsl-n100 volumio[958]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Jun 21 20:43:52 smsl-n100 ntpd[3864]: ntpd 4.2.8p12@1.3728-o (1): Starting Jun 21 20:43:52 smsl-n100 ntpd[3864]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Jun 21 20:43:52 smsl-n100 volumio[958]: error: Serial API: Failed to decode command: 2, message: undefined Jun 21 20:43:52 smsl-n100 systemd[1]: Started Network Time Service. Jun 21 20:43:52 smsl-n100 ntpd[3879]: proto: precision = 0.875 usec (-20) Jun 21 20:43:52 smsl-n100 ntpd[3879]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Jun 21 20:43:52 smsl-n100 ntpd[3879]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Jun 21 20:43:52 smsl-n100 ntpd[3879]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 907 days ago Jun 21 20:43:52 smsl-n100 ntpd[3879]: Listen and drop on 0 v6wildcard [::]:123 Jun 21 20:43:52 smsl-n100 ntpd[3879]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Jun 21 20:43:52 smsl-n100 ntpd[3879]: Listen normally on 2 lo 127.0.0.1:123 Jun 21 20:43:52 smsl-n100 iwconfig[3878]: Error for wireless request "Set Power Management" (8B2C) : Jun 21 20:43:52 smsl-n100 iwconfig[3878]: SET failed on device wlan0 ; Operation not permitted. Jun 21 20:43:52 smsl-n100 ntpd[3879]: Listen normally on 3 wlan0 192.168.1.106:123 Jun 21 20:43:52 smsl-n100 systemd[1]: Started Wireless Services. Jun 21 20:43:52 smsl-n100 ntpd[3879]: Listening on routing socket on fd #20 for interface updates Jun 21 20:43:52 smsl-n100 ntpd[3879]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jun 21 20:43:52 smsl-n100 ntpd[3879]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Jun 21 20:43:52 smsl-n100 sudo[3663]: pam_unix(sudo:session): session closed for user root Jun 21 20:43:52 smsl-n100 volumio[958]: info: Discovery: adding d52e44a3-64ef-4876-b36e-2ada64e6b4aa Jun 21 20:43:52 smsl-n100 volumio[958]: info: Discovery: Found device SMSL N100 Jun 21 20:43:52 smsl-n100 volumio[958]: info: CoreCommandRouter::volumioGetState Jun 21 20:43:52 smsl-n100 volumio[958]: info: CorePlayQueue::getTrack 0 Jun 21 20:43:52 smsl-n100 volumio[958]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 20:43:52 smsl-n100 volumio[958]: Error: The value NaN is not a number Jun 21 20:43:52 smsl-n100 volumio[958]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Jun 21 20:43:52 smsl-n100 volumio[958]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Jun 21 20:43:52 smsl-n100 volumio[958]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Jun 21 20:43:52 smsl-n100 volumio[958]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Jun 21 20:43:52 smsl-n100 volumio[958]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Jun 21 20:43:52 smsl-n100 volumio[958]: at Browser.emit (events.js:400:28) Jun 21 20:43:52 smsl-n100 volumio[958]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Jun 21 20:43:52 smsl-n100 volumio[958]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Jun 21 20:43:52 smsl-n100 volumio[958]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Jun 21 20:43:52 smsl-n100 volumio[958]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Jun 21 20:43:52 smsl-n100 volumio[958]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Jun 21 20:43:52 smsl-n100 volumio[958]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Jun 21 20:43:52 smsl-n100 volumio[958]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Jun 21 20:43:52 smsl-n100 volumio[958]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 21 20:43:53 smsl-n100 ntpd[3879]: Soliciting pool server 195.54.177.84 Jun 21 20:43:53 smsl-n100 sudo[3890]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-06-21 20:42 Jun 21 20:43:53 smsl-n100 sudo[3890]: 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="cc0042a1eab1cb7982711a1347e242f8947b54b6" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="723ba1d50268911818ebdca474fb7656df6050cb" VOLUMIO_ARCH="armv7" VOLUMIO_VARIANT="smsl" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 14 Feb 2025 02:46:15 PM CET" VOLUMIO_VERSION="3.789" VOLUMIO_HARDWARE="nanopineo3" VOLUMIO_DEVICENAME="Nanopi Neo3" VOLUMIO_VENDOR_MODEL="SMSL S100" VOLUMIO_VENDOR="SMSL" VOLUMIO_MODEL="S100" VOLUMIO_HASH="215d731aee688e00fb3cc923952be9a7"