Dec 27 12:30:18 lh-vol ntpd[820]: CLOCK: time stepped by 906115.042674 Dec 27 12:30:18 lh-vol ntpd[820]: CLOCK: time changed from 2025-12-17 to 2025-12-27 Dec 27 12:30:18 lh-vol ntpd[820]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes Dec 27 12:30:18 lh-vol kernel: netfs: FS-Cache loaded Dec 27 12:30:18 lh-vol kernel: Key type cifs.spnego registered Dec 27 12:30:18 lh-vol kernel: Key type cifs.idmap registered Dec 27 12:30:18 lh-vol 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 27 12:30:18 lh-vol kernel: CIFS: Attempting to mount //192.168.26.187/music Dec 27 12:30:18 lh-vol volumio[1056]: info: Loading plugin "upnp_browser"... Dec 27 12:30:19 lh-vol systemd[1]: systemd-fsckd.service: Deactivated successfully. Dec 27 12:30:19 lh-vol systemd[1]: Starting dpkg-db-backup.service - Daily dpkg database backup service... Dec 27 12:30:19 lh-vol systemd[1]: Starting e2scrub_all.service - Online ext4 Metadata Check for All Filesystems... Dec 27 12:30:19 lh-vol systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. Dec 27 12:30:19 lh-vol systemd[1]: e2scrub_all.service: Deactivated successfully. Dec 27 12:30:19 lh-vol systemd[1]: Finished e2scrub_all.service - Online ext4 Metadata Check for All Filesystems. Dec 27 12:30:19 lh-vol systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. Dec 27 12:30:19 lh-vol sudo[1144]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:19 lh-vol systemd[1]: dpkg-db-backup.service: Deactivated successfully. Dec 27 12:30:19 lh-vol systemd[1]: Finished dpkg-db-backup.service - Daily dpkg database backup service. Dec 27 12:30:19 lh-vol sh[523]: timed out Dec 27 12:30:19 lh-vol dhcpcd[533]: timed out Dec 27 12:30:19 lh-vol sh[486]: ifup: failed to bring up eth0 Dec 27 12:30:19 lh-vol systemd[1]: ifup@eth0.service: Main process exited, code=exited, status=1/FAILURE Dec 27 12:30:19 lh-vol systemd[1]: ifup@eth0.service: Failed with result 'exit-code'. Dec 27 12:30:20 lh-vol volumio[1056]: info: Loading plugin "alarm-clock"... Dec 27 12:30:20 lh-vol volumio[1056]: info: Loading plugin "airplay_emulation"... Dec 27 12:30:20 lh-vol volumio[1056]: info: Starting Shairport Sync Dec 27 12:30:20 lh-vol volumio[1056]: info: Loading plugin "last_100"... Dec 27 12:30:20 lh-vol volumio[1056]: info: Loading plugin "webradio"... Dec 27 12:30:20 lh-vol volumio[1056]: info: Loading plugin "i2s_dacs"... Dec 27 12:30:20 lh-vol volumio[1056]: info: Loading plugin "volumiodiscovery"... Dec 27 12:30:20 lh-vol volumio[1056]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 12:30:20 lh-vol volumio[1056]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 12:30:20 lh-vol node[1056]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. Dec 27 12:30:20 lh-vol volumio[1056]: *** WARNING *** For more information see Dec 27 12:30:20 lh-vol volumio[1056]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 12:30:20 lh-vol volumio[1056]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 12:30:20 lh-vol volumio[1056]: *** WARNING *** For more information see Dec 27 12:30:20 lh-vol node[1056]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 12:30:20 lh-vol node[1056]: *** WARNING *** For more information see Dec 27 12:30:20 lh-vol node[1056]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. Dec 27 12:30:20 lh-vol node[1056]: *** WARNING *** Please fix your application to use the native API of Avahi! Dec 27 12:30:20 lh-vol node[1056]: *** WARNING *** For more information see Dec 27 12:30:20 lh-vol volumio[1056]: info: Applying required configuration parameters for plugin volumiodiscovery Dec 27 12:30:20 lh-vol volumio[1056]: info: Discovery: Started advertising with name: lh-vol Dec 27 12:30:20 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback Dec 27 12:30:20 lh-vol volumio[1056]: info: Loading plugin "youtube2"... Dec 27 12:30:21 lh-vol volumio[1056]: info: Loading plugin "ytcr"... Dec 27 12:30:22 lh-vol sudo[1123]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:22 lh-vol systemd[1]: systemd-hostnamed.service: Deactivated successfully. Dec 27 12:30:23 lh-vol volumio[1056]: info: Loading plugin "ytmusic"... Dec 27 12:30:24 lh-vol volumio[1056]: info: Loading plugin "outputs"... Dec 27 12:30:24 lh-vol volumio[1056]: info: Loading plugin "albumart"... Dec 27 12:30:24 lh-vol volumio[1056]: info: Plugin example_plugin is not enabled Dec 27 12:30:24 lh-vol volumio[1056]: info: Loading plugin "inputs"... Dec 27 12:30:24 lh-vol volumio[1056]: info: Loading plugin "updater_comm"... Dec 27 12:30:25 lh-vol volumio[1056]: info: Plugin mpdemulation is not enabled Dec 27 12:30:25 lh-vol volumio[1056]: info: Loading plugin "rest_api"... Dec 27 12:30:25 lh-vol volumio[1056]: info: Loading plugin "websocket"... Dec 27 12:30:25 lh-vol volumio[1056]: info: Starting Socket.io Server version 1.7.4 Dec 27 12:30:25 lh-vol volumio[1056]: info: Loading plugin "fusiondsp"... Dec 27 12:30:25 lh-vol volumio[1200]: Forking 3 albumart workers Dec 27 12:30:26 lh-vol volumio[1056]: info: Applying required configuration parameters for plugin fusiondsp Dec 27 12:30:27 lh-vol volumio[1210]: Starting albumart workers Dec 27 12:30:27 lh-vol volumio[1056]: info: Loading i18n strings for locale en Dec 27 12:30:27 lh-vol volumio[1056]: Updating browse sources language Dec 27 12:30:27 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 12:30:28 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 12:30:28 lh-vol volumio[1056]: info: CoreCommandRouter::initPlayerControls Dec 27 12:30:28 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:28 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:28 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:28 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:28 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:28 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:28 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:28 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:28 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 12:30:28 lh-vol volumio[1056]: Express server listening on port 3000 Dec 27 12:30:28 lh-vol volumio[1056]: [Metrics] WebUI: 14s 794.24ms Dec 27 12:30:28 lh-vol volumio[1056]: info: CoreStateMachine::resetVolumioState Dec 27 12:30:28 lh-vol volumio[1056]: info: CoreStateMachine::getcurrentVolume Dec 27 12:30:28 lh-vol volumio[1056]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 12:30:28 lh-vol sudo[1264]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 27 12:30:28 lh-vol sudo[1264]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:28 lh-vol sudo[1266]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 27 12:30:28 lh-vol sudo[1266]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:28 lh-vol sudo[1264]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:28 lh-vol sudo[1266]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:29 lh-vol volumio[1056]: info: Volumio Network Manager: Network status updated: 2 Dec 27 12:30:29 lh-vol volumio-remote-updater[608]: [2025-12-27 12:30:29] [connect] Successful connection Dec 27 12:30:29 lh-vol volumio[1056]: info: CoreStateMachine::pushState Dec 27 12:30:29 lh-vol volumio[1056]: info: CorePlayQueue::getTrack 0 Dec 27 12:30:29 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 12:30:29 lh-vol volumio[1056]: info: CoreCommandRouter::volumioPushState Dec 27 12:30:29 lh-vol volumio[1056]: info: CoreStateMachine::updateTrackBlock Dec 27 12:30:29 lh-vol volumio[1056]: info: CorePlayQueue::getTrackBlock Dec 27 12:30:29 lh-vol volumio[1056]: info: CoreCommandRouter::volumioRetrievevolume Dec 27 12:30:29 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:30:29 lh-vol volumio[1056]: info: Reloading queue from file Dec 27 12:30:29 lh-vol volumio-remote-updater[608]: [2025-12-27 12:30:29] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=3&transport=websocket&t=1766809829 101 Dec 27 12:30:29 lh-vol volumio[1056]: 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: 1 Dec 27 12:30:29 lh-vol volumio[1056]: info: CoreStateMachine::setRepeat null single undefined Dec 27 12:30:29 lh-vol volumio[1056]: info: CoreStateMachine::pushState Dec 27 12:30:29 lh-vol volumio[1056]: info: CorePlayQueue::getTrack 0 Dec 27 12:30:29 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 27 12:30:29 lh-vol volumio[1056]: info: CoreCommandRouter::volumioPushState Dec 27 12:30:29 lh-vol volumio[1056]: info: CoreStateMachine::setRandom null Dec 27 12:30:29 lh-vol volumio[1056]: info: CoreStateMachine::pushState Dec 27 12:30:29 lh-vol volumio[1056]: info: CorePlayQueue::getTrack 0 Dec 27 12:30:29 lh-vol volumio[1056]: info: CoreCommandRouter::volumioPushState Dec 27 12:30:29 lh-vol volumio[1056]: info: Setting Device type: Raspberry PI Dec 27 12:30:29 lh-vol volumio[1056]: info: Completed loading Core Plugins Dec 27 12:30:29 lh-vol volumio[1056]: info: Preparing to generate the ALSA configuration file Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreStateMachine::pushState Dec 27 12:30:30 lh-vol volumio[1056]: info: CorePlayQueue::getTrack 0 Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::volumioPushState Dec 27 12:30:30 lh-vol volumio[1056]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 27 12:30:30 lh-vol volumio[1056]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 27 12:30:30 lh-vol volumio[1056]: info: Reading ALSA contributions from plugins. Dec 27 12:30:30 lh-vol volumio[1056]: info: Discovery: adding 1fdf58e8-8a51-4c6f-89d0-7b8c905a18dd Dec 27 12:30:30 lh-vol volumio[1056]: info: Discovery: Found device lh-vol Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::volumioGetState Dec 27 12:30:30 lh-vol volumio[1056]: info: CorePlayQueue::getTrack 0 Dec 27 12:30:30 lh-vol volumio[1056]: info: Discovery: this is already registered, 1fdf58e8-8a51-4c6f-89d0-7b8c905a18dd Dec 27 12:30:30 lh-vol volumio[1056]: info: Discovery: Found device lh-vol Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::volumioGetState Dec 27 12:30:30 lh-vol volumio[1056]: info: CorePlayQueue::getTrack 0 Dec 27 12:30:30 lh-vol sudo[1292]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service Dec 27 12:30:30 lh-vol volumio[1056]: info: Asound.conf file unchanged, so no further update is needed Dec 27 12:30:30 lh-vol volumio[1056]: info: Output device has changed, restarting MPD Dec 27 12:30:30 lh-vol volumio[1056]: info: Output device has changed, restarting Shairport Sync Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:30:30 lh-vol sudo[1292]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:30 lh-vol volumio[1212]: Starting albumart workers Dec 27 12:30:30 lh-vol volumio[1056]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 12:30:30 lh-vol volumio[1056]: info: ___________ START PLUGINS ___________ Dec 27 12:30:30 lh-vol sudo[1295]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 12:30:30 lh-vol sudo[1295]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:30 lh-vol volumio[1056]: info: ControllerMpd::onStart: Initializing MPD Dec 27 12:30:30 lh-vol sudo[1297]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 12:30:30 lh-vol volumio[1056]: info: Creating MPD Configuration file Dec 27 12:30:30 lh-vol sudo[1295]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 12:30:30 lh-vol sudo[1297]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:30 lh-vol volumio[1056]: info: [1766809830417] CoreMusicLibrary::Adding element Media Servers Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 12:30:30 lh-vol systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. Dec 27 12:30:30 lh-vol sudo[1292]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:30 lh-vol sudo[1305]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 12:30:30 lh-vol sudo[1305]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:30 lh-vol sudo[1305]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:30:30 lh-vol sudo[1308]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 12:30:30 lh-vol sudo[1308]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:30 lh-vol volumio[1211]: Starting albumart workers Dec 27 12:30:30 lh-vol systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 27 12:30:30 lh-vol systemd[1]: Starting mpd.service - Music Player Daemon... Dec 27 12:30:30 lh-vol systemd[1]: mpd.service: Deactivated successfully. Dec 27 12:30:30 lh-vol systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 27 12:30:30 lh-vol systemd[1]: mpd.socket: Deactivated successfully. Dec 27 12:30:30 lh-vol systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 27 12:30:30 lh-vol systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 27 12:30:30 lh-vol systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 27 12:30:30 lh-vol systemd[1]: Starting mpd.service - Music Player Daemon... Dec 27 12:30:30 lh-vol volumio[1056]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:30:30 lh-vol systemd[1]: systemd-timedated.service: Deactivated successfully. Dec 27 12:30:30 lh-vol volumio[1056]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 12:30:30 lh-vol volumio[1056]: info: [1766809830919] CoreMusicLibrary::Adding element Last_100 Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 12:30:30 lh-vol volumio[1056]: info: [1766809830966] CoreMusicLibrary::Adding element Webradio Dec 27 12:30:30 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 12:30:31 lh-vol sudo[1329]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 27 12:30:31 lh-vol sudo[1329]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 27 12:30:31 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 12:30:31 lh-vol sudo[1336]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 27 12:30:31 lh-vol sudo[1329]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:31 lh-vol volumio[1056]: info: Initializing BBC Radios Dec 27 12:30:31 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 12:30:31 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:30:31 lh-vol volumio[1056]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 12:30:31 lh-vol volumio[1056]: info: [1766809831573] CoreMusicLibrary::Adding element YouTube2 Dec 27 12:30:31 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 12:30:31 lh-vol volumio[1056]: Cannot find translation for source YouTube2 Dec 27 12:30:31 lh-vol volumio[1056]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 27 12:30:31 lh-vol volumio[1056]: info: [1766809831784] CoreMusicLibrary::Adding element YouTube Music Dec 27 12:30:31 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 27 12:30:31 lh-vol volumio[1056]: Cannot find translation for source YouTube2 Dec 27 12:30:31 lh-vol volumio[1056]: Cannot find translation for source YouTube Music Dec 27 12:30:32 lh-vol volumio[1056]: info: Loading i18n strings for locale en Dec 27 12:30:32 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 27 12:30:32 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:32 lh-vol volumio[1056]: info: FusionDsp - mixtype--------------------- Software Dec 27 12:30:32 lh-vol volumio[1056]: info: Volumio Calling Home Dec 27 12:30:32 lh-vol volumio[1056]: info: Preparing to generate the ALSA configuration file Dec 27 12:30:33 lh-vol volumio[1056]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Dec 27 12:30:33 lh-vol volumio[1056]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Dec 27 12:30:33 lh-vol volumio[1056]: info: Reading ALSA contributions from plugins. Dec 27 12:30:33 lh-vol volumio[1056]: info: MPD Permissions set Dec 27 12:30:33 lh-vol volumio[1056]: info: MPD Permissions set Dec 27 12:30:33 lh-vol volumio[1056]: info: Upmpdcli Daemon Started Dec 27 12:30:33 lh-vol volumio[1056]: info: Volumio called home Dec 27 12:30:33 lh-vol volumio[1056]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:30:33 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:34 lh-vol volumio[1056]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 Dec 27 12:30:34 lh-vol volumio[1056]: info: camilladsp service started and running in background, instance 1 Dec 27 12:30:34 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:34 lh-vol volumio[1056]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA Dec 27 12:30:34 lh-vol volumio[1056]: info: FusionDsp loaded Dec 27 12:30:34 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 27 12:30:34 lh-vol volumio[1056]: error: FusionDsp - Socket connection not established Dec 27 12:30:34 lh-vol volumio[1056]: info: FusionDsp - Reporting Fusion DSP Enabled Dec 27 12:30:34 lh-vol volumio[1056]: info: Adding Signal Path Element [object Object] Dec 27 12:30:34 lh-vol volumio[1056]: info: Adding fusiondspeq DSP Signal Path Element Dec 27 12:30:34 lh-vol volumio[1056]: info: FusionDsp - ---- installed callbackRead Dec 27 12:30:34 lh-vol sudo[1392]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Dec 27 12:30:34 lh-vol volumio[1056]: info: FusionDsp - If filter freq >samplerate/2 then disable it Dec 27 12:30:34 lh-vol sudo[1392]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:35 lh-vol systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Dec 27 12:30:35 lh-vol sudo[1392]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:35 lh-vol volumio[1056]: error: FusionDsp - Reload WebSocket error: [object Object] Dec 27 12:30:35 lh-vol volumio[1056]: info: Starting Shairport Sync Dec 27 12:30:35 lh-vol volumio[1056]: info: Starting Shairport Sync Dec 27 12:30:35 lh-vol volumio[1056]: info: Starting Shairport Sync Dec 27 12:30:35 lh-vol sudo[1399]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 12:30:35 lh-vol sudo[1399]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:35 lh-vol sudo[1401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 12:30:35 lh-vol sudo[1401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:35 lh-vol sudo[1403]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 12:30:35 lh-vol sudo[1403]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:35 lh-vol volumio[1056]: info: Asound.conf file unchanged, so no further update is needed Dec 27 12:30:35 lh-vol volumio[1056]: info: Output device has changed, restarting MPD Dec 27 12:30:35 lh-vol systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 27 12:30:35 lh-vol volumio[1056]: info: Output device has changed, restarting Shairport Sync Dec 27 12:30:35 lh-vol systemd[1]: shairport-sync.service: Deactivated successfully. Dec 27 12:30:35 lh-vol systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 12:30:35 lh-vol systemd[1]: shairport-sync.service: Consumed 1.622s CPU time. Dec 27 12:30:35 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:35 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:30:36 lh-vol systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 12:30:36 lh-vol sudo[1399]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:36 lh-vol sudo[1408]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 27 12:30:36 lh-vol sudo[1401]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:36 lh-vol sudo[1403]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:36 lh-vol sudo[1412]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 27 12:30:36 lh-vol sudo[1408]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:36 lh-vol volumio[1056]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 27 12:30:36 lh-vol sudo[1412]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:36 lh-vol sudo[1408]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:36 lh-vol systemd[1]: mpd.service: Deactivated successfully. Dec 27 12:30:36 lh-vol systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 27 12:30:36 lh-vol systemd[1]: mpd.service: Consumed 3.860s CPU time. Dec 27 12:30:36 lh-vol systemd[1]: mpd.socket: Deactivated successfully. Dec 27 12:30:36 lh-vol systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 27 12:30:36 lh-vol systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 27 12:30:36 lh-vol systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 27 12:30:36 lh-vol systemd[1]: Starting mpd.service - Music Player Daemon... Dec 27 12:30:36 lh-vol sudo[1433]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 27 12:30:36 lh-vol sudo[1433]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 27 12:30:36 lh-vol sudo[1435]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Dec 27 12:30:36 lh-vol sudo[1433]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:36 lh-vol volumio[1056]: error: Upnp client error: Error: read ECONNRESET Dec 27 12:30:36 lh-vol volumio[1056]: error: Upnp client error: Error: read ECONNRESET Dec 27 12:30:37 lh-vol volumio[1056]: info: MPD Permissions set Dec 27 12:30:37 lh-vol volumio[1056]: info: Shairport-Sync Started Dec 27 12:30:37 lh-vol volumio[1056]: Error adding Membership: Error: addMembership EINVAL Dec 27 12:30:37 lh-vol volumio[1056]: info: Shairport-Sync Started Dec 27 12:30:37 lh-vol volumio[1056]: info: Shairport-Sync Started Dec 27 12:30:37 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:37 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:37 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:37 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:37 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:37 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:37 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:37 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:37 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:30:37 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 27 12:30:37 lh-vol volumio[1056]: info: CoreCommandRouter::volumioGetState Dec 27 12:30:37 lh-vol volumio[1056]: info: CorePlayQueue::getTrack 0 Dec 27 12:30:38 lh-vol volumio[1056]: info: Starting Shairport Sync Dec 27 12:30:38 lh-vol sudo[1447]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 27 12:30:38 lh-vol sudo[1447]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:38 lh-vol systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 27 12:30:38 lh-vol systemd[1]: shairport-sync.service: Deactivated successfully. Dec 27 12:30:38 lh-vol systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 12:30:38 lh-vol systemd[1]: shairport-sync.service: Consumed 1.303s CPU time. Dec 27 12:30:38 lh-vol systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 27 12:30:38 lh-vol sudo[1447]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:38 lh-vol volumio[1056]: info: Shairport-Sync Started Dec 27 12:30:42 lh-vol mpd[1436]: 2025-12-27T12:30:42 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 27 12:30:42 lh-vol systemd[1]: Started mpd.service - Music Player Daemon. Dec 27 12:30:42 lh-vol sudo[1297]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:42 lh-vol sudo[1308]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:42 lh-vol sudo[1412]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:42 lh-vol volumio[1056]: info: Completed starting Core Plugins Dec 27 12:30:42 lh-vol volumio[1056]: info: ------------------------------------------- Dec 27 12:30:42 lh-vol volumio[1056]: info: ----- MyVolumio plugins startup ---- Dec 27 12:30:42 lh-vol volumio[1056]: info: ------------------------------------------- Dec 27 12:30:42 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Fetching plans data.... Dec 27 12:30:42 lh-vol volumio[1056]: error: MPD error: The expression evaluated to a falsy value: Dec 27 12:30:42 lh-vol volumio[1056]: assert.ok(self.idling) Dec 27 12:30:42 lh-vol volumio[1056]: error: The expression evaluated to a falsy value: Dec 27 12:30:42 lh-vol volumio[1056]: assert.ok(self.idling) Dec 27 12:30:43 lh-vol volumio[1056]: error: MPD error: The expression evaluated to a falsy value: Dec 27 12:30:43 lh-vol volumio[1056]: assert.ok(self.idling) Dec 27 12:30:43 lh-vol volumio[1056]: error: The expression evaluated to a falsy value: Dec 27 12:30:43 lh-vol volumio[1056]: assert.ok(self.idling) Dec 27 12:30:43 lh-vol volumio[1056]: info: MPD running with PID1436 Dec 27 12:30:43 lh-vol volumio[1056]: ,establishing connection Dec 27 12:30:43 lh-vol volumio[1056]: error: updateQueue error: null Dec 27 12:30:43 lh-vol volumio[1056]: error: updateQueue error: null Dec 27 12:30:47 lh-vol volumio[1056]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso Dec 27 12:30:53 lh-vol volumio[1056]: info: Adding plugin bluetooth to MyMusic Plugins Dec 27 12:30:53 lh-vol volumio[1056]: info: Adding plugin multiroom to MyMusic Plugins Dec 27 12:30:53 lh-vol volumio[1056]: info: Adding plugin metavolumio to MyMusic Plugins Dec 27 12:30:53 lh-vol volumio[1056]: info: Adding plugin cd_controller to MyMusic Plugins Dec 27 12:30:53 lh-vol volumio[1056]: info: Adding plugin smart_inputs to MyMusic Plugins Dec 27 12:30:53 lh-vol volumio[1056]: info: Adding plugin tidalconnect to MyMusic Plugins Dec 27 12:30:53 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... Dec 27 12:30:56 lh-vol volumio[1056]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded Dec 27 12:30:56 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio Dec 27 12:30:56 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:30:56 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:30:56 lh-vol volumio[1056]: info: Starting MyVolumio Remote Streaming Endpoints Dec 27 12:30:56 lh-vol volumio[1056]: info: MyVolumio login type: Token Dec 27 12:30:56 lh-vol volumio[1056]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started Dec 27 12:30:56 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... Dec 27 12:30:57 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded Dec 27 12:30:57 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services Dec 27 12:30:57 lh-vol volumio[1056]: info: Streaming services startup Dec 27 12:30:57 lh-vol volumio[1056]: info: Starting Streaming Daemon Dec 27 12:30:57 lh-vol volumio[1056]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started Dec 27 12:30:58 lh-vol sudo[1495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 27 12:30:58 lh-vol sudo[1495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:30:58 lh-vol sudo[1495]: pam_unix(sudo:session): session closed for user root Dec 27 12:30:58 lh-vol volumio[1056]: error: Cannot start Volumio Streaming Daemon Dec 27 12:30:58 lh-vol volumio[1056]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service Dec 27 12:30:58 lh-vol volumio[1056]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. Dec 27 12:30:58 lh-vol volumio[1056]: error: MyVolumio Custom Token format not valid, refreshing it Dec 27 12:30:59 lh-vol volumio[1056]: info: MyVolumio login type: Token Dec 27 12:31:00 lh-vol volumio[1056]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN Dec 27 12:31:01 lh-vol volumio[1056]: info: MyVolumio token set successfully Dec 27 12:31:01 lh-vol volumio[1056]: info: MYVOLUMIO: Adding device Dec 27 12:31:01 lh-vol volumio[1056]: info: MYVOLUMIO: Evaluating Server Dec 27 12:31:01 lh-vol systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Dec 27 12:31:01 lh-vol dbus-daemon[584]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.24' (uid=0 pid=1516 comm="timedatectl show --property=NTPSynchronized --valu") Dec 27 12:31:01 lh-vol systemd[1]: Starting systemd-timedated.service - Time & Date Service... Dec 27 12:31:02 lh-vol dbus-daemon[584]: [system] Successfully activated service 'org.freedesktop.timedate1' Dec 27 12:31:02 lh-vol systemd[1]: Started systemd-timedated.service - Time & Date Service. Dec 27 12:31:02 lh-vol setdatetime-helper.sh[1515]: Time is not synchronized. Attempting to sync... Dec 27 12:31:02 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 27 12:31:02 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 27 12:31:02 lh-vol volumio[1056]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 27 12:31:02 lh-vol volumio[1056]: info: MyVolumio status changed Dec 27 12:31:02 lh-vol sudo[1545]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 27 Dec 2025 04:31:02 GMT#015' Dec 27 12:31:02 lh-vol sudo[1545]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 27 12:31:02 lh-vol volumio[1056]: info: Streaming services startup Dec 27 12:31:02 lh-vol volumio[1056]: info: Starting Streaming Daemon Dec 27 12:31:02 lh-vol setdatetime-helper.sh[1546]: Sat Dec 27 12:31:02 CST 2025 Dec 27 12:31:02 lh-vol systemd-journald[319]: Time jumped backwards, rotating. Dec 27 12:31:02 lh-vol systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Dec 27 12:31:02 lh-vol sudo[1545]: pam_unix(sudo:session): session closed for user root Dec 27 12:31:02 lh-vol setdatetime-helper.sh[1515]: Time synchronized successfully. Dec 27 12:31:02 lh-vol volumio[1056]: info: Removing browser output: myVolumio user plan is not superstar Dec 27 12:31:02 lh-vol systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Dec 27 12:31:02 lh-vol volumio[1056]: info: Removing audio output: Dec 27 12:31:02 lh-vol volumio[1056]: info: Stoppping Tunnel 1 Dec 27 12:31:02 lh-vol sudo[1548]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service Dec 27 12:31:02 lh-vol sudo[1548]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:31:02 lh-vol sudo[1552]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service Dec 27 12:31:02 lh-vol sudo[1552]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 27 12:31:02 lh-vol volumio[1056]: error: Failed to ping endpoint us2.myvolumio.org : unknown error Dec 27 12:31:02 lh-vol volumio[1056]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 12:31:02 lh-vol systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 27 12:31:02 lh-vol systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 27 12:31:02 lh-vol sudo[1548]: pam_unix(sudo:session): session closed for user root Dec 27 12:31:02 lh-vol systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 27 12:31:02 lh-vol systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 27 12:31:02 lh-vol systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 27 12:31:02 lh-vol systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 27 12:31:02 lh-vol systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 27 12:31:02 lh-vol systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 27 12:31:02 lh-vol volumio[1056]: Error: Unable to resolve or reject the same promise twice Dec 27 12:31:02 lh-vol volumio[1056]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) Dec 27 12:31:02 lh-vol volumio[1056]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) Dec 27 12:31:02 lh-vol volumio[1056]: at Socket.emit (node:events:514:28) Dec 27 12:31:02 lh-vol volumio[1056]: at addChunk (node:internal/streams/readable:343:12) Dec 27 12:31:02 lh-vol volumio[1056]: at readableAddChunk (node:internal/streams/readable:316:9) Dec 27 12:31:02 lh-vol volumio[1056]: at Readable.push (node:internal/streams/readable:253:10) Dec 27 12:31:02 lh-vol volumio[1056]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) Dec 27 12:31:02 lh-vol volumio[1056]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 27 12:31:02 lh-vol sudo[1552]: pam_unix(sudo:session): session closed for user root Dec 27 12:31:05 lh-vol sudo[1568]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-27 12:30' Dec 27 12:31:05 lh-vol sudo[1568]: 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="1da895bfdbb6eb90d965df32a25a3568df7c84ed" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="04c18c158cc4bac70764c3360b25fcce0c69266a" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 28 19:10:18 UTC 2025" VOLUMIO_VERSION="4.003" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1bd40b20372a0e1ffe6ebc6d3df45570"