-- Logs begin at Thu 2019-02-14 18:11:59 CST, end at Thu 2025-11-06 00:25:19 CST. -- Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 wireless.js[563]: trying... Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 sudo[945]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 06 00:24:00 smsl-n100 sudo[945]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:00 smsl-n100 sudo[945]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 sh[482]: timed out Nov 06 00:24:00 smsl-n100 dhcpcd[540]: timed out Nov 06 00:24:00 smsl-n100 sh[482]: dhcpcd exited Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:00 smsl-n100 dhcpcd[540]: dhcpcd exited Nov 06 00:24:00 smsl-n100 sh[482]: ifup: failed to bring up eth0 Nov 06 00:24:00 smsl-n100 systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:24:00 smsl-n100 systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Nov 06 00:24:00 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:01 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:01 smsl-n100 wireless.js[563]: trying... Nov 06 00:24:01 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:01 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:01 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:01 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:01 smsl-n100 sudo[948]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 06 00:24:01 smsl-n100 sudo[948]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:01 smsl-n100 sudo[948]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:01 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:01 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:02 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:24:02 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:02 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:02 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:02 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:24:02 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:24:02 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:02 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:02 smsl-n100 wireless.js[563]: trying... Nov 06 00:24:02 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:02 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:02 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:02 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:02 smsl-n100 sudo[956]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 06 00:24:02 smsl-n100 sudo[956]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:02 smsl-n100 sudo[956]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:02 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:02 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:03 smsl-n100 wireless.js[563]: trying... Nov 06 00:24:03 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:03 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:03 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:03 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:03 smsl-n100 sudo[959]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 06 00:24:03 smsl-n100 sudo[959]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:03 smsl-n100 sudo[959]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:03 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:03 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:04 smsl-n100 wireless.js[563]: trying... Nov 06 00:24:04 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:04 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:04 smsl-n100 sudo[962]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Nov 06 00:24:04 smsl-n100 sudo[962]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:04 smsl-n100 sudo[962]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:04 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:04 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:04 smsl-n100 volumio-remote-updater[571]: [2025-11-06 00:24:04] [info] asio async_connect error: system:111 (Connection refused) Nov 06 00:24:04 smsl-n100 volumio-remote-updater[571]: [2025-11-06 00:24:04] [info] Error getting remote endpoint: system:107 (Transport endpoint is not connected) Nov 06 00:24:04 smsl-n100 volumio-remote-updater[571]: [2025-11-06 00:24:04] [error] handle_connect error: Underlying Transport Error Nov 06 00:24:04 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:04 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:05 smsl-n100 wireless.js[563]: Overtime, starting plan B Nov 06 00:24:05 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:05 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:05 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:05 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:05 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:05 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:05 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:06 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:06 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:06 smsl-n100 iwconfig[966]: Error for wireless request "Set Power Management" (8B2C) : Nov 06 00:24:06 smsl-n100 iwconfig[966]: SET failed on device wlan0 ; Operation not permitted. Nov 06 00:24:06 smsl-n100 systemd[1]: Started Wireless Services. Nov 06 00:24:06 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:06 smsl-n100 systemd[1]: Started Volumio Backend Module. Nov 06 00:24:06 smsl-n100 kernel: vdd_arm: ramp_delay not set Nov 06 00:24:06 smsl-n100 systemd[1]: Started Volumio Cpu Tweaker. Nov 06 00:24:06 smsl-n100 volumio-cpu-tweak[968]: Setting RT Priority for mpd Nov 06 00:24:06 smsl-n100 volumio-cpu-tweak[968]: Setting MPD Affinity Nov 06 00:24:06 smsl-n100 volumio-cpu-tweak[968]: pid 781's current affinity mask: f Nov 06 00:24:06 smsl-n100 volumio-cpu-tweak[968]: pid 781's new affinity mask: 3 Nov 06 00:24:06 smsl-n100 volumio-cpu-tweak[968]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Nov 06 00:24:06 smsl-n100 volumio-cpu-tweak[968]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Nov 06 00:24:06 smsl-n100 systemd[1]: volumio_cpu_tweak.service: Succeeded. Nov 06 00:24:07 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:24:07 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:24:07 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:24:12 smsl-n100 volumio[967]: info: ------------------------------------------- Nov 06 00:24:12 smsl-n100 volumio[967]: info: ----- Volumio3 ---- Nov 06 00:24:12 smsl-n100 volumio[967]: info: ------------------------------------------- Nov 06 00:24:12 smsl-n100 volumio[967]: info: ----- System startup ---- Nov 06 00:24:12 smsl-n100 volumio[967]: info: ------------------------------------------- Nov 06 00:24:12 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:24:12 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:24:12 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:24:14 smsl-n100 volumio[967]: info: MYVOLUMIO Environment detected Nov 06 00:24:14 smsl-n100 volumio[967]: info: Plugin folders cleanup Nov 06 00:24:14 smsl-n100 volumio[967]: info: Scanning into folder /volumio/app/plugins/ Nov 06 00:24:14 smsl-n100 volumio[967]: info: Scanning category audio_interface Nov 06 00:24:14 smsl-n100 volumio[967]: info: Scanning category miscellanea Nov 06 00:24:14 smsl-n100 volumio[967]: info: Scanning category music_service Nov 06 00:24:14 smsl-n100 volumio[967]: info: Scanning category plugins.json Nov 06 00:24:14 smsl-n100 volumio[967]: info: Scanning category system_controller Nov 06 00:24:14 smsl-n100 volumio[967]: info: Scanning category user_interface Nov 06 00:24:14 smsl-n100 volumio[967]: info: Scanning into folder /data/plugins/ Nov 06 00:24:14 smsl-n100 volumio[967]: info: Scanning category music_service Nov 06 00:24:14 smsl-n100 volumio[967]: info: Plugin folders cleanup completed Nov 06 00:24:14 smsl-n100 volumio[967]: info: ------------------------------------------- Nov 06 00:24:14 smsl-n100 volumio[967]: info: ----- Core plugins startup ---- Nov 06 00:24:14 smsl-n100 volumio[967]: info: ------------------------------------------- Nov 06 00:24:14 smsl-n100 volumio[967]: info: Loading plugins from folder /volumio/app/plugins/ Nov 06 00:24:14 smsl-n100 volumio[967]: info: Adding plugin bluetooth to MyMusic Plugins Nov 06 00:24:14 smsl-n100 volumio[967]: info: Adding plugin multiroom to MyMusic Plugins Nov 06 00:24:14 smsl-n100 volumio[967]: info: Adding plugin upnp to MyMusic Plugins Nov 06 00:24:14 smsl-n100 volumio[967]: info: Adding plugin metavolumio to MyMusic Plugins Nov 06 00:24:14 smsl-n100 volumio[967]: info: Adding plugin airplay_emulation to MyMusic Plugins Nov 06 00:24:14 smsl-n100 volumio[967]: info: Adding plugin cd_controller to MyMusic Plugins Nov 06 00:24:14 smsl-n100 volumio[967]: info: Adding plugin raat to MyMusic Plugins Nov 06 00:24:14 smsl-n100 volumio[967]: info: Adding plugin smart_inputs to MyMusic Plugins Nov 06 00:24:14 smsl-n100 volumio[967]: info: Adding plugin tidalconnect to MyMusic Plugins Nov 06 00:24:14 smsl-n100 volumio[967]: info: Adding plugin upnp_browser to MyMusic Plugins Nov 06 00:24:14 smsl-n100 volumio-remote-updater[571]: [2025-11-06 00:24:14] [connect] Successful connection Nov 06 00:24:14 smsl-n100 volumio[967]: info: Loading plugins from folder /data/plugins/ Nov 06 00:24:14 smsl-n100 volumio[967]: info: Loading plugin "system"... Nov 06 00:24:14 smsl-n100 volumio[967]: info: Loading plugin "appearance"... Nov 06 00:24:17 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:24:17 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:24:17 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:24:18 smsl-n100 volumio[967]: info: Loading plugin "network"... Nov 06 00:24:18 smsl-n100 volumio[967]: info: Refreshing Cached IP Addresses Nov 06 00:24:18 smsl-n100 sudo[1019]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 06 00:24:18 smsl-n100 sudo[1019]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:18 smsl-n100 sudo[1019]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:18 smsl-n100 sudo[1021]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 06 00:24:18 smsl-n100 sudo[1021]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:18 smsl-n100 sudo[1021]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:18 smsl-n100 volumio[967]: info: Loading plugin "services"... Nov 06 00:24:18 smsl-n100 volumio[967]: info: Loading plugin "alsa_controller"... Nov 06 00:24:18 smsl-n100 sudo[1030]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Nov 06 00:24:18 smsl-n100 sudo[1030]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:18 smsl-n100 volumio[967]: warn: Unable to locate the audio output device VMV D1 USB2.0. Please configure a valid output device. Nov 06 00:24:18 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 06 00:24:18 smsl-n100 volumio[967]: info: Loading plugin "wizard"... Nov 06 00:24:18 smsl-n100 volumio[967]: info: Loading plugin "networkfs"... Nov 06 00:24:18 smsl-n100 volumio[967]: info: Starting Udev Watcher for removable devices Nov 06 00:24:18 smsl-n100 sudo[1045]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.43/USB /mnt/NAS/USB Nov 06 00:24:18 smsl-n100 sudo[1045]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:18 smsl-n100 sudo[1047]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.54/USB /mnt/NAS/USB Nov 06 00:24:18 smsl-n100 sudo[1047]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:18 smsl-n100 volumio[967]: info: Ignoring mount for partition: boot Nov 06 00:24:18 smsl-n100 volumio[967]: info: Ignoring mount for partition: volumio Nov 06 00:24:18 smsl-n100 volumio[967]: info: Ignoring mount for partition: volumio_data Nov 06 00:24:18 smsl-n100 kernel: FS-Cache: Loaded Nov 06 00:24:18 smsl-n100 volumio[967]: info: Mounting Device HIFI_Audio_HD-1 Nov 06 00:24:18 smsl-n100 kernel: FS-Cache: Netfs 'cifs' registered for caching Nov 06 00:24:18 smsl-n100 kernel: Key type cifs.spnego registered Nov 06 00:24:18 smsl-n100 kernel: Key type cifs.idmap registered Nov 06 00:24:18 smsl-n100 kernel: CIFS: Attempting to mount //192.168.1.54/USB Nov 06 00:24:18 smsl-n100 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Nov 06 00:24:18 smsl-n100 kernel: CIFS: Attempting to mount //192.168.1.43/USB Nov 06 00:24:18 smsl-n100 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Nov 06 00:24:18 smsl-n100 sudo[1069]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sda1 /mnt/USB/HIFI_Audio_HD-1 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 06 00:24:18 smsl-n100 sudo[1069]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:18 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Nov 06 00:24:18 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -101 Nov 06 00:24:18 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Nov 06 00:24:18 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -101 Nov 06 00:24:18 smsl-n100 sudo[1045]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:18 smsl-n100 sudo[1047]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:21 smsl-n100 sudo[1030]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:22 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:24:22 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:24:22 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:24:24 smsl-n100 ntfs-3g[1077]: Version 2017.3.23AR.3 integrated FUSE 28 Nov 06 00:24:24 smsl-n100 ntfs-3g[1077]: Mounted /dev/sda1 (Read-Write, label "HIFI Audio HD-1", NTFS 3.1) Nov 06 00:24:24 smsl-n100 ntfs-3g[1077]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 06 00:24:24 smsl-n100 ntfs-3g[1077]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sda1,blkdev,blksize=4096 Nov 06 00:24:24 smsl-n100 ntfs-3g[1077]: Global ownership and permissions enforced, configuration type 7 Nov 06 00:24:24 smsl-n100 sudo[1069]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:24 smsl-n100 volumio[967]: info: Mounting Device HIFI_Audio_HD-2 Nov 06 00:24:24 smsl-n100 sudo[1083]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount /dev/sdb1 /mnt/USB/HIFI_Audio_HD-2 -o noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 06 00:24:24 smsl-n100 sudo[1083]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:27 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:24:27 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:24:27 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:24:29 smsl-n100 volumio-remote-updater[571]: [2025-11-06 00:24:29] [connect] Successful connection Nov 06 00:24:30 smsl-n100 sudo[1083]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:30 smsl-n100 ntfs-3g[1116]: Version 2017.3.23AR.3 integrated FUSE 28 Nov 06 00:24:30 smsl-n100 ntfs-3g[1116]: Mounted /dev/sdb1 (Read-Write, label "HIFI Audio HD-2", NTFS 3.1) Nov 06 00:24:30 smsl-n100 ntfs-3g[1116]: Cmdline options: rw,noatime,dmask=0000,fmask=0000,iocharset=utf8 Nov 06 00:24:30 smsl-n100 ntfs-3g[1116]: Mount options: iocharset=utf8,allow_other,nonempty,noatime,rw,default_permissions,fsname=/dev/sdb1,blkdev,blksize=4096 Nov 06 00:24:30 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 06 00:24:30 smsl-n100 ntfs-3g[1116]: Global ownership and permissions enforced, configuration type 7 Nov 06 00:24:30 smsl-n100 volumio[967]: info: Loading plugin "volumio_command_line_client"... Nov 06 00:24:30 smsl-n100 volumio[967]: info: Loading plugin "upnp"... Nov 06 00:24:30 smsl-n100 volumio[967]: info: [1762359870873] Starting Upmpd Daemon Nov 06 00:24:30 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 06 00:24:30 smsl-n100 volumio[967]: info: Loading plugin "my_music"... Nov 06 00:24:30 smsl-n100 volumio[967]: info: Loading plugin "mpd"... Nov 06 00:24:31 smsl-n100 volumio[967]: info: Loading plugin "upnp_browser"... Nov 06 00:24:32 smsl-n100 volumio[967]: info: Plugin bluetooth is not enabled Nov 06 00:24:32 smsl-n100 volumio[967]: info: Loading plugin "alarm-clock"... Nov 06 00:24:32 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:24:32 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:24:32 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:24:32 smsl-n100 volumio[967]: info: Loading plugin "manifestui"... Nov 06 00:24:33 smsl-n100 volumio[967]: info: Loading plugin "metavolumio"... Nov 06 00:24:36 smsl-n100 volumio[967]: info: Loading plugin "airplay_emulation"... Nov 06 00:24:36 smsl-n100 volumio[967]: info: Starting Shairport Sync Nov 06 00:24:36 smsl-n100 volumio[967]: info: Plugin cd_controller is not enabled Nov 06 00:24:36 smsl-n100 volumio[967]: info: Loading plugin "last_100"... Nov 06 00:24:36 smsl-n100 volumio[967]: info: Plugin raat is not enabled Nov 06 00:24:36 smsl-n100 volumio[967]: info: Loading plugin "streaming_services"... Nov 06 00:24:37 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:24:37 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:24:37 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:24:38 smsl-n100 volumio[967]: info: Starting Streaming Service Transparent Proxy Nov 06 00:24:38 smsl-n100 volumio[967]: info: Loading plugin "tidalconnect"... Nov 06 00:24:39 smsl-n100 ntpd[778]: error resolving pool 0.debian.pool.ntp.org: System error (-11) Nov 06 00:24:39 smsl-n100 volumio[967]: info: Loading plugin "webradio"... Nov 06 00:24:39 smsl-n100 volumio[967]: info: Loading plugin "i2s_dacs"... Nov 06 00:24:39 smsl-n100 volumio[967]: info: I2S DAC not set, start Auto-detection Nov 06 00:24:39 smsl-n100 volumio[967]: info: Loading plugin "volumiodiscovery"... Nov 06 00:24:39 smsl-n100 volumio[967]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 06 00:24:39 smsl-n100 volumio[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 06 00:24:39 smsl-n100 volumio[967]: *** WARNING *** For more information see Nov 06 00:24:39 smsl-n100 node[967]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Nov 06 00:24:39 smsl-n100 volumio[967]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 06 00:24:39 smsl-n100 volumio[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 06 00:24:39 smsl-n100 volumio[967]: *** WARNING *** For more information see Nov 06 00:24:39 smsl-n100 node[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 06 00:24:39 smsl-n100 node[967]: *** WARNING *** For more information see Nov 06 00:24:39 smsl-n100 node[967]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Nov 06 00:24:39 smsl-n100 node[967]: *** WARNING *** Please fix your application to use the native API of Avahi! Nov 06 00:24:39 smsl-n100 node[967]: *** WARNING *** For more information see Nov 06 00:24:39 smsl-n100 volumio[967]: info: Applying required configuration parameters for plugin volumiodiscovery Nov 06 00:24:39 smsl-n100 volumio[967]: info: Discovery: Started advertising with name: SMSL N100 Nov 06 00:24:39 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Nov 06 00:24:39 smsl-n100 volumio[967]: info: Loading plugin "spop"... Nov 06 00:24:40 smsl-n100 volumio[967]: STREAMING PROXY: Starting server on port 3245 Nov 06 00:24:41 smsl-n100 volumio[967]: Node JS runtime: 14 Nov 06 00:24:41 smsl-n100 ntpd[778]: error resolving pool 1.debian.pool.ntp.org: System error (-11) Nov 06 00:24:42 smsl-n100 ntpd[778]: error resolving pool 2.debian.pool.ntp.org: System error (-11) Nov 06 00:24:42 smsl-n100 volumio[967]: info: Plugin multiroom is not enabled Nov 06 00:24:42 smsl-n100 volumio[967]: info: Loading plugin "outputs"... Nov 06 00:24:42 smsl-n100 volumio[967]: info: Loading plugin "albumart"... Nov 06 00:24:42 smsl-n100 volumio[967]: info: Plugin example_plugin is not enabled Nov 06 00:24:42 smsl-n100 volumio[967]: info: Loading plugin "hi_res_audio"... Nov 06 00:24:42 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:24:42 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:24:42 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:24:43 smsl-n100 ntpd[778]: error resolving pool 3.debian.pool.ntp.org: System error (-11) Nov 06 00:24:43 smsl-n100 volumio[967]: Forking 3 albumart workers Nov 06 00:24:44 smsl-n100 volumio-remote-updater[571]: [2025-11-06 00:24:44] [connect] Successful connection Nov 06 00:24:45 smsl-n100 volumio[967]: Starting albumart workers Nov 06 00:24:45 smsl-n100 volumio[967]: Starting albumart workers Nov 06 00:24:45 smsl-n100 volumio[967]: Starting albumart workers Nov 06 00:24:46 smsl-n100 volumio[967]: info: Applying required configuration parameters for plugin hi_res_audio Nov 06 00:24:46 smsl-n100 volumio[967]: info: Loading plugin "inputs"... Nov 06 00:24:47 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:24:47 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:24:47 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:24:48 smsl-n100 volumio[967]: info: Loading plugin "qobuz"... Nov 06 00:24:50 smsl-n100 volumio[967]: info: Loading plugin "smart_inputs"... Nov 06 00:24:51 smsl-n100 volumio[967]: info: Loading plugin "tidal"... Nov 06 00:24:52 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:24:52 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:24:52 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:24:54 smsl-n100 volumio[967]: info: Loading plugin "oem_helper"... Nov 06 00:24:56 smsl-n100 volumio[967]: info: Applying required configuration parameters for plugin oem_helper Nov 06 00:24:56 smsl-n100 volumio[967]: info: Loading plugin "smslcontrol"... Nov 06 00:24:56 smsl-n100 kernel: gpio gpiochip0: Persistence not supported for GPIO 5 Nov 06 00:24:56 smsl-n100 volumio[967]: info: Adding this device properties Nov 06 00:24:56 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , setThisDeviceVolumioProperties Nov 06 00:24:56 smsl-n100 volumio[967]: info: Setting Additional Device Volumio Properties: [object Object] Nov 06 00:24:56 smsl-n100 volumio[967]: info: Loading plugin "updater_comm"... Nov 06 00:24:56 smsl-n100 volumio[967]: info: Plugin mpdemulation is not enabled Nov 06 00:24:56 smsl-n100 volumio[967]: info: Loading plugin "rest_api"... Nov 06 00:24:56 smsl-n100 volumio[967]: info: Loading plugin "websocket"... Nov 06 00:24:56 smsl-n100 volumio[967]: info: Starting Socket.io Server version 2.3.0 Nov 06 00:24:56 smsl-n100 volumio[967]: info: Loading i18n strings for locale en Nov 06 00:24:56 smsl-n100 volumio[967]: Updating browse sources language Nov 06 00:24:56 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::initPlayerControls Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 06 00:24:57 smsl-n100 volumio[967]: Express server listening on port 3000 Nov 06 00:24:57 smsl-n100 volumio[967]: [Metrics] WebUI: 47s 202.54ms Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreStateMachine::resetVolumioState Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreStateMachine::getcurrentVolume Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioRetrievevolume Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreStateMachine::pushState Nov 06 00:24:57 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioPushState Nov 06 00:24:57 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 00:24:57 smsl-n100 sudo[1265]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Nov 06 00:24:57 smsl-n100 sudo[1265]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:57 smsl-n100 sudo[1265]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:57 smsl-n100 sudo[1267]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Nov 06 00:24:57 smsl-n100 sudo[1267]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:57 smsl-n100 volumio[967]: error: Cannot read config.txt file: Error: ENOENT: no such file or directory, open '/boot/config.txt' Nov 06 00:24:57 smsl-n100 volumio[967]: info: Completed loading Core Plugins Nov 06 00:24:57 smsl-n100 sudo[1267]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:57 smsl-n100 volumio[967]: info: Preparing to generate the ALSA configuration file Nov 06 00:24:57 smsl-n100 volumio[967]: info: Volumio Network Manager: Network status updated: 0 Nov 06 00:24:57 smsl-n100 volumio[967]: info: Cannot mount NAS USB at system boot, trial number 1 ,retrying in 5 seconds Nov 06 00:24:57 smsl-n100 volumio[967]: info: Cannot mount NAS USB at system boot, trial number 1 ,retrying in 5 seconds Nov 06 00:24:57 smsl-n100 volumio[967]: info: Cannot read proc/cpuinfo: Error: Command failed: cat /proc/cpuinfo | grep Revision Nov 06 00:24:57 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:24:58 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:24:58 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:24:58 smsl-n100 volumio[967]: info: Reloading queue from file Nov 06 00:24:58 smsl-n100 volumio[967]: info: CoreStateMachine::setRepeat false single undefined Nov 06 00:24:58 smsl-n100 volumio[967]: info: CoreStateMachine::pushState Nov 06 00:24:58 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:24:58 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 06 00:24:58 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioPushState Nov 06 00:24:58 smsl-n100 volumio[967]: info: CoreStateMachine::setRandom null Nov 06 00:24:58 smsl-n100 volumio[967]: info: CoreStateMachine::pushState Nov 06 00:24:58 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:24:58 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioPushState Nov 06 00:24:58 smsl-n100 volumio[967]: info: Setting Device type: NanoPi NEO 3 Nov 06 00:24:58 smsl-n100 volumio[967]: info: Asound.conf file unchanged, so no further update is needed Nov 06 00:24:58 smsl-n100 volumio[967]: info: Output device has changed, restarting MPD Nov 06 00:24:58 smsl-n100 sudo[1283]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 06 00:24:58 smsl-n100 sudo[1283]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:58 smsl-n100 volumio[967]: info: Output device has changed, restarting Shairport Sync Nov 06 00:24:58 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:24:58 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 00:24:58 smsl-n100 sudo[1283]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:58 smsl-n100 sudo[1285]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 06 00:24:58 smsl-n100 sudo[1285]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:58 smsl-n100 systemd[1]: Stopping Music Player Daemon... Nov 06 00:24:58 smsl-n100 volumio[967]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 06 00:24:58 smsl-n100 volumio[967]: info: ___________ START PLUGINS ___________ Nov 06 00:24:58 smsl-n100 volumio[967]: info: ControllerMpd::onStart: Initializing MPD Nov 06 00:24:58 smsl-n100 volumio[967]: info: Creating MPD Configuration file Nov 06 00:24:58 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Nov 06 00:24:58 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 06 00:24:58 smsl-n100 volumio[967]: info: [1762359898521] CoreMusicLibrary::Adding element Media Servers Nov 06 00:24:58 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 06 00:24:59 smsl-n100 systemd[1]: mpd.service: Succeeded. Nov 06 00:24:59 smsl-n100 systemd[1]: Stopped Music Player Daemon. Nov 06 00:24:59 smsl-n100 systemd[1]: Starting Music Player Daemon... Nov 06 00:24:59 smsl-n100 sudo[1292]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Nov 06 00:24:59 smsl-n100 sudo[1292]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:59 smsl-n100 sudo[1294]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Nov 06 00:24:59 smsl-n100 sudo[1292]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:59 smsl-n100 sudo[1294]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:59 smsl-n100 volumio[967]: info: Adding METAVOLUMIO REST API Endpoints Nov 06 00:24:59 smsl-n100 volumio[967]: info: Adding metavolumio REST Endpoint for plugin: miscellanea/metavolumio Nov 06 00:24:59 smsl-n100 volumio[967]: info: Adding getSimilarArtists REST Endpoint for plugin: miscellanea/metavolumio Nov 06 00:24:59 smsl-n100 volumio[967]: info: Adding getSimilarAlbums REST Endpoint for plugin: miscellanea/metavolumio Nov 06 00:24:59 smsl-n100 volumio[967]: info: Adding getSimilarTracks REST Endpoint for plugin: miscellanea/metavolumio Nov 06 00:24:59 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:24:59 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 00:24:59 smsl-n100 systemd[1]: mpd.service: Control process exited, code=killed, status=15/TERM Nov 06 00:24:59 smsl-n100 systemd[1]: mpd.service: Succeeded. Nov 06 00:24:59 smsl-n100 systemd[1]: Stopped Music Player Daemon. Nov 06 00:24:59 smsl-n100 systemd[1]: Starting Music Player Daemon... Nov 06 00:24:59 smsl-n100 volumio[967]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 06 00:24:59 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:24:59 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 00:24:59 smsl-n100 volumio[967]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Nov 06 00:24:59 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 06 00:24:59 smsl-n100 volumio[967]: info: [1762359899615] CoreMusicLibrary::Adding element Last_100 Nov 06 00:24:59 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 06 00:24:59 smsl-n100 volumio[967]: info: Streaming services startup Nov 06 00:24:59 smsl-n100 volumio[967]: info: Starting Streaming Daemon Nov 06 00:24:59 smsl-n100 sudo[1315]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 06 00:24:59 smsl-n100 sudo[1315]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:59 smsl-n100 sudo[1315]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:59 smsl-n100 volumio-remote-updater[571]: [2025-11-06 00:24:59] [connect] Successful connection Nov 06 00:24:59 smsl-n100 sudo[1321]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Nov 06 00:24:59 smsl-n100 sudo[1321]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:59 smsl-n100 sudo[1321]: pam_unix(sudo:session): session closed for user root Nov 06 00:24:59 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Nov 06 00:24:59 smsl-n100 volumio[967]: info: [1762359899824] CoreMusicLibrary::Adding element Webradio Nov 06 00:24:59 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Nov 06 00:24:59 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 06 00:24:59 smsl-n100 volumio[967]: info: Initializing BBC Radios Nov 06 00:24:59 smsl-n100 sudo[1329]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Nov 06 00:24:59 smsl-n100 sudo[1329]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:24:59 smsl-n100 sudo[1329]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:00 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 06 00:25:00 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 00:25:00 smsl-n100 volumio[967]: info: Creating Spotify config file Nov 06 00:25:00 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:00 smsl-n100 volumio[967]: error: Hi Res Audio Failed Login: Missing Login Data Nov 06 00:25:00 smsl-n100 volumio[967]: info: Adding HIGHRESAUDIO REST API Endpoints Nov 06 00:25:00 smsl-n100 volumio[967]: info: Adding getStreamUrlhi_res_audio REST Endpoint for plugin: music_service/hi_res_audio Nov 06 00:25:00 smsl-n100 volumio[967]: info: Initializing Serial Communication on port /dev/ttyS1 Nov 06 00:25:00 smsl-n100 volumio[967]: info: Touch Event Listener Process Starting Nov 06 00:25:00 smsl-n100 volumio[967]: info: Adding inputs REST Endpoints Nov 06 00:25:00 smsl-n100 volumio[967]: info: Adding scanAudioInputs REST Endpoint for plugin: music_service/smart_inputs Nov 06 00:25:00 smsl-n100 volumio[967]: info: Scanning Audio Inputs Nov 06 00:25:00 smsl-n100 sudo[1343]: pam_unix(sudo:auth): conversation failed Nov 06 00:25:00 smsl-n100 sudo[1343]: pam_unix(sudo:auth): auth could not identify password for [volumio] Nov 06 00:25:00 smsl-n100 volumio[967]: info: Checking against Known Cards name Nov 06 00:25:00 smsl-n100 volumio[967]: info: Adding Server instance for streaming Nov 06 00:25:00 smsl-n100 volumio[967]: info: Refreshing TIDAL token Nov 06 00:25:00 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Nov 06 00:25:00 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 00:25:00 smsl-n100 volumio[967]: info: Initializing GPIOs Nov 06 00:25:00 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 1 Nov 06 00:25:00 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 15 Nov 06 00:25:00 smsl-n100 kernel: gpio gpiochip3: Persistence not supported for GPIO 0 Nov 06 00:25:00 smsl-n100 kernel: gpio gpiochip2: Persistence not supported for GPIO 2 Nov 06 00:25:00 smsl-n100 volumio[967]: info: Starting Button Listener Nov 06 00:25:00 smsl-n100 volumio[967]: info: Turning off Green LED Nov 06 00:25:00 smsl-n100 volumio[967]: info: Tuning on Blue LED Nov 06 00:25:00 smsl-n100 volumio[967]: info: Signaling System Ready Nov 06 00:25:00 smsl-n100 volumio[967]: info: Volumio Calling Home Nov 06 00:25:00 smsl-n100 volumio[967]: info: Stopping AccessToken refresher cron for QOBUZ Nov 06 00:25:00 smsl-n100 volumio[967]: info: AccessToken refresher cron started for QOBUZ Nov 06 00:25:00 smsl-n100 volumio[967]: info: Adding QOBUZ REST API Endpoints Nov 06 00:25:00 smsl-n100 volumio[967]: info: Adding getStreamUrlqobuz REST Endpoint for plugin: music_service/qobuz Nov 06 00:25:01 smsl-n100 volumio[967]: Unhandled rejection Error: No sockets available, cannot start. Nov 06 00:25:01 smsl-n100 volumio[967]: at SsdpClient.SSDP._createSockets (/volumio/node_modules/node-ssdp/lib/index.js:186:11) Nov 06 00:25:01 smsl-n100 volumio[967]: at SsdpClient.SSDP._start (/volumio/node_modules/node-ssdp/lib/index.js:229:10) Nov 06 00:25:01 smsl-n100 volumio[967]: at /volumio/node_modules/node-ssdp/lib/client.js:60:10 Nov 06 00:25:01 smsl-n100 volumio[967]: at Promise._execute (/volumio/node_modules/bluebird/js/release/debuggability.js:384:9) Nov 06 00:25:01 smsl-n100 volumio[967]: at Promise._resolveFromExecutor (/volumio/node_modules/bluebird/js/release/promise.js:518:18) Nov 06 00:25:01 smsl-n100 volumio[967]: at new Promise (/volumio/node_modules/bluebird/js/release/promise.js:103:10) Nov 06 00:25:01 smsl-n100 volumio[967]: at SsdpClient.start (/volumio/node_modules/node-ssdp/lib/client.js:54:10) Nov 06 00:25:01 smsl-n100 volumio[967]: at SsdpClient.search (/volumio/node_modules/node-ssdp/lib/client.js:82:17) Nov 06 00:25:01 smsl-n100 volumio[967]: at ControllerUPNPBrowser.onStart (/volumio/app/plugins/music_service/upnp_browser/index.js:126:12) Nov 06 00:25:01 smsl-n100 volumio[967]: at PluginManager.startCorePlugin (/volumio/app/pluginmanager.js:398:24) Nov 06 00:25:01 smsl-n100 volumio[967]: at HashMap. (/volumio/app/pluginmanager.js:496:31) Nov 06 00:25:01 smsl-n100 volumio[967]: at HashMap.forEach (/volumio/node_modules/hashmap/hashmap.js:171:10) Nov 06 00:25:01 smsl-n100 volumio[967]: at HashMap.proto. [as forEach] (/volumio/node_modules/hashmap/hashmap.js:201:7) Nov 06 00:25:01 smsl-n100 volumio[967]: at PluginManager.startCorePlugins (/volumio/app/pluginmanager.js:495:20) Nov 06 00:25:01 smsl-n100 volumio[967]: at Promise._successFn (/volumio/app/pluginmanager.js:142:29) Nov 06 00:25:01 smsl-n100 volumio[967]: at nextTickCallback (/volumio/node_modules/kew/kew.js:47:28) Nov 06 00:25:01 smsl-n100 volumio[967]: info: Serial port opened successfully Nov 06 00:25:01 smsl-n100 volumio[967]: info: Sending serial start messages Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:01 smsl-n100 volumio[967]: info: Reporting MCU Network Status: 0 Nov 06 00:25:01 smsl-n100 volumio[967]: error: No access token received for TIDAL Nov 06 00:25:01 smsl-n100 sudo[1374]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Nov 06 00:25:01 smsl-n100 sudo[1374]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:01 smsl-n100 volumio[967]: info: Touch Event Listener Process Closed Nov 06 00:25:01 smsl-n100 volumio[967]: error: Cannot start Volumio Streaming Daemon Nov 06 00:25:01 smsl-n100 volumio[967]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Nov 06 00:25:01 smsl-n100 volumio[967]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Nov 06 00:25:01 smsl-n100 volumio[967]: info: MPD Permissions set Nov 06 00:25:01 smsl-n100 volumio[967]: info: MPD Permissions set Nov 06 00:25:01 smsl-n100 systemd[1]: Started UPnP Renderer front-end to MPD. Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setDeviceVolumeOverride Nov 06 00:25:01 smsl-n100 volumio[967]: info: Setting Device Volume Override Nov 06 00:25:01 smsl-n100 sudo[1374]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioUpdateVolumeSettings Nov 06 00:25:01 smsl-n100 volumio[967]: info: Updating Volume Controller Parameters: Device: 5 Name: VMV D1 USB2.0 Mixer: Max Vol: 100 Vol Curve; logarithmic Vol Steps: 1 Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , setExternalVolume Nov 06 00:25:01 smsl-n100 volumio[967]: info: Disabling external Volume Control Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreStateMachine::pushState Nov 06 00:25:01 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioPushState Nov 06 00:25:01 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:01 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 06 00:25:01 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:01 smsl-n100 volumio[967]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreStateMachine::pushState Nov 06 00:25:01 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioPushState Nov 06 00:25:01 smsl-n100 volumio-remote-updater[571]: [2025-11-06 00:25:01] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1762359899 101 Nov 06 00:25:01 smsl-n100 volumio[967]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 Nov 06 00:25:01 smsl-n100 volumio[967]: info: Spotify config file written Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreStateMachine::pushState Nov 06 00:25:01 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioPushState Nov 06 00:25:01 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:01 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 06 00:25:01 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:01 smsl-n100 volumio[967]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreStateMachine::pushState Nov 06 00:25:01 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioPushState Nov 06 00:25:01 smsl-n100 sudo[1380]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Nov 06 00:25:01 smsl-n100 sudo[1380]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:01 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:01 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 06 00:25:01 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:01 smsl-n100 volumio[967]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreStateMachine::pushState Nov 06 00:25:01 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioPushState Nov 06 00:25:01 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:01 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 06 00:25:01 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:01 smsl-n100 volumio[967]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 06 00:25:01 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 0, message: undefined Nov 06 00:25:01 smsl-n100 systemd[1]: Started go-librespot Daemon. Nov 06 00:25:01 smsl-n100 go-librespot[1382]: go-librespot daemon starting... Nov 06 00:25:01 smsl-n100 sudo[1380]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:01 smsl-n100 volumio[967]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Nov 06 00:25:01 smsl-n100 volumio[967]: info: Upmpdcli Daemon Started Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:01 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: system , getCPUCoresNumber Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Nov 06 00:25:02 smsl-n100 volumio[967]: info: No need to fix Spotify hosts Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreStateMachine::pushState Nov 06 00:25:02 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioPushState Nov 06 00:25:02 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:02 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 06 00:25:02 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:02 smsl-n100 volumio[967]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 06 00:25:02 smsl-n100 go-librespot[1382]: time="2025-11-06T00:25:02+08:00" level=info msg="running go-librespot 0.4.0" Nov 06 00:25:02 smsl-n100 go-librespot[1382]: time="2025-11-06T00:25:02+08:00" level=debug msg="app state loaded" Nov 06 00:25:02 smsl-n100 go-librespot[1382]: time="2025-11-06T00:25:02+08:00" level=debug msg="stored credentials not found" Nov 06 00:25:02 smsl-n100 go-librespot[1382]: time="2025-11-06T00:25:02+08:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:25:02 smsl-n100 go-librespot[1382]: time="2025-11-06T00:25:02+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Nov 06 00:25:02 smsl-n100 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:25:02 smsl-n100 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:25:02 smsl-n100 volumio[967]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Nov 06 00:25:02 smsl-n100 volumio[967]: info: Starting Shairport Sync Nov 06 00:25:02 smsl-n100 volumio[967]: info: Starting Shairport Sync Nov 06 00:25:02 smsl-n100 sudo[1402]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 06 00:25:02 smsl-n100 sudo[1402]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:02 smsl-n100 volumio[967]: info: Starting Shairport Sync Nov 06 00:25:02 smsl-n100 systemd[1]: nmbd.service: Start operation timed out. Terminating. Nov 06 00:25:02 smsl-n100 systemd[1]: nmbd.service: Main process exited, code=killed, status=15/TERM Nov 06 00:25:02 smsl-n100 systemd[1]: nmbd.service: Failed with result 'timeout'. Nov 06 00:25:02 smsl-n100 systemd[1]: Failed to start Samba NMB Daemon. Nov 06 00:25:02 smsl-n100 sudo[1404]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 06 00:25:02 smsl-n100 sudo[1404]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreStateMachine::pushState Nov 06 00:25:02 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioPushState Nov 06 00:25:02 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:02 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 06 00:25:02 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:02 smsl-n100 volumio[967]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreStateMachine::pushState Nov 06 00:25:02 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioPushState Nov 06 00:25:02 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:02 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 06 00:25:02 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:02 smsl-n100 volumio[967]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 06 00:25:02 smsl-n100 sudo[1408]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Nov 06 00:25:02 smsl-n100 sudo[1408]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:02 smsl-n100 systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Nov 06 00:25:02 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioGetState Nov 06 00:25:02 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:02 smsl-n100 systemd[1]: Starting Samba Winbind Daemon... Nov 06 00:25:02 smsl-n100 systemd[1]: shairport-sync.service: Succeeded. Nov 06 00:25:02 smsl-n100 systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Nov 06 00:25:03 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:25:03 smsl-n100 systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Nov 06 00:25:03 smsl-n100 sudo[1404]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:03 smsl-n100 sudo[1402]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:03 smsl-n100 sudo[1408]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:03 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:25:03 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:25:03 smsl-n100 sudo[1417]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.54/USB /mnt/NAS/USB Nov 06 00:25:03 smsl-n100 sudo[1417]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:03 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioGetState Nov 06 00:25:03 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:03 smsl-n100 kernel: CIFS: Attempting to mount //192.168.1.54/USB Nov 06 00:25:03 smsl-n100 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Nov 06 00:25:03 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Nov 06 00:25:03 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -101 Nov 06 00:25:03 smsl-n100 sudo[1417]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:03 smsl-n100 sudo[1422]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.43/USB /mnt/NAS/USB Nov 06 00:25:03 smsl-n100 sudo[1422]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:03 smsl-n100 kernel: CIFS: Attempting to mount //192.168.1.43/USB Nov 06 00:25:03 smsl-n100 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Nov 06 00:25:03 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Nov 06 00:25:03 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -101 Nov 06 00:25:03 smsl-n100 sudo[1422]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:03 smsl-n100 volumio[967]: info: Shairport-Sync Started Nov 06 00:25:03 smsl-n100 volumio[967]: Error adding Membership: Error: addMembership EINVAL Nov 06 00:25:03 smsl-n100 volumio[967]: info: Shairport-Sync Started Nov 06 00:25:03 smsl-n100 volumio[967]: info: Shairport-Sync Started Nov 06 00:25:03 smsl-n100 volumio[967]: info: TidalConnect service stoped! Nov 06 00:25:03 smsl-n100 volumio[967]: info: Cannot mount NAS USB at system boot, trial number 2 ,retrying in 5 seconds Nov 06 00:25:03 smsl-n100 volumio[967]: info: Cannot mount NAS USB at system boot, trial number 2 ,retrying in 5 seconds Nov 06 00:25:03 smsl-n100 volumio[967]: info: CoreStateMachine::pushState Nov 06 00:25:03 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:03 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioPushState Nov 06 00:25:03 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:03 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: MAXVOL, message: Nov 06 00:25:03 smsl-n100 volumio[967]: error: Serial API: Failed to decode command: 100, message: undefined Nov 06 00:25:03 smsl-n100 volumio[967]: info: MCU Signalled PowerOff Capabilities, enabling MCU Poweroff Nov 06 00:25:03 smsl-n100 volumio[967]: info: Adding tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Nov 06 00:25:03 smsl-n100 volumio[967]: info: Adding tc_connect REST Endpoint for plugin: music_service/tidalconnect Nov 06 00:25:03 smsl-n100 winbindd[1410]: [2025/11/06 00:25:03.847932, 0] ../source3/winbindd/winbindd_cache.c:3160(initialize_winbindd_cache) Nov 06 00:25:03 smsl-n100 winbindd[1410]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Nov 06 00:25:03 smsl-n100 systemd[1]: Started Samba Winbind Daemon. Nov 06 00:25:03 smsl-n100 sudo[1439]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Nov 06 00:25:03 smsl-n100 sudo[1439]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:03 smsl-n100 winbindd[1410]: [2025/11/06 00:25:03.909289, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 06 00:25:03 smsl-n100 winbindd[1410]: daemon_ready: STATUS=daemon 'winbindd' finished starting up and ready to serve connections Nov 06 00:25:03 smsl-n100 systemd[1]: Starting Samba SMB Daemon... Nov 06 00:25:03 smsl-n100 systemd[1]: Started Volumio Tidal Connect Service. Nov 06 00:25:03 smsl-n100 sudo[1439]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:04 smsl-n100 volumio[967]: info: Executing endpoint tc_getconfig Nov 06 00:25:04 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Nov 06 00:25:04 smsl-n100 vtcs[1443]: STARTING TidalConnect services, version: 1.4.0.34 Nov 06 00:25:05 smsl-n100 vtcs[1443]: STARTED TidalConnect services. Nov 06 00:25:05 smsl-n100 volumio[967]: info: Executing endpoint tc_connect Nov 06 00:25:05 smsl-n100 volumio[967]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Nov 06 00:25:05 smsl-n100 volumio[967]: info: Connecting to TidalConnect Nov 06 00:25:05 smsl-n100 volumio[967]: info: CoreCommandRouter::servicePushState Nov 06 00:25:05 smsl-n100 volumio[967]: info: CoreStateMachine::pushState Nov 06 00:25:05 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:05 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioPushState Nov 06 00:25:05 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:05 smsl-n100 volumio[967]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Nov 06 00:25:05 smsl-n100 volumio[967]: info: CoreCommandRouter::servicePushState Nov 06 00:25:05 smsl-n100 volumio[967]: info: CoreStateMachine::pushState Nov 06 00:25:05 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:05 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioPushState Nov 06 00:25:05 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:05 smsl-n100 volumio[967]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current tidal Received tidalconnect Nov 06 00:25:05 smsl-n100 mpd[1322]: Nov 06 00:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 06 00:25:05 smsl-n100 smbd[1446]: [2025/11/06 00:25:05.311876, 0] ../lib/util/become_daemon.c:138(daemon_ready) Nov 06 00:25:05 smsl-n100 systemd[1]: Started Samba SMB Daemon. Nov 06 00:25:05 smsl-n100 smbd[1446]: daemon_ready: STATUS=daemon 'smbd' finished starting up and ready to serve connections Nov 06 00:25:05 smsl-n100 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 06 00:25:05 smsl-n100 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Nov 06 00:25:05 smsl-n100 systemd[1]: Stopped go-librespot Daemon. Nov 06 00:25:05 smsl-n100 systemd[1]: Started go-librespot Daemon. Nov 06 00:25:05 smsl-n100 volumio[967]: info: go-librespot daemon successfully initialized Nov 06 00:25:05 smsl-n100 go-librespot[1470]: go-librespot daemon starting... Nov 06 00:25:05 smsl-n100 go-librespot[1470]: time="2025-11-06T00:25:05+08:00" level=info msg="running go-librespot 0.4.0" Nov 06 00:25:05 smsl-n100 go-librespot[1470]: time="2025-11-06T00:25:05+08:00" level=debug msg="app state loaded" Nov 06 00:25:05 smsl-n100 go-librespot[1470]: time="2025-11-06T00:25:05+08:00" level=debug msg="stored credentials not found" Nov 06 00:25:05 smsl-n100 go-librespot[1470]: time="2025-11-06T00:25:05+08:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:25:05 smsl-n100 go-librespot[1470]: time="2025-11-06T00:25:05+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Nov 06 00:25:05 smsl-n100 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:25:05 smsl-n100 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:25:07 smsl-n100 volumio[967]: info: TidalConnect service started! Nov 06 00:25:07 smsl-n100 kernel: rk_gmac-dwmac ff540000.ethernet eth0: Link is Up - 1Gbps/Full - flow control off Nov 06 00:25:07 smsl-n100 dhcpcd[620]: eth0: carrier acquired Nov 06 00:25:07 smsl-n100 dhcpcd[620]: eth0: IAID d8:37:3d:c1 Nov 06 00:25:07 smsl-n100 dhcpcd[620]: eth0: soliciting an IPv6 router Nov 06 00:25:08 smsl-n100 dhcpcd[620]: eth0: soliciting a DHCP lease Nov 06 00:25:08 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:25:08 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:25:08 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:25:08 smsl-n100 ifplugd(eth0)[774]: Link beat detected. Nov 06 00:25:08 smsl-n100 ifplugd(eth0)[774]: Executing '/etc/ifplugd/ifplugd.action eth0 up'. Nov 06 00:25:08 smsl-n100 ifplugd(eth0)[774]: client: sending commands to master dhcpcd process Nov 06 00:25:08 smsl-n100 dhcpcd[1505]: sending commands to master dhcpcd process Nov 06 00:25:08 smsl-n100 dhcpcd[620]: control command: /sbin/dhcpcd eth0 Nov 06 00:25:08 smsl-n100 volumio[967]: info: Initializing connection to go-librespot Websocket Nov 06 00:25:08 smsl-n100 volumio[967]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:25:08 smsl-n100 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 06 00:25:08 smsl-n100 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Nov 06 00:25:08 smsl-n100 systemd[1]: Stopped go-librespot Daemon. Nov 06 00:25:08 smsl-n100 sudo[1526]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.43/USB /mnt/NAS/USB Nov 06 00:25:08 smsl-n100 sudo[1526]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:08 smsl-n100 systemd[1]: Started go-librespot Daemon. Nov 06 00:25:08 smsl-n100 kernel: CIFS: Attempting to mount //192.168.1.43/USB Nov 06 00:25:08 smsl-n100 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Nov 06 00:25:08 smsl-n100 go-librespot[1529]: go-librespot daemon starting... Nov 06 00:25:08 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Nov 06 00:25:08 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -101 Nov 06 00:25:08 smsl-n100 sudo[1526]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:08 smsl-n100 sudo[1532]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.54/USB /mnt/NAS/USB Nov 06 00:25:08 smsl-n100 sudo[1532]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:08 smsl-n100 volumio[967]: info: Cannot mount NAS USB at system boot, trial number 3 ,retrying in 5 seconds Nov 06 00:25:08 smsl-n100 go-librespot[1529]: time="2025-11-06T00:25:08+08:00" level=info msg="running go-librespot 0.4.0" Nov 06 00:25:08 smsl-n100 go-librespot[1529]: time="2025-11-06T00:25:08+08:00" level=debug msg="app state loaded" Nov 06 00:25:08 smsl-n100 go-librespot[1529]: time="2025-11-06T00:25:08+08:00" level=debug msg="stored credentials not found" Nov 06 00:25:08 smsl-n100 kernel: CIFS: Attempting to mount //192.168.1.54/USB Nov 06 00:25:08 smsl-n100 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Nov 06 00:25:08 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Nov 06 00:25:08 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -101 Nov 06 00:25:08 smsl-n100 go-librespot[1529]: time="2025-11-06T00:25:08+08:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:25:08 smsl-n100 sudo[1532]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:08 smsl-n100 go-librespot[1529]: time="2025-11-06T00:25:08+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Nov 06 00:25:08 smsl-n100 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:25:08 smsl-n100 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:25:08 smsl-n100 volumio[967]: info: Cannot mount NAS USB at system boot, trial number 3 ,retrying in 5 seconds Nov 06 00:25:08 smsl-n100 ifplugd(eth0)[774]: Program executed successfully. Nov 06 00:25:09 smsl-n100 systemd[1]: mpd.service: Main process exited, code=killed, status=15/TERM Nov 06 00:25:09 smsl-n100 systemd[1]: mpd.service: Failed with result 'protocol'. Nov 06 00:25:09 smsl-n100 systemd[1]: Failed to start Music Player Daemon. Nov 06 00:25:09 smsl-n100 sudo[1285]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:09 smsl-n100 sudo[1294]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:09 smsl-n100 volumio[967]: info: Cannot restart MPD: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Nov 06 00:25:09 smsl-n100 volumio[967]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Nov 06 00:25:09 smsl-n100 volumio[967]: See "systemctl status mpd.service" and "journalctl -xe" for details. Nov 06 00:25:09 smsl-n100 volumio[967]: error: Cannot start MPD on system Start: Error: Command failed: /usr/bin/sudo /bin/systemctl restart mpd.service Nov 06 00:25:09 smsl-n100 volumio[967]: Job for mpd.service failed because the service did not take the steps required by its unit configuration. Nov 06 00:25:09 smsl-n100 volumio[967]: See "systemctl status mpd.service" and "journalctl -xe" for details. Nov 06 00:25:09 smsl-n100 volumio[967]: info: Completed starting Core Plugins Nov 06 00:25:09 smsl-n100 volumio[967]: info: ------------------------------------------- Nov 06 00:25:09 smsl-n100 systemd[1]: Starting Music Player Daemon... Nov 06 00:25:09 smsl-n100 volumio[967]: info: ----- MyVolumio plugins startup ---- Nov 06 00:25:09 smsl-n100 volumio[967]: info: ------------------------------------------- Nov 06 00:25:09 smsl-n100 volumio[967]: info: [MyVolumio PluginManager] Fetching plans data.... Nov 06 00:25:10 smsl-n100 sudo[1555]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Nov 06 00:25:10 smsl-n100 sudo[1555]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:10 smsl-n100 sudo[1555]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:11 smsl-n100 volumio[967]: info: Initializing connection to go-librespot Websocket Nov 06 00:25:11 smsl-n100 volumio[967]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:25:11 smsl-n100 volumio[967]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=nanopineo3&variante=smsl&version=3.789&uuid=d59522d0729b6d1568b6a7fce0a621e4" http://updates.volumio.org/downloader-v1/track-device Nov 06 00:25:11 smsl-n100 volumio[967]: % Total % Received % Xferd Average Speed Time Time Time Current Nov 06 00:25:11 smsl-n100 volumio[967]: Dload Upload Total Spent Left Speed Nov 06 00:25:11 smsl-n100 volumio[967]: [132B blob data] Nov 06 00:25:11 smsl-n100 volumio[967]: retrying in 5 seconds, trial 0 Nov 06 00:25:11 smsl-n100 volumio[967]: info: Volumio Calling Home Nov 06 00:25:12 smsl-n100 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 06 00:25:12 smsl-n100 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Nov 06 00:25:12 smsl-n100 systemd[1]: Stopped go-librespot Daemon. Nov 06 00:25:12 smsl-n100 systemd[1]: Started go-librespot Daemon. Nov 06 00:25:12 smsl-n100 go-librespot[1571]: go-librespot daemon starting... Nov 06 00:25:12 smsl-n100 go-librespot[1571]: time="2025-11-06T00:25:12+08:00" level=info msg="running go-librespot 0.4.0" Nov 06 00:25:12 smsl-n100 go-librespot[1571]: time="2025-11-06T00:25:12+08:00" level=debug msg="app state loaded" Nov 06 00:25:12 smsl-n100 go-librespot[1571]: time="2025-11-06T00:25:12+08:00" level=debug msg="stored credentials not found" Nov 06 00:25:12 smsl-n100 go-librespot[1571]: time="2025-11-06T00:25:12+08:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:25:12 smsl-n100 go-librespot[1571]: time="2025-11-06T00:25:12+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Nov 06 00:25:12 smsl-n100 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:25:12 smsl-n100 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:25:12 smsl-n100 dhcpcd[620]: eth0: offered 192.168.1.54 from 192.168.1.254 Nov 06 00:25:12 smsl-n100 dhcpcd[620]: eth0: probing address 192.168.1.54/24 Nov 06 00:25:13 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:25:13 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Date not found in response Nov 06 00:25:13 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Retrying in 5 seconds... Nov 06 00:25:13 smsl-n100 mpd[1567]: Nov 06 00:25 : decoder: Decoder plugin 'wildmidi' is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Nov 06 00:25:13 smsl-n100 sudo[1584]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.43/USB /mnt/NAS/USB Nov 06 00:25:13 smsl-n100 sudo[1584]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:13 smsl-n100 kernel: CIFS: Attempting to mount //192.168.1.43/USB Nov 06 00:25:13 smsl-n100 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Nov 06 00:25:13 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Nov 06 00:25:13 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -101 Nov 06 00:25:13 smsl-n100 sudo[1584]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:13 smsl-n100 volumio[967]: info: Cannot mount NAS USB at system boot, trial number 4 ,retrying in 5 seconds Nov 06 00:25:13 smsl-n100 sudo[1589]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o guest,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.1.54/USB /mnt/NAS/USB Nov 06 00:25:13 smsl-n100 sudo[1589]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:13 smsl-n100 kernel: CIFS: Attempting to mount //192.168.1.54/USB Nov 06 00:25:13 smsl-n100 kernel: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3 (or SMB2.1) specify vers=1.0 on mount. Nov 06 00:25:13 smsl-n100 kernel: CIFS VFS: Error connecting to socket. Aborting operation. Nov 06 00:25:13 smsl-n100 kernel: CIFS VFS: cifs_mount failed w/return code = -101 Nov 06 00:25:13 smsl-n100 sudo[1589]: pam_unix(sudo:session): session closed for user root Nov 06 00:25:13 smsl-n100 volumio[967]: info: Cannot mount NAS USB at system boot, trial number 4 ,retrying in 5 seconds Nov 06 00:25:14 smsl-n100 volumio[967]: info: Initializing connection to go-librespot Websocket Nov 06 00:25:14 smsl-n100 volumio[967]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:25:15 smsl-n100 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 06 00:25:15 smsl-n100 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Nov 06 00:25:15 smsl-n100 systemd[1]: Stopped go-librespot Daemon. Nov 06 00:25:15 smsl-n100 systemd[1]: Started go-librespot Daemon. Nov 06 00:25:15 smsl-n100 go-librespot[1593]: go-librespot daemon starting... Nov 06 00:25:15 smsl-n100 go-librespot[1593]: time="2025-11-06T00:25:15+08:00" level=info msg="running go-librespot 0.4.0" Nov 06 00:25:15 smsl-n100 go-librespot[1593]: time="2025-11-06T00:25:15+08:00" level=debug msg="app state loaded" Nov 06 00:25:15 smsl-n100 go-librespot[1593]: time="2025-11-06T00:25:15+08:00" level=debug msg="stored credentials not found" Nov 06 00:25:15 smsl-n100 go-librespot[1593]: time="2025-11-06T00:25:15+08:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:25:15 smsl-n100 go-librespot[1593]: time="2025-11-06T00:25:15+08:00" level=fatal msg="failed running zeroconf" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Nov 06 00:25:15 smsl-n100 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Nov 06 00:25:15 smsl-n100 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Nov 06 00:25:16 smsl-n100 dhcpcd[620]: eth0: leased 192.168.1.54 for 86400 seconds Nov 06 00:25:16 smsl-n100 dhcpcd[620]: eth0: adding route to 192.168.1.0/24 Nov 06 00:25:16 smsl-n100 avahi-daemon[564]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.1.54. Nov 06 00:25:16 smsl-n100 avahi-daemon[564]: New relevant interface eth0.IPv4 for mDNS. Nov 06 00:25:16 smsl-n100 dhcpcd[620]: eth0: adding default route via 192.168.1.254 Nov 06 00:25:16 smsl-n100 avahi-daemon[564]: Registering new address record for 192.168.1.54 on eth0.IPv4. Nov 06 00:25:17 smsl-n100 ntpd[778]: ntpd exiting on signal 15 (Terminated) Nov 06 00:25:17 smsl-n100 systemd[1]: Stopping Network Time Service... Nov 06 00:25:17 smsl-n100 systemd[1]: ntp.service: Succeeded. Nov 06 00:25:17 smsl-n100 systemd[1]: Stopped Network Time Service. Nov 06 00:25:17 smsl-n100 systemd[1]: Starting Network Time Service... Nov 06 00:25:17 smsl-n100 ntpd[1634]: ntpd 4.2.8p12@1.3728-o (1): Starting Nov 06 00:25:17 smsl-n100 ntpd[1634]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103 Nov 06 00:25:17 smsl-n100 systemd[1]: Started Network Time Service. Nov 06 00:25:17 smsl-n100 ntpd[1640]: proto: precision = 1.166 usec (-20) Nov 06 00:25:17 smsl-n100 ntpd[1640]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature Nov 06 00:25:17 smsl-n100 ntpd[1640]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37 Nov 06 00:25:17 smsl-n100 ntpd[1640]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 1044 days ago Nov 06 00:25:17 smsl-n100 ntpd[1640]: Listen and drop on 0 v6wildcard [::]:123 Nov 06 00:25:17 smsl-n100 ntpd[1640]: Listen and drop on 1 v4wildcard 0.0.0.0:123 Nov 06 00:25:17 smsl-n100 ntpd[1640]: Listen normally on 2 lo 127.0.0.1:123 Nov 06 00:25:17 smsl-n100 ntpd[1640]: Listen normally on 3 eth0 192.168.1.54:123 Nov 06 00:25:17 smsl-n100 ntpd[1640]: Listening on routing socket on fd #20 for interface updates Nov 06 00:25:17 smsl-n100 ntpd[1640]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 06 00:25:17 smsl-n100 ntpd[1640]: kernel reports TIME_ERROR: 0x41: Clock Unsynchronized Nov 06 00:25:17 smsl-n100 volumio[967]: info: Initializing connection to go-librespot Websocket Nov 06 00:25:17 smsl-n100 volumio[967]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Nov 06 00:25:17 smsl-n100 volumio[967]: info: Discovery: adding c32a3912-3ad2-4e38-b09f-ae11ecd87622 Nov 06 00:25:17 smsl-n100 volumio[967]: info: Discovery: Found device SMSL N100 Nov 06 00:25:17 smsl-n100 volumio[967]: info: CoreCommandRouter::volumioGetState Nov 06 00:25:17 smsl-n100 volumio[967]: info: CorePlayQueue::getTrack 0 Nov 06 00:25:17 smsl-n100 volumio[967]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 06 00:25:17 smsl-n100 volumio[967]: Error: The value NaN is not a number Nov 06 00:25:17 smsl-n100 volumio[967]: at Config.forceToType (/volumio/node_modules/v-conf/index.js:322:20) Nov 06 00:25:17 smsl-n100 volumio[967]: at Config.set (/volumio/node_modules/v-conf/index.js:153:25) Nov 06 00:25:17 smsl-n100 volumio[967]: at ControllerVolumioDiscovery.updateMultiroomDevice (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:323:25) Nov 06 00:25:17 smsl-n100 volumio[967]: at ControllerVolumioDiscovery.connectToRemoteVolumio (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:273:10) Nov 06 00:25:17 smsl-n100 volumio[967]: at Browser. (/volumio/app/plugins/system_controller/volumiodiscovery/index.js:192:12) Nov 06 00:25:17 smsl-n100 volumio[967]: at Browser.emit (events.js:400:28) Nov 06 00:25:17 smsl-n100 volumio[967]: at on_resolver_done (/volumio/node_modules/mdns/lib/browser.js:31:14) Nov 06 00:25:17 smsl-n100 volumio[967]: at next (/volumio/node_modules/mdns/lib/browser.js:106:7) Nov 06 00:25:17 smsl-n100 volumio[967]: at Array.makeAddressesUnique (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:177:5) Nov 06 00:25:17 smsl-n100 volumio[967]: at next (/volumio/node_modules/mdns/lib/browser.js:109:21) Nov 06 00:25:17 smsl-n100 volumio[967]: at /volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:160:11 Nov 06 00:25:17 smsl-n100 volumio[967]: at getaddrinfo_complete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:108:7) Nov 06 00:25:17 smsl-n100 volumio[967]: at GetAddrInfoReqWrap.oncomplete (/volumio/node_modules/mdns/lib/resolver_sequence_tasks.js:120:9) Nov 06 00:25:17 smsl-n100 volumio[967]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Nov 06 00:25:18 smsl-n100 ntpd[1640]: Soliciting pool server 103.16.182.23 Nov 06 00:25:18 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Fetching time from Volumio... Nov 06 00:25:18 smsl-n100 systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart. Nov 06 00:25:18 smsl-n100 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Nov 06 00:25:18 smsl-n100 systemd[1]: Stopped go-librespot Daemon. Nov 06 00:25:18 smsl-n100 systemd[1]: Started go-librespot Daemon. Nov 06 00:25:18 smsl-n100 go-librespot[1655]: go-librespot daemon starting... Nov 06 00:25:18 smsl-n100 go-librespot[1655]: time="2025-11-06T00:25:18+08:00" level=info msg="running go-librespot 0.4.0" Nov 06 00:25:18 smsl-n100 go-librespot[1655]: time="2025-11-06T00:25:18+08:00" level=debug msg="app state loaded" Nov 06 00:25:18 smsl-n100 go-librespot[1655]: time="2025-11-06T00:25:18+08:00" level=debug msg="stored credentials not found" Nov 06 00:25:18 smsl-n100 go-librespot[1655]: time="2025-11-06T00:25:18+08:00" level=info msg="api server listening on 127.0.0.1:9879" Nov 06 00:25:18 smsl-n100 volumio-time-update[529]: volumio-time-update-util: Setting system time to: 2025-11-06 09:32:13 Nov 06 00:25:18 smsl-n100 sudo[1663]: root : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/usr/bin/timedatectl set-time 2025-11-06 09:32:13 Nov 06 00:25:18 smsl-n100 sudo[1663]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:18 smsl-n100 dbus-daemon[535]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.11' (uid=0 pid=1664 comm="timedatectl set-time 2025-11-06 09:32:13 ") Nov 06 00:25:18 smsl-n100 systemd[1]: Starting Time & Date Service... Nov 06 00:25:18 smsl-n100 systemd[1]: Started Music Player Daemon. Nov 06 00:25:18 smsl-n100 mpd_monitor.sh[544]: MPD restarted due to no mpc output. Nov 06 00:25:19 smsl-n100 sudo[1671]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-11-06 00:24 Nov 06 00:25:19 smsl-n100 sudo[1671]: pam_unix(sudo:session): session opened for user root by (uid=0) Nov 06 00:25:19 smsl-n100 dbus-daemon[535]: [system] Successfully activated service 'org.freedesktop.timedate1' Nov 06 00:25:19 smsl-n100 systemd[1]: Started Time & Date Service. Nov 06 00:25:19 smsl-n100 ntpd[1640]: Soliciting pool server 45.249.91.156 Nov 06 09:32:13 smsl-n100 systemd-timedated[1665]: Changed local time to Thu Nov 6 09:32:13 2025 Nov 06 09:32:13 smsl-n100 sudo[1663]: pam_unix(sudo:session): session closed for user root Nov 06 09:32:13 smsl-n100 volumio-time-update[529]: volumio-time-update-util: System time updated successfully. Nov 06 09:32:13 smsl-n100 systemd[1]: Started Volumio Time Update Utility. 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"