-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Sat 2025-05-24 18:09:55 CST. -- May 24 18:09:41 smsl-n100 wpa_supplicant[914]: wlan0: CTRL-EVENT-DISCONNECTED bssid=b8:60:61:47:ad:52 reason=0 May 24 18:09:41 smsl-n100 dhcpcd[546]: wlan0: carrier lost May 24 18:09:41 smsl-n100 wpa_supplicant[914]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=CORE type=WORLD May 24 18:09:41 smsl-n100 wpa_supplicant[914]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=USER type=COUNTRY alpha2=CN May 24 18:09:41 smsl-n100 avahi-daemon[514]: Withdrawing address record for 192.168.31.160 on wlan0. May 24 18:09:41 smsl-n100 avahi-daemon[514]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.160. May 24 18:09:41 smsl-n100 avahi-daemon[514]: Interface wlan0.IPv4 no longer relevant for mDNS. May 24 18:09:41 smsl-n100 dhcpcd[546]: wlan0: deleting route to 192.168.31.0/24 May 24 18:09:41 smsl-n100 dhcpcd[546]: wlan0: deleting default route via 192.168.31.1 May 24 18:09:41 smsl-n100 volumio[24633]: info: Discovery: A device disappeared from network May 24 18:09:41 smsl-n100 kernel: usb 5-1: Device not responding to setup address. May 24 18:09:42 smsl-n100 kernel: usb 5-1: Device not responding to setup address. May 24 18:09:42 smsl-n100 volumio[24633]: info: Reporting MCU Network Status: 0 May 24 18:09:42 smsl-n100 volumio[24633]: info: Volumio Network Manager: Network status updated: 0 May 24 18:09:42 smsl-n100 volumio[24633]: info: CoreStateMachine::pushState May 24 18:09:42 smsl-n100 volumio[24633]: info: CorePlayQueue::getTrack 14 May 24 18:09:42 smsl-n100 volumio[24633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 18:09:42 smsl-n100 volumio[24633]: info: CoreCommandRouter::volumioPushState May 24 18:09:42 smsl-n100 kernel: usb 5-1: device not accepting address 2, error -71 May 24 18:09:42 smsl-n100 volumio[24633]: info: MRS: Pushing multiroomSync output update for this device May 24 18:09:42 smsl-n100 volumio[24633]: info: MRS: Pushing multiroomSync output May 24 18:09:42 smsl-n100 volumio[24633]: error: Serial API: Failed to decode command: 100, message: undefined May 24 18:09:42 smsl-n100 volumio[24633]: error: Serial API: Failed to decode command: MAXVOL, message: May 24 18:09:42 smsl-n100 volumio[24633]: error: Serial API: Failed to decode command: 100, message: undefined May 24 18:09:42 smsl-n100 volumio[24633]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 24 18:09:42 smsl-n100 ntpd[16984]: Deleting interface #3 wlan0, 192.168.31.160#123, interface stats: received=264, sent=287, dropped=0, active_time=3520 secs May 24 18:09:42 smsl-n100 ntpd[16984]: 162.159.200.1 local addr 192.168.31.160 -> May 24 18:09:42 smsl-n100 ntpd[16984]: 202.118.1.81 local addr 192.168.31.160 -> May 24 18:09:42 smsl-n100 ntpd[16984]: 116.203.151.74 local addr 192.168.31.160 -> May 24 18:09:42 smsl-n100 ntpd[16984]: 202.118.1.130 local addr 192.168.31.160 -> May 24 18:09:42 smsl-n100 ntpd[16984]: 211.68.71.26 local addr 192.168.31.160 -> May 24 18:09:42 smsl-n100 ntpd[16984]: 193.182.111.14 local addr 192.168.31.160 -> May 24 18:09:45 smsl-n100 kernel: usb usb5-port1: Cannot enable. Maybe the USB cable is bad? May 24 18:09:45 smsl-n100 kernel: usb 4-1: new high-speed USB device number 2 using xhci-hcd May 24 18:09:45 smsl-n100 wpa_supplicant[914]: wlan0: Trying to associate with 88:c3:97:cd:15:de (SSID='Xiaomi_F01A_5G' freq=5180 MHz) May 24 18:09:46 smsl-n100 wpa_supplicant[914]: wlan0: Associated with 88:c3:97:cd:15:de May 24 18:09:46 smsl-n100 wpa_supplicant[914]: wlan0: CTRL-EVENT-SUBNET-STATUS-UPDATE status=0 May 24 18:09:46 smsl-n100 wpa_supplicant[914]: wlan0: WPA: Key negotiation completed with 88:c3:97:cd:15:de [PTK=CCMP GTK=CCMP] May 24 18:09:46 smsl-n100 wpa_supplicant[914]: wlan0: CTRL-EVENT-CONNECTED - Connection to 88:c3:97:cd:15:de completed [id=0 id_str=] May 24 18:09:46 smsl-n100 dhcpcd[546]: wlan0: carrier acquired May 24 18:09:46 smsl-n100 dhcpcd[546]: wlan0: IAID 2b:ee:c8:f3 May 24 18:09:46 smsl-n100 dhcpcd[546]: wlan0: rebinding lease of 192.168.31.160 May 24 18:09:46 smsl-n100 dhcpcd[546]: wlan0: soliciting an IPv6 router May 24 18:09:46 smsl-n100 wpa_supplicant[914]: wlan0: CTRL-EVENT-REGDOM-CHANGE init=COUNTRY_IE type=COUNTRY alpha2=CN May 24 18:09:46 smsl-n100 kernel: usb 5-1: device not accepting address 2, error -22 May 24 18:09:46 smsl-n100 dhcpcd[546]: wlan0: probing address 192.168.31.160/24 May 24 18:09:47 smsl-n100 kernel: usb 5-1: device not accepting address 2, error -22 May 24 18:09:47 smsl-n100 kernel: usb 5-1: USB disconnect, device number 2 May 24 18:09:47 smsl-n100 kernel: blk_update_request: I/O error, dev sda, sector 114001136 op 0x0:(READ) flags 0x80700 phys_seg 68 prio class 0 May 24 18:09:47 smsl-n100 kernel: blk_update_request: I/O error, dev sda, sector 114001136 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 May 24 18:09:47 smsl-n100 kernel: blk_update_request: I/O error, dev sda, sector 114001136 op 0x0:(READ) flags 0x0 phys_seg 1 prio class 0 May 24 18:09:48 smsl-n100 volumio[24633]: info: CoreCommandRouter::volumioGetState May 24 18:09:48 smsl-n100 volumio[24633]: info: CorePlayQueue::getTrack 14 May 24 18:09:48 smsl-n100 volumio[24633]: info: Clearing queue from CD entries May 24 18:09:48 smsl-n100 volumio[24633]: info: CoreCommandRouter::volumioPushQueue May 24 18:09:48 smsl-n100 volumio[24633]: info: CoreCommandRouter::volumioRemoveToBrowseSourcesAudio CD May 24 18:09:48 smsl-n100 volumio[24633]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 24 18:09:48 smsl-n100 volumio[24633]: info: Partition removed: {"syspath":"/sys/devices/platform/usb@ff600000/ff600000.dwc3/xhci-hcd.0.auto/usb5/5-1/5-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","ACTION":"remove","DEVLINKS":"/dev/disk/by-path/platform-xhci-hcd.0.auto-usb-0:1:1.0-scsi-0:0:0:0-part1 /dev/disk/by-partuuid/611292f4-01 /dev/disk/by-uuid/186D-6F9F /dev/disk/by-id/usb-aigo_U330_969FD19A31A98C94-0:0-part1 /dev/disk/by-label/\\xca\\xfd\\xbe\\xdd","DEVNAME":"/dev/sda1","DEVPATH":"/devices/platform/usb@ff600000/ff600000.dwc3/xhci-hcd.0.auto/usb5/5-1/5-1:1.0/host0/target0:0:0/0:0:0:0/block/sda/sda1","DEVTYPE":"partition","ID_BUS":"usb","ID_FS_LABEL":"____","ID_FS_LABEL_ENC":"\\xca\\xfd\\xbe\\xdd","ID_FS_TYPE":"vfat","ID_FS_USAGE":"filesystem","ID_FS_UUID":"186D-6F9F","ID_FS_UUID_ENC":"186D-6F9F","ID_FS_VERSION":"FAT32","ID_INSTANCE":"0:0","ID_MODEL":"U330","ID_MODEL_ENC":"U330\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20\\x20","ID_MODEL_ID":"6300","ID_PART_ENTRY_DISK":"8:0","ID_PART_ENTRY_FLAGS":"0x80","ID_PART_ENTRY_NUMBER":"1","ID_PART_ENTRY_OFFSET":"2048","ID_PART_ENTRY_SCHEME":"dos","ID_PART_ENTRY_SIZE":"122877952","ID_PART_ENTRY_TYPE":"0xc","ID_PART_ENTRY_UUID":"611292f4-01","ID_PART_TABLE_TYPE":"dos","ID_PART_TABLE_UUID":"611292f4","ID_PATH":"platform-xhci-hcd.0.auto-usb-0:1:1.0-scsi-0:0:0:0","ID_PATH_TAG":"platform-xhci-hcd_0_auto-usb-0_1_1_0-scsi-0_0_0_0","ID_REVISION":"PMAP","ID_SERIAL":"aigo_U330_969FD19A31A98C94-0:0","ID_SERIAL_SHORT":"969FD19A31A98C94","ID_TYPE":"disk","ID_USB_DRIVER":"usb-storage","ID_USB_INTERFACES":":080650:","ID_USB_INTERFACE_NUM":"00","ID_VENDOR":"aigo","ID_VENDOR_ENC":"aigo\\x20\\x20\\x20\\x20","ID_VENDOR_ID":"3535","MAJOR":"8","MINOR":"1","PARTN":"1","SEQNUM":"2391","SUBSYSTEM":"block","TAGS":":systemd:","USEC_INITIALIZED":"2933626"} May 24 18:09:48 smsl-n100 kernel: usb 5-1: new SuperSpeed Gen 1 USB device number 3 using xhci-hcd May 24 18:09:48 smsl-n100 kernel: usb 5-1: New USB device found, idVendor=3535, idProduct=6300, bcdDevice= 1.10 May 24 18:09:48 smsl-n100 kernel: usb 5-1: New USB device strings: Mfr=1, Product=2, SerialNumber=3 May 24 18:09:48 smsl-n100 kernel: usb 5-1: Product: U330 May 24 18:09:48 smsl-n100 kernel: usb 5-1: Manufacturer: aigo May 24 18:09:48 smsl-n100 kernel: usb 5-1: SerialNumber: 969FD19A31A98C94 May 24 18:09:48 smsl-n100 kernel: usb-storage 5-1:1.0: USB Mass Storage device detected May 24 18:09:48 smsl-n100 kernel: scsi host3: usb-storage 5-1:1.0 May 24 18:09:49 smsl-n100 sudo[25799]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/umount -f /dev/sda1 May 24 18:09:49 smsl-n100 sudo[25799]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 18:09:49 smsl-n100 systemd[1]: media-____.mount: Succeeded. May 24 18:09:49 smsl-n100 kernel: FAT-fs (sda1): unable to read boot sector to mark fs as dirty May 24 18:09:49 smsl-n100 sudo[25799]: pam_unix(sudo:session): session closed for user root May 24 18:09:49 smsl-n100 kernel: scsi 3:0:0:0: Direct-Access aigo U330 PMAP PQ: 0 ANSI: 6 May 24 18:09:49 smsl-n100 kernel: sd 3:0:0:0: [sda] 122880000 512-byte logical blocks: (62.9 GB/58.6 GiB) May 24 18:09:49 smsl-n100 kernel: sd 3:0:0:0: [sda] Write Protect is off May 24 18:09:49 smsl-n100 kernel: sd 3:0:0:0: [sda] Mode Sense: 45 00 00 00 May 24 18:09:49 smsl-n100 kernel: sd 3:0:0:0: [sda] Write cache: disabled, read cache: enabled, doesn't support DPO or FUA May 24 18:09:50 smsl-n100 kernel: sda: sda1 May 24 18:09:50 smsl-n100 kernel: sd 3:0:0:0: [sda] Attached SCSI removable disk May 24 18:09:50 smsl-n100 volumio[24633]: info: Mounting Device ____ May 24 18:09:50 smsl-n100 sudo[25806]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/____ -o noatime,dmask=0000,fmask=0000,iocharset=utf8 May 24 18:09:50 smsl-n100 sudo[25806]: pam_unix(sudo:session): session opened for user root by (uid=0) May 24 18:09:50 smsl-n100 kernel: FAT-fs (sda1): utf8 is not a recommended IO charset for FAT filesystems, filesystem will be case sensitive! May 24 18:09:50 smsl-n100 kernel: FAT-fs (sda1): Volume was not properly unmounted. Some data may be corrupt. Please run fsck. May 24 18:09:50 smsl-n100 sudo[25806]: pam_unix(sudo:session): session closed for user root May 24 18:09:51 smsl-n100 dhcpcd[546]: wlan0: leased 192.168.31.160 for 43200 seconds May 24 18:09:51 smsl-n100 avahi-daemon[514]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.31.160. May 24 18:09:51 smsl-n100 avahi-daemon[514]: New relevant interface wlan0.IPv4 for mDNS. May 24 18:09:51 smsl-n100 avahi-daemon[514]: Registering new address record for 192.168.31.160 on wlan0.IPv4. May 24 18:09:51 smsl-n100 dhcpcd[546]: wlan0: adding route to 192.168.31.0/24 May 24 18:09:51 smsl-n100 dhcpcd[546]: wlan0: adding default route via 192.168.31.1 May 24 18:09:52 smsl-n100 volumio[24633]: verbose: New Socket.io Connection to 192.168.31.160 from 192.168.31.78 UA: okhttp/4.9.2 Engine version: 3 Transport: polling Total Clients: 9 May 24 18:09:52 smsl-n100 volumio[24633]: info: Reporting MCU Network Status: 2 May 24 18:09:52 smsl-n100 volumio[24633]: info: Volumio Network Manager: Network status updated: 2 May 24 18:09:52 smsl-n100 ntpd[16984]: ntpd exiting on signal 15 (Terminated) May 24 18:09:52 smsl-n100 systemd[1]: Stopping Network Time Service... May 24 18:09:52 smsl-n100 volumio[24633]: info: CoreStateMachine::pushState May 24 18:09:52 smsl-n100 volumio[24633]: info: CorePlayQueue::getTrack 14 May 24 18:09:52 smsl-n100 volumio[24633]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 24 18:09:52 smsl-n100 volumio[24633]: info: CoreCommandRouter::volumioPushState May 24 18:09:52 smsl-n100 volumio[24633]: info: MRS: Pushing multiroomSync output update for this device May 24 18:09:52 smsl-n100 volumio[24633]: info: MRS: Pushing multiroomSync output May 24 18:09:52 smsl-n100 systemd[1]: ntp.service: Succeeded. May 24 18:09:52 smsl-n100 volumio[24633]: error: Serial API: Failed to decode command: 100, message: undefined May 24 18:09:52 smsl-n100 volumio[24633]: error: Serial API: Failed to decode command: MAXVOL, message: May 24 18:09:52 smsl-n100 volumio[24633]: error: Serial API: Failed to decode command: 100, message: undefined May 24 18:09:52 smsl-n100 volumio[24633]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff May 24 18:09:52 smsl-n100 volumio[24633]: error: Serial API: Failed to decode command: 2, message: undefined May 24 18:09:52 smsl-n100 systemd[1]: Stopped Network Time Service. May 24 18:09:52 smsl-n100 systemd[1]: Starting Network Time Service... May 24 18:09:52 smsl-n100 volumio[24633]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 18:09:52 smsl-n100 volumio[24633]: ------------------------------------ BT MESSAGE: BT STATUS: waiting May 24 18:09:52 smsl-n100 volumio[24633]: ------------------------------------ BT MESSAGE: BT STATUS: running May 24 18:09:52 smsl-n100 ntpd[25857]: ntpd 4.2.8p12@1.3728-o (1): Starting May 24 18:09:52 smsl-n100 ntpd[25857]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 May 24 18:09:52 smsl-n100 systemd[1]: Started Network Time Service. May 24 18:09:52 smsl-n100 ntpd[25863]: proto: precision = 1.166 usec (-20) May 24 18:09:52 smsl-n100 ntpd[25863]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature May 24 18:09:52 smsl-n100 ntpd[25863]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 May 24 18:09:52 smsl-n100 ntpd[25863]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 879 days ago May 24 18:09:52 smsl-n100 ntpd[25863]: Listen and drop on 0 v6wildcard [::]:123 May 24 18:09:52 smsl-n100 ntpd[25863]: Listen and drop on 1 v4wildcard 0.0.0.0:123 May 24 18:09:52 smsl-n100 ntpd[25863]: Listen normally on 2 lo 127.0.0.1:123 May 24 18:09:52 smsl-n100 ntpd[25863]: Listen normally on 3 wlan0 192.168.31.160:123 May 24 18:09:52 smsl-n100 ntpd[25863]: Listening on routing socket on fd #20 for interface updates May 24 18:09:52 smsl-n100 ntpd[25863]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized May 24 18:09:52 smsl-n100 ntpd[25863]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized May 24 18:09:52 smsl-n100 volumio[24633]: info: Discovery: adding 35e56652-3b81-4fdc-87e9-351d8153ec5f May 24 18:09:52 smsl-n100 volumio[24633]: info: Discovery: Found device SMSL N100 May 24 18:09:52 smsl-n100 volumio[24633]: info: CoreCommandRouter::volumioGetState May 24 18:09:52 smsl-n100 volumio[24633]: info: CorePlayQueue::getTrack 14 May 24 18:09:52 smsl-n100 volumio[24633]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 18:09:52 smsl-n100 volumio[24633]: Error: The value NaN is not a number May 24 18:09:52 smsl-n100 volumio[24633]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) May 24 18:09:52 smsl-n100 volumio[24633]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) May 24 18:09:52 smsl-n100 volumio[24633]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) May 24 18:09:52 smsl-n100 volumio[24633]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) May 24 18:09:52 smsl-n100 volumio[24633]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) May 24 18:09:52 smsl-n100 volumio[24633]: at Browser.emit (events.js:400:28) May 24 18:09:52 smsl-n100 volumio[24633]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) May 24 18:09:52 smsl-n100 volumio[24633]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) May 24 18:09:52 smsl-n100 volumio[24633]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) May 24 18:09:52 smsl-n100 volumio[24633]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) May 24 18:09:52 smsl-n100 volumio[24633]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 May 24 18:09:52 smsl-n100 volumio[24633]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) May 24 18:09:52 smsl-n100 volumio[24633]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) May 24 18:09:53 smsl-n100 volumio[24633]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 24 18:09:53 smsl-n100 ntpd[25863]: Soliciting pool server 193.182.111.141 May 24 18:09:54 smsl-n100 ntpd[25863]: Soliciting pool server 193.182.111.14 May 24 18:09:54 smsl-n100 ntpd[25863]: Soliciting pool server 202.118.1.130 May 24 18:09:55 smsl-n100 sudo[25878]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-05-24 18:08 May 24 18:09:55 smsl-n100 sudo[25878]: 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"