-- Logs begin at Sat 2025-03-08 08:30:08 PST, end at Sat 2025-03-08 09:12:14 PST. -- Mar 08 09:11:19 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 09:11:19 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 09:11:19 smsl-n100 volumio[928]: info: Discovery: Getting this device information Mar 08 09:11:19 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioGetState Mar 08 09:11:19 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:11:19 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 09:11:32 smsl-n100 volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 09:11:32 smsl-n100 volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 09:11:32 smsl-n100 volumio[928]: info: Retrieving Cloud Streaming UI Mar 08 09:11:33 smsl-n100 volumio[928]: info: Getting Tidal Cloud Configuration Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 09:11:33 smsl-n100 volumio[928]: info: Getting Qobuz Cloud Configuration Mar 08 09:11:33 smsl-n100 volumio[928]: info: Asking plugin for UI Config Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 09:11:33 smsl-n100 volumio[928]: info: Getting Spotify Cloud Configuration Mar 08 09:11:33 smsl-n100 volumio[928]: info: Asking plugin for UI Config Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 09:11:33 smsl-n100 volumio[928]: info: Saving Spotify Acccount Mar 08 09:11:33 smsl-n100 volumio[928]: info: Got it Mar 08 09:11:33 smsl-n100 volumio[928]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Mar 08 09:11:33 smsl-n100 volumio[928]: info: Got Tidal Cloud Configuration Mar 08 09:11:33 smsl-n100 volumio[928]: info: Got it Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioGetBrowseSources Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioGetBrowseSources Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioGetBrowseSources Mar 08 09:11:33 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 09:11:34 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Mar 08 09:11:37 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 09:11:41 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Mar 08 09:11:47 smsl-n100 volumio[928]: info: CoreCommandRouter::getUIConfigOnPlugin Mar 08 09:11:48 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Mar 08 09:11:48 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Mar 08 09:11:48 smsl-n100 sudo[3548]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Mar 08 09:11:48 smsl-n100 sudo[3548]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:11:48 smsl-n100 sudo[3553]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 08 09:11:48 smsl-n100 sudo[3553]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:11:48 smsl-n100 sudo[3548]: pam_unix(sudo:session): session closed for user root Mar 08 09:11:48 smsl-n100 sudo[3553]: pam_unix(sudo:session): session closed for user root Mar 08 09:11:48 smsl-n100 sudo[3561]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 08 09:11:48 smsl-n100 sudo[3561]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:11:48 smsl-n100 sudo[3567]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Mar 08 09:11:48 smsl-n100 sudo[3567]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:11:48 smsl-n100 sudo[3561]: pam_unix(sudo:session): session closed for user root Mar 08 09:11:48 smsl-n100 sudo[3567]: pam_unix(sudo:session): session closed for user root Mar 08 09:11:48 smsl-n100 sudo[3574]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Mar 08 09:11:48 smsl-n100 sudo[3574]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:11:48 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Mar 08 09:11:48 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Mar 08 09:11:48 smsl-n100 sudo[3574]: pam_unix(sudo:session): session closed for user root Mar 08 09:11:48 smsl-n100 sudo[3577]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Mar 08 09:11:48 smsl-n100 sudo[3577]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:11:48 smsl-n100 sudo[3577]: pam_unix(sudo:session): session closed for user root Mar 08 09:11:48 smsl-n100 sudo[3580]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Mar 08 09:11:48 smsl-n100 sudo[3580]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:11:49 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Mar 08 09:11:49 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Mar 08 09:11:49 smsl-n100 volumio[928]: info: Discovery: Getting this device information Mar 08 09:11:49 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioGetState Mar 08 09:11:49 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:11:49 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Mar 08 09:11:58 smsl-n100 sudo[3580]: pam_unix(sudo:session): session closed for user root Mar 08 09:12:06 smsl-n100 volumio[928]: info: CALLMETHOD: system_controller network saveHotspotSettings [object Object] Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: network , saveHotspotSettings Mar 08 09:12:06 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:06 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 1 Mar 08 09:12:06 smsl-n100 volumio[928]: info: Prefetching next song Mar 08 09:12:06 smsl-n100 volumio[928]: info: DOING PREFETCH IN MPD Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand add "USB/LaCie/ROFI/Airto Moreira/Killer Bees/02 - Airto Moreira - Be There.flac" Mar 08 09:12:06 smsl-n100 volumio[928]: info: sendMpdCommand add "USB/LaCie/ROFI/Airto Moreira/Killer Bees/02 - Airto Moreira - Be There.flac" took 4 milliseconds Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand consume 1 Mar 08 09:12:06 smsl-n100 volumio[928]: info: Mar 08 09:12:06 smsl-n100 volumio[928]: ---------------------------- MPD announces system playlist update Mar 08 09:12:06 smsl-n100 volumio[928]: info: Ignoring MPD Status Update Mar 08 09:12:06 smsl-n100 volumio[928]: info: Mar 08 09:12:06 smsl-n100 volumio[928]: ---------------------------- MPD announces state update: options Mar 08 09:12:06 smsl-n100 volumio[928]: info: sendMpdCommand consume 1 took 15 milliseconds Mar 08 09:12:06 smsl-n100 volumio[928]: info: ControllerMpd::getState Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand status Mar 08 09:12:06 smsl-n100 volumio[928]: info: Mar 08 09:12:06 smsl-n100 volumio[928]: ---------------------------- MPD announces system playlist update Mar 08 09:12:06 smsl-n100 volumio[928]: info: Ignoring MPD Status Update Mar 08 09:12:06 smsl-n100 volumio[928]: info: Mar 08 09:12:06 smsl-n100 volumio[928]: ---------------------------- MPD announces system playlist update Mar 08 09:12:06 smsl-n100 volumio[928]: info: Ignoring MPD Status Update Mar 08 09:12:06 smsl-n100 volumio[928]: info: ------------------------------ 44ms Mar 08 09:12:06 smsl-n100 volumio[928]: info: sendMpdCommand status took 32 milliseconds Mar 08 09:12:06 smsl-n100 volumio[928]: info: ------------------------------ 27ms Mar 08 09:12:06 smsl-n100 volumio[928]: info: ------------------------------ 21ms Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::parseState Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 09:12:06 smsl-n100 volumio[928]: info: Mar 08 09:12:06 smsl-n100 volumio[928]: ---------------------------- MPD announces state update: options Mar 08 09:12:06 smsl-n100 volumio[928]: info: ControllerMpd::getState Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand status Mar 08 09:12:06 smsl-n100 volumio[928]: info: Mar 08 09:12:06 smsl-n100 volumio[928]: ---------------------------- MPD announces state update: options Mar 08 09:12:06 smsl-n100 volumio[928]: info: ControllerMpd::getState Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand status Mar 08 09:12:06 smsl-n100 volumio[928]: info: sendMpdCommand playlistinfo took 31 milliseconds Mar 08 09:12:06 smsl-n100 volumio[928]: info: sendMpdCommand status took 26 milliseconds Mar 08 09:12:06 smsl-n100 volumio[928]: info: sendMpdCommand status took 9 milliseconds Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::parseTrackInfo Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::parseState Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::parseState Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 09:12:06 smsl-n100 volumio[928]: info: ControllerMpd::pushState Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreCommandRouter::servicePushState Mar 08 09:12:06 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: STATE SERVICE {"status":"play","position":0,"seek":359556,"duration":364,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"212 Kbps","isStreaming":false,"title":"Banana Jam","artist":"Airto Moreira","album":"Killer Bees","uri":"USB/LaCie/ROFI/Airto Moreira/Killer Bees/01 - Airto Moreira - Banana Jam.flac","trackType":"flac"} Mar 08 09:12:06 smsl-n100 volumio[928]: verbose: CURRENT POSITION 0 Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreStateMachine::syncState stateService play Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreStateMachine::syncState currentStatus play Mar 08 09:12:06 smsl-n100 volumio[928]: info: Received an update from plugin. extracting info from payload Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreStateMachine::pushState Mar 08 09:12:06 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState Mar 08 09:12:06 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device Mar 08 09:12:06 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreStateMachine::pushState Mar 08 09:12:06 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:06 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState Mar 08 09:12:06 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device Mar 08 09:12:06 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output Mar 08 09:12:07 smsl-n100 volumio[928]: info: ------------------------------ 189ms Mar 08 09:12:07 smsl-n100 volumio[928]: info: sendMpdCommand playlistinfo took 104 milliseconds Mar 08 09:12:07 smsl-n100 volumio[928]: info: sendMpdCommand playlistinfo took 105 milliseconds Mar 08 09:12:07 smsl-n100 volumio[928]: verbose: ControllerMpd::parseTrackInfo Mar 08 09:12:07 smsl-n100 volumio[928]: verbose: ControllerMpd::parseTrackInfo Mar 08 09:12:07 smsl-n100 volumio[928]: info: ControllerMpd::pushState Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreCommandRouter::servicePushState Mar 08 09:12:07 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:07 smsl-n100 volumio[928]: verbose: STATE SERVICE {"status":"play","position":0,"seek":359693,"duration":364,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"203 Kbps","isStreaming":false,"title":"Banana Jam","artist":"Airto Moreira","album":"Killer Bees","uri":"USB/LaCie/ROFI/Airto Moreira/Killer Bees/01 - Airto Moreira - Banana Jam.flac","trackType":"flac"} Mar 08 09:12:07 smsl-n100 volumio[928]: verbose: CURRENT POSITION 0 Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::syncState stateService play Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::syncState currentStatus play Mar 08 09:12:07 smsl-n100 volumio[928]: info: Received an update from plugin. extracting info from payload Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::pushState Mar 08 09:12:07 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::pushState Mar 08 09:12:07 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output Mar 08 09:12:07 smsl-n100 volumio[928]: info: ControllerMpd::pushState Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreCommandRouter::servicePushState Mar 08 09:12:07 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:07 smsl-n100 volumio[928]: verbose: STATE SERVICE {"status":"play","position":0,"seek":359693,"duration":364,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"203 Kbps","isStreaming":false,"title":"Banana Jam","artist":"Airto Moreira","album":"Killer Bees","uri":"USB/LaCie/ROFI/Airto Moreira/Killer Bees/01 - Airto Moreira - Banana Jam.flac","trackType":"flac"} Mar 08 09:12:07 smsl-n100 volumio[928]: verbose: CURRENT POSITION 0 Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::syncState stateService play Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::syncState currentStatus play Mar 08 09:12:07 smsl-n100 volumio[928]: info: Received an update from plugin. extracting info from payload Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::pushState Mar 08 09:12:07 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreStateMachine::pushState Mar 08 09:12:07 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device Mar 08 09:12:07 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output Mar 08 09:12:07 smsl-n100 volumio[928]: info: ------------------------------ 318ms Mar 08 09:12:07 smsl-n100 volumio[928]: info: ------------------------------ 315ms Mar 08 09:12:07 smsl-n100 sudo[3658]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Mar 08 09:12:07 smsl-n100 sudo[3658]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:12:07 smsl-n100 sudo[3658]: pam_unix(sudo:session): session closed for user root Mar 08 09:12:07 smsl-n100 volumio[928]: info: Permissions for /etc/hostapd/hostapd.conf Mar 08 09:12:07 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Mar 08 09:12:07 smsl-n100 volumio[928]: info: Discovery: Restarting Advertising due to device name change Mar 08 09:12:07 smsl-n100 sudo[3663]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Mar 08 09:12:07 smsl-n100 sudo[3663]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:12:07 smsl-n100 systemd[1]: Starting Wireless Services... Mar 08 09:12:07 smsl-n100 volumio[928]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 09:12:08 smsl-n100 wireless.js[3665]: Cleaning previous... Mar 08 09:12:08 smsl-n100 volumio[928]: info: Discovery: A device disappeared from network Mar 08 09:12:08 smsl-n100 volumio[928]: info: Discovery: A device disappeared from network Mar 08 09:12:08 smsl-n100 systemd[1]: Stopping dnsmasq - A lightweight DHCP and caching DNS server... Mar 08 09:12:08 smsl-n100 volumio[928]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 09:12:08 smsl-n100 dnsmasq[951]: exiting on receipt of SIGTERM Mar 08 09:12:08 smsl-n100 systemd[1]: dnsmasq.service: Succeeded. Mar 08 09:12:08 smsl-n100 systemd[1]: Stopped dnsmasq - A lightweight DHCP and caching DNS server. Mar 08 09:12:08 smsl-n100 systemd[1]: Stopping Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator... Mar 08 09:12:08 smsl-n100 volumio[928]: ------------------------------------ BT MESSAGE: BT STATUS: running Mar 08 09:12:08 smsl-n100 dhcpcd[969]: wlan0: carrier lost Mar 08 09:12:08 smsl-n100 systemd[1]: hostapd.service: Succeeded. Mar 08 09:12:08 smsl-n100 systemd[1]: Stopped Advanced IEEE 802.11 AP and IEEE 802.1X/WPA/WPA2/EAP Authenticator. Mar 08 09:12:08 smsl-n100 dhcpcd[969]: wlan0: deleting route to 169.254.0.0/16 Mar 08 09:12:08 smsl-n100 avahi-daemon[571]: Withdrawing address record for 169.254.131.52 on wlan0. Mar 08 09:12:08 smsl-n100 sudo[3702]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 08 09:12:08 smsl-n100 sudo[3702]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:12:08 smsl-n100 avahi-daemon[571]: Withdrawing address record for 192.168.211.1 on wlan0. Mar 08 09:12:08 smsl-n100 avahi-daemon[571]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.211.1. Mar 08 09:12:08 smsl-n100 avahi-daemon[571]: Interface wlan0.IPv4 no longer relevant for mDNS. Mar 08 09:12:08 smsl-n100 sudo[3702]: pam_unix(sudo:session): session closed for user root Mar 08 09:12:09 smsl-n100 sudo[3715]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 08 09:12:09 smsl-n100 sudo[3715]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:12:09 smsl-n100 sudo[3715]: pam_unix(sudo:session): session closed for user root Mar 08 09:12:09 smsl-n100 wireless.js[3665]: Stopped aP Mar 08 09:12:09 smsl-n100 sudo[3742]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 08 09:12:09 smsl-n100 sudo[3742]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:12:09 smsl-n100 sudo[3742]: pam_unix(sudo:session): session closed for user root Mar 08 09:12:09 smsl-n100 sudo[3744]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Mar 08 09:12:09 smsl-n100 sudo[3744]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:12:09 smsl-n100 sudo[3744]: pam_unix(sudo:session): session closed for user root Mar 08 09:12:09 smsl-n100 sudo[3751]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Mar 08 09:12:09 smsl-n100 sudo[3751]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:12:09 smsl-n100 sudo[3751]: pam_unix(sudo:session): session closed for user root Mar 08 09:12:09 smsl-n100 sudo[3753]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Mar 08 09:12:09 smsl-n100 sudo[3753]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:12:09 smsl-n100 dhcpcd[969]: wlan0: carrier acquired Mar 08 09:12:09 smsl-n100 dhcpcd[969]: wlan0: IAID 2b:ee:c9:22 Mar 08 09:12:10 smsl-n100 dhcpcd[969]: wlan0: soliciting a DHCP lease Mar 08 09:12:10 smsl-n100 dhcpcd[969]: wlan0: soliciting an IPv6 router Mar 08 09:12:11 smsl-n100 volumio[928]: info: Mar 08 09:12:11 smsl-n100 volumio[928]: ---------------------------- MPD announces system playlist update Mar 08 09:12:11 smsl-n100 volumio[928]: info: Ignoring MPD Status Update Mar 08 09:12:11 smsl-n100 volumio[928]: info: Mar 08 09:12:11 smsl-n100 volumio[928]: ---------------------------- MPD announces state update: player Mar 08 09:12:11 smsl-n100 volumio[928]: info: ControllerMpd::getState Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand status Mar 08 09:12:11 smsl-n100 volumio[928]: info: Mar 08 09:12:11 smsl-n100 volumio[928]: ---------------------------- MPD announces system playlist update Mar 08 09:12:11 smsl-n100 volumio[928]: info: Ignoring MPD Status Update Mar 08 09:12:11 smsl-n100 volumio[928]: info: Mar 08 09:12:11 smsl-n100 volumio[928]: ---------------------------- MPD announces state update: player Mar 08 09:12:11 smsl-n100 volumio[928]: info: ControllerMpd::getState Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand status Mar 08 09:12:11 smsl-n100 volumio[928]: info: Mar 08 09:12:11 smsl-n100 volumio[928]: ---------------------------- MPD announces system playlist update Mar 08 09:12:11 smsl-n100 volumio[928]: info: Ignoring MPD Status Update Mar 08 09:12:11 smsl-n100 volumio[928]: info: Mar 08 09:12:11 smsl-n100 volumio[928]: ---------------------------- MPD announces state update: player Mar 08 09:12:11 smsl-n100 volumio[928]: info: ControllerMpd::getState Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand status Mar 08 09:12:11 smsl-n100 volumio[928]: info: ------------------------------ 39ms Mar 08 09:12:11 smsl-n100 volumio[928]: info: sendMpdCommand status took 33 milliseconds Mar 08 09:12:11 smsl-n100 volumio[928]: info: ------------------------------ 31ms Mar 08 09:12:11 smsl-n100 volumio[928]: info: sendMpdCommand status took 26 milliseconds Mar 08 09:12:11 smsl-n100 volumio[928]: info: ------------------------------ 24ms Mar 08 09:12:11 smsl-n100 volumio[928]: info: sendMpdCommand status took 19 milliseconds Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::parseState Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::parseState Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::parseState Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::sendMpdCommand playlistinfo Mar 08 09:12:11 smsl-n100 volumio[928]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 08 09:12:11 smsl-n100 volumio[928]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 08 09:12:11 smsl-n100 volumio[928]: info: sendMpdCommand playlistinfo took 13 milliseconds Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::parseTrackInfo Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::parseTrackInfo Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: ControllerMpd::parseTrackInfo Mar 08 09:12:11 smsl-n100 volumio[928]: info: ControllerMpd::pushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::servicePushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"669 Kbps","isStreaming":false,"title":"Be There","artist":"Airto Moreira","album":"Killer Bees","uri":"USB/LaCie/ROFI/Airto Moreira/Killer Bees/02 - Airto Moreira - Be There.flac","trackType":"flac"} Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: CURRENT POSITION 0 Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::syncState stateService play Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::syncState currentStatus play Mar 08 09:12:11 smsl-n100 volumio[928]: info: Received an update from plugin. extracting info from payload Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::pushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::pushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output Mar 08 09:12:11 smsl-n100 volumio[928]: info: ControllerMpd::pushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::servicePushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"669 Kbps","isStreaming":false,"title":"Be There","artist":"Airto Moreira","album":"Killer Bees","uri":"USB/LaCie/ROFI/Airto Moreira/Killer Bees/02 - Airto Moreira - Be There.flac","trackType":"flac"} Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: CURRENT POSITION 0 Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::syncState stateService play Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::syncState currentStatus play Mar 08 09:12:11 smsl-n100 volumio[928]: info: Received an update from plugin. extracting info from payload Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::pushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::pushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output Mar 08 09:12:11 smsl-n100 volumio[928]: info: ControllerMpd::pushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::servicePushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":284,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"669 Kbps","isStreaming":false,"title":"Be There","artist":"Airto Moreira","album":"Killer Bees","uri":"USB/LaCie/ROFI/Airto Moreira/Killer Bees/02 - Airto Moreira - Be There.flac","trackType":"flac"} Mar 08 09:12:11 smsl-n100 volumio[928]: verbose: CURRENT POSITION 0 Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::syncState stateService play Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::syncState currentStatus play Mar 08 09:12:11 smsl-n100 volumio[928]: info: Received an update from plugin. extracting info from payload Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::pushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::pushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 0 Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output Mar 08 09:12:11 smsl-n100 volumio[928]: info: ------------------------------ 325ms Mar 08 09:12:11 smsl-n100 volumio[928]: info: ------------------------------ 317ms Mar 08 09:12:11 smsl-n100 volumio[928]: info: ------------------------------ 310ms Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::startPlaybackTimer Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 1 Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreStateMachine::pushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 1 Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Mar 08 09:12:11 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioPushState Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output update for this device Mar 08 09:12:11 smsl-n100 volumio[928]: info: MRS: Pushing multiroomSync output Mar 08 09:12:11 smsl-n100 ntpd[967]: Deleting interface #4 wlan0, 192.168.211.1#123, interface stats: received=0, sent=0, dropped=0, active_time=583 secs Mar 08 09:12:11 smsl-n100 ntpd[967]: Deleting interface #5 wlan0, 169.254.131.52#123, interface stats: received=0, sent=0, dropped=0, active_time=573 secs Mar 08 09:12:12 smsl-n100 volumio[928]: info: Discovery: Started advertising with name: SMSL N100 Mar 08 09:12:13 smsl-n100 volumio[928]: info: Discovery: adding 6cc4b515-a1e1-4201-8136-6ed68e33cfa3 Mar 08 09:12:13 smsl-n100 volumio[928]: info: Discovery: Found device SMSL N100 Mar 08 09:12:13 smsl-n100 volumio[928]: info: CoreCommandRouter::volumioGetState Mar 08 09:12:13 smsl-n100 volumio[928]: info: CorePlayQueue::getTrack 1 Mar 08 09:12:13 smsl-n100 volumio[928]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 09:12:13 smsl-n100 volumio[928]: Error: The value NaN is not a number Mar 08 09:12:13 smsl-n100 volumio[928]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Mar 08 09:12:13 smsl-n100 volumio[928]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Mar 08 09:12:13 smsl-n100 volumio[928]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Mar 08 09:12:13 smsl-n100 volumio[928]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Mar 08 09:12:13 smsl-n100 volumio[928]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Mar 08 09:12:13 smsl-n100 volumio[928]: at Browser.emit (events.js:400:28) Mar 08 09:12:13 smsl-n100 volumio[928]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Mar 08 09:12:13 smsl-n100 volumio[928]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Mar 08 09:12:13 smsl-n100 volumio[928]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Mar 08 09:12:13 smsl-n100 volumio[928]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Mar 08 09:12:13 smsl-n100 volumio[928]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Mar 08 09:12:13 smsl-n100 volumio[928]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Mar 08 09:12:13 smsl-n100 volumio[928]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Mar 08 09:12:13 smsl-n100 volumio[928]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Mar 08 09:12:13 smsl-n100 sudo[3753]: pam_unix(sudo:session): session closed for user root Mar 08 09:12:14 smsl-n100 sudo[3795]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Mar 08 09:12:14 smsl-n100 sudo[3795]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:12:14 smsl-n100 sudo[3795]: pam_unix(sudo:session): session closed for user root Mar 08 09:12:14 smsl-n100 sudo[3797]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Mar 08 09:12:14 smsl-n100 sudo[3797]: pam_unix(sudo:session): session opened for user root by (uid=0) Mar 08 09:12:14 smsl-n100 dhcpcd[969]: wlan0: carrier lost Mar 08 09:12:14 smsl-n100 sudo[3797]: pam_unix(sudo:session): session closed for user root Mar 08 09:12:14 smsl-n100 wireless.js[3665]: Hotspot is disabled, not starting it Mar 08 09:12:14 smsl-n100 iwconfig[3818]: Error for wireless request "Set Power Management" (8B2C) : Mar 08 09:12:14 smsl-n100 iwconfig[3818]: SET failed on device wlan0 ; Operation not permitted. Mar 08 09:12:14 smsl-n100 systemd[1]: wireless.service: Succeeded. Mar 08 09:12:14 smsl-n100 systemd[1]: Started Wireless Services. Mar 08 09:12:14 smsl-n100 sudo[3663]: pam_unix(sudo:session): session closed for user root Mar 08 09:12:14 smsl-n100 dhcpcd[969]: wlan0: carrier acquired Mar 08 09:12:14 smsl-n100 dhcpcd[969]: wlan0: IAID 2b:ee:c9:22 Mar 08 09:12:14 smsl-n100 sudo[3836]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-08 09:11 Mar 08 09:12:14 smsl-n100 sudo[3836]: 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"