Dec 23 03:43:00 jukebox wireless.js[598]: WIRELESS.JS: trying... Dec 23 03:43:00 jukebox systemd[1]: Starting welcome.service - Show a welcome message on console... Dec 23 03:43:00 jukebox systemd[1]: Started nmbd.service - Samba NMB Daemon. Dec 23 03:43:00 jukebox systemd[1]: Starting winbind.service - Samba Winbind Daemon... Dec 23 03:43:00 jukebox welcome[1056]: Resolved ip:[1] 10.0.4.4 Dec 23 03:43:00 jukebox sudo[1064]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwgetid -r Dec 23 03:43:00 jukebox sudo[1064]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:43:00 jukebox systemd[1]: Finished welcome.service - Show a welcome message on console. Dec 23 03:43:00 jukebox sudo[1064]: pam_unix(sudo:session): session closed for user root Dec 23 03:43:00 jukebox systemd[1]: Reached target ip-changed@wlan0.target - IP Address changed on wlan0. Dec 23 03:43:00 jukebox wireless.js[598]: WIRELESS.JS: Connected to: ----Oa---- Dec 23 03:43:00 jukebox sudo[1073]: root : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 03:43:00 jukebox sudo[1073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 23 03:43:00 jukebox sudo[1073]: pam_unix(sudo:session): session closed for user root Dec 23 03:43:00 jukebox wireless.js[598]: WIRELESS.JS: ... joined AP, wlan0 IPv4 is 10.0.4.4, ipV6 is undefined Dec 23 03:43:00 jukebox wireless.js[598]: WIRELESS.JS: It's done! AP Dec 23 03:43:00 jukebox wireless.js[598]: WIRELESS.JS: Restarting avahi-daemon... Dec 23 03:43:00 jukebox wireless.js[598]: WIRELESS.JS: Notified systemd about wireless ready Dec 23 03:43:00 jukebox kernel: brcmfmac: brcmf_cfg80211_set_power_mgmt: power save disabled Dec 23 03:43:00 jukebox systemd[1]: Started wireless.service - Wireless Services. Dec 23 03:43:00 jukebox systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 23 03:43:00 jukebox systemd[1]: Started volumio.service - Volumio Backend Module. Dec 23 03:43:00 jukebox systemd[1]: Starting plymouth-quit.service - Terminate Plymouth Boot Screen... Dec 23 03:43:00 jukebox systemd[1]: Started volumio_cpu_tweak.service - Volumio Cpu Tweaker. Dec 23 03:43:00 jukebox systemd[1]: shairport-sync.service: Deactivated successfully. Dec 23 03:43:00 jukebox systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 23 03:43:00 jukebox systemd[1]: shairport-sync.service: Consumed 1.523s CPU time. Dec 23 03:43:00 jukebox avahi-daemon[579]: Got SIGTERM, quitting. Dec 23 03:43:00 jukebox systemd[1]: Stopping avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Dec 23 03:43:00 jukebox avahi-daemon[579]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 10.0.4.4. Dec 23 03:43:00 jukebox avahi-daemon[579]: Leaving mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 23 03:43:00 jukebox avahi-daemon[579]: avahi-daemon 0.8 exiting. Dec 23 03:43:00 jukebox systemd[1]: avahi-daemon.service: Deactivated successfully. Dec 23 03:43:00 jukebox systemd[1]: Stopped avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Dec 23 03:43:00 jukebox volumio-cpu-tweak[1084]: Setting RT Priority for mpd Dec 23 03:43:00 jukebox volumio-cpu-tweak[1099]: pid 35's current scheduling policy: SCHED_OTHER Dec 23 03:43:00 jukebox volumio-cpu-tweak[1099]: pid 35's current scheduling priority: 0 Dec 23 03:43:00 jukebox volumio-cpu-tweak[1084]: Setting MPD Affinity Dec 23 03:43:00 jukebox systemd[1]: Starting avahi-daemon.service - Avahi mDNS/DNS-SD Stack... Dec 23 03:43:00 jukebox avahi-daemon[1098]: Process 579 died: No such process; trying to remove PID file. (/run/avahi-daemon//pid) Dec 23 03:43:00 jukebox systemd[1]: Finished plymouth-quit-wait.service - Hold until boot process finishes up. Dec 23 03:43:00 jukebox volumio-cpu-tweak[1100]: pid 3's current affinity mask: f Dec 23 03:43:00 jukebox systemd[1]: Finished plymouth-quit.service - Terminate Plymouth Boot Screen. Dec 23 03:43:00 jukebox avahi-daemon[1098]: Found user 'avahi' (UID 103) and group 'avahi' (GID 109). Dec 23 03:43:00 jukebox avahi-daemon[1098]: Successfully dropped root privileges. Dec 23 03:43:00 jukebox avahi-daemon[1098]: avahi-daemon 0.8 starting up. Dec 23 03:43:00 jukebox volumio-cpu-tweak[1084]: /bin/volumio_cpu_tweak: line 27: warning: command substitution: ignored null byte in input Dec 23 03:43:00 jukebox volumio-cpu-tweak[1084]: VOLUMIO CPU TWEAK: Setting CPU Governor: performance Dec 23 03:43:00 jukebox systemd[1]: Received SIGRTMIN+21 from PID 265 (n/a). Dec 23 03:43:00 jukebox systemd[1]: Received SIGRTMIN+21 from PID 265 (n/a). Dec 23 03:43:00 jukebox systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 23 03:43:00 jukebox systemd[1]: Reached target getty.target - Login Prompts. Dec 23 03:43:00 jukebox avahi-daemon[1098]: Successfully called chroot(). Dec 23 03:43:00 jukebox avahi-daemon[1098]: Successfully dropped remaining capabilities. Dec 23 03:43:00 jukebox systemd[1]: Started avahi-daemon.service - Avahi mDNS/DNS-SD Stack. Dec 23 03:43:00 jukebox avahi-daemon[1098]: Loading service file /services/volumio.service. Dec 23 03:43:00 jukebox avahi-daemon[1098]: Joining mDNS multicast group on interface wlan0.IPv4 with address 10.0.4.4. Dec 23 03:43:00 jukebox avahi-daemon[1098]: New relevant interface wlan0.IPv4 for mDNS. Dec 23 03:43:00 jukebox avahi-daemon[1098]: Joining mDNS multicast group on interface lo.IPv4 with address 127.0.0.1. Dec 23 03:43:00 jukebox avahi-daemon[1098]: New relevant interface lo.IPv4 for mDNS. Dec 23 03:43:00 jukebox avahi-daemon[1098]: Network interface enumeration completed. Dec 23 03:43:00 jukebox avahi-daemon[1098]: Registering new address record for 10.0.4.4 on wlan0.IPv4. Dec 23 03:43:00 jukebox avahi-daemon[1098]: Registering new address record for 127.0.0.1 on lo.IPv4. Dec 23 03:43:00 jukebox systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 23 03:43:00 jukebox systemd[1]: volumio_cpu_tweak.service: Deactivated successfully. Dec 23 03:43:01 jukebox winbindd[1077]: [2025/12/23 03:43:01.077869, 0] ../../source3/winbindd/winbindd.c:1440(main) Dec 23 03:43:01 jukebox winbindd[1077]: winbindd version 4.17.8-Raspbian started. Dec 23 03:43:01 jukebox winbindd[1077]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 23 03:43:01 jukebox ntpd[756]: IO: Listen normally on 3 wlan0 10.0.4.4:123 Dec 23 03:43:01 jukebox ntpd[756]: IO: new interface(s) found: waking up resolver Dec 23 03:43:01 jukebox ntpd[756]: DNS: dns_probe: 3.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 23 03:43:01 jukebox winbindd[1077]: [2025/12/23 03:43:01.109543, 0] ../../source3/winbindd/winbindd_cache.c:3116(initialize_winbindd_cache) Dec 23 03:43:01 jukebox winbindd[1077]: initialize_winbindd_cache: clearing cache and re-creating with version number 2 Dec 23 03:43:01 jukebox ntpd[756]: DNS: dns_check: processing 3.debian.pool.ntp.org, 8, 101 Dec 23 03:43:01 jukebox ntpd[756]: DNS: Pool taking: 23.142.248.8 Dec 23 03:43:01 jukebox ntpd[756]: DNS: Pool taking: 23.186.168.130 Dec 23 03:43:01 jukebox ntpd[756]: DNS: Pool taking: 72.87.88.202 Dec 23 03:43:01 jukebox ntpd[756]: DNS: Pool taking: 69.89.207.199 Dec 23 03:43:01 jukebox ntpd[756]: DNS: dns_take_status: 3.debian.pool.ntp.org=>good, 8 Dec 23 03:43:01 jukebox systemd[1]: Started winbind.service - Samba Winbind Daemon. Dec 23 03:43:01 jukebox systemd[1]: Starting smbd.service - Samba SMB Daemon... Dec 23 03:43:01 jukebox avahi-daemon[1098]: Server startup complete. Host name is jukebox.local. Local service cookie is 2940758416. Dec 23 03:43:02 jukebox ntpd[756]: DNS: dns_probe: 2.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 23 03:43:02 jukebox ntpd[756]: DNS: dns_check: processing 2.debian.pool.ntp.org, 8, 101 Dec 23 03:43:02 jukebox ntpd[756]: DNS: Pool taking: 23.155.72.147 Dec 23 03:43:02 jukebox ntpd[756]: DNS: Pool taking: 46.37.96.107 Dec 23 03:43:02 jukebox ntpd[756]: DNS: Pool taking: 108.61.215.221 Dec 23 03:43:02 jukebox ntpd[756]: DNS: Pool taking: 69.89.207.99 Dec 23 03:43:02 jukebox ntpd[756]: DNS: Pool taking: 2606:4700:f1::123 Dec 23 03:43:02 jukebox ntpd[756]: DNS: Pool taking: 2603:c020:0:8369::bad:babe Dec 23 03:43:02 jukebox ntpd[756]: DNS: Pool taking: 2603:c020:0:8369::feeb:dab Dec 23 03:43:02 jukebox ntpd[756]: DNS: Pool taking: 2600:3c02::f03c:94ff:fe59:f411 Dec 23 03:43:02 jukebox ntpd[756]: DNS: dns_take_status: 2.debian.pool.ntp.org=>good, 8 Dec 23 03:43:02 jukebox smbd[1138]: [2025/12/23 03:43:02.222152, 0] ../../source3/smbd/server.c:1741(main) Dec 23 03:43:02 jukebox smbd[1138]: smbd version 4.17.8-Raspbian started. Dec 23 03:43:02 jukebox smbd[1138]: Copyright Andrew Tridgell and the Samba Team 1992-2022 Dec 23 03:43:02 jukebox avahi-daemon[1098]: Service "Jukebox" (/services/volumio.service) successfully established. Dec 23 03:43:03 jukebox ntpd[756]: DNS: dns_probe: 1.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 23 03:43:03 jukebox ntpd[756]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 Dec 23 03:43:03 jukebox ntpd[756]: DNS: Pool taking: 208.113.130.146 Dec 23 03:43:03 jukebox ntpd[756]: DNS: Pool taking: 66.118.229.14 Dec 23 03:43:03 jukebox ntpd[756]: DNS: Pool skipping: 108.61.215.221 Dec 23 03:43:03 jukebox ntpd[756]: DNS: Pool taking: 162.159.200.123 Dec 23 03:43:03 jukebox ntpd[756]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 Dec 23 03:43:04 jukebox ntpd[756]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 Dec 23 03:43:04 jukebox ntpd[756]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 Dec 23 03:43:04 jukebox ntpd[756]: DNS: Pool taking: 5.161.74.242 Dec 23 03:43:04 jukebox ntpd[756]: DNS: Pool taking: 64.44.115.65 Dec 23 03:43:04 jukebox ntpd[756]: DNS: Pool taking: 207.58.172.126 Dec 23 03:43:04 jukebox ntpd[756]: DNS: Pool taking: 69.176.84.38 Dec 23 03:43:04 jukebox ntpd[756]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 Dec 23 03:43:04 jukebox systemd[1]: Started smbd.service - Samba SMB Daemon. Dec 23 03:43:04 jukebox systemd[1]: Reached target multi-user.target - Multi-User System. Dec 23 03:43:04 jukebox systemd[1]: Reached target graphical.target - Graphical Interface. Dec 23 03:43:04 jukebox systemd[1]: Starting systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP... Dec 23 03:43:04 jukebox systemd[1]: systemd-update-utmp-runlevel.service: Deactivated successfully. Dec 23 03:43:04 jukebox systemd[1]: Finished systemd-update-utmp-runlevel.service - Record Runlevel Change in UTMP. Dec 23 03:43:04 jukebox systemd[1]: Startup finished in 17.053s (kernel) + 29.630s (userspace) = 46.683s. Dec 23 03:43:05 jukebox volumio-remote-updater[596]: [2025-12-23 03:43:05] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 23 03:43:05 jukebox volumio-remote-updater[596]: [2025-12-23 03:43:05] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 23 03:43:05 jukebox volumio-remote-updater[596]: [2025-12-23 03:43:05] [error] handle_connect error: Connection refused Dec 23 03:43:32 jukebox ntpd[756]: CLOCK: time stepped by 25.688664 Dec 23 03:43:32 jukebox ntpd[756]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 23 03:43:35 jukebox volumio[1082]: info: ------------------------------------------- Dec 23 03:43:35 jukebox volumio[1082]: info: ----- Volumio3 ---- Dec 23 03:43:35 jukebox volumio[1082]: info: ------------------------------------------- Dec 23 03:43:35 jukebox volumio[1082]: info: ----- System startup ---- Dec 23 03:43:35 jukebox volumio[1082]: info: ------------------------------------------- Dec 23 03:43:35 jukebox systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 23 03:43:36 jukebox volumio[1082]: info: MYVOLUMIO Environment detected Dec 23 03:43:36 jukebox volumio[1082]: info: Plugin folders cleanup Dec 23 03:43:36 jukebox volumio[1082]: info: Scanning into folder /volumio/app/plugins/ Dec 23 03:43:36 jukebox volumio[1082]: info: Scanning category audio_interface Dec 23 03:43:36 jukebox volumio[1082]: info: Scanning category miscellanea Dec 23 03:43:36 jukebox volumio[1082]: info: Scanning category music_service Dec 23 03:43:36 jukebox volumio[1082]: info: Scanning category plugins.json Dec 23 03:43:36 jukebox volumio[1082]: info: Scanning category system_controller Dec 23 03:43:36 jukebox volumio[1082]: info: Scanning category user_interface Dec 23 03:43:36 jukebox volumio[1082]: info: Scanning into folder /data/plugins/ Dec 23 03:43:36 jukebox volumio[1082]: info: Plugin folders cleanup completed Dec 23 03:43:36 jukebox volumio[1082]: info: ------------------------------------------- Dec 23 03:43:36 jukebox volumio[1082]: info: ----- Core plugins startup ---- Dec 23 03:43:36 jukebox volumio[1082]: info: ------------------------------------------- Dec 23 03:43:36 jukebox volumio[1082]: info: Loading plugins from folder /volumio/app/plugins/ Dec 23 03:43:36 jukebox volumio[1082]: info: Adding plugin upnp to MyMusic Plugins Dec 23 03:43:36 jukebox volumio[1082]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 23 03:43:36 jukebox volumio[1082]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 23 03:43:36 jukebox volumio[1082]: info: Loading plugins from folder /data/plugins/ Dec 23 03:43:36 jukebox volumio[1082]: info: Loading plugin "system"... Dec 23 03:43:37 jukebox volumio[1082]: info: Loading plugin "appearance"... Dec 23 03:43:39 jukebox volumio[1082]: info: Loading plugin "network"... Dec 23 03:43:39 jukebox volumio[1082]: info: Refreshing Cached IP Addresses Dec 23 03:43:39 jukebox systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 23 03:43:39 jukebox sudo[1164]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 03:43:39 jukebox systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 23 03:43:39 jukebox sudo[1164]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:43:39 jukebox sudo[1166]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 03:43:39 jukebox sudo[1166]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:43:39 jukebox volumio[1082]: info: Loading plugin "services"... Dec 23 03:43:39 jukebox volumio[1082]: info: Loading plugin "alsa_controller"... Dec 23 03:43:39 jukebox sudo[1166]: pam_unix(sudo:session): session closed for user root Dec 23 03:43:39 jukebox systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 23 03:43:39 jukebox sudo[1164]: pam_unix(sudo:session): session closed for user root Dec 23 03:43:39 jukebox sudo[1171]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 23 03:43:39 jukebox sudo[1171]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:43:39 jukebox upmpdcli[1172]: Could not open config: /tmp/upmpdcli.conf Dec 23 03:43:39 jukebox systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 23 03:43:39 jukebox systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 23 03:43:40 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 23 03:43:40 jukebox volumio[1082]: info: Loading plugin "wizard"... Dec 23 03:43:40 jukebox volumio[1082]: info: Loading plugin "networkfs"... Dec 23 03:43:40 jukebox volumio[1082]: info: Starting Udev Watcher for removable devices Dec 23 03:43:40 jukebox sudo[1201]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mount -t cifs -o 'username=misterdeejay,password=Play that beat!,ro,dir_mode=0777,file_mode=0666,iocharset=utf8,noauto,soft' //HomeVault/music /mnt/NAS/HomeVault Dec 23 03:43:40 jukebox sudo[1201]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:43:40 jukebox volumio[1082]: info: Ignoring mount for partition: boot Dec 23 03:43:40 jukebox volumio[1082]: info: Ignoring mount for partition: volumio Dec 23 03:43:40 jukebox volumio[1082]: info: Ignoring mount for partition: volumio_data Dec 23 03:43:40 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 23 03:43:40 jukebox volumio[1082]: info: Loading plugin "volumio_command_line_client"... Dec 23 03:43:40 jukebox volumio[1082]: info: Loading plugin "upnp"... Dec 23 03:43:40 jukebox volumio[1082]: info: [1766490220502] Starting Upmpd Daemon Dec 23 03:43:40 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 23 03:43:40 jukebox volumio[1082]: info: Loading plugin "my_music"... Dec 23 03:43:40 jukebox volumio[1082]: info: Loading plugin "mpd"... Dec 23 03:43:40 jukebox kernel: netfs: FS-Cache loaded Dec 23 03:43:40 jukebox sudo[1171]: pam_unix(sudo:session): session closed for user root Dec 23 03:43:40 jukebox kernel: Key type cifs.spnego registered Dec 23 03:43:40 jukebox kernel: Key type cifs.idmap registered Dec 23 03:43:40 jukebox kernel: CIFS: No dialect specified on mount. Default has changed to a more secure dialect, SMB2.1 or later (e.g. SMB3.1.1), from CIFS (SMB1). To use the less secure SMB1 dialect to access old servers which do not support SMB3.1.1 (or even SMB3 or SMB2.1) specify vers=1.0 on mount. Dec 23 03:43:40 jukebox kernel: CIFS: Attempting to mount //HomeVault/music Dec 23 03:43:41 jukebox volumio-remote-updater[596]: [2025-12-23 03:43:41] [connect] Successful connection Dec 23 03:43:41 jukebox sudo[1201]: pam_unix(sudo:session): session closed for user root Dec 23 03:43:41 jukebox systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 23 03:43:41 jukebox volumio[1082]: info: Loading plugin "upnp_browser"... Dec 23 03:43:45 jukebox volumio[1082]: info: Starting UPNP Browser Dec 23 03:43:45 jukebox volumio[1082]: info: Loading plugin "alarm-clock"... Dec 23 03:43:45 jukebox volumio[1082]: info: Loading plugin "airplay_emulation"... Dec 23 03:43:45 jukebox volumio[1082]: info: Starting Shairport Sync Dec 23 03:43:45 jukebox volumio[1082]: info: Loading plugin "last_100"... Dec 23 03:43:45 jukebox volumio[1082]: info: Loading plugin "webradio"... Dec 23 03:43:46 jukebox volumio[1082]: info: Loading plugin "i2s_dacs"... Dec 23 03:43:46 jukebox volumio[1082]: info: Loading plugin "volumiodiscovery"... Dec 23 03:43:46 jukebox volumio[1082]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 23 03:43:46 jukebox volumio[1082]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 03:43:46 jukebox volumio[1082]: *** WARNING *** For more information see Dec 23 03:43:46 jukebox volumio[1082]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 23 03:43:46 jukebox volumio[1082]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 03:43:46 jukebox volumio[1082]: *** WARNING *** For more information see Dec 23 03:43:46 jukebox node[1082]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 23 03:43:46 jukebox node[1082]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 03:43:46 jukebox node[1082]: *** WARNING *** For more information see Dec 23 03:43:46 jukebox node[1082]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 23 03:43:46 jukebox node[1082]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 23 03:43:46 jukebox node[1082]: *** WARNING *** For more information see Dec 23 03:43:46 jukebox volumio[1082]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 23 03:43:46 jukebox volumio[1082]: info: Discovery: Started advertising with name: Jukebox Dec 23 03:43:46 jukebox systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 23 03:43:46 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 23 03:43:46 jukebox volumio[1082]: info: Loading plugin "outputs"... Dec 23 03:43:46 jukebox volumio[1082]: info: Loading plugin "albumart"... Dec 23 03:43:46 jukebox volumio[1082]: info: Plugin example_plugin is not enabled Dec 23 03:43:46 jukebox volumio[1082]: info: Loading plugin "inputs"... Dec 23 03:43:46 jukebox volumio[1082]: info: Loading plugin "updater_comm"... Dec 23 03:43:47 jukebox volumio[1082]: info: Plugin mpdemulation is not enabled Dec 23 03:43:47 jukebox volumio[1082]: info: Loading plugin "rest_api"... Dec 23 03:43:47 jukebox volumio[1082]: info: Loading plugin "websocket"... Dec 23 03:43:47 jukebox volumio[1082]: info: Starting Socket.io Server version 1.7.4 Dec 23 03:43:47 jukebox volumio[1082]: info: Loading i18n strings for locale en Dec 23 03:43:47 jukebox volumio[1082]: Updating browse sources language Dec 23 03:43:47 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 03:43:49 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 03:43:49 jukebox volumio[1082]: info: CoreCommandRouter::initPlayerControls Dec 23 03:43:49 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:43:49 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:43:49 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:43:49 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:43:49 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:43:49 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:43:49 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:43:49 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:43:49 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 03:43:49 jukebox volumio[1082]: Express server listening on port 3000 Dec 23 03:43:49 jukebox systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 23 03:43:49 jukebox systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 23 03:43:49 jukebox volumio[1082]: [Metrics] WebUI: 16s 275.86ms Dec 23 03:43:49 jukebox systemd[1]: setdatetime-helper.service: Consumed 1.274s CPU time. Dec 23 03:43:49 jukebox volumio[1082]: info: CoreStateMachine::resetVolumioState Dec 23 03:43:49 jukebox volumio[1082]: info: CoreStateMachine::getcurrentVolume Dec 23 03:43:49 jukebox volumio[1082]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 03:43:49 jukebox volumio[1256]: Forking 3 albumart workers Dec 23 03:43:49 jukebox volumio[1082]: info: Volumio Network Manager: Network status updated: 2 Dec 23 03:43:50 jukebox volumio[1082]: verbose: New Socket.io Connection to 10.0.4.4 from 10.0.4.41 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 1 Dec 23 03:43:51 jukebox sudo[1328]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 03:43:51 jukebox sudo[1328]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:43:51 jukebox volumio[1082]: verbose: New Socket.io Connection to 10.0.4.4 from 10.0.4.41 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 2 Dec 23 03:43:51 jukebox sudo[1331]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 03:43:51 jukebox sudo[1331]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:43:51 jukebox sudo[1328]: pam_unix(sudo:session): session closed for user root Dec 23 03:43:51 jukebox volumio[1082]: info: VolumeController:: Volume=5 Mute =false Dec 23 03:43:51 jukebox sudo[1331]: pam_unix(sudo:session): session closed for user root Dec 23 03:43:51 jukebox volumio[1082]: info: CoreStateMachine::pushState Dec 23 03:43:51 jukebox volumio[1082]: info: CorePlayQueue::getTrack 0 Dec 23 03:43:51 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 03:43:51 jukebox volumio[1082]: info: CoreCommandRouter::volumioPushState Dec 23 03:43:51 jukebox volumio[1082]: info: CoreStateMachine::updateTrackBlock Dec 23 03:43:51 jukebox volumio[1082]: info: CorePlayQueue::getTrackBlock Dec 23 03:43:51 jukebox volumio[1082]: info: CoreCommandRouter::volumioRetrievevolume Dec 23 03:43:52 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 03:43:52 jukebox volumio[1082]: info: Executing start script for DAC IQaudIO DAC Plus Dec 23 03:43:52 jukebox volumio[1082]: info: Reloading queue from file Dec 23 03:43:52 jukebox volumio[1082]: info: CoreStateMachine::setRepeat null single undefined Dec 23 03:43:52 jukebox volumio[1082]: info: CoreStateMachine::pushState Dec 23 03:43:52 jukebox volumio[1082]: info: CorePlayQueue::getTrack 0 Dec 23 03:43:52 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 03:43:52 jukebox volumio[1082]: info: CoreCommandRouter::volumioPushState Dec 23 03:43:52 jukebox volumio[1082]: info: CoreStateMachine::setRandom null Dec 23 03:43:52 jukebox volumio[1082]: info: CoreStateMachine::pushState Dec 23 03:43:52 jukebox volumio[1082]: info: CorePlayQueue::getTrack 0 Dec 23 03:43:52 jukebox volumio[1082]: info: CoreCommandRouter::volumioPushState Dec 23 03:43:53 jukebox volumio[1082]: info: Setting Device type: Raspberry PI Dec 23 03:43:53 jukebox volumio[1082]: verbose: New Socket.io Connection to 10.0.4.4 from 10.0.4.41 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 3 Dec 23 03:43:53 jukebox volumio[1082]: info: DAC script executed Dec 23 03:43:53 jukebox volumio[1082]: info: Completed loading Core Plugins Dec 23 03:43:53 jukebox volumio[1082]: info: Preparing to generate the ALSA configuration file Dec 23 03:43:53 jukebox volumio[1082]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 23 03:43:53 jukebox volumio[1082]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Dec 23 03:43:53 jukebox volumio[1082]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 23 03:43:53 jukebox volumio[1082]: verbose: New Socket.io Connection to 10.0.4.4 from 10.0.4.41 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 4 Dec 23 03:43:53 jukebox volumio[1082]: info: VolumeController:: Volume=5 Mute =false Dec 23 03:43:53 jukebox volumio[1082]: info: CoreStateMachine::pushState Dec 23 03:43:53 jukebox volumio[1082]: info: CorePlayQueue::getTrack 0 Dec 23 03:43:53 jukebox volumio[1082]: info: CoreCommandRouter::volumioPushState Dec 23 03:43:53 jukebox volumio[1082]: info: Asound.conf file unchanged, so no further update is needed Dec 23 03:43:54 jukebox volumio[1082]: info: Output device has changed, restarting MPD Dec 23 03:43:54 jukebox volumio[1082]: info: Output device has changed, restarting Shairport Sync Dec 23 03:43:54 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:43:54 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 03:43:54 jukebox sudo[1351]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 03:43:54 jukebox sudo[1351]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:43:54 jukebox sudo[1351]: pam_unix(sudo:session): session closed for user root Dec 23 03:43:54 jukebox volumio[1082]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 23 03:43:54 jukebox volumio[1082]: info: ___________ START PLUGINS ___________ Dec 23 03:43:54 jukebox sudo[1353]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 03:43:54 jukebox sudo[1353]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:43:54 jukebox volumio[1082]: info: ControllerMpd::onStart: Initializing MPD Dec 23 03:43:54 jukebox volumio[1082]: info: Creating MPD Configuration file Dec 23 03:43:54 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 03:43:54 jukebox volumio[1082]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 03:43:54 jukebox volumio[1082]: info: [1766490234715] CoreMusicLibrary::Adding element Media Servers Dec 23 03:43:54 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 03:43:54 jukebox systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 23 03:43:54 jukebox systemd[1]: Starting mpd.service - Music Player Daemon... Dec 23 03:43:54 jukebox sudo[1361]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 23 03:43:54 jukebox sudo[1361]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:43:54 jukebox volumio[1082]: info: UPNP Browser: Client initialized successfully Dec 23 03:43:54 jukebox sudo[1361]: pam_unix(sudo:session): session closed for user root Dec 23 03:43:54 jukebox sudo[1363]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 23 03:43:54 jukebox sudo[1363]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:43:55 jukebox systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 23 03:43:55 jukebox systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 23 03:43:55 jukebox systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 23 03:43:55 jukebox sudo[1365]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 23 03:43:55 jukebox sudo[1365]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 23 03:43:55 jukebox sudo[1369]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 23 03:43:55 jukebox sudo[1365]: pam_unix(sudo:session): session closed for user root Dec 23 03:43:55 jukebox systemd[1]: mpd.service: Deactivated successfully. Dec 23 03:43:55 jukebox systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 23 03:43:55 jukebox systemd[1]: mpd.socket: Deactivated successfully. Dec 23 03:43:55 jukebox systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 23 03:43:55 jukebox systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 23 03:43:55 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:43:55 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 03:43:55 jukebox systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 23 03:43:55 jukebox systemd[1]: Starting mpd.service - Music Player Daemon... Dec 23 03:43:55 jukebox volumio[1082]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 23 03:43:55 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:43:55 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 03:43:55 jukebox sudo[1384]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 23 03:43:55 jukebox sudo[1384]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 23 03:43:55 jukebox sudo[1387]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 23 03:43:55 jukebox sudo[1384]: pam_unix(sudo:session): session closed for user root Dec 23 03:43:55 jukebox volumio[1082]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 23 03:43:55 jukebox volumio[1082]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 03:43:56 jukebox volumio[1082]: info: [1766490236028] CoreMusicLibrary::Adding element Last_100 Dec 23 03:43:56 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 03:43:56 jukebox volumio[1082]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 23 03:43:56 jukebox volumio[1082]: info: [1766490236034] CoreMusicLibrary::Adding element Webradio Dec 23 03:43:56 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 03:43:56 jukebox volumio-remote-updater[596]: [2025-12-23 03:43:56] [connect] Successful connection Dec 23 03:43:56 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 23 03:43:56 jukebox volumio[1082]: info: Initializing BBC Radios Dec 23 03:43:56 jukebox volumio[1082]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Dec 23 03:43:56 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 03:43:57 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 23 03:43:57 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 03:43:57 jukebox volumio[1082]: info: Volumio Calling Home Dec 23 03:44:02 jukebox volumio[1283]: Starting albumart workers Dec 23 03:44:05 jukebox volumio[1082]: info: Discovery: adding 1c96f442-924c-4a73-b273-adea47014ff1 Dec 23 03:44:05 jukebox volumio[1082]: info: Discovery: Found device Jukebox Dec 23 03:44:05 jukebox volumio[1082]: info: CoreCommandRouter::volumioGetState Dec 23 03:44:05 jukebox volumio[1082]: info: CorePlayQueue::getTrack 0 Dec 23 03:44:05 jukebox volumio[1082]: info: Discovery: this is already registered, 1c96f442-924c-4a73-b273-adea47014ff1 Dec 23 03:44:05 jukebox volumio[1082]: info: Discovery: Found device Jukebox Dec 23 03:44:05 jukebox volumio[1082]: info: CoreCommandRouter::volumioGetState Dec 23 03:44:05 jukebox volumio[1082]: info: CorePlayQueue::getTrack 0 Dec 23 03:44:05 jukebox volumio[1082]: info: MPD Permissions set Dec 23 03:44:06 jukebox volumio[1082]: info: MPD Permissions set Dec 23 03:44:06 jukebox mpd[1389]: 2025-12-23T03:44:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 23 03:44:06 jukebox volumio[1282]: Starting albumart workers Dec 23 03:44:06 jukebox volumio[1281]: Starting albumart workers Dec 23 03:44:06 jukebox systemd[1]: Started mpd.service - Music Player Daemon. Dec 23 03:44:06 jukebox sudo[1363]: pam_unix(sudo:session): session closed for user root Dec 23 03:44:06 jukebox sudo[1353]: pam_unix(sudo:session): session closed for user root Dec 23 03:44:06 jukebox sudo[1424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 23 03:44:06 jukebox sudo[1424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:44:06 jukebox sudo[1424]: pam_unix(sudo:session): session closed for user root Dec 23 03:44:08 jukebox volumio[1082]: verbose: New Socket.io Connection to 10.0.4.4 from 10.0.4.41 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 5 Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 03:44:08 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:09 jukebox volumio[1082]: verbose: New Socket.io Connection to 10.0.4.4 from 10.0.4.41 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 6 Dec 23 03:44:09 jukebox volumio[1082]: info: Upmpdcli Daemon Started Dec 23 03:44:09 jukebox volumio[1082]: info: Volumio called home Dec 23 03:44:10 jukebox volumio[1082]: info: Completed starting Core Plugins Dec 23 03:44:10 jukebox volumio[1082]: info: ------------------------------------------- Dec 23 03:44:10 jukebox volumio[1082]: info: ----- MyVolumio plugins startup ---- Dec 23 03:44:10 jukebox volumio[1082]: info: ------------------------------------------- Dec 23 03:44:10 jukebox volumio[1082]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 23 03:44:10 jukebox volumio[1082]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 23 03:44:11 jukebox volumio-remote-updater[596]: [2025-12-23 03:44:11] [connect] Successful connection Dec 23 03:44:11 jukebox volumio[1082]: error: MPD error: The expression evaluated to a falsy value: Dec 23 03:44:11 jukebox volumio[1082]: assert.ok(self.idling) Dec 23 03:44:11 jukebox volumio[1082]: error: The expression evaluated to a falsy value: Dec 23 03:44:11 jukebox volumio[1082]: assert.ok(self.idling) Dec 23 03:44:12 jukebox volumio[1082]: verbose: New Socket.io Connection to 10.0.4.4 from 10.0.4.41 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 8 Dec 23 03:44:12 jukebox volumio[1082]: info: MPD running with PID1389 Dec 23 03:44:12 jukebox volumio[1082]: ,establishing connection Dec 23 03:44:12 jukebox volumio[1082]: info: Starting Shairport Sync Dec 23 03:44:12 jukebox volumio[1082]: info: Starting Shairport Sync Dec 23 03:44:12 jukebox volumio[1082]: info: Starting Shairport Sync Dec 23 03:44:12 jukebox sudo[1466]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 23 03:44:12 jukebox sudo[1466]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:44:12 jukebox volumio[1082]: error: updateQueue error: null Dec 23 03:44:12 jukebox sudo[1468]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 23 03:44:12 jukebox volumio[1082]: verbose: New Socket.io Connection to 10.0.4.4 from 10.0.4.41 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 9 Dec 23 03:44:12 jukebox sudo[1468]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:44:12 jukebox sudo[1474]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 23 03:44:12 jukebox sudo[1474]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:44:12 jukebox systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 23 03:44:12 jukebox systemd[1]: shairport-sync.service: Deactivated successfully. Dec 23 03:44:12 jukebox systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 23 03:44:12 jukebox systemd[1]: shairport-sync.service: Consumed 1.539s CPU time. Dec 23 03:44:13 jukebox sudo[1481]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 23 03:44:13 jukebox sudo[1481]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:44:13 jukebox systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 23 03:44:13 jukebox sudo[1466]: pam_unix(sudo:session): session closed for user root Dec 23 03:44:13 jukebox systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 23 03:44:13 jukebox systemd[1]: shairport-sync.service: Deactivated successfully. Dec 23 03:44:13 jukebox systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 23 03:44:13 jukebox systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 23 03:44:13 jukebox systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 23 03:44:13 jukebox volumio-remote-updater[596]: [2025-12-23 03:44:13] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766490251 101 Dec 23 03:44:13 jukebox sudo[1481]: pam_unix(sudo:session): session closed for user root Dec 23 03:44:13 jukebox mpd_monitor.sh[1489]: MPD Monitor Service: Starting MPD Monitor Service Dec 23 03:44:13 jukebox sudo[1474]: pam_unix(sudo:session): session closed for user root Dec 23 03:44:13 jukebox sudo[1468]: pam_unix(sudo:session): session closed for user root Dec 23 03:44:13 jukebox sudo[1486]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 23 03:44:13 jukebox sudo[1486]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 03:44:13 jukebox volumio[1082]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 3 Transport: websocket Total Clients: 10 Dec 23 03:44:13 jukebox systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 23 03:44:13 jukebox systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 23 03:44:13 jukebox systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 23 03:44:13 jukebox volumio[1082]: info: Shairport-Sync Started Dec 23 03:44:13 jukebox systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 23 03:44:13 jukebox mpd_monitor.sh[1506]: MPD Monitor Service: Starting MPD Monitor Service Dec 23 03:44:13 jukebox sudo[1486]: pam_unix(sudo:session): session closed for user root Dec 23 03:44:13 jukebox volumio[1082]: Error adding Membership: Error: addMembership EINVAL Dec 23 03:44:13 jukebox volumio[1082]: error: updateQueue error: null Dec 23 03:44:13 jukebox volumio[1082]: info: Shairport-Sync Started Dec 23 03:44:13 jukebox volumio[1082]: info: Shairport-Sync Started Dec 23 03:44:13 jukebox volumio[1082]: info: Successfully started MPD Monitor Dec 23 03:44:14 jukebox volumio[1082]: info: Successfully started MPD Monitor Dec 23 03:44:14 jukebox volumio[1082]: info: CoreCommandRouter::volumioGetState Dec 23 03:44:14 jukebox volumio[1082]: info: CorePlayQueue::getTrack 0 Dec 23 03:44:16 jukebox volumio[1082]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 23 03:44:18 jukebox volumio[1082]: verbose: New Socket.io Connection to 10.0.4.4 from 10.0.4.41 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Dec 23 03:44:32 jukebox volumio[1082]: /usr/bin/md5sum: /sys/class/net/eth0/address: No such file or directory Dec 23 03:44:32 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 03:44:32 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 23 03:44:32 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 03:44:32 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 23 03:44:32 jukebox volumio[1082]: error: MyVolumio Plugin failed to start in a timely fashion Dec 23 03:44:32 jukebox volumio[1082]: [Metrics] CommandRouter: 57s 359.70ms Dec 23 03:44:32 jukebox volumio[1082]: info: CoreCommandRouter::volumiosetStartupVolume Dec 23 03:44:32 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 23 03:44:32 jukebox volumio[1082]: info: VolumeController:: Setting startup Volume 5 Dec 23 03:44:32 jukebox volumio[1082]: info: VolumeController::SetAlsaVolume5 Dec 23 03:44:32 jukebox volumio[1082]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 23 03:44:32 jukebox volumio[1082]: info: CoreCommandRouter::Close All Modals sent Dec 23 03:44:32 jukebox volumio[1082]: info: CoreCommandRouter::Close All Modals sent Dec 23 03:44:32 jukebox volumio[1082]: info: CoreStateMachine::pushState Dec 23 03:44:32 jukebox volumio[1082]: info: CorePlayQueue::getTrack 0 Dec 23 03:44:32 jukebox volumio[1082]: info: CoreCommandRouter::volumioPushState Dec 23 03:44:32 jukebox volumio[1082]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 03:44:32 jukebox volumio[1082]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 23 03:44:32 jukebox volumio[1082]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 23 03:44:32 jukebox volumio[1082]: at new NodeError (node:internal/errors:405:5) Dec 23 03:44:32 jukebox volumio[1082]: at assert (node:internal/assert:14:11) Dec 23 03:44:32 jukebox volumio[1082]: at internalConnectMultiple (node:net:1118:3) Dec 23 03:44:32 jukebox volumio[1082]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 23 03:44:32 jukebox volumio[1082]: at listOnTimeout (node:internal/timers:575:11) Dec 23 03:44:32 jukebox volumio[1082]: at process.processTimers (node:internal/timers:514:7) { Dec 23 03:44:32 jukebox volumio[1082]: code: 'ERR_INTERNAL_ASSERTION' Dec 23 03:44:32 jukebox volumio[1082]: } Dec 23 03:44:32 jukebox volumio[1082]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 03:44:34 jukebox upmpdcli[1571]: writing RSA key Dec 23 03:44:40 jukebox sudo[1580]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-23 03:43' Dec 23 03:44:40 jukebox sudo[1580]: 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="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"