Dec 19 15:07:01 jukebox volumio-remote-updater[658]: [2025-12-19 15:07:01] [info] asio async_connect error: asio.system:111 (Connection refused) Dec 19 15:07:01 jukebox volumio-remote-updater[658]: [2025-12-19 15:07:01] [info] Error getting remote endpoint: asio.system:107 (Transport endpoint is not connected) Dec 19 15:07:01 jukebox volumio-remote-updater[658]: [2025-12-19 15:07:01] [error] handle_connect error: Connection refused Dec 19 15:07:31 jukebox ntpd[846]: CLOCK: time stepped by 28.570047 Dec 19 15:07:31 jukebox ntpd[846]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 19 15:07:32 jukebox volumio[1196]: info: ------------------------------------------- Dec 19 15:07:32 jukebox volumio[1196]: info: ----- Volumio3 ---- Dec 19 15:07:32 jukebox volumio[1196]: info: ------------------------------------------- Dec 19 15:07:32 jukebox volumio[1196]: info: ----- System startup ---- Dec 19 15:07:32 jukebox volumio[1196]: info: ------------------------------------------- Dec 19 15:07:34 jukebox systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 19 15:07:34 jukebox volumio[1196]: info: MYVOLUMIO Environment detected Dec 19 15:07:34 jukebox volumio[1196]: info: Plugin folders cleanup Dec 19 15:07:34 jukebox volumio[1196]: info: Scanning into folder /volumio/app/plugins/ Dec 19 15:07:34 jukebox volumio[1196]: info: Scanning category audio_interface Dec 19 15:07:34 jukebox volumio[1196]: info: Scanning category miscellanea Dec 19 15:07:34 jukebox volumio[1196]: info: Scanning category music_service Dec 19 15:07:34 jukebox volumio[1196]: info: Scanning category plugins.json Dec 19 15:07:34 jukebox volumio[1196]: info: Scanning category system_controller Dec 19 15:07:34 jukebox volumio[1196]: info: Scanning category user_interface Dec 19 15:07:34 jukebox volumio[1196]: info: Scanning into folder /data/plugins/ Dec 19 15:07:34 jukebox volumio[1196]: info: Scanning category user_interface Dec 19 15:07:35 jukebox volumio[1196]: info: Plugin folders cleanup completed Dec 19 15:07:35 jukebox volumio[1196]: info: ------------------------------------------- Dec 19 15:07:35 jukebox volumio[1196]: info: ----- Core plugins startup ---- Dec 19 15:07:35 jukebox volumio[1196]: info: ------------------------------------------- Dec 19 15:07:35 jukebox volumio[1196]: info: Loading plugins from folder /volumio/app/plugins/ Dec 19 15:07:35 jukebox volumio[1196]: info: Adding plugin upnp to MyMusic Plugins Dec 19 15:07:35 jukebox volumio[1196]: info: Adding plugin airplay_emulation to MyMusic Plugins Dec 19 15:07:35 jukebox volumio[1196]: info: Adding plugin upnp_browser to MyMusic Plugins Dec 19 15:07:35 jukebox volumio[1196]: info: Loading plugins from folder /data/plugins/ Dec 19 15:07:35 jukebox volumio[1196]: info: Loading plugin "system"... Dec 19 15:07:35 jukebox volumio[1196]: info: Loading plugin "appearance"... Dec 19 15:07:35 jukebox dhcpcd[599]: timed out Dec 19 15:07:35 jukebox sh[587]: timed out Dec 19 15:07:35 jukebox sh[517]: ifup: failed to bring up eth0 Dec 19 15:07:35 jukebox systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 19 15:07:35 jukebox systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 19 15:07:37 jukebox volumio[1196]: info: Loading plugin "network"... Dec 19 15:07:37 jukebox volumio[1196]: info: Refreshing Cached IP Addresses Dec 19 15:07:37 jukebox sudo[1276]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 19 15:07:37 jukebox sudo[1276]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:07:37 jukebox sudo[1278]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 15:07:37 jukebox volumio[1196]: info: Loading plugin "services"... Dec 19 15:07:37 jukebox sudo[1278]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:07:37 jukebox sudo[1276]: pam_unix(sudo:session): session closed for user root Dec 19 15:07:37 jukebox volumio[1196]: info: Loading plugin "alsa_controller"... Dec 19 15:07:37 jukebox sudo[1278]: pam_unix(sudo:session): session closed for user root Dec 19 15:07:37 jukebox sudo[1281]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Dec 19 15:07:37 jukebox sudo[1281]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:07:38 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 15:07:38 jukebox volumio[1196]: info: Loading plugin "wizard"... Dec 19 15:07:38 jukebox volumio[1196]: info: Loading plugin "networkfs"... Dec 19 15:07:38 jukebox volumio[1196]: info: Starting Udev Watcher for removable devices Dec 19 15:07:38 jukebox volumio[1196]: info: Ignoring mount for partition: boot Dec 19 15:07:38 jukebox volumio[1196]: info: Ignoring mount for partition: volumio Dec 19 15:07:38 jukebox volumio[1196]: info: Ignoring mount for partition: volumio_data Dec 19 15:07:38 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 19 15:07:38 jukebox volumio[1196]: info: Loading plugin "volumio_command_line_client"... Dec 19 15:07:38 jukebox volumio[1196]: info: Loading plugin "upnp"... Dec 19 15:07:38 jukebox sudo[1312]: 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 19 15:07:38 jukebox sudo[1312]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:07:38 jukebox volumio[1196]: info: [1766185658263] Starting Upmpd Daemon Dec 19 15:07:38 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 19 15:07:38 jukebox volumio[1196]: info: Loading plugin "my_music"... Dec 19 15:07:38 jukebox volumio[1196]: info: Loading plugin "mpd"... Dec 19 15:07:38 jukebox kernel: netfs: FS-Cache loaded Dec 19 15:07:38 jukebox systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 2. Dec 19 15:07:38 jukebox kernel: Key type cifs.spnego registered Dec 19 15:07:38 jukebox kernel: Key type cifs.idmap registered Dec 19 15:07:38 jukebox systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 19 15:07:38 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 19 15:07:38 jukebox kernel: CIFS: Attempting to mount //HomeVault/music Dec 19 15:07:38 jukebox sudo[1281]: pam_unix(sudo:session): session closed for user root Dec 19 15:07:38 jukebox systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 19 15:07:38 jukebox upmpdcli[1328]: Could not open config: /tmp/upmpdcli.conf Dec 19 15:07:38 jukebox systemd[1]: upmpdcli.service: Main process exited, code=exited, status=1/FAILURE Dec 19 15:07:38 jukebox systemd[1]: upmpdcli.service: Failed with result 'exit-code'. Dec 19 15:07:39 jukebox sudo[1312]: pam_unix(sudo:session): session closed for user root Dec 19 15:07:39 jukebox volumio[1196]: info: Loading plugin "upnp_browser"... Dec 19 15:07:40 jukebox volumio-remote-updater[658]: [2025-12-19 15:07:40] [connect] Successful connection Dec 19 15:07:41 jukebox systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 19 15:07:42 jukebox volumio[1196]: info: Starting UPNP Browser Dec 19 15:07:42 jukebox volumio[1196]: info: Loading plugin "alarm-clock"... Dec 19 15:07:43 jukebox volumio[1196]: info: Loading plugin "airplay_emulation"... Dec 19 15:07:43 jukebox volumio[1196]: info: Starting Shairport Sync Dec 19 15:07:43 jukebox volumio[1196]: info: Loading plugin "last_100"... Dec 19 15:07:43 jukebox volumio[1196]: info: Loading plugin "webradio"... Dec 19 15:07:43 jukebox volumio[1196]: info: Loading plugin "i2s_dacs"... Dec 19 15:07:43 jukebox volumio[1196]: info: Loading plugin "volumiodiscovery"... Dec 19 15:07:43 jukebox volumio[1196]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 19 15:07:43 jukebox volumio[1196]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 19 15:07:43 jukebox volumio[1196]: *** WARNING *** For more information see Dec 19 15:07:43 jukebox volumio[1196]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 19 15:07:43 jukebox volumio[1196]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 19 15:07:43 jukebox volumio[1196]: *** WARNING *** For more information see Dec 19 15:07:43 jukebox node[1196]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 19 15:07:43 jukebox node[1196]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 19 15:07:43 jukebox node[1196]: *** WARNING *** For more information see Dec 19 15:07:43 jukebox node[1196]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 19 15:07:43 jukebox node[1196]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 19 15:07:43 jukebox node[1196]: *** WARNING *** For more information see Dec 19 15:07:43 jukebox volumio[1196]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 19 15:07:43 jukebox volumio[1196]: info: Discovery: Started advertising with name: Jukebox Dec 19 15:07:44 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 19 15:07:44 jukebox volumio[1196]: info: Loading plugin "outputs"... Dec 19 15:07:44 jukebox volumio[1196]: info: Loading plugin "albumart"... Dec 19 15:07:44 jukebox volumio[1196]: info: Plugin example_plugin is not enabled Dec 19 15:07:44 jukebox volumio[1196]: info: Loading plugin "inputs"... Dec 19 15:07:44 jukebox volumio[1196]: info: Loading plugin "updater_comm"... Dec 19 15:07:44 jukebox volumio[1196]: info: Plugin mpdemulation is not enabled Dec 19 15:07:44 jukebox volumio[1196]: info: Loading plugin "rest_api"... Dec 19 15:07:44 jukebox volumio[1196]: info: Loading plugin "websocket"... Dec 19 15:07:44 jukebox systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 19 15:07:44 jukebox volumio[1196]: info: Starting Socket.io Server version 1.7.4 Dec 19 15:07:44 jukebox volumio[1196]: info: Plugin touch_display is not enabled Dec 19 15:07:44 jukebox volumio[1196]: info: Loading i18n strings for locale en Dec 19 15:07:45 jukebox volumio[1196]: Updating browse sources language Dec 19 15:07:45 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 15:07:46 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 15:07:46 jukebox volumio[1196]: info: CoreCommandRouter::initPlayerControls Dec 19 15:07:46 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:07:46 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:07:46 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:07:46 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:07:46 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:07:46 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:07:46 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:07:46 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:07:46 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 15:07:46 jukebox volumio[1196]: Express server listening on port 3000 Dec 19 15:07:46 jukebox volumio[1196]: [Metrics] WebUI: 15s 1.25ms Dec 19 15:07:46 jukebox volumio[1196]: info: CoreStateMachine::resetVolumioState Dec 19 15:07:46 jukebox volumio[1196]: info: CoreStateMachine::getcurrentVolume Dec 19 15:07:46 jukebox volumio[1196]: info: CoreCommandRouter::volumioRetrievevolume Dec 19 15:07:46 jukebox volumio[1196]: info: Cannot read play queue from file Dec 19 15:07:46 jukebox volumio[1196]: info: Volumio Network Manager: Network status updated: 2 Dec 19 15:07:46 jukebox volumio[1196]: 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 19 15:07:46 jukebox volumio[1196]: 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 19 15:07:46 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 15:07:46 jukebox volumio[1196]: info: Executing start script for DAC IQaudIO DAC Plus Dec 19 15:07:46 jukebox volumio[1354]: Forking 3 albumart workers Dec 19 15:07:47 jukebox volumio[1196]: info: CoreStateMachine::setRepeat null single undefined Dec 19 15:07:47 jukebox volumio[1196]: info: CoreStateMachine::pushState Dec 19 15:07:47 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:07:47 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 15:07:47 jukebox volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 19 15:07:47 jukebox volumio[1196]: info: CoreStateMachine::setRandom null Dec 19 15:07:47 jukebox volumio[1196]: info: CoreStateMachine::pushState Dec 19 15:07:47 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:07:47 jukebox volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 19 15:07:47 jukebox volumio[1196]: info: Setting Device type: Raspberry PI Dec 19 15:07:48 jukebox volumio[1196]: 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 19 15:07:48 jukebox volumio[1196]: info: DAC script executed Dec 19 15:07:48 jukebox volumio[1196]: info: Completed loading Core Plugins Dec 19 15:07:48 jukebox volumio[1196]: info: Preparing to generate the ALSA configuration file Dec 19 15:07:48 jukebox volumio[1196]: info: VolumeController:: Volume=100 Mute =false Dec 19 15:07:48 jukebox volumio[1196]: info: CoreStateMachine::pushState Dec 19 15:07:49 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:07:49 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 19 15:07:49 jukebox volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 19 15:07:49 jukebox volumio[1196]: info: CoreStateMachine::updateTrackBlock Dec 19 15:07:49 jukebox volumio[1196]: info: CorePlayQueue::getTrackBlock Dec 19 15:07:49 jukebox volumio[1196]: info: CoreCommandRouter::volumioRetrievevolume Dec 19 15:07:49 jukebox volumio[1196]: info: USB Boot Capable - Checking Install to Disk functions for: bootusb Dec 19 15:07:49 jukebox volumio[1196]: info: USB Boot Capable - System SBC Revision found in cpuinfo: 902120 Dec 19 15:07:49 jukebox systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 19 15:07:49 jukebox systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 19 15:07:49 jukebox systemd[1]: setdatetime-helper.service: Consumed 1.271s CPU time. Dec 19 15:07:49 jukebox volumio[1196]: info: USB Boot Capable - Found matching device in SBC capable list: Raspberry PI Dec 19 15:07:49 jukebox sudo[1450]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 19 15:07:49 jukebox sudo[1450]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:07:49 jukebox sudo[1450]: pam_unix(sudo:session): session closed for user root Dec 19 15:07:49 jukebox volumio[1196]: 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 19 15:07:49 jukebox sudo[1449]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 19 15:07:49 jukebox sudo[1449]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:07:49 jukebox volumio[1196]: info: Discovery: adding d98bcdd8-a7a8-4733-bf9a-ad55385ff6a9 Dec 19 15:07:49 jukebox sudo[1449]: pam_unix(sudo:session): session closed for user root Dec 19 15:07:49 jukebox volumio[1196]: info: Discovery: Found device Jukebox Dec 19 15:07:49 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:07:49 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:07:49 jukebox volumio[1196]: info: Asound.conf file unchanged, so no further update is needed Dec 19 15:07:49 jukebox volumio[1196]: info: Output device has changed, restarting MPD Dec 19 15:07:50 jukebox volumio[1196]: info: Output device has changed, restarting Shairport Sync Dec 19 15:07:50 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:07:50 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 15:07:50 jukebox sudo[1459]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 19 15:07:50 jukebox sudo[1459]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:07:50 jukebox sudo[1459]: pam_unix(sudo:session): session closed for user root Dec 19 15:07:50 jukebox sudo[1461]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 15:07:50 jukebox sudo[1461]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:07:50 jukebox volumio[1196]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 19 15:07:50 jukebox volumio[1196]: info: ___________ START PLUGINS ___________ Dec 19 15:07:50 jukebox volumio[1196]: info: ControllerMpd::onStart: Initializing MPD Dec 19 15:07:50 jukebox volumio[1196]: info: Creating MPD Configuration file Dec 19 15:07:50 jukebox systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 19 15:07:50 jukebox systemd[1]: Starting mpd.service - Music Player Daemon... Dec 19 15:07:50 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 19 15:07:50 jukebox volumio[1196]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 19 15:07:50 jukebox volumio[1196]: info: [1766185670830] CoreMusicLibrary::Adding element Media Servers Dec 19 15:07:50 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 15:07:50 jukebox sudo[1472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 19 15:07:51 jukebox volumio[1196]: info: UPNP Browser: Client initialized successfully Dec 19 15:07:51 jukebox sudo[1473]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 19 15:07:51 jukebox sudo[1473]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 19 15:07:51 jukebox sudo[1470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 19 15:07:51 jukebox sudo[1470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:07:51 jukebox sudo[1474]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 19 15:07:51 jukebox sudo[1473]: pam_unix(sudo:session): session closed for user root Dec 19 15:07:51 jukebox sudo[1472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:07:51 jukebox sudo[1470]: pam_unix(sudo:session): session closed for user root Dec 19 15:07:51 jukebox systemd[1]: mpd.service: Deactivated successfully. Dec 19 15:07:51 jukebox systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 19 15:07:51 jukebox systemd[1]: mpd.socket: Deactivated successfully. Dec 19 15:07:51 jukebox systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 19 15:07:51 jukebox systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 19 15:07:51 jukebox systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 19 15:07:51 jukebox systemd[1]: Starting mpd.service - Music Player Daemon... Dec 19 15:07:51 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:07:51 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 15:07:51 jukebox sudo[1479]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 19 15:07:51 jukebox sudo[1479]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 19 15:07:51 jukebox sudo[1484]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 19 15:07:51 jukebox sudo[1479]: pam_unix(sudo:session): session closed for user root Dec 19 15:07:51 jukebox volumio[1196]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 19 15:07:51 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:07:51 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 15:07:51 jukebox volumio[1196]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 19 15:07:51 jukebox volumio[1196]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 19 15:07:51 jukebox volumio[1196]: info: [1766185671999] CoreMusicLibrary::Adding element Last_100 Dec 19 15:07:52 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 15:07:52 jukebox volumio[1196]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 19 15:07:52 jukebox volumio[1196]: info: [1766185672125] CoreMusicLibrary::Adding element Webradio Dec 19 15:07:52 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 15:07:52 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 15:07:52 jukebox volumio[1196]: info: Initializing BBC Radios Dec 19 15:07:52 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 15:07:52 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 15:07:52 jukebox volumio[1196]: info: Volumio Calling Home Dec 19 15:07:54 jukebox systemd[1]: upmpdcli.service: Scheduled restart job, restart counter is at 3. Dec 19 15:07:54 jukebox systemd[1]: Stopped upmpdcli.service - UPnP Renderer front-end to MPD. Dec 19 15:07:54 jukebox systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 19 15:07:54 jukebox upmpdcli[1506]: :3:../libupnpp/upnpplib.cxx:273::LibUPnP: Using IPV4 10.0.4.4 port 49152 IPV6 port 49152 Dec 19 15:07:55 jukebox volumio-remote-updater[658]: [2025-12-19 15:07:55] [connect] Successful connection Dec 19 15:07:56 jukebox upmpdcli[1506]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Dec 19 15:07:56 jukebox upmpdcli[1506]: :2:../src/main.cxx:728::MPD connection failed Dec 19 15:08:00 jukebox upmpdcli[1506]: :2:../src/mpdcli.cxx:113::MPDCli::openconn: mpd_connection_new failed: Timeout Dec 19 15:08:00 jukebox upmpdcli[1506]: :2:../src/main.cxx:728::MPD connection failed Dec 19 15:08:01 jukebox volumio[1196]: info: Discovery: this is already registered, d98bcdd8-a7a8-4733-bf9a-ad55385ff6a9 Dec 19 15:08:01 jukebox volumio[1196]: info: Discovery: Found device Jukebox Dec 19 15:08:01 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:01 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:01 jukebox mpd[1485]: 2025-12-19T15:08:01 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 19 15:08:01 jukebox systemd[1]: Started mpd.service - Music Player Daemon. Dec 19 15:08:02 jukebox sudo[1461]: pam_unix(sudo:session): session closed for user root Dec 19 15:08:02 jukebox sudo[1472]: pam_unix(sudo:session): session closed for user root Dec 19 15:08:02 jukebox volumio[1196]: info: Completed starting Core Plugins Dec 19 15:08:02 jukebox volumio[1196]: info: ------------------------------------------- Dec 19 15:08:02 jukebox volumio[1196]: info: ----- MyVolumio plugins startup ---- Dec 19 15:08:02 jukebox volumio[1196]: info: ------------------------------------------- Dec 19 15:08:02 jukebox volumio[1196]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 19 15:08:02 jukebox volumio[1196]: info: MPD Permissions set Dec 19 15:08:02 jukebox volumio[1196]: info: MPD Permissions set Dec 19 15:08:02 jukebox volumio[1196]: info: VolumeController:: Volume=100 Mute =false Dec 19 15:08:02 jukebox volumio[1196]: info: CoreStateMachine::pushState Dec 19 15:08:02 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:02 jukebox volumio[1196]: info: CoreCommandRouter::volumioPushState Dec 19 15:08:03 jukebox volumio[1196]: info: Volumio called home Dec 19 15:08:04 jukebox sudo[1538]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 19 15:08:04 jukebox sudo[1538]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:08:04 jukebox sudo[1538]: pam_unix(sudo:session): session closed for user root Dec 19 15:08:05 jukebox volumio[1396]: Starting albumart workers Dec 19 15:08:05 jukebox volumio[1397]: Starting albumart workers Dec 19 15:08:05 jukebox volumio[1395]: Starting albumart workers Dec 19 15:08:07 jukebox volumio[1196]: error: MPD error: The expression evaluated to a falsy value: Dec 19 15:08:07 jukebox volumio[1196]: assert.ok(self.idling) Dec 19 15:08:07 jukebox volumio[1196]: error: The expression evaluated to a falsy value: Dec 19 15:08:07 jukebox volumio[1196]: assert.ok(self.idling) Dec 19 15:08:07 jukebox volumio[1196]: 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 19 15:08:07 jukebox volumio[1196]: info: MPD running with PID1485 Dec 19 15:08:07 jukebox volumio[1196]: ,establishing connection Dec 19 15:08:07 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:07 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:07 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:07 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:07 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:07 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:07 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:07 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:07 jukebox sudo[1543]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 15:08:07 jukebox sudo[1543]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:08:08 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 15:08:08 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:08 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:08 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:08 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:08 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:08 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:08 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:08 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:08 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:08 jukebox sudo[1545]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 19 15:08:08 jukebox sudo[1545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:08:08 jukebox systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 19 15:08:08 jukebox sudo[1543]: pam_unix(sudo:session): session closed for user root Dec 19 15:08:08 jukebox mpd_monitor.sh[1552]: MPD Monitor Service: Starting MPD Monitor Service Dec 19 15:08:08 jukebox systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 19 15:08:08 jukebox systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 19 15:08:08 jukebox systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 19 15:08:08 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 15:08:08 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 19 15:08:08 jukebox systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 19 15:08:08 jukebox mpd_monitor.sh[1563]: MPD Monitor Service: Starting MPD Monitor Service Dec 19 15:08:08 jukebox sudo[1545]: pam_unix(sudo:session): session closed for user root Dec 19 15:08:09 jukebox volumio[1196]: error: updateQueue error: null Dec 19 15:08:09 jukebox volumio[1196]: 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 19 15:08:10 jukebox volumio[1196]: info: Upmpdcli Daemon Started Dec 19 15:08:10 jukebox volumio-remote-updater[658]: [2025-12-19 15:08:10] [connect] Successful connection Dec 19 15:08:10 jukebox volumio[1196]: 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: 7 Dec 19 15:08:10 jukebox volumio[1196]: info: Successfully started MPD Monitor Dec 19 15:08:10 jukebox volumio[1196]: info: Successfully started MPD Monitor Dec 19 15:08:10 jukebox volumio[1196]: error: updateQueue error: null Dec 19 15:08:10 jukebox volumio[1196]: 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 19 15:08:10 jukebox volumio[1196]: info: Starting Shairport Sync Dec 19 15:08:10 jukebox volumio[1196]: info: Starting Shairport Sync Dec 19 15:08:10 jukebox volumio[1196]: info: Starting Shairport Sync Dec 19 15:08:10 jukebox sudo[1585]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 15:08:10 jukebox sudo[1585]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:08:10 jukebox sudo[1587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 15:08:11 jukebox volumio[1196]: 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 19 15:08:10 jukebox sudo[1587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:08:11 jukebox sudo[1589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 19 15:08:11 jukebox sudo[1589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:08:11 jukebox volumio[1196]: verbose: New Socket.io Connection to 10.0.4.4 from 10.0.4.71 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Dec 19 15:08:11 jukebox systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 19 15:08:11 jukebox systemd[1]: shairport-sync.service: Deactivated successfully. Dec 19 15:08:11 jukebox systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 15:08:11 jukebox systemd[1]: shairport-sync.service: Consumed 1.832s CPU time. Dec 19 15:08:11 jukebox systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 15:08:11 jukebox sudo[1587]: pam_unix(sudo:session): session closed for user root Dec 19 15:08:11 jukebox systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 19 15:08:11 jukebox systemd[1]: shairport-sync.service: Deactivated successfully. Dec 19 15:08:11 jukebox systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 15:08:11 jukebox systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 19 15:08:11 jukebox sudo[1585]: pam_unix(sudo:session): session closed for user root Dec 19 15:08:11 jukebox sudo[1589]: pam_unix(sudo:session): session closed for user root Dec 19 15:08:12 jukebox volumio[1196]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 19 15:08:12 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:12 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:14 jukebox volumio[1196]: info: Shairport-Sync Started Dec 19 15:08:14 jukebox volumio[1196]: Error adding Membership: Error: addMembership EINVAL Dec 19 15:08:14 jukebox volumio[1196]: info: Shairport-Sync Started Dec 19 15:08:14 jukebox volumio[1196]: info: Shairport-Sync Started Dec 19 15:08:14 jukebox volumio[1196]: info: Received Get System Info Dec 19 15:08:14 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 15:08:14 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 15:08:14 jukebox volumio[1196]: info: Discovery: Getting this device information Dec 19 15:08:14 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:14 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:14 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 15:08:14 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:14 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:14 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 19 15:08:14 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetVisibleSources Dec 19 15:08:14 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 15:08:14 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:14 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:14 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:14 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:14 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 19 15:08:15 jukebox volumio[1196]: info: Received Get System Info Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 15:08:15 jukebox volumio[1196]: info: Discovery: Getting this device information Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:15 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:15 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:15 jukebox volumio[1196]: info: Listing playlists Dec 19 15:08:15 jukebox volumio[1196]: info: Received Get System Info Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 15:08:15 jukebox volumio[1196]: info: Discovery: Getting this device information Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:15 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:15 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetVisibleSources Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:15 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:15 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 19 15:08:15 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 19 15:08:16 jukebox volumio[1196]: info: Received Get System Info Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 15:08:16 jukebox volumio[1196]: info: Discovery: Getting this device information Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:16 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:16 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:16 jukebox volumio[1196]: info: Listing playlists Dec 19 15:08:16 jukebox volumio[1196]: info: Received Get System Info Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 15:08:16 jukebox volumio[1196]: info: Discovery: Getting this device information Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:16 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:16 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetVisibleSources Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:16 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:16 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 19 15:08:16 jukebox volumio[1196]: info: Received Get System Info Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 15:08:16 jukebox volumio[1196]: info: Discovery: Getting this device information Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:16 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 15:08:16 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:16 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:17 jukebox volumio[1196]: info: Listing playlists Dec 19 15:08:17 jukebox volumio[1196]: info: Received Get System Info Dec 19 15:08:17 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 19 15:08:17 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 19 15:08:17 jukebox volumio[1196]: info: Discovery: Getting this device information Dec 19 15:08:17 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:17 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:17 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 19 15:08:17 jukebox volumio[1196]: info: CoreCommandRouter::volumioGetState Dec 19 15:08:17 jukebox volumio[1196]: info: CorePlayQueue::getTrack 0 Dec 19 15:08:22 jukebox volumio[1196]: verbose: New Socket.io Connection to 10.0.4.4 from 10.0.4.71 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Dec 19 15:08:25 jukebox volumio-remote-updater[658]: [2025-12-19 15:08:25] [connect] Successful connection Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 19 15:08:27 jukebox volumio[1196]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 19 15:08:27 jukebox volumio[1196]: info: Adding plugin bluetooth to MyMusic Plugins Dec 19 15:08:27 jukebox volumio[1196]: info: Adding plugin multiroom to MyMusic Plugins Dec 19 15:08:27 jukebox volumio[1196]: info: Adding plugin metavolumio to MyMusic Plugins Dec 19 15:08:27 jukebox volumio[1196]: info: Adding plugin cd_controller to MyMusic Plugins Dec 19 15:08:27 jukebox volumio[1196]: info: Adding plugin qobuzconnect to MyMusic Plugins Dec 19 15:08:27 jukebox volumio[1196]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 19 15:08:28 jukebox volumio[1196]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 19 15:08:28 jukebox volumio[1196]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 19 15:08:32 jukebox upmpdcli[1661]: writing RSA key Dec 19 15:08:38 jukebox volumio[1196]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 19 15:08:38 jukebox volumio[1196]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 19 15:08:38 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 15:08:38 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 15:08:38 jukebox volumio[1196]: info: Starting MyVolumio Remote Streaming Endpoints Dec 19 15:08:38 jukebox volumio[1196]: info: MyVolumio login type: Token Dec 19 15:08:39 jukebox volumio[1196]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 19 15:08:39 jukebox volumio[1196]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 19 15:08:40 jukebox volumio-remote-updater[658]: [2025-12-19 15:08:40] [connect] Successful connection Dec 19 15:08:45 jukebox volumio[1196]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 19 15:08:45 jukebox volumio[1196]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 19 15:08:45 jukebox volumio[1196]: info: Streaming services startup Dec 19 15:08:45 jukebox volumio[1196]: info: Starting Streaming Daemon Dec 19 15:08:45 jukebox volumio[1196]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 19 15:08:45 jukebox sudo[1685]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 19 15:08:45 jukebox sudo[1685]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:08:45 jukebox sudo[1685]: pam_unix(sudo:session): session closed for user root Dec 19 15:08:45 jukebox volumio[1196]: verbose: New Socket.io Connection to 10.0.4.4 from 10.0.4.71 UA: Mozilla/5.0 (X11; Linux x86_64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/142.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 12 Dec 19 15:08:46 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 19 15:08:46 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 19 15:08:46 jukebox volumio[1196]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 19 15:08:46 jukebox volumio[1196]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 15:08:46 jukebox volumio[1196]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Dec 19 15:08:46 jukebox volumio[1196]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Dec 19 15:08:46 jukebox volumio[1196]: at new NodeError (node:internal/errors:405:5) Dec 19 15:08:46 jukebox volumio[1196]: at assert (node:internal/assert:14:11) Dec 19 15:08:46 jukebox volumio[1196]: at internalConnectMultiple (node:net:1118:3) Dec 19 15:08:46 jukebox volumio[1196]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Dec 19 15:08:46 jukebox volumio[1196]: at listOnTimeout (node:internal/timers:575:11) Dec 19 15:08:46 jukebox volumio[1196]: at process.processTimers (node:internal/timers:514:7) { Dec 19 15:08:46 jukebox volumio[1196]: code: 'ERR_INTERNAL_ASSERTION' Dec 19 15:08:46 jukebox volumio[1196]: } Dec 19 15:08:46 jukebox volumio[1196]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 19 15:08:54 jukebox sudo[1728]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-19 15:07' Dec 19 15:08:54 jukebox sudo[1728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 19 15:08:55 jukebox volumio-remote-updater[658]: [2025-12-19 15:08:55] [connect] Successful connection 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"