-- Logs begin at Thu 2019-02-14 18:11:59 +08, end at Tue 2025-09-02 19:23:44 +08. -- Sep 02 19:23:26 smsl-n100 volumio[1156]: info: CorePlayQueue::getTrack 35 Sep 02 19:23:26 smsl-n100 volumio[1156]: info: CorePlayQueue::getTrack 36 Sep 02 19:23:26 smsl-n100 volumio[1156]: info: Prefetching next song Sep 02 19:23:26 smsl-n100 volumio[1156]: info: DOING PREFETCH IN MPD Sep 02 19:23:26 smsl-n100 volumio[1156]: verbose: ControllerMpd::sendMpdCommand add "USB/MUSIC_TD02/Contemporary/V.A. - Bravo Hits (2024) (2CD) (Flac) (MOD)/213. David Guetta & OneRepublic - I Don't Wanna Wait.flac" Sep 02 19:23:26 smsl-n100 volumio[1156]: info: sendMpdCommand add "USB/MUSIC_TD02/Contemporary/V.A. - Bravo Hits (2024) (2CD) (Flac) (MOD)/213. David Guetta & OneRepublic - I Don't Wanna Wait.flac" took 4 milliseconds Sep 02 19:23:26 smsl-n100 volumio[1156]: verbose: ControllerMpd::sendMpdCommand consume 1 Sep 02 19:23:26 smsl-n100 volumio[1156]: info: Sep 02 19:23:26 smsl-n100 volumio[1156]: ---------------------------- MPD announces system playlist update Sep 02 19:23:26 smsl-n100 volumio[1156]: info: Ignoring MPD Status Update Sep 02 19:23:26 smsl-n100 volumio[1156]: info: sendMpdCommand consume 1 took 6 milliseconds Sep 02 19:23:26 smsl-n100 volumio[1156]: info: Sep 02 19:23:26 smsl-n100 volumio[1156]: ---------------------------- MPD announces system playlist update Sep 02 19:23:26 smsl-n100 volumio[1156]: info: Ignoring MPD Status Update Sep 02 19:23:26 smsl-n100 volumio[1156]: info: ------------------------------ 13ms Sep 02 19:23:26 smsl-n100 volumio[1156]: info: ------------------------------ 9ms Sep 02 19:23:30 smsl-n100 volumio[1156]: info: CoreStateMachine::startPlaybackTimer Sep 02 19:23:30 smsl-n100 volumio[1156]: info: CorePlayQueue::getTrack 36 Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreStateMachine::pushState Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CorePlayQueue::getTrack 36 Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreCommandRouter::volumioPushState Sep 02 19:23:31 smsl-n100 volumio[1156]: info: MRS: Pushing multiroomSync output update for this device Sep 02 19:23:31 smsl-n100 volumio[1156]: info: MRS: Pushing multiroomSync output Sep 02 19:23:31 smsl-n100 volumio[1156]: info: Sep 02 19:23:31 smsl-n100 volumio[1156]: ---------------------------- MPD announces system playlist update Sep 02 19:23:31 smsl-n100 volumio[1156]: info: Ignoring MPD Status Update Sep 02 19:23:31 smsl-n100 volumio[1156]: info: Sep 02 19:23:31 smsl-n100 volumio[1156]: ---------------------------- MPD announces state update: player Sep 02 19:23:31 smsl-n100 volumio[1156]: info: ControllerMpd::getState Sep 02 19:23:31 smsl-n100 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Sep 02 19:23:31 smsl-n100 volumio[1156]: info: Sep 02 19:23:31 smsl-n100 volumio[1156]: ---------------------------- MPD announces system playlist update Sep 02 19:23:31 smsl-n100 volumio[1156]: info: Ignoring MPD Status Update Sep 02 19:23:31 smsl-n100 volumio[1156]: info: Sep 02 19:23:31 smsl-n100 volumio[1156]: ---------------------------- MPD announces state update: player Sep 02 19:23:31 smsl-n100 volumio[1156]: info: ControllerMpd::getState Sep 02 19:23:31 smsl-n100 volumio[1156]: verbose: ControllerMpd::sendMpdCommand status Sep 02 19:23:31 smsl-n100 volumio[1156]: info: ------------------------------ 14ms Sep 02 19:23:31 smsl-n100 volumio[1156]: info: sendMpdCommand status took 11 milliseconds Sep 02 19:23:31 smsl-n100 volumio[1156]: info: ------------------------------ 9ms Sep 02 19:23:31 smsl-n100 volumio[1156]: info: sendMpdCommand status took 6 milliseconds Sep 02 19:23:31 smsl-n100 volumio[1156]: verbose: ControllerMpd::parseState Sep 02 19:23:31 smsl-n100 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 19:23:31 smsl-n100 volumio[1156]: verbose: ControllerMpd::parseState Sep 02 19:23:31 smsl-n100 volumio[1156]: verbose: ControllerMpd::sendMpdCommand playlistinfo Sep 02 19:23:31 smsl-n100 volumio[1156]: info: sendMpdCommand playlistinfo took 5 milliseconds Sep 02 19:23:31 smsl-n100 volumio[1156]: info: sendMpdCommand playlistinfo took 4 milliseconds Sep 02 19:23:31 smsl-n100 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Sep 02 19:23:31 smsl-n100 volumio[1156]: verbose: ControllerMpd::parseTrackInfo Sep 02 19:23:31 smsl-n100 volumio[1156]: info: ControllerMpd::pushState Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreCommandRouter::servicePushState Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CorePlayQueue::getTrack 36 Sep 02 19:23:31 smsl-n100 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"813 Kbps","isStreaming":false,"title":" I Don't Wanna Wait","artist":"David Guetta & OneRepublic","album":"Bravo The Hits 2024","uri":"USB/MUSIC_TD02/Contemporary/V.A. - Bravo Hits (2024) (2CD) (Flac) (MOD)/213. David Guetta & OneRepublic - I Don't Wanna Wait.flac","trackType":"flac"} Sep 02 19:23:31 smsl-n100 volumio[1156]: verbose: CURRENT POSITION 36 Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreStateMachine::syncState stateService play Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Sep 02 19:23:31 smsl-n100 volumio[1156]: info: Received an update from plugin. extracting info from payload Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreStateMachine::pushState Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CorePlayQueue::getTrack 36 Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreCommandRouter::volumioPushState Sep 02 19:23:31 smsl-n100 volumio[1156]: info: MRS: Pushing multiroomSync output update for this device Sep 02 19:23:31 smsl-n100 volumio[1156]: info: MRS: Pushing multiroomSync output Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreStateMachine::pushState Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CorePlayQueue::getTrack 36 Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreCommandRouter::volumioPushState Sep 02 19:23:31 smsl-n100 volumio[1156]: info: MRS: Pushing multiroomSync output update for this device Sep 02 19:23:31 smsl-n100 volumio[1156]: info: MRS: Pushing multiroomSync output Sep 02 19:23:31 smsl-n100 volumio[1156]: info: ControllerMpd::pushState Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreCommandRouter::servicePushState Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CorePlayQueue::getTrack 36 Sep 02 19:23:31 smsl-n100 volumio[1156]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":151,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"813 Kbps","isStreaming":false,"title":" I Don't Wanna Wait","artist":"David Guetta & OneRepublic","album":"Bravo The Hits 2024","uri":"USB/MUSIC_TD02/Contemporary/V.A. - Bravo Hits (2024) (2CD) (Flac) (MOD)/213. David Guetta & OneRepublic - I Don't Wanna Wait.flac","trackType":"flac"} Sep 02 19:23:31 smsl-n100 volumio[1156]: verbose: CURRENT POSITION 36 Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreStateMachine::syncState stateService play Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreStateMachine::syncState currentStatus play Sep 02 19:23:31 smsl-n100 volumio[1156]: info: Received an update from plugin. extracting info from payload Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreStateMachine::pushState Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CorePlayQueue::getTrack 36 Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreCommandRouter::volumioPushState Sep 02 19:23:31 smsl-n100 volumio[1156]: info: MRS: Pushing multiroomSync output update for this device Sep 02 19:23:31 smsl-n100 volumio[1156]: info: MRS: Pushing multiroomSync output Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreStateMachine::pushState Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CorePlayQueue::getTrack 36 Sep 02 19:23:31 smsl-n100 volumio[1156]: info: CoreCommandRouter::volumioPushState Sep 02 19:23:31 smsl-n100 volumio[1156]: info: MRS: Pushing multiroomSync output update for this device Sep 02 19:23:31 smsl-n100 volumio[1156]: info: MRS: Pushing multiroomSync output Sep 02 19:23:31 smsl-n100 volumio[1156]: info: ------------------------------ 90ms Sep 02 19:23:31 smsl-n100 volumio[1156]: info: ------------------------------ 88ms Sep 02 19:23:36 smsl-n100 dhcpcd[918]: wlan0: NAK: from 192.168.68.1 Sep 02 19:23:36 smsl-n100 avahi-daemon[587]: Withdrawing address record for 192.168.68.109 on wlan0. Sep 02 19:23:36 smsl-n100 avahi-daemon[587]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.109. Sep 02 19:23:36 smsl-n100 avahi-daemon[587]: Interface wlan0.IPv4 no longer relevant for mDNS. Sep 02 19:23:36 smsl-n100 dhcpcd[918]: wlan0: deleting route to 192.168.68.0/24 Sep 02 19:23:36 smsl-n100 dhcpcd[918]: wlan0: deleting default route via 192.168.68.1 Sep 02 19:23:36 smsl-n100 volumio[1156]: info: Discovery: A device disappeared from network Sep 02 19:23:36 smsl-n100 dhcpcd[918]: wlan0: soliciting a DHCP lease Sep 02 19:23:36 smsl-n100 dhcpcd[918]: wlan0: offered 192.168.68.114 from 192.168.68.1 Sep 02 19:23:36 smsl-n100 dhcpcd[918]: wlan0: probing address 192.168.68.114/24 Sep 02 19:23:37 smsl-n100 ntpd[1145]: Deleting interface #4 wlan0, 192.168.68.109#123, interface stats: received=0, sent=0, dropped=0, active_time=3601 secs Sep 02 19:23:40 smsl-n100 volumio[1156]: info: Reporting MCU Network Status: 1 Sep 02 19:23:40 smsl-n100 volumio[1156]: info: Volumio Network Manager: Network status updated: 1 Sep 02 19:23:40 smsl-n100 volumio[1156]: info: CoreStateMachine::pushState Sep 02 19:23:40 smsl-n100 volumio[1156]: info: CorePlayQueue::getTrack 36 Sep 02 19:23:40 smsl-n100 volumio[1156]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Sep 02 19:23:40 smsl-n100 volumio[1156]: info: CoreCommandRouter::volumioPushState Sep 02 19:23:40 smsl-n100 volumio[1156]: info: MRS: Pushing multiroomSync output update for this device Sep 02 19:23:40 smsl-n100 volumio[1156]: info: MRS: Pushing multiroomSync output Sep 02 19:23:40 smsl-n100 volumio[1156]: error: Serial API: Failed to decode command: 100, message: undefined Sep 02 19:23:40 smsl-n100 volumio[1156]: error: Serial API: Failed to decode command: MAXVOL, message: Sep 02 19:23:40 smsl-n100 volumio[1156]: error: Serial API: Failed to decode command: 100, message: undefined Sep 02 19:23:40 smsl-n100 volumio[1156]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Sep 02 19:23:42 smsl-n100 dhcpcd[918]: wlan0: leased 192.168.68.114 for 7200 seconds Sep 02 19:23:42 smsl-n100 avahi-daemon[587]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.68.114. Sep 02 19:23:42 smsl-n100 avahi-daemon[587]: New relevant interface wlan0.IPv4 for mDNS. Sep 02 19:23:42 smsl-n100 dhcpcd[918]: wlan0: adding route to 192.168.68.0/24 Sep 02 19:23:42 smsl-n100 dhcpcd[918]: wlan0: adding default route via 192.168.68.1 Sep 02 19:23:42 smsl-n100 avahi-daemon[587]: Registering new address record for 192.168.68.114 on wlan0.IPv4. Sep 02 19:23:42 smsl-n100 ntpd[1145]: ntpd exiting on signal 15 (Terminated) Sep 02 19:23:42 smsl-n100 ntpd[1145]: 38.60.193.17 local addr 192.168.68.110 -> Sep 02 19:23:42 smsl-n100 systemd[1]: Stopping Network Time Service... Sep 02 19:23:42 smsl-n100 volumio[1156]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 19:23:42 smsl-n100 systemd[1]: ntp.service: Succeeded. Sep 02 19:23:42 smsl-n100 systemd[1]: Stopped Network Time Service. Sep 02 19:23:42 smsl-n100 volumio[1156]: ------------------------------------ BT MESSAGE: BT STATUS: waiting Sep 02 19:23:42 smsl-n100 systemd[1]: Starting Network Time Service... Sep 02 19:23:42 smsl-n100 volumio[1156]: ------------------------------------ BT MESSAGE: BT STATUS: running Sep 02 19:23:42 smsl-n100 ntpd[17076]: ntpd 4.2.8p12@1.3728-o (1): Starting Sep 02 19:23:42 smsl-n100 ntpd[17076]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -c /run/ntp.conf.dhcp -u 104:103 Sep 02 19:23:42 smsl-n100 systemd[1]: Started Network Time Service. Sep 02 19:23:42 smsl-n100 ntpd[17084]: proto: precision = 1.166 usec (-20) Sep 02 19:23:42 smsl-n100 ntpd[17084]: Listen and drop on 0 v6wildcard [::]:123 Sep 02 19:23:42 smsl-n100 ntpd[17084]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Sep 02 19:23:42 smsl-n100 ntpd[17084]: Listen normally on 2 lo 127.0.0.1:123 Sep 02 19:23:42 smsl-n100 ntpd[17084]: Listen normally on 3 eth0 192.168.68.110:123 Sep 02 19:23:42 smsl-n100 ntpd[17084]: Listen normally on 4 wlan0 192.168.68.114:123 Sep 02 19:23:42 smsl-n100 ntpd[17084]: Listening on routing socket on fd #21 for interface updates Sep 02 19:23:42 smsl-n100 ntpd[17084]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Sep 02 19:23:42 smsl-n100 ntpd[17084]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized Sep 02 19:23:42 smsl-n100 volumio[1156]: info: Discovery: adding 42f7af11-5624-4e9e-8e92-e3356fe4ce45 Sep 02 19:23:42 smsl-n100 volumio[1156]: info: Discovery: Found device SMSL N100 Sep 02 19:23:42 smsl-n100 volumio[1156]: info: CoreCommandRouter::volumioGetState Sep 02 19:23:42 smsl-n100 volumio[1156]: info: CorePlayQueue::getTrack 36 Sep 02 19:23:42 smsl-n100 volumio[1156]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 19:23:42 smsl-n100 volumio[1156]: Error: The value NaN is not a number Sep 02 19:23:42 smsl-n100 volumio[1156]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Sep 02 19:23:42 smsl-n100 volumio[1156]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Sep 02 19:23:42 smsl-n100 volumio[1156]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Sep 02 19:23:42 smsl-n100 volumio[1156]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Sep 02 19:23:42 smsl-n100 volumio[1156]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Sep 02 19:23:42 smsl-n100 volumio[1156]: at Browser.emit (events.js:400:28) Sep 02 19:23:42 smsl-n100 volumio[1156]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Sep 02 19:23:42 smsl-n100 volumio[1156]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Sep 02 19:23:42 smsl-n100 volumio[1156]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Sep 02 19:23:42 smsl-n100 volumio[1156]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Sep 02 19:23:42 smsl-n100 volumio[1156]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Sep 02 19:23:42 smsl-n100 volumio[1156]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Sep 02 19:23:42 smsl-n100 volumio[1156]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Sep 02 19:23:42 smsl-n100 volumio[1156]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Sep 02 19:23:44 smsl-n100 sudo[17095]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-09-02 19:22 Sep 02 19:23:44 smsl-n100 sudo[17095]: 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"