Dec 25 05:01:02 pi2aes volumio[1210]: info: Discovery: adding 6b823da1-ff7a-4a9c-8efe-582264ea08a1 Dec 25 05:01:02 pi2aes volumio[1210]: info: Discovery: Found device Pi2AES Dec 25 05:01:02 pi2aes volumio[1210]: info: CoreCommandRouter::volumioGetState Dec 25 05:01:02 pi2aes volumio[1210]: info: CorePlayQueue::getTrack 0 Dec 25 05:01:02 pi2aes volumio[1210]: info: Discovery: this is already registered, 6b823da1-ff7a-4a9c-8efe-582264ea08a1 Dec 25 05:01:02 pi2aes volumio[1210]: info: Discovery: Found device Pi2AES Dec 25 05:01:02 pi2aes volumio[1210]: info: CoreCommandRouter::volumioGetState Dec 25 05:01:02 pi2aes volumio[1210]: info: CorePlayQueue::getTrack 0 Dec 25 05:01:02 pi2aes volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 25 05:01:02 pi2aes volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 05:01:02 pi2aes volumio[1210]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 25 05:01:03 pi2aes volumio[1210]: error: MyVolumio Plugin failed to start in a timely fashion Dec 25 05:01:03 pi2aes volumio[1210]: [Metrics] CommandRouter: 47s 197.40ms Dec 25 05:01:03 pi2aes volumio[1210]: info: CoreCommandRouter::volumiosetStartupVolume Dec 25 05:01:03 pi2aes volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 25 05:01:03 pi2aes volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 25 05:01:03 pi2aes volumio[1210]: info: CoreCommandRouter::Close All Modals sent Dec 25 05:01:03 pi2aes volumio[1210]: info: CoreCommandRouter::Close All Modals sent Dec 25 05:01:04 pi2aes kernel: CIFS: VFS: Error connecting to socket. Aborting operation. Dec 25 05:01:04 pi2aes kernel: CIFS: VFS: cifs_mount failed w/return code = -115 Dec 25 05:01:04 pi2aes sudo[1790]: pam_unix(sudo:session): session closed for user root Dec 25 05:01:05 pi2aes ntpd[1013]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 25 05:01:05 pi2aes ntpd[1013]: DNS: dns_check: DNS error: -11, System error Dec 25 05:01:05 pi2aes ntpd[1013]: DNS: dns_take_status: 0.debian.pool.ntp.org=>error, 12 Dec 25 05:01:14 pi2aes volumio[1210]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Dec 25 05:01:14 pi2aes volumio[1210]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Dec 25 05:01:14 pi2aes volumio[1210]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Dec 25 05:01:14 pi2aes volumio[1210]: info: BOOT COMPLETED Dec 25 05:01:16 pi2aes dhcpcd[692]: eth0: offered 192.168.50.106 from 192.168.50.1 Dec 25 05:01:16 pi2aes dhcpcd[834]: eth0: offered 192.168.50.106 from 192.168.50.1 Dec 25 05:01:16 pi2aes dhcpcd[834]: eth0: probing address 192.168.50.106/24 Dec 25 05:01:16 pi2aes dhcpcd[692]: eth0: probing address 192.168.50.106/24 Dec 25 05:01:18 pi2aes volumio[1210]: info: FusionDsp - ---- read samplerate, raw: 44100,S32_LE,2,32 Dec 25 05:01:18 pi2aes volumio[1210]: info: FusionDsp - ---- read samplerate from file: 44100 Dec 25 05:01:18 pi2aes volumio[1210]: info: camilladsp stopping service pid 1604... Dec 25 05:01:18 pi2aes volumio[1210]: grep: /proc/1604/cmdline: No such file or directory Dec 25 05:01:18 pi2aes volumio[1210]: info: camilladsp service terminated, instance 1 Dec 25 05:01:18 pi2aes volumio[1210]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 25 05:01:18 pi2aes volumio[1210]: info: FusionDsp - Nulleq applied Dec 25 05:01:18 pi2aes volumio[1210]: info: camilladsp service started and running in background, instance 1 Dec 25 05:01:18 pi2aes volumio[1210]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 25 05:01:18 pi2aes volumio[1210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 25 05:01:18 pi2aes volumio[1210]: info: Cannot mount NAS SONIC at system boot, trial number 3 ,retrying in 5 seconds Dec 25 05:01:19 pi2aes volumio[1210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 25 05:01:21 pi2aes volumio[1210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 Dec 25 05:01:21 pi2aes dhcpcd[692]: eth0: leased 192.168.50.106 for 86400 seconds Dec 25 05:01:21 pi2aes dhcpcd[692]: eth0: adding route to 192.168.50.0/24 Dec 25 05:01:21 pi2aes dhcpcd[692]: eth0: changing default route via 192.168.50.1 Dec 25 05:01:21 pi2aes avahi-daemon[757]: Registering new address record for 192.168.50.106 on eth0.IPv4. Dec 25 05:01:21 pi2aes dhcpcd[834]: eth0: pid 692 deleted default route Dec 25 05:01:21 pi2aes systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 25 05:01:21 pi2aes systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 25 05:01:21 pi2aes systemd[1]: welcome.service: Deactivated successfully. Dec 25 05:01:21 pi2aes systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 25 05:01:21 pi2aes systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 25 05:01:21 pi2aes dhcpcd[692]: eth0: deleting route to 169.254.0.0/16 Dec 25 05:01:21 pi2aes avahi-daemon[757]: Withdrawing address record for 169.254.85.147 on eth0. Dec 25 05:01:21 pi2aes avahi-daemon[757]: Leaving mDNS multicast group on interface eth0.IPv4 with address 169.254.85.147. Dec 25 05:01:21 pi2aes avahi-daemon[757]: Joining mDNS multicast group on interface eth0.IPv4 with address 192.168.50.106. Dec 25 05:01:21 pi2aes dhcpcd[834]: eth0: pid 692 deleted IP address 169.254.85.147/16 Dec 25 05:01:21 pi2aes dhcpcd[834]: eth0: deleting route to 169.254.0.0/16 Dec 25 05:01:21 pi2aes systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 25 05:01:21 pi2aes welcome[1894]: Resolved ip:[1] 192.168.50.106 Dec 25 05:01:21 pi2aes systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 25 05:01:21 pi2aes systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 25 05:01:21 pi2aes dhcpcd[834]: eth0: probing for an IPv4LL address Dec 25 05:01:21 pi2aes systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 25 05:01:21 pi2aes systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 25 05:01:21 pi2aes systemd[1]: welcome.service: Deactivated successfully. Dec 25 05:01:21 pi2aes systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 25 05:01:21 pi2aes systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 25 05:01:21 pi2aes systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 25 05:01:21 pi2aes welcome[1943]: Resolved ip:[1] 192.168.50.106 Dec 25 05:01:21 pi2aes systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 25 05:01:21 pi2aes systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 25 05:01:21 pi2aes dhcpcd[834]: eth0: leased 192.168.50.106 for 86400 seconds Dec 25 05:01:21 pi2aes dhcpcd[834]: eth0: adding route to 192.168.50.0/24 Dec 25 05:01:21 pi2aes dhcpcd[834]: eth0: adding default route via 192.168.50.1 Dec 25 05:01:22 pi2aes systemd[1]: Stopped target ip-changed@eth0.target - IP Address changed on eth0. Dec 25 05:01:22 pi2aes systemd[1]: Stopping ip-changed@eth0.target - IP Address changed on eth0... Dec 25 05:01:22 pi2aes systemd[1]: welcome.service: Deactivated successfully. Dec 25 05:01:22 pi2aes systemd[1]: Stopped welcome.service - Show a welcome message on console. Dec 25 05:01:22 pi2aes systemd[1]: Stopping welcome.service - Show a welcome message on console... Dec 25 05:01:22 pi2aes systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 25 05:01:22 pi2aes welcome[1965]: Resolved ip:[1] 192.168.50.106 Dec 25 05:01:22 pi2aes systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 25 05:01:22 pi2aes systemd[1]: Reached target ip-changed@eth0.target - IP Address changed on eth0. Dec 25 05:01:22 pi2aes volumio[1210]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 5 Dec 25 05:01:23 pi2aes ntpd[1013]: IO: Listen normally on 4 eth0 192.168.50.106:123 Dec 25 05:01:23 pi2aes ntpd[1013]: IO: Deleting interface #3 eth0, 169.254.85.147#123, interface stats: received=0, sent=0, dropped=0, active_time=63 secs Dec 25 05:01:23 pi2aes ntpd[1013]: IO: new interface(s) found: waking up resolver Dec 25 05:01:23 pi2aes ntpd[1013]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 25 05:01:24 pi2aes ntpd[1013]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 25 05:01:24 pi2aes ntpd[1013]: DNS: Pool taking: 194.58.202.20 Dec 25 05:01:24 pi2aes ntpd[1013]: DNS: Pool taking: 85.24.237.71 Dec 25 05:01:24 pi2aes ntpd[1013]: DNS: Pool taking: 98.128.175.45 Dec 25 05:01:24 pi2aes ntpd[1013]: DNS: Pool taking: 85.24.237.72 Dec 25 05:01:24 pi2aes ntpd[1013]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 25 05:01:24 pi2aes bash[1754]: setdatetime-helper: all HTTPS Date fallbacks failed Dec 25 05:01:24 pi2aes systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 25 05:01:24 pi2aes systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 25 05:01:24 pi2aes ntpd[1013]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 25 05:01:25 pi2aes ntpd[1013]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 25 05:01:25 pi2aes ntpd[1013]: DNS: Pool taking: 193.182.111.13 Dec 25 05:01:25 pi2aes ntpd[1013]: DNS: Pool taking: 194.58.205.148 Dec 25 05:01:25 pi2aes ntpd[1013]: DNS: Pool taking: 194.58.203.148 Dec 25 05:01:25 pi2aes ntpd[1013]: DNS: Pool taking: 172.232.132.19 Dec 25 05:01:25 pi2aes ntpd[1013]: DNS: Pool taking: 2001:9b1:9bd0::bb Dec 25 05:01:25 pi2aes ntpd[1013]: DNS: Pool taking: 2a01:3f7:7:2::1 Dec 25 05:01:25 pi2aes ntpd[1013]: DNS: Pool taking: 2a03:8600::cc Dec 25 05:01:25 pi2aes ntpd[1013]: DNS: Pool taking: 2a03:8600::aa Dec 25 05:01:25 pi2aes ntpd[1013]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 25 05:01:25 pi2aes ntpd[1013]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 25 05:01:26 pi2aes ntpd[1013]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 25 05:01:26 pi2aes ntpd[1013]: DNS: Pool taking: 192.36.143.134 Dec 25 05:01:26 pi2aes ntpd[1013]: DNS: Pool taking: 162.159.200.1 Dec 25 05:01:26 pi2aes ntpd[1013]: DNS: Pool taking: 162.159.200.123 Dec 25 05:01:26 pi2aes ntpd[1013]: DNS: Pool taking: 172.232.157.27 Dec 25 05:01:26 pi2aes ntpd[1013]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 25 05:01:26 pi2aes ntpd[1013]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 25 05:01:27 pi2aes ntpd[1013]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 25 05:01:27 pi2aes ntpd[1013]: DNS: Pool taking: 192.36.143.130 Dec 25 05:01:27 pi2aes ntpd[1013]: DNS: Pool taking: 192.121.108.99 Dec 25 05:01:27 pi2aes ntpd[1013]: DNS: Pool taking: 194.58.207.148 Dec 25 05:01:27 pi2aes ntpd[1013]: DNS: Pool taking: 92.246.137.39 Dec 25 05:01:27 pi2aes ntpd[1013]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 25 05:02:06 pi2aes ntpd[1013]: CLOCK: time stepped by 36.304761 Dec 25 05:02:06 pi2aes ntpd[1013]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 25 05:02:06 pi2aes nmbd[1082]: [2025/12/25 05:02:06.415032, 0] ../../source3/libsmb/nmblib.c:923(send_udp) Dec 25 05:02:06 pi2aes nmbd[1082]: Packet send failed to 169.254.255.255(138) ERRNO=Network is unreachable Dec 25 05:02:14 pi2aes volumio[1210]: info: Discovery: adding 4d31b383-105d-4113-a81e-da6ff9c16703 Dec 25 05:02:14 pi2aes volumio[1210]: info: Discovery: Found device Pi2IIS Dec 25 05:02:14 pi2aes volumio[1210]: info: Discovery: Connecting to remote: 192.168.50.9 Dec 25 05:02:14 pi2aes volumio[1210]: info: Cannot call home: Error: Command failed: /usr/bin/curl -X POST --data-binary "device=pi&variante=volumio&version=4.082&uuid=3c1de9b54ecb51d9acf1ee44e0cd7c06" http://updates.volumio.org/downloader-v1/track-device Dec 25 05:02:14 pi2aes volumio[1210]: % Total % Received % Xferd Average Speed Time Time Time Current Dec 25 05:02:14 pi2aes volumio[1210]: Dload Upload Total Spent Left Speed Dec 25 05:02:14 pi2aes volumio[1210]: [211B blob data] Dec 25 05:02:14 pi2aes volumio[1210]: retrying in 5 seconds, trial 1 Dec 25 05:02:14 pi2aes sudo[1992]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o username=Pseudonym,password=1NASVERid,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft //192.168.50.108/sonic /mnt/NAS/SONIC Dec 25 05:02:14 pi2aes volumio[1210]: info: Volumio Calling Home Dec 25 05:02:14 pi2aes sudo[1992]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 25 05:02:14 pi2aes kernel: CIFS: Attempting to mount //192.168.50.108/sonic Dec 25 05:02:14 pi2aes volumio[1210]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 05:02:14 pi2aes volumio[1210]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 25 05:02:14 pi2aes volumio[1210]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 25 05:02:14 pi2aes volumio[1210]: at new NodeError (node:internal/errors:405:5) Dec 25 05:02:14 pi2aes volumio[1210]: at assert (node:internal/assert:14:11) Dec 25 05:02:14 pi2aes volumio[1210]: at internalConnectMultiple (node:net:1118:3) Dec 25 05:02:14 pi2aes volumio[1210]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 25 05:02:14 pi2aes volumio[1210]: at listOnTimeout (node:internal/timers:575:11) Dec 25 05:02:14 pi2aes volumio[1210]: at process.processTimers (node:internal/timers:514:7) { Dec 25 05:02:14 pi2aes volumio[1210]: code: 'ERR_INTERNAL_ASSERTION' Dec 25 05:02:14 pi2aes volumio[1210]: } Dec 25 05:02:14 pi2aes volumio[1210]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 25 05:02:15 pi2aes kernel: CIFS: VFS: Autodisabling the use of server inode numbers on new server Dec 25 05:02:15 pi2aes kernel: CIFS: VFS: The server doesn't seem to support them properly or the files might be on different servers (DFS) Dec 25 05:02:15 pi2aes kernel: CIFS: VFS: Hardlinks will not be recognized on this mount. Consider mounting with the "noserverino" option to silence this message. Dec 25 05:02:15 pi2aes sudo[1992]: pam_unix(sudo:session): session closed for user root Dec 25 05:02:18 pi2aes sudo[2051]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-25 05:01' Dec 25 05:02:18 pi2aes sudo[2051]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="5250573efb99f2852fa27ea7e92def8586093039" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="d9e2b6784b9276d5a676bf564ce7524ad160509b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 19 15:53:15 UTC 2025" VOLUMIO_VERSION="4.082" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="47d7740e8c55792fe06a847eb7ad6b29"