May 29 21:20:24 volumiostreamer ntpd[813]: CLOCK: time stepped by 76486.712722 May 29 21:20:24 volumiostreamer ntpd[813]: INIT: MRU 13107 entries, 13 hash bits, 32768 bytes May 29 21:20:25 volumiostreamer systemd[1]: Started ntpsec-rotate-stats.service - Rotate ntpd stats. May 29 21:20:25 volumiostreamer systemd[1]: ntpsec-rotate-stats.service: Deactivated successfully. May 29 21:20:25 volumiostreamer volumio[1049]: info: Loading plugin "alarm-clock"... May 29 21:20:25 volumiostreamer volumio[1049]: info: Loading plugin "airplay_emulation"... May 29 21:20:25 volumiostreamer volumio[1049]: info: Starting Shairport Sync May 29 21:20:25 volumiostreamer volumio[1049]: info: Loading plugin "last_100"... May 29 21:20:25 volumiostreamer volumio[1049]: info: Loading plugin "webradio"... May 29 21:20:25 volumiostreamer volumio[1049]: info: Loading plugin "i2s_dacs"... May 29 21:20:25 volumiostreamer volumio[1049]: info: Loading plugin "volumiodiscovery"... May 29 21:20:25 volumiostreamer volumio[1049]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 29 21:20:25 volumiostreamer volumio[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 21:20:25 volumiostreamer node[1049]: *** WARNING *** The program 'node' uses the Apple Bonjour compatibility layer of Avahi. May 29 21:20:25 volumiostreamer volumio[1049]: *** WARNING *** For more information see May 29 21:20:25 volumiostreamer volumio[1049]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 29 21:20:25 volumiostreamer volumio[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 21:20:25 volumiostreamer volumio[1049]: *** WARNING *** For more information see May 29 21:20:25 volumiostreamer node[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 21:20:25 volumiostreamer node[1049]: *** WARNING *** For more information see May 29 21:20:25 volumiostreamer node[1049]: *** WARNING *** The program 'node' called 'DNSServiceRegister()' which is not supported (or only supported partially) in the Apple Bonjour compatibility layer of Avahi. May 29 21:20:25 volumiostreamer node[1049]: *** WARNING *** Please fix your application to use the native API of Avahi! May 29 21:20:25 volumiostreamer node[1049]: *** WARNING *** For more information see May 29 21:20:25 volumiostreamer volumio[1049]: info: Applying required configuration parameters for plugin volumiodiscovery May 29 21:20:25 volumiostreamer volumio[1049]: info: Discovery: Started advertising with name: Volumiostreamer May 29 21:20:25 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , registerCallback May 29 21:20:25 volumiostreamer volumio[1049]: info: Loading plugin "ytmusic"... May 29 21:20:26 volumiostreamer systemd[1]: systemd-hostnamed.service: Deactivated successfully. May 29 21:20:26 volumiostreamer ntpd[813]: DNS: dns_check: processing 1.debian.pool.ntp.org, 8, 101 May 29 21:20:26 volumiostreamer ntpd[813]: DNS: Pool taking: 195.95.153.43 May 29 21:20:27 volumiostreamer ntpd[813]: DNS: Pool taking: 195.20.235.143 May 29 21:20:27 volumiostreamer ntpd[813]: DNS: Pool skipping: 195.95.153.59 May 29 21:20:27 volumiostreamer ntpd[813]: DNS: Pool skipping: 5.250.184.159 May 29 21:20:27 volumiostreamer ntpd[813]: DNS: dns_take_status: 1.debian.pool.ntp.org=>good, 8 May 29 21:20:27 volumiostreamer volumio-remote-updater[577]: [2025-05-29 21:20:27] [connect] Successful connection May 29 21:20:27 volumiostreamer sudo[1140]: pam_unix(sudo:session): session closed for user root May 29 21:20:27 volumiostreamer ntpd[813]: DNS: dns_probe: 0.debian.pool.ntp.org, cast_flags:8, flags:101 May 29 21:20:30 volumiostreamer volumio[1049]: info: Loading plugin "outputs"... May 29 21:20:30 volumiostreamer volumio[1049]: info: Loading plugin "albumart"... May 29 21:20:30 volumiostreamer volumio[1049]: info: Plugin example_plugin is not enabled May 29 21:20:30 volumiostreamer volumio[1049]: info: Loading plugin "inputs"... May 29 21:20:30 volumiostreamer volumio[1049]: info: Loading plugin "updater_comm"... May 29 21:20:30 volumiostreamer volumio[1049]: info: Plugin mpdemulation is not enabled May 29 21:20:30 volumiostreamer volumio[1049]: info: Loading plugin "rest_api"... May 29 21:20:30 volumiostreamer volumio[1049]: info: Loading plugin "websocket"... May 29 21:20:30 volumiostreamer volumio[1049]: info: Starting Socket.io Server version 1.7.4 May 29 21:20:30 volumiostreamer volumio[1049]: info: Loading plugin "fusiondsp"... May 29 21:20:30 volumiostreamer ntpd[813]: DNS: dns_check: processing 0.debian.pool.ntp.org, 8, 101 May 29 21:20:30 volumiostreamer ntpd[813]: DNS: Pool taking: 90.68.206.60 May 29 21:20:30 volumiostreamer ntpd[813]: DNS: Pool taking: 94.143.139.219 May 29 21:20:30 volumiostreamer ntpd[813]: DNS: Pool skipping: 217.160.163.33 May 29 21:20:30 volumiostreamer ntpd[813]: DNS: Pool taking: 162.159.200.123 May 29 21:20:30 volumiostreamer ntpd[813]: DNS: dns_take_status: 0.debian.pool.ntp.org=>good, 8 May 29 21:20:31 volumiostreamer volumio[1175]: Forking 3 albumart workers May 29 21:20:31 volumiostreamer volumio[1049]: info: Applying required configuration parameters for plugin fusiondsp May 29 21:20:32 volumiostreamer volumio[1049]: info: Loading i18n strings for locale es May 29 21:20:32 volumiostreamer volumio[1049]: Updating browse sources language May 29 21:20:32 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 21:20:33 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 21:20:33 volumiostreamer volumio[1049]: info: CoreCommandRouter::initPlayerControls May 29 21:20:33 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:33 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:33 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:33 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:33 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:33 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:33 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:33 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:33 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 21:20:33 volumiostreamer volumio[1049]: Express server listening on port 3000 May 29 21:20:33 volumiostreamer volumio[1049]: [Metrics] WebUI: 14s 899.54ms May 29 21:20:34 volumiostreamer volumio[1049]: info: CoreStateMachine::resetVolumioState May 29 21:20:34 volumiostreamer volumio[1049]: info: CoreStateMachine::getcurrentVolume May 29 21:20:34 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioRetrievevolume May 29 21:20:34 volumiostreamer sudo[1235]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 May 29 21:20:34 volumiostreamer sudo[1235]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:20:34 volumiostreamer sudo[1235]: pam_unix(sudo:session): session closed for user root May 29 21:20:34 volumiostreamer sudo[1237]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 May 29 21:20:34 volumiostreamer sudo[1237]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:20:34 volumiostreamer sudo[1237]: pam_unix(sudo:session): session closed for user root May 29 21:20:34 volumiostreamer volumio[1049]: info: Volumio Network Manager: Network status updated: 2 May 29 21:20:34 volumiostreamer volumio[1049]: info: VolumeController:: Volume=45 Mute =false May 29 21:20:34 volumiostreamer volumio[1049]: info: CoreStateMachine::pushState May 29 21:20:34 volumiostreamer volumio[1049]: info: CorePlayQueue::getTrack 0 May 29 21:20:34 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 21:20:34 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioPushState May 29 21:20:34 volumiostreamer volumio[1049]: info: CoreStateMachine::updateTrackBlock May 29 21:20:34 volumiostreamer volumio[1049]: info: CorePlayQueue::getTrackBlock May 29 21:20:34 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioRetrievevolume May 29 21:20:34 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:20:34 volumiostreamer volumio[1049]: info: Executing start script for DAC IQaudIO DAC Plus May 29 21:20:34 volumiostreamer volumio[1049]: info: Reloading queue from file May 29 21:20:35 volumiostreamer volumio[1049]: info: CoreStateMachine::setRepeat null single undefined May 29 21:20:35 volumiostreamer volumio[1049]: info: CoreStateMachine::pushState May 29 21:20:35 volumiostreamer volumio[1049]: info: CorePlayQueue::getTrack 0 May 29 21:20:35 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 29 21:20:35 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioPushState May 29 21:20:35 volumiostreamer volumio[1049]: info: CoreStateMachine::setRandom null May 29 21:20:35 volumiostreamer volumio[1049]: info: CoreStateMachine::pushState May 29 21:20:35 volumiostreamer volumio[1049]: info: CorePlayQueue::getTrack 0 May 29 21:20:35 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioPushState May 29 21:20:35 volumiostreamer volumio[1049]: info: Setting Device type: Raspberry PI May 29 21:20:35 volumiostreamer volumio[1049]: info: DAC script executed May 29 21:20:35 volumiostreamer volumio[1049]: info: Completed loading Core Plugins May 29 21:20:35 volumiostreamer volumio[1049]: info: Preparing to generate the ALSA configuration file May 29 21:20:35 volumiostreamer volumio[1186]: Starting albumart workers May 29 21:20:35 volumiostreamer sudo[1255]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start upmpdcli.service May 29 21:20:35 volumiostreamer sudo[1255]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:20:35 volumiostreamer volumio[1049]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 29 21:20:35 volumiostreamer volumio[1049]: info: Reading ALSA contributions from plugins. May 29 21:20:35 volumiostreamer volumio[1049]: info: VolumeController:: Volume=45 Mute =false May 29 21:20:35 volumiostreamer volumio[1049]: info: CoreStateMachine::pushState May 29 21:20:35 volumiostreamer volumio[1049]: info: CorePlayQueue::getTrack 0 May 29 21:20:35 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioPushState May 29 21:20:35 volumiostreamer systemd[1]: Started upmpdcli.service - UPnP Renderer front-end to MPD. May 29 21:20:35 volumiostreamer sudo[1255]: pam_unix(sudo:session): session closed for user root May 29 21:20:35 volumiostreamer volumio[1049]: info: Upmpdcli Daemon Started May 29 21:20:35 volumiostreamer volumio[1049]: info: Asound.conf file unchanged, so no further update is needed May 29 21:20:35 volumiostreamer volumio[1049]: info: Output device has changed, restarting MPD May 29 21:20:35 volumiostreamer sudo[1271]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 21:20:35 volumiostreamer sudo[1271]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:20:35 volumiostreamer sudo[1271]: pam_unix(sudo:session): session closed for user root May 29 21:20:35 volumiostreamer volumio[1049]: info: Output device has changed, restarting Shairport Sync May 29 21:20:35 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:35 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:20:35 volumiostreamer sudo[1274]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 21:20:35 volumiostreamer sudo[1274]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:20:35 volumiostreamer volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 21:20:35 volumiostreamer volumio[1049]: info: ___________ START PLUGINS ___________ May 29 21:20:35 volumiostreamer volumio[1049]: info: ControllerMpd::onStart: Initializing MPD May 29 21:20:35 volumiostreamer volumio[1049]: info: Creating MPD Configuration file May 29 21:20:35 volumiostreamer sudo[1282]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 21:20:35 volumiostreamer sudo[1282]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:20:35 volumiostreamer sudo[1282]: pam_unix(sudo:session): session closed for user root May 29 21:20:35 volumiostreamer volumio[1188]: Starting albumart workers May 29 21:20:35 volumiostreamer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 29 21:20:35 volumiostreamer systemd[1]: Starting mpd.service - Music Player Daemon... May 29 21:20:35 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam May 29 21:20:35 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 21:20:35 volumiostreamer volumio[1049]: info: [1748546435981] CoreMusicLibrary::Adding element Media Servers May 29 21:20:35 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 21:20:35 volumiostreamer sudo[1286]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 21:20:35 volumiostreamer sudo[1286]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:20:36 volumiostreamer systemd[1]: mpd.service: Deactivated successfully. May 29 21:20:36 volumiostreamer systemd[1]: Stopped mpd.service - Music Player Daemon. May 29 21:20:36 volumiostreamer systemd[1]: mpd.socket: Deactivated successfully. May 29 21:20:36 volumiostreamer systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 29 21:20:36 volumiostreamer systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 29 21:20:36 volumiostreamer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 29 21:20:36 volumiostreamer systemd[1]: Starting mpd.service - Music Player Daemon... May 29 21:20:36 volumiostreamer volumio[1187]: Starting albumart workers May 29 21:20:36 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:36 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:20:36 volumiostreamer sudo[1289]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 21:20:36 volumiostreamer sudo[1289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 21:20:36 volumiostreamer sudo[1294]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 29 21:20:36 volumiostreamer sudo[1289]: pam_unix(sudo:session): session closed for user root May 29 21:20:36 volumiostreamer volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 21:20:36 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:36 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:20:36 volumiostreamer volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 21:20:36 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 21:20:36 volumiostreamer volumio[1049]: info: [1748546436525] CoreMusicLibrary::Adding element Last_100 May 29 21:20:36 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 21:20:36 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 21:20:36 volumiostreamer volumio[1049]: info: [1748546436538] CoreMusicLibrary::Adding element Webradio May 29 21:20:36 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 21:20:36 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 21:20:36 volumiostreamer volumio[1049]: info: Initializing BBC Radios May 29 21:20:36 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 21:20:36 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:20:36 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] May 29 21:20:36 volumiostreamer volumio[1049]: info: [1748546436883] CoreMusicLibrary::Adding element YouTube Music May 29 21:20:36 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources May 29 21:20:36 volumiostreamer volumio[1049]: Cannot find translation for source YouTube Music May 29 21:20:37 volumiostreamer volumio[1049]: info: Loading i18n strings for locale es May 29 21:20:37 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile May 29 21:20:37 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:37 volumiostreamer volumio[1049]: info: FusionDsp - mixtype--------------------- Hardware May 29 21:20:37 volumiostreamer volumio[1049]: info: Volumio Calling Home May 29 21:20:37 volumiostreamer volumio[1049]: info: Preparing to generate the ALSA configuration file May 29 21:20:39 volumiostreamer volumio[1049]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 May 29 21:20:39 volumiostreamer volumio[1049]: info: camilladsp service started and running in background, instance 1 May 29 21:20:39 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: FusionDsp - AAAAAAAAAAAAAA-> 22050 44100 48000 88200 96000 176400 192000 384000 <-AAAAAAAAAAAAA May 29 21:20:40 volumiostreamer volumio[1049]: info: FusionDsp loaded May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses May 29 21:20:40 volumiostreamer volumio[1049]: error: FusionDsp - Socket connection not established May 29 21:20:40 volumiostreamer volumio[1049]: info: FusionDsp - Reporting Fusion DSP Enabled May 29 21:20:40 volumiostreamer volumio[1049]: info: Adding Signal Path Element [object Object] May 29 21:20:40 volumiostreamer volumio[1049]: info: Adding fusiondspeq DSP Signal Path Element May 29 21:20:40 volumiostreamer volumio[1049]: info: FusionDsp - ---- installed callbackRead May 29 21:20:40 volumiostreamer volumio[1049]: info: FusionDsp - If filter freq >samplerate/2 then disable it May 29 21:20:40 volumiostreamer volumio[1049]: info: FusionDsp - Loudness is ON true May 29 21:20:40 volumiostreamer sudo[1347]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service May 29 21:20:40 volumiostreamer sudo[1347]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:20:40 volumiostreamer volumio[1049]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf May 29 21:20:40 volumiostreamer volumio[1049]: info: Reading ALSA contributions from plugins. May 29 21:20:40 volumiostreamer volumio[1049]: info: MPD Permissions set May 29 21:20:40 volumiostreamer volumio[1049]: info: MPD Permissions set May 29 21:20:40 volumiostreamer volumio[1049]: info: Volumio called home May 29 21:20:40 volumiostreamer systemd[1]: Started fusiondsp.service - FusionDsp Daemon. May 29 21:20:40 volumiostreamer sudo[1347]: pam_unix(sudo:session): session closed for user root May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer volumio[1049]: info: Starting Shairport Sync May 29 21:20:40 volumiostreamer volumio[1049]: info: Starting Shairport Sync May 29 21:20:40 volumiostreamer volumio[1049]: info: Starting Shairport Sync May 29 21:20:40 volumiostreamer sudo[1368]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 21:20:40 volumiostreamer sudo[1368]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:20:40 volumiostreamer volumio[1049]: info: Asound.conf file unchanged, so no further update is needed May 29 21:20:40 volumiostreamer volumio[1049]: info: Output device has changed, restarting MPD May 29 21:20:40 volumiostreamer sudo[1370]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 21:20:40 volumiostreamer sudo[1370]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:20:40 volumiostreamer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 29 21:20:40 volumiostreamer volumio[1049]: info: Output device has changed, restarting Shairport Sync May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:40 volumiostreamer sudo[1372]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 21:20:40 volumiostreamer systemd[1]: shairport-sync.service: Deactivated successfully. May 29 21:20:40 volumiostreamer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 21:20:40 volumiostreamer systemd[1]: shairport-sync.service: Consumed 1.495s CPU time. May 29 21:20:40 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:20:40 volumiostreamer sudo[1372]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:20:41 volumiostreamer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 21:20:41 volumiostreamer volumio[1049]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 May 29 21:20:41 volumiostreamer sudo[1368]: pam_unix(sudo:session): session closed for user root May 29 21:20:41 volumiostreamer sudo[1375]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf May 29 21:20:41 volumiostreamer sudo[1375]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:20:41 volumiostreamer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 29 21:20:41 volumiostreamer systemd[1]: shairport-sync.service: Deactivated successfully. May 29 21:20:41 volumiostreamer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 21:20:41 volumiostreamer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 21:20:41 volumiostreamer sudo[1379]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service May 29 21:20:41 volumiostreamer sudo[1379]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:20:41 volumiostreamer volumio[1049]: info: Shairport-Sync Started May 29 21:20:41 volumiostreamer sudo[1370]: pam_unix(sudo:session): session closed for user root May 29 21:20:41 volumiostreamer sudo[1372]: pam_unix(sudo:session): session closed for user root May 29 21:20:41 volumiostreamer volumio[1049]: Error adding Membership: Error: addMembership EINVAL May 29 21:20:41 volumiostreamer sudo[1375]: pam_unix(sudo:session): session closed for user root May 29 21:20:41 volumiostreamer systemd[1]: mpd.service: Deactivated successfully. May 29 21:20:41 volumiostreamer systemd[1]: Stopped mpd.service - Music Player Daemon. May 29 21:20:41 volumiostreamer systemd[1]: mpd.service: Consumed 3.494s CPU time. May 29 21:20:41 volumiostreamer systemd[1]: mpd.socket: Deactivated successfully. May 29 21:20:41 volumiostreamer volumio[1049]: info: MPD Permissions set May 29 21:20:41 volumiostreamer volumio[1049]: info: Shairport-Sync Started May 29 21:20:41 volumiostreamer volumio[1049]: info: Shairport-Sync Started May 29 21:20:41 volumiostreamer systemd[1]: Closed mpd.socket - Music Player Daemon Socket. May 29 21:20:41 volumiostreamer systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... May 29 21:20:41 volumiostreamer volumio[1049]: error: Upnp client error: Error: read ECONNRESET May 29 21:20:41 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:41 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:41 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:41 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:41 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:41 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:41 volumiostreamer systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. May 29 21:20:41 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:41 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:41 volumiostreamer systemd[1]: Starting mpd.service - Music Player Daemon... May 29 21:20:41 volumiostreamer sudo[1416]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log May 29 21:20:41 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:20:41 volumiostreamer sudo[1416]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 21:20:41 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:20:41 volumiostreamer sudo[1421]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory May 29 21:20:41 volumiostreamer sudo[1416]: pam_unix(sudo:session): session closed for user root May 29 21:20:41 volumiostreamer volumio[1049]: info: Starting Shairport Sync May 29 21:20:41 volumiostreamer sudo[1424]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync May 29 21:20:41 volumiostreamer sudo[1424]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:20:42 volumiostreamer systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... May 29 21:20:42 volumiostreamer systemd[1]: shairport-sync.service: Deactivated successfully. May 29 21:20:42 volumiostreamer systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 21:20:42 volumiostreamer systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. May 29 21:20:42 volumiostreamer sudo[1424]: pam_unix(sudo:session): session closed for user root May 29 21:20:42 volumiostreamer volumio[1049]: info: Shairport-Sync Started May 29 21:20:42 volumiostreamer volumio-remote-updater[577]: [2025-05-29 21:20:42] [connect] Successful connection May 29 21:20:42 volumiostreamer volumio-remote-updater[577]: [2025-05-29 21:20:42] [connect] WebSocket Connection 127.0.0.1:3000 v-2 "WebSocket++/0.8.2" /socket.io/?EIO=4&transport=websocket&t=1748546442 101 May 29 21:20:42 volumiostreamer volumio[1049]: verbose: New Socket.io Connection to 127.0.0.1:3000 from 127.0.0.1 UA: WebSocket++/0.8.2 Engine version: 4 Transport: websocket Total Clients: 1 May 29 21:20:45 volumiostreamer systemd[1]: systemd-timedated.service: Deactivated successfully. May 29 21:20:45 volumiostreamer volumio[1049]: info: Discovery: adding 7477ff00-8360-44d7-bb0f-f0a16825bf78 May 29 21:20:45 volumiostreamer volumio[1049]: info: Discovery: Found device Volumiostreamer May 29 21:20:45 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioGetState May 29 21:20:45 volumiostreamer volumio[1049]: info: CorePlayQueue::getTrack 0 May 29 21:20:45 volumiostreamer volumio[1049]: info: Discovery: this is already registered, 7477ff00-8360-44d7-bb0f-f0a16825bf78 May 29 21:20:45 volumiostreamer volumio[1049]: info: Discovery: Found device Volumiostreamer May 29 21:20:45 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioGetState May 29 21:20:45 volumiostreamer volumio[1049]: info: CorePlayQueue::getTrack 0 May 29 21:20:45 volumiostreamer volumio[1049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 2 May 29 21:20:45 volumiostreamer volumio[1049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 3 May 29 21:20:45 volumiostreamer volumio[1049]: error: FusionDsp - Reload WebSocket error: [object Object] May 29 21:20:46 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioGetState May 29 21:20:46 volumiostreamer volumio[1049]: info: CorePlayQueue::getTrack 0 May 29 21:20:47 volumiostreamer mpd[1422]: 2025-05-29T21:20:46 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg May 29 21:20:47 volumiostreamer systemd[1]: Started mpd.service - Music Player Daemon. May 29 21:20:47 volumiostreamer sudo[1274]: pam_unix(sudo:session): session closed for user root May 29 21:20:47 volumiostreamer sudo[1379]: pam_unix(sudo:session): session closed for user root May 29 21:20:47 volumiostreamer sudo[1286]: pam_unix(sudo:session): session closed for user root May 29 21:20:47 volumiostreamer volumio[1049]: info: Completed starting Core Plugins May 29 21:20:47 volumiostreamer volumio[1049]: info: ------------------------------------------- May 29 21:20:47 volumiostreamer volumio[1049]: info: ----- MyVolumio plugins startup ---- May 29 21:20:47 volumiostreamer volumio[1049]: info: ------------------------------------------- May 29 21:20:47 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Fetching plans data.... May 29 21:20:47 volumiostreamer volumio[1049]: error: MPD error: The expression evaluated to a falsy value: May 29 21:20:47 volumiostreamer volumio[1049]: assert.ok(self.idling) May 29 21:20:47 volumiostreamer volumio[1049]: error: The expression evaluated to a falsy value: May 29 21:20:47 volumiostreamer volumio[1049]: assert.ok(self.idling) May 29 21:20:47 volumiostreamer volumio[1049]: error: MPD error: The expression evaluated to a falsy value: May 29 21:20:47 volumiostreamer volumio[1049]: assert.ok(self.idling) May 29 21:20:47 volumiostreamer volumio[1049]: error: The expression evaluated to a falsy value: May 29 21:20:47 volumiostreamer volumio[1049]: assert.ok(self.idling) May 29 21:20:47 volumiostreamer volumio[1049]: info: MPD running with PID1422 May 29 21:20:47 volumiostreamer volumio[1049]: ,establishing connection May 29 21:20:47 volumiostreamer volumio[1049]: error: updateQueue error: null May 29 21:20:47 volumiostreamer volumio[1049]: error: updateQueue error: null May 29 21:20:52 volumiostreamer volumio[1049]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan premium May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan premium May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan premium May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan premium May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan premium May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan premium May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan premium May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan premium May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan premium May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan premium May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan premium May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan superstar May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin multiroom to plan superstar May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin metavolumio to plan superstar May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan superstar May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan superstar May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin smart_inputs to plan superstar May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin hi_res_audio to plan superstar May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan superstar May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan superstar May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan superstar May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuzconnect to plan superstar May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin bluetooth to plan virtuoso May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin manifestui to plan virtuoso May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin cd_controller to plan virtuoso May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidal to plan virtuoso May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin qobuz to plan virtuoso May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Adding plugin tidalconnect to plan virtuoso May 29 21:20:56 volumiostreamer volumio[1049]: info: Adding plugin bluetooth to MyMusic Plugins May 29 21:20:56 volumiostreamer volumio[1049]: info: Adding plugin multiroom to MyMusic Plugins May 29 21:20:56 volumiostreamer volumio[1049]: info: Adding plugin metavolumio to MyMusic Plugins May 29 21:20:56 volumiostreamer volumio[1049]: info: Adding plugin cd_controller to MyMusic Plugins May 29 21:20:56 volumiostreamer volumio[1049]: info: Adding plugin smart_inputs to MyMusic Plugins May 29 21:20:56 volumiostreamer volumio[1049]: info: Adding plugin tidalconnect to MyMusic Plugins May 29 21:20:56 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "my_volumio"... May 29 21:21:00 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] MyVolumio plugin successfully loaded May 29 21:21:00 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Starting plugin system_controller.my_volumio May 29 21:21:00 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:21:00 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:21:00 volumiostreamer volumio[1049]: info: Starting MyVolumio Remote Streaming Endpoints May 29 21:21:00 volumiostreamer volumio[1049]: info: MyVolumio login type: Token May 29 21:21:00 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] MyVolumio plugin successfully started May 29 21:21:00 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Loading plugin "streaming_services"... May 29 21:21:05 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Streaming Services plugin successfully loaded May 29 21:21:05 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Starting plugin music_service.streaming_services May 29 21:21:05 volumiostreamer volumio[1049]: info: Streaming services startup May 29 21:21:05 volumiostreamer volumio[1049]: info: Starting Streaming Daemon May 29 21:21:05 volumiostreamer volumio[1049]: info: [MyVolumio PluginManager] Streaming Services plugin successfully started May 29 21:21:05 volumiostreamer sudo[1497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 21:21:05 volumiostreamer sudo[1497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:21:05 volumiostreamer sudo[1497]: pam_unix(sudo:session): session closed for user root May 29 21:21:05 volumiostreamer volumio[1049]: error: Cannot start Volumio Streaming Daemon May 29 21:21:05 volumiostreamer volumio[1049]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 21:21:05 volumiostreamer volumio[1049]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 21:21:06 volumiostreamer volumio[1049]: error: MyVolumio Custom Token format not valid, refreshing it May 29 21:21:06 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 21:21:06 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:21:06 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled May 29 21:21:07 volumiostreamer volumio-remote-updater[577]: No test mode May 29 21:21:07 volumiostreamer volumio-remote-updater[577]: Alpha test mode enabled May 29 21:21:07 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateEnabled May 29 21:21:07 volumiostreamer volumio[1049]: info: Update Ready: {"changeLogLink":"http://volumio.org","description":"

NEW ADDITIONS

\n
    \n
  • Updater 1.8.12
    • \n
  • Release channel selector
    • \n
\n

FIXES

\n
    \n
  • Upstream Realtek modules
    • \n
  • Force updater static libs
    • \n
\n","title":"Update v4.004","updateavailable":true} May 29 21:21:07 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache May 29 21:21:07 volumiostreamer volumio[1049]: info: MyVolumio login type: Token May 29 21:21:08 volumiostreamer volumio[1049]: info: MYVOLUMIO SUCCESSFULLY LOGGED IN May 29 21:21:09 volumiostreamer volumio[1049]: info: MyVolumio token set successfully May 29 21:21:09 volumiostreamer volumio[1049]: info: MYVOLUMIO: Adding device May 29 21:21:09 volumiostreamer volumio[1049]: info: MYVOLUMIO: Evaluating Server May 29 21:21:09 volumiostreamer volumio[1049]: info: MyVolumio status changed May 29 21:21:09 volumiostreamer volumio[1049]: info: Streaming services startup May 29 21:21:09 volumiostreamer volumio[1049]: info: Starting Streaming Daemon May 29 21:21:10 volumiostreamer volumio[1049]: info: Removing browser output: myVolumio user plan is not superstar May 29 21:21:10 volumiostreamer volumio[1049]: info: Removing audio output: May 29 21:21:10 volumiostreamer volumio[1049]: info: Stoppping Tunnel 1 May 29 21:21:10 volumiostreamer sudo[1525]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart volumio-streaming-daemon.service May 29 21:21:10 volumiostreamer sudo[1525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:21:10 volumiostreamer sudo[1527]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop sshtunnel.service May 29 21:21:10 volumiostreamer sudo[1527]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:21:10 volumiostreamer sudo[1525]: pam_unix(sudo:session): session closed for user root May 29 21:21:10 volumiostreamer volumio[1049]: error: Cannot start Volumio Streaming Daemon May 29 21:21:10 volumiostreamer volumio[1049]: error: Failed initialization of streaming services: Error: Error: Command failed: /usr/bin/sudo systemctl restart volumio-streaming-daemon.service May 29 21:21:10 volumiostreamer volumio[1049]: Failed to restart volumio-streaming-daemon.service: Unit volumio-streaming-daemon.service not found. May 29 21:21:10 volumiostreamer 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. May 29 21:21:10 volumiostreamer 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. May 29 21:21:10 volumiostreamer 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. May 29 21:21:10 volumiostreamer 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. May 29 21:21:10 volumiostreamer 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. May 29 21:21:10 volumiostreamer 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. May 29 21:21:10 volumiostreamer 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. May 29 21:21:10 volumiostreamer 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. May 29 21:21:10 volumiostreamer sudo[1527]: pam_unix(sudo:session): session closed for user root May 29 21:21:10 volumiostreamer volumio[1049]: info: Remote SSH Stopped May 29 21:21:13 volumiostreamer volumio[1049]: info: Setting Geolocation for MyVolumio to eu8 May 29 21:21:13 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:21:13 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:21:13 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:21:14 volumiostreamer volumio[1049]: error: Failed to add MyVolumio device: {"message":"USER_NOT_FOUND"} May 29 21:21:14 volumiostreamer volumio[1049]: info: Updating MyVolumio device info May 29 21:21:14 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:21:14 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:21:14 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam May 29 21:21:14 volumiostreamer volumio[1049]: error: Failed to update MyVolumio device: {"message":"DEVICE_NOT_FOUND"} May 29 21:21:15 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings May 29 21:21:15 volumiostreamer volumio[1049]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 4 May 29 21:21:15 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioGetState May 29 21:21:15 volumiostreamer volumio[1049]: info: CorePlayQueue::getTrack 0 May 29 21:21:18 volumiostreamer volumio[1049]: info: MYVOLUMIO: Adding device May 29 21:21:18 volumiostreamer volumio[1049]: info: MYVOLUMIO: Evaluating Server May 29 21:21:18 volumiostreamer systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... May 29 21:21:18 volumiostreamer dbus-daemon[560]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.24' (uid=0 pid=1545 comm="timedatectl show --property=NTPSynchronized --valu") May 29 21:21:18 volumiostreamer systemd[1]: Starting systemd-timedated.service - Time & Date Service... May 29 21:21:18 volumiostreamer dbus-daemon[560]: [system] Successfully activated service 'org.freedesktop.timedate1' May 29 21:21:18 volumiostreamer systemd[1]: Started systemd-timedated.service - Time & Date Service. May 29 21:21:18 volumiostreamer setdatetime-helper.sh[1544]: Time is not synchronized. Attempting to sync... May 29 21:21:20 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:21:20 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam May 29 21:21:20 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 29 21:21:20 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getConfigParam May 29 21:21:20 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam May 29 21:21:20 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject May 29 21:21:20 volumiostreamer volumio[1049]: info: CoreCommandRouter::volumioGetBrowseSources May 29 21:21:20 volumiostreamer volumio[1049]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion May 29 21:21:22 volumiostreamer sudo[1568]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Thu, 29 May 2025 19:21:22 GMT#015' May 29 21:21:22 volumiostreamer sudo[1568]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) May 29 21:21:22 volumiostreamer setdatetime-helper.sh[1569]: Thu May 29 21:21:22 CEST 2025 May 29 21:21:22 volumiostreamer setdatetime-helper.sh[1544]: Time synchronized successfully. May 29 21:21:22 volumiostreamer sudo[1568]: pam_unix(sudo:session): session closed for user root May 29 21:21:22 volumiostreamer systemd-journald[320]: Time jumped backwards, rotating. May 29 21:21:22 volumiostreamer systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. May 29 21:21:22 volumiostreamer systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. May 29 21:21:22 volumiostreamer volumio[1049]: error: Failed to ping endpoint as2.myvolumio.org : unknown error May 29 21:21:22 volumiostreamer volumio[1049]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 21:21:22 volumiostreamer volumio[1049]: Error: Unable to resolve or reject the same promise twice May 29 21:21:22 volumiostreamer volumio[1049]: at Promise.resolve (/volumio/node_modules/kew/kew.js:140:43) May 29 21:21:22 volumiostreamer volumio[1049]: at Socket. (/myvolumio/plugins/system_controller/my_volumio/my_volumio_real:1:32371) May 29 21:21:22 volumiostreamer volumio[1049]: at Socket.emit (node:events:514:28) May 29 21:21:22 volumiostreamer volumio[1049]: at addChunk (node:internal/streams/readable:343:12) May 29 21:21:22 volumiostreamer volumio[1049]: at readableAddChunk (node:internal/streams/readable:316:9) May 29 21:21:22 volumiostreamer volumio[1049]: at Readable.push (node:internal/streams/readable:253:10) May 29 21:21:22 volumiostreamer volumio[1049]: at Pipe.onStreamRead (node:internal/stream_base_commons:190:23) May 29 21:21:22 volumiostreamer volumio[1049]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 29 21:21:25 volumiostreamer sudo[1599]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-05-29 21:20' May 29 21:21:25 volumiostreamer sudo[1599]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) May 29 21:21:25 volumiostreamer upmpdcli[1601]: writing RSA key 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="26d819930af572ef184e636a18ef1127de78f391" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="35e4a13c94df42ebab19fd6178d6be47190c244d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Wed May 21 13:47:54 UTC 2025" VOLUMIO_VERSION="0.068" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="82c7b61aa1619bd495d6c4f648975de8"