-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Thu 2025-10-16 05:59:39 CST. -- Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 16 05:58:10 smsl-n100 volumio[1082]: info: Prefetching next song Oct 16 05:58:10 smsl-n100 volumio[1082]: info: DOING PREFETCH IN MPD Oct 16 05:58:10 smsl-n100 volumio[1082]: verbose: ControllerMpd::sendMpdCommand add "USB/KINGSTON/63372200381090822.flac" Oct 16 05:58:10 smsl-n100 volumio[1082]: info: Oct 16 05:58:10 smsl-n100 volumio[1082]: ---------------------------- MPD announces system playlist update Oct 16 05:58:10 smsl-n100 volumio[1082]: info: Ignoring MPD Status Update Oct 16 05:58:10 smsl-n100 volumio[1082]: info: sendMpdCommand add "USB/KINGSTON/63372200381090822.flac" took 4 milliseconds Oct 16 05:58:10 smsl-n100 volumio[1082]: verbose: ControllerMpd::sendMpdCommand consume 1 Oct 16 05:58:10 smsl-n100 volumio[1082]: info: Oct 16 05:58:10 smsl-n100 volumio[1082]: ---------------------------- MPD announces system playlist update Oct 16 05:58:10 smsl-n100 volumio[1082]: info: Ignoring MPD Status Update Oct 16 05:58:10 smsl-n100 volumio[1082]: info: Oct 16 05:58:10 smsl-n100 volumio[1082]: ---------------------------- MPD announces state update: options Oct 16 05:58:10 smsl-n100 volumio[1082]: info: ------------------------------ 10ms Oct 16 05:58:10 smsl-n100 volumio[1082]: info: sendMpdCommand consume 1 took 8 milliseconds Oct 16 05:58:10 smsl-n100 volumio[1082]: info: ControllerMpd::getState Oct 16 05:58:10 smsl-n100 volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 16 05:58:10 smsl-n100 volumio[1082]: info: ------------------------------ 7ms Oct 16 05:58:10 smsl-n100 volumio[1082]: info: Oct 16 05:58:10 smsl-n100 volumio[1082]: ---------------------------- MPD announces state update: options Oct 16 05:58:10 smsl-n100 volumio[1082]: info: ControllerMpd::getState Oct 16 05:58:10 smsl-n100 volumio[1082]: verbose: ControllerMpd::sendMpdCommand status Oct 16 05:58:10 smsl-n100 volumio[1082]: info: sendMpdCommand status took 6 milliseconds Oct 16 05:58:10 smsl-n100 volumio[1082]: info: sendMpdCommand status took 3 milliseconds Oct 16 05:58:10 smsl-n100 volumio[1082]: verbose: ControllerMpd::parseState Oct 16 05:58:10 smsl-n100 volumio[1082]: verbose: ControllerMpd::parseState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: ControllerMpd::pushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreCommandRouter::servicePushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreStateMachine::pushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 16 05:58:10 smsl-n100 volumio[1082]: info: MRS: Pushing multiroomSync output Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 16 05:58:10 smsl-n100 volumio[1082]: 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} Oct 16 05:58:10 smsl-n100 volumio[1082]: verbose: CURRENT POSITION 7 Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreStateMachine::syncState stateService stop Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreStateMachine::pushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 16 05:58:10 smsl-n100 volumio[1082]: info: MRS: Pushing multiroomSync output Oct 16 05:58:10 smsl-n100 volumio[1082]: info: No code Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreStateMachine::pushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 16 05:58:10 smsl-n100 volumio[1082]: info: MRS: Pushing multiroomSync output Oct 16 05:58:10 smsl-n100 volumio[1082]: info: ControllerMpd::pushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreCommandRouter::servicePushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreStateMachine::pushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 16 05:58:10 smsl-n100 volumio[1082]: info: MRS: Pushing multiroomSync output Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 16 05:58:10 smsl-n100 volumio[1082]: 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} Oct 16 05:58:10 smsl-n100 volumio[1082]: verbose: CURRENT POSITION 7 Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreStateMachine::syncState stateService stop Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreStateMachine::syncState currentStatus stop Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreStateMachine::pushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 16 05:58:10 smsl-n100 volumio[1082]: info: MRS: Pushing multiroomSync output Oct 16 05:58:10 smsl-n100 volumio[1082]: info: No code Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreStateMachine::pushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 7 Oct 16 05:58:10 smsl-n100 volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 16 05:58:10 smsl-n100 volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 16 05:58:10 smsl-n100 volumio[1082]: info: MRS: Pushing multiroomSync output Oct 16 05:58:10 smsl-n100 volumio[1082]: info: ------------------------------ 141ms Oct 16 05:58:10 smsl-n100 volumio[1082]: info: ------------------------------ 137ms Oct 16 05:58:14 smsl-n100 volumio[1082]: info: CoreStateMachine::startPlaybackTimer Oct 16 05:58:14 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 16 05:58:15 smsl-n100 volumio[1082]: info: CoreStateMachine::pushState Oct 16 05:58:15 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 16 05:58:15 smsl-n100 volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Oct 16 05:58:15 smsl-n100 volumio[1082]: info: CoreCommandRouter::volumioPushState Oct 16 05:58:15 smsl-n100 volumio[1082]: info: MRS: Pushing multiroomSync output update for this device Oct 16 05:58:15 smsl-n100 volumio[1082]: info: MRS: Pushing multiroomSync output Oct 16 05:58:25 smsl-n100 volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 05:58:25 smsl-n100 volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 05:58:25 smsl-n100 volumio[1082]: info: Discovery: Getting this device information Oct 16 05:58:25 smsl-n100 volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 16 05:58:25 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 16 05:58:25 smsl-n100 volumio[1082]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 05:58:55 smsl-n100 volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 05:58:55 smsl-n100 volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 05:58:55 smsl-n100 volumio[1082]: info: Discovery: Getting this device information Oct 16 05:58:55 smsl-n100 volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 16 05:58:55 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 16 05:58:55 smsl-n100 volumio[1082]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 05:59:25 smsl-n100 volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Oct 16 05:59:25 smsl-n100 volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Oct 16 05:59:25 smsl-n100 volumio[1082]: info: Discovery: Getting this device information Oct 16 05:59:25 smsl-n100 volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 16 05:59:25 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 16 05:59:25 smsl-n100 volumio[1082]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Oct 16 05:59:32 smsl-n100 volumio[1082]: info: Button was pressed for 5 seconds, starting hotspot Oct 16 05:59:32 smsl-n100 volumio[1082]: info: CoreCommandRouter::executeOnPlugin: network , forceHotspot Oct 16 05:59:32 smsl-n100 volumio[1082]: error: Forcing Hotspot mode Oct 16 05:59:32 smsl-n100 sudo[4575]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/hostapd/hostapd.conf Oct 16 05:59:32 smsl-n100 sudo[4575]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 05:59:32 smsl-n100 sudo[4575]: pam_unix(sudo:session): session closed for user root Oct 16 05:59:32 smsl-n100 volumio[1082]: info: Permissions for /etc/hostapd/hostapd.conf Oct 16 05:59:32 smsl-n100 volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , onNetworkingRestart Oct 16 05:59:32 smsl-n100 volumio[1082]: info: Discovery: Restarting Advertising due to device name change Oct 16 05:59:32 smsl-n100 sudo[4578]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart wireless.service Oct 16 05:59:32 smsl-n100 sudo[4578]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 05:59:32 smsl-n100 systemd[1]: Stopping Wireless Services... Oct 16 05:59:32 smsl-n100 volumio[1082]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 05:59:32 smsl-n100 wpa_supplicant[976]: nl80211: deinit ifname=wlan0 disabled_11b_rates=0 Oct 16 05:59:32 smsl-n100 dhcpcd[587]: wlan0: carrier acquired Oct 16 05:59:32 smsl-n100 wpa_supplicant[976]: wlan0: CTRL-EVENT-TERMINATING Oct 16 05:59:32 smsl-n100 systemd[1]: wireless.service: Succeeded. Oct 16 05:59:32 smsl-n100 systemd[1]: Stopped Wireless Services. Oct 16 05:59:32 smsl-n100 dhcpcd[587]: wlan0: IAID 2b:ee:c9:d9 Oct 16 05:59:32 smsl-n100 dhcpcd[587]: wlan0: carrier lost Oct 16 05:59:32 smsl-n100 volumio[1082]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Oct 16 05:59:32 smsl-n100 systemd[1]: Starting Wireless Services... Oct 16 05:59:32 smsl-n100 volumio[1082]: ------------------------------------ BT MESSAGE: BT STATUS: running Oct 16 05:59:33 smsl-n100 wireless.js[4583]: Cleaning previous... Oct 16 05:59:33 smsl-n100 volumio[1082]: info: Discovery: A device disappeared from network Oct 16 05:59:33 smsl-n100 sudo[4609]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ip addr flush dev wlan0 Oct 16 05:59:33 smsl-n100 sudo[4609]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 05:59:33 smsl-n100 sudo[4609]: pam_unix(sudo:session): session closed for user root Oct 16 05:59:33 smsl-n100 sudo[4611]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 down Oct 16 05:59:33 smsl-n100 sudo[4611]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 05:59:33 smsl-n100 sudo[4611]: pam_unix(sudo:session): session closed for user root Oct 16 05:59:33 smsl-n100 wireless.js[4583]: Stopped aP Oct 16 05:59:33 smsl-n100 sudo[4618]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 16 05:59:33 smsl-n100 sudo[4618]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 05:59:33 smsl-n100 dhcpcd[587]: wlan0: carrier acquired Oct 16 05:59:33 smsl-n100 sudo[4618]: pam_unix(sudo:session): session closed for user root Oct 16 05:59:33 smsl-n100 dhcpcd[587]: wlan0: IAID 2b:ee:c9:d9 Oct 16 05:59:33 smsl-n100 sudo[4621]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw reg get Oct 16 05:59:33 smsl-n100 sudo[4621]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 05:59:33 smsl-n100 sudo[4621]: pam_unix(sudo:session): session closed for user root Oct 16 05:59:33 smsl-n100 sudo[4628]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 up Oct 16 05:59:33 smsl-n100 sudo[4628]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 05:59:33 smsl-n100 sudo[4628]: pam_unix(sudo:session): session closed for user root Oct 16 05:59:33 smsl-n100 sudo[4630]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iw wlan0 scan Oct 16 05:59:33 smsl-n100 sudo[4630]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 05:59:34 smsl-n100 dhcpcd[587]: wlan0: soliciting an IPv6 router Oct 16 05:59:34 smsl-n100 dhcpcd[587]: wlan0: soliciting a DHCP lease Oct 16 05:59:37 smsl-n100 volumio[1082]: info: Discovery: Started advertising with name: SMSL N100 Oct 16 05:59:38 smsl-n100 volumio[1082]: info: Discovery: adding aa5620f4-cc3f-412c-ad4a-8d2bf2b921d1 Oct 16 05:59:38 smsl-n100 volumio[1082]: info: Discovery: Found device SMSL N100 Oct 16 05:59:38 smsl-n100 volumio[1082]: info: CoreCommandRouter::volumioGetState Oct 16 05:59:38 smsl-n100 volumio[1082]: info: CorePlayQueue::getTrack 8 Oct 16 05:59:38 smsl-n100 volumio[1082]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 05:59:38 smsl-n100 volumio[1082]: Error: The value NaN is not a number Oct 16 05:59:38 smsl-n100 volumio[1082]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Oct 16 05:59:38 smsl-n100 volumio[1082]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Oct 16 05:59:38 smsl-n100 volumio[1082]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Oct 16 05:59:38 smsl-n100 volumio[1082]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Oct 16 05:59:38 smsl-n100 volumio[1082]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Oct 16 05:59:38 smsl-n100 volumio[1082]: at Browser.emit (events.js:400:28) Oct 16 05:59:38 smsl-n100 volumio[1082]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Oct 16 05:59:38 smsl-n100 volumio[1082]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Oct 16 05:59:38 smsl-n100 volumio[1082]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Oct 16 05:59:38 smsl-n100 volumio[1082]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Oct 16 05:59:38 smsl-n100 volumio[1082]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Oct 16 05:59:38 smsl-n100 volumio[1082]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Oct 16 05:59:38 smsl-n100 volumio[1082]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Oct 16 05:59:38 smsl-n100 volumio[1082]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Oct 16 05:59:39 smsl-n100 sudo[4659]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-10-16 05:58 Oct 16 05:59:39 smsl-n100 sudo[4659]: pam_unix(sudo:session): session opened for user root by (uid=0) Oct 16 05:59:39 smsl-n100 sudo[4630]: pam_unix(sudo:session): session closed for user root Oct 16 05:59:39 smsl-n100 wireless.js[4583]: Wireless networking forced to hotspot mode 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"