Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 20:27:00 volumio volumio[1245]: info: Discovery: Getting this device information Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 02 20:27:00 volumio volumio[1245]: info: Received Get System Info Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 20:27:00 volumio volumio[1245]: info: Discovery: Getting this device information Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: Listing playlists Jun 02 20:27:00 volumio volumio[1245]: info: Received Get System Info Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 20:27:00 volumio volumio[1245]: info: Discovery: Getting this device information Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: Received Get System Info Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 20:27:00 volumio volumio[1245]: info: Discovery: Getting this device information Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jun 02 20:27:00 volumio volumio[1245]: info: Received Get System Info Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 20:27:00 volumio volumio[1245]: info: Discovery: Getting this device information Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: Listing playlists Jun 02 20:27:00 volumio volumio[1245]: info: Received Get System Info Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 20:27:00 volumio volumio[1245]: info: Discovery: Getting this device information Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: Received Get System Info Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 20:27:00 volumio volumio[1245]: info: Discovery: Getting this device information Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 20:27:00 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:00 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:01 volumio volumio[1245]: error: Upnp client error: Error: connect ECONNREFUSED 127.0.0.1:6600 Jun 02 20:27:01 volumio volumio[1245]: info: Asound.conf file unchanged, so no further update is needed Jun 02 20:27:01 volumio volumio[1245]: info: Output device has changed, restarting MPD Jun 02 20:27:01 volumio sudo[1621]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 20:27:01 volumio sudo[1621]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 20:27:01 volumio volumio[1245]: info: Output device has changed, restarting Shairport Sync Jun 02 20:27:01 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:01 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 20:27:01 volumio sudo[1623]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 20:27:01 volumio sudo[1623]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 20:27:01 volumio sudo[1621]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:01 volumio volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 20:27:01 volumio volumio[1245]: info: ___________ START PLUGINS ___________ Jun 02 20:27:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 02 20:27:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 02 20:27:01 volumio volumio[1245]: info: pi_screen_setup: Starting plugin Jun 02 20:27:01 volumio sudo[1632]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio5-onboarding.service Jun 02 20:27:01 volumio sudo[1632]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 20:27:01 volumio volumio[1245]: info: pi_screen_setup: Loaded bundled presets v1.5.9 Jun 02 20:27:01 volumio volumio[1245]: info: pi_screen_setup: Active presets source: bundled, 231 presets loaded Jun 02 20:27:01 volumio volumio[1245]: info: pi_screen_setup: Detected model: Raspberry Pi 4 Model B Rev 1.1 Jun 02 20:27:01 volumio volumio[1245]: info: pi_screen_setup: Detected SoC: bcm2711 Jun 02 20:27:01 volumio volumio[1245]: info: pi_screen_setup: Detected RAM: 3783 MB Jun 02 20:27:01 volumio volumio[1245]: info: pi_screen_setup: Hardware detection complete - {"model":"Pi 4","model_raw":"Raspberry Pi 4 Model B Rev 1.1","soc":"bcm2711","ram_mb":3783,"hdmi_ports":2,"dsi_ports":["dsi0","dsi1"],"has_composite":true,"kms_supported":true,"kms_overlay":"vc4-kms-v3d-pi4","is_pi":true} Jun 02 20:27:01 volumio volumio[1245]: info: ControllerMpd::onStart: Initializing MPD Jun 02 20:27:01 volumio volumio[1245]: info: Creating MPD Configuration file Jun 02 20:27:01 volumio sudo[1633]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 20:27:01 volumio sudo[1633]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 02 20:27:01 volumio sudo[1637]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 02 20:27:01 volumio sudo[1633]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:01 volumio sudo[1636]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Jun 02 20:27:01 volumio sudo[1636]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 20:27:01 volumio sudo[1636]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:01 volumio sudo[1641]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Jun 02 20:27:01 volumio sudo[1641]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 20:27:01 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Jun 02 20:27:01 volumio volumio[1245]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 20:27:01 volumio volumio[1245]: info: [1780417621842] CoreMusicLibrary::Adding element Media Servers Jun 02 20:27:01 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 20:27:01 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Jun 02 20:27:01 volumio sudo[1632]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:01 volumio systemd[1]: mpd.service: Deactivated successfully. Jun 02 20:27:01 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Jun 02 20:27:01 volumio systemd[1]: mpd.socket: Deactivated successfully. Jun 02 20:27:01 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Jun 02 20:27:01 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Jun 02 20:27:01 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Jun 02 20:27:01 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Jun 02 20:27:01 volumio volumio[1245]: info: UPNP Browser: Client initialized successfully Jun 02 20:27:01 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:02 volumio sudo[1645]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Jun 02 20:27:02 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 20:27:02 volumio sudo[1645]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Jun 02 20:27:02 volumio sudo[1648]: /bin/chown: cannot access '/var/log/mpd.log': No such file or directory Jun 02 20:27:02 volumio sudo[1645]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:02 volumio volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 20:27:02 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:02 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 20:27:02 volumio volumio[1245]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Jun 02 20:27:02 volumio volumio[1245]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 20:27:02 volumio volumio[1245]: info: [1780417622438] CoreMusicLibrary::Adding element Last_100 Jun 02 20:27:02 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 20:27:02 volumio volumio[1245]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jun 02 20:27:02 volumio volumio5-onboarding[1638]: time=2026-06-02T20:27:02.464+04:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Jun 02 20:27:02 volumio volumio[1245]: info: [1780417622485] CoreMusicLibrary::Adding element Webradio Jun 02 20:27:02 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 20:27:02 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 20:27:02 volumio volumio[1245]: info: Initializing BBC Radios Jun 02 20:27:02 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 20:27:02 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 20:27:02 volumio volumio[1245]: info: Creating Spotify config file Jun 02 20:27:02 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:04 volumio volumio[1245]: info: [now-playing] ConfigUpdater: config is up to date. Jun 02 20:27:04 volumio volumio[1245]: info: Stylish Player: Peppy data path ready at /data/INTERNAL/stylish_player Jun 02 20:27:04 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 02 20:27:04 volumio volumio[1245]: info: Stylish Player: Found process ID Jun 02 20:27:04 volumio volumio[1245]: info: Stylish Player: Starting audio server on port 9993 Jun 02 20:27:05 volumio volumio[1245]: info: FusionDsp - CamillaDSP binary found. Jun 02 20:27:05 volumio volumio[1245]: info: Loading i18n strings for locale en Jun 02 20:27:05 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Jun 02 20:27:05 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:05 volumio volumio[1245]: info: FusionDsp - mixtype--------------------- Software Jun 02 20:27:05 volumio volumio[1245]: info: Loading i18n strings for locale en Jun 02 20:27:05 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 20:27:05 volumio volumio[1245]: info: Volumio Calling Home Jun 02 20:27:05 volumio volumio[1245]: info: pi_screen_setup: Detected prior configuration (backup=true, banner=true) - restoring wizard_complete Jun 02 20:27:05 volumio sudo[1688]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/mkdir -p /data/volumiokioskextensions/IframeKeyboardBridge Jun 02 20:27:05 volumio sudo[1688]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 20:27:05 volumio volumio[1245]: info: pi_screen_setup: Boot validation - {"valid":true,"drift_detected":false,"missing_videoconfig":false,"missing_include":false,"config_mismatch":false,"volumioconfig_mismatch":false,"errors":[]} Jun 02 20:27:05 volumio sudo[1688]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:05 volumio volumio[1245]: info: Preparing to generate the ALSA configuration file Jun 02 20:27:05 volumio volumio[1245]: info: Stylish Player: Resilient Audio Streamer on port 9993 Jun 02 20:27:05 volumio volumio[1245]: info: Stylish Player: FIFO sentinel opened Jun 02 20:27:05 volumio volumio[1245]: info: Stylish Player: No stream clients — draining FIFO to keep ALSA unblocked Jun 02 20:27:05 volumio volumio[1245]: info: Stylish Player: Server listening on port 3339 Jun 02 20:27:06 volumio sudo[1692]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop getty@tty1.service Jun 02 20:27:06 volumio volumio[1245]: info: pi_screen_setup: Management server started on port 4567 Jun 02 20:27:06 volumio sudo[1692]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 20:27:06 volumio sudo[1694]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl disable getty@tty1.service Jun 02 20:27:06 volumio sudo[1694]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 20:27:06 volumio sudo[1697]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl daemon-reload Jun 02 20:27:06 volumio sudo[1697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 20:27:06 volumio sudo[1692]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:06 volumio systemd[1]: Reloading. Jun 02 20:27:06 volumio volumio[1245]: error: [now-playing] Caught error in callback of WeatherAPI.#getFetchPromise(): OpenWeatherMap API key not configured. Add it in Weather Service settings. Jun 02 20:27:06 volumio volumio[1245]: info: [now-playing] App is listening on port 4004. Jun 02 20:27:06 volumio mpd[1653]: 2026-06-02T20:27:06 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Jun 02 20:27:06 volumio volumio[1245]: warn: [now-playing] MyBackgroundMonitor is now watching /data/INTERNAL/NowPlayingPlugin/My Backgrounds Jun 02 20:27:07 volumio volumio[1245]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Jun 02 20:27:07 volumio volumio[1245]: info: The plugin fusiondsp has an ALSA contribution file volumioDsp.postDsp.10.conf Jun 02 20:27:07 volumio volumio[1245]: info: The plugin stylish_player has an ALSA contribution file sp_in.sp_out.7.conf Jun 02 20:27:07 volumio volumio[1245]: info: Reading ALSA contributions from plugins. Jun 02 20:27:07 volumio volumio[1245]: info: touch_display: No backlight interface detected. Jun 02 20:27:07 volumio systemd[1]: Started mpd.service - Music Player Daemon. Jun 02 20:27:07 volumio sudo[1697]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:07 volumio systemd[1]: Reloading. Jun 02 20:27:07 volumio sudo[1623]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:07 volumio sudo[1641]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:08 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Jun 02 20:27:08 volumio sudo[1694]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:09 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Jun 02 20:27:10 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:10 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:11 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: CoreCommandRouter::getUIConfigOnPlugin Jun 02 20:27:12 volumio volumio[1245]: info: touch_display: systemctl stop getty@tty1.service succeeded. Jun 02 20:27:12 volumio volumio[1245]: info: MPD Permissions set Jun 02 20:27:12 volumio volumio[1245]: info: MPD Permissions set Jun 02 20:27:12 volumio volumio5-onboarding[1638]: failed to create app: failed to initialize host: failed to create socket connection: could not connect to socket.io server: read tcp 127.0.0.1:60790->127.0.0.1:3000: i/o timeout Jun 02 20:27:12 volumio systemd[1]: volumio5-onboarding.service: Main process exited, code=exited, status=1/FAILURE Jun 02 20:27:12 volumio systemd[1]: volumio5-onboarding.service: Failed with result 'exit-code'. Jun 02 20:27:12 volumio sudo[1780]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/cp -r /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/content.js /data/plugins/user_interface/touch_display/iframe_keyboard_bridge/manifest.json /data/volumiokioskextensions/IframeKeyboardBridge/ Jun 02 20:27:12 volumio sudo[1780]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 20:27:12 volumio sudo[1780]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:12 volumio systemd[1]: volumio5-onboarding.service: Scheduled restart job, restart counter is at 1. Jun 02 20:27:12 volumio systemd[1]: Stopped volumio5-onboarding.service - Volumio5 Onboarding Server. Jun 02 20:27:12 volumio systemd[1]: Started volumio5-onboarding.service - Volumio5 Onboarding Server. Jun 02 20:27:12 volumio volumio5-onboarding[1782]: time=2026-06-02T20:27:12.961+04:00 level=INFO msg="running volumio5-device-gateway" version=6370e0a8+CHANGES buildDate=2026-03-06T16:29:42Z Jun 02 20:27:13 volumio volumio[1245]: info: touch_display: systemctl disable getty@tty1.service succeeded. Jun 02 20:27:14 volumio volumio[1245]: info: touch_display: systemctl daemon-reload succeeded. Jun 02 20:27:14 volumio volumio[1245]: info: Volumio called home Jun 02 20:27:14 volumio sudo[1792]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start volumio-kiosk.service Jun 02 20:27:14 volumio sudo[1792]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 20:27:14 volumio volumio[1245]: info: An error occurred while refreshing Spotify Token Error: Client network socket disconnected before secure TLS connection was established Jun 02 20:27:14 volumio volumio[1245]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Jun 02 20:27:14 volumio volumio[1245]: info: touch_display: IframeKeyboardBridge extension installed successfully Jun 02 20:27:14 volumio systemd[1]: Started volumio-kiosk.service - Volumio Kiosk. Jun 02 20:27:14 volumio sudo[1792]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:14 volumio startx[1816]: X.Org X Server 1.21.1.7 Jun 02 20:27:14 volumio startx[1816]: X Protocol Version 11, Revision 0 Jun 02 20:27:14 volumio startx[1816]: Current Operating System: Linux volumio 6.12.74-v7l+ #1948 SMP Mon Mar 2 11:27:49 GMT 2026 armv7l Jun 02 20:27:14 volumio startx[1816]: Kernel command line: coherent_pool=1M 8250.nr_uarts=0 snd_bcm2835.enable_headphones=0 cgroup_disable=memory numa_policy=interleave nvme.max_host_mem_size_mb=0 snd_bcm2835.enable_headphones=1 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_hdmi=0 numa=fake=2 system_heap.max_order=0 iommu_dma_numa_policy=interleave smsc95xx.macaddr=DC:A6:32:3B:1A:65 vc_mem.mem_base=0x3eb00000 vc_mem.mem_size=0x3ff00000 splash plymouth.ignore-serial-consoles dwc_otg.fiq_enable=1 dwc_otg.fiq_fsm_enable=1 dwc_otg.fiq_fsm_mask=0xF dwc_otg.nak_holdoff=1 quiet console=ttyS0,115200 console=tty1 imgpart=UUID=9caf6474-168f-4972-add4-ace36a467bc2 imgfile=/volumio_current.sqsh bootpart=UUID=807D-2966 datapart=UUID=bea3e05c-62cf-4b22-a58d-66213927f56a uuidconfig=cmdline.txt rootwait bootdelay=7 logo.nologo vt.global_cursor_default=0 net.ifnames=0 snd_bcm2835.enable_hdmi=1 snd_bcm2835.enable_headphones=1 loglevel=0 nodebug use_kmsg=no fbcon=rotate:0 Jun 02 20:27:14 volumio startx[1816]: xorg-server 2:21.1.7-3+rpt3+deb12u11 (https://www.debian.org/support) Jun 02 20:27:14 volumio startx[1816]: Current version of pixman: 0.44.0 Jun 02 20:27:14 volumio startx[1816]: Before reporting problems, check http://wiki.x.org Jun 02 20:27:14 volumio startx[1816]: to make sure that you have the latest version. Jun 02 20:27:14 volumio startx[1816]: Markers: (--) probed, (**) from config file, (==) default setting, Jun 02 20:27:14 volumio startx[1816]: (++) from command line, (!!) notice, (II) informational, Jun 02 20:27:14 volumio startx[1816]: (WW) warning, (EE) error, (NI) not implemented, (??) unknown. Jun 02 20:27:14 volumio startx[1816]: (==) Log file: "/var/log/Xorg.0.log", Time: Tue Jun 2 20:27:14 2026 Jun 02 20:27:14 volumio startx[1816]: (==) Using config directory: "/etc/X11/xorg.conf.d" Jun 02 20:27:14 volumio startx[1816]: (==) Using system config directory "/usr/share/X11/xorg.conf.d" Jun 02 20:27:14 volumio volumio[1245]: error: MPD error: The expression evaluated to a falsy value: Jun 02 20:27:14 volumio volumio[1245]: assert.ok(self.idling) Jun 02 20:27:14 volumio volumio[1245]: error: The expression evaluated to a falsy value: Jun 02 20:27:14 volumio volumio[1245]: assert.ok(self.idling) Jun 02 20:27:14 volumio volumio[1245]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 15 Jun 02 20:27:14 volumio volumio[1245]: info: MPD running with PID1653 Jun 02 20:27:14 volumio volumio[1245]: ,establishing connection Jun 02 20:27:14 volumio volumio[1245]: info: FusionDsp - If filter freq >samplerate/2 then disable it Jun 02 20:27:14 volumio volumio[1245]: info: FusionDsp - Effects disabled Jun 02 20:27:15 volumio volumio[1245]: info: camilladsp service started and running in background, instance 1 Jun 02 20:27:15 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:15 volumio volumio[1245]: info: FusionDsp - AAAAAAAAAAAAAA-> 44100 48000 88200 96000 176400 192000 384000 768000 <-AAAAAAAAAAAAA Jun 02 20:27:15 volumio volumio[1245]: info: FusionDsp loaded Jun 02 20:27:15 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 20:27:15 volumio volumio[1245]: info: FusionDsp - --[object Object] Jun 02 20:27:15 volumio volumio[1245]: error: FusionDsp - Socket connection not established Jun 02 20:27:15 volumio volumio[1245]: info: FusionDsp - Reporting Fusion DSP Enabled Jun 02 20:27:15 volumio volumio[1245]: info: Adding Signal Path Element [object Object] Jun 02 20:27:15 volumio sudo[1830]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start fusiondsp.service Jun 02 20:27:15 volumio sudo[1830]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 20:27:15 volumio volumio[1245]: info: Adding fusiondspeq DSP Signal Path Element Jun 02 20:27:15 volumio volumio[1245]: info: FusionDsp - ---- installed callbackRead (debounced 200ms) Jun 02 20:27:15 volumio volumio[1245]: info: FusionDsp - PEQ graph server listening on port 10015 Jun 02 20:27:15 volumio systemd[1]: Starting fusiondsp.service - FusionDsp Daemon... Jun 02 20:27:15 volumio volumio[1245]: error: updateQueue error: null Jun 02 20:27:15 volumio volumio[1245]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 16 Jun 02 20:27:16 volumio volumio[1245]: info: touch_display: systemctl start volumio-kiosk.service succeeded. Jun 02 20:27:16 volumio volumio[1245]: info: touch_display: Volumio Kiosk started. Jun 02 20:27:16 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:16 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:16 volumio volumio[1245]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: Go-http-client/1.1 Engine version: 3 Transport: websocket Total Clients: 16 Jun 02 20:27:16 volumio volumio[1245]: info: Received Get System Info Jun 02 20:27:16 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 20:27:16 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 20:27:16 volumio volumio[1245]: info: Discovery: Getting this device information Jun 02 20:27:16 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:16 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:16 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 20:27:16 volumio volumio5-onboarding[1782]: time=2026-06-02T20:27:16.537+04:00 level=INFO msg="system info for 1f59145626c504f7f0cfab7190e93c86" deviceName=Volumio deviceVariant=volumio deviceModel= softwareVersion=4.119 Jun 02 20:27:16 volumio volumio5-onboarding[1782]: time=2026-06-02T20:27:16.551+04:00 level=INFO msg="bootstrapping state" hasInternet=true Jun 02 20:27:16 volumio volumio[1245]: verbose: New Socket.io Connection to 192.168.3.101:3000 from 192.168.3.104 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/148.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 17 Jun 02 20:27:16 volumio volumio[1245]: error: FusionDsp - Reload WebSocket error: [object Object] Jun 02 20:27:16 volumio volumio[1245]: info: Received Get System Info Jun 02 20:27:16 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 20:27:16 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 20:27:16 volumio volumio[1245]: info: Discovery: Getting this device information Jun 02 20:27:16 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:16 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:16 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 20:27:16 volumio volumio[1245]: error: updateQueue error: null Jun 02 20:27:16 volumio volumio[1245]: info: touch_display: X display number found: 0 Jun 02 20:27:16 volumio volumio-remote-updater[836]: Test mode disabled Jun 02 20:27:16 volumio volumio-remote-updater[836]: Alpha mode disabled Jun 02 20:27:16 volumio volumio-remote-updater[836]: Alpha legacy test mode disabled Jun 02 20:27:17 volumio volumio[1245]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 20:27:17 volumio volumio[1245]: info: Discovery: Getting this device information Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:17 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 20:27:17 volumio volumio[1245]: info: touch_display: Using Xserver unix domain socket /tmp/.X11-unix/X0 Jun 02 20:27:17 volumio volumio[1245]: info: Listing playlists Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::volumioGetQueue Jun 02 20:27:17 volumio volumio[1245]: info: CoreStateMachine::getQueue Jun 02 20:27:17 volumio volumio[1245]: info: CorePlayQueue::getQueue Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:17 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:17 volumio volumio[1245]: info: CALLMETHOD: user_interface now_playing getPluginInfo undefined Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: now_playing , getPluginInfo Jun 02 20:27:17 volumio volumio[1245]: info: Listing playlists Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::volumioGetQueue Jun 02 20:27:17 volumio volumio[1245]: info: CoreStateMachine::getQueue Jun 02 20:27:17 volumio volumio[1245]: info: CorePlayQueue::getQueue Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::volumioGetVisibleSources Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:17 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:17 volumio volumio[1245]: info: touch_display: X display number found: 0 Jun 02 20:27:17 volumio volumio[1245]: info: Spotify config file written Jun 02 20:27:17 volumio volumio[1245]: info: Update Ready: {"changeLogLink":"","description":"You're already on the latest version","title":"No Updates Available","updateavailable":false} Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: updater_comm , setUpdateMessageCache Jun 02 20:27:17 volumio sudo[1916]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jun 02 20:27:17 volumio sudo[1916]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:17 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 02 20:27:17 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jun 02 20:27:17 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jun 02 20:27:17 volumio go-librespot[1920]: go-librespot daemon starting... Jun 02 20:27:17 volumio sudo[1916]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 20:27:17 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 20:27:18 volumio volumio5-onboarding[1782]: time=2026-06-02T20:27:18.028+04:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=tidal error="could not open plugin config file for \"tidal\": open /data/configuration/music_service/tidal/config.json: no such file or directory" Jun 02 20:27:18 volumio volumio5-onboarding[1782]: time=2026-06-02T20:27:18.029+04:00 level=WARN msg="could not read OAuth data for music provider" component=volumio provider=qobuz error="could not open plugin config file for \"qobuz\": open /data/configuration/music_service/qobuz/config.json: no such file or directory" Jun 02 20:27:18 volumio volumio5-onboarding[1782]: time=2026-06-02T20:27:18.029+04:00 level=WARN msg="could not read username/password data for music provider" component=volumio provider=hi_res_audio error="could not open plugin config file for \"hi_res_audio\": open /data/configuration/music_service/hi_res_audio/config.json: no such file or directory" Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jun 02 20:27:18 volumio volumio[1245]: info: Received Get System Info Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jun 02 20:27:18 volumio volumio[1245]: info: Discovery: Getting this device information Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:18 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:18 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Jun 02 20:27:18 volumio volumio5-onboarding[1782]: time=2026-06-02T20:27:18.593+04:00 level=INFO msg="enabling local network discovery" Jun 02 20:27:18 volumio volumio5-onboarding[1782]: time=2026-06-02T20:27:18.612+04:00 level=INFO msg="enabling BLE discovery" Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:18 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:19 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 20:27:19 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:19 volumio volumio[1245]: info: No need to fix Spotify hosts Jun 02 20:27:19 volumio go-librespot[1925]: time="2026-06-02T20:27:19+04:00" level=info msg="running go-librespot 0.7.1" Jun 02 20:27:19 volumio go-librespot[1925]: time="2026-06-02T20:27:19+04:00" level=debug msg="app state loaded" Jun 02 20:27:19 volumio go-librespot[1925]: time="2026-06-02T20:27:19+04:00" level=info msg="api server listening on 127.0.0.1:9879" Jun 02 20:27:19 volumio volumio5-onboarding[1782]: time=2026-06-02T20:27:19.623+04:00 level=INFO msg="service successfully established" component=discovery/localnet Jun 02 20:27:20 volumio go-librespot[1925]: time="2026-06-02T20:27:20+04:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jun 02 20:27:20 volumio go-librespot[1925]: time="2026-06-02T20:27:20+04:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jun 02 20:27:20 volumio go-librespot[1925]: time="2026-06-02T20:27:20+04:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jun 02 20:27:20 volumio go-librespot[1925]: time="2026-06-02T20:27:20+04:00" level=info msg="zeroconf server listening on port 46813" Jun 02 20:27:20 volumio go-librespot[1925]: time="2026-06-02T20:27:20+04:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Jun 02 20:27:20 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:27:20 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:27:20 volumio systemd[1]: Started fusiondsp.service - FusionDsp Daemon. Jun 02 20:27:20 volumio sudo[1830]: pam_unix(sudo:session): session closed for user root Jun 02 20:27:20 volumio go-librespot[1925]: time="2026-06-02T20:27:20+04:00" level=debug msg="obtained new client token: AAAW2sH3g58MWd0lwJvR8jpY/NfaikOmSonBcSZwiHFEXQioykLX9ncXuhyJ5vzqSWuOPD6gMXBwQ7vT+5HvzIFXGFYwqZ66GCls6U4hK+aJ/LQl323V/VzLFqTqADYD2aVt+VyPUD23ReWXHSwYyQmRoByO1kdkb8wc0G5+TjgBhATmOA7Xp89zvV1J9yHgZ3HEyi7E1QKa1FV0VrPjtywCxDbNDsemJkpUP/Ww35U20Voz4lZ3uQ==" Jun 02 20:27:20 volumio go-librespot[1925]: time="2026-06-02T20:27:20+04:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jun 02 20:27:20 volumio go-librespot[1925]: time="2026-06-02T20:27:20+04:00" level=debug msg="completed keyexchange" Jun 02 20:27:20 volumio go-librespot[1925]: time="2026-06-02T20:27:20+04:00" level=debug msg="completed challenge" Jun 02 20:27:21 volumio go-librespot[1925]: time="2026-06-02T20:27:21+04:00" level=info msg="authenticated AP" username="22*********************zy" Jun 02 20:27:21 volumio go-librespot[1925]: time="2026-06-02T20:27:21+04:00" level=info msg="authenticated Login5" username="22*********************zy" Jun 02 20:27:21 volumio go-librespot[1925]: time="2026-06-02T20:27:21+04:00" level=debug msg="initializing zeroconf session" username="22*********************zy" Jun 02 20:27:21 volumio go-librespot[1925]: time="2026-06-02T20:27:21+04:00" level=debug msg="dealer connection opened" Jun 02 20:27:21 volumio go-librespot[1925]: time="2026-06-02T20:27:21+04:00" level=trace msg="starting accesspoint recv loop" Jun 02 20:27:21 volumio go-librespot[1925]: time="2026-06-02T20:27:21+04:00" level=trace msg="starting dealer recv loop" Jun 02 20:27:21 volumio go-librespot[1925]: time="2026-06-02T20:27:21+04:00" level=trace msg="received accesspoint ping" Jun 02 20:27:21 volumio go-librespot[1925]: time="2026-06-02T20:27:21+04:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jun 02 20:27:21 volumio go-librespot[1925]: time="2026-06-02T20:27:21+04:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jun 02 20:27:22 volumio go-librespot[1925]: time="2026-06-02T20:27:21+04:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jun 02 20:27:22 volumio go-librespot[1925]: time="2026-06-02T20:27:21+04:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jun 02 20:27:22 volumio go-librespot[1925]: time="2026-06-02T20:27:21+04:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jun 02 20:27:22 volumio go-librespot[1925]: time="2026-06-02T20:27:22+04:00" level=debug msg="received connection id: NzIxOWNiZWItMGY5...M0U1RTdBQ0M2RQ==" Jun 02 20:27:22 volumio go-librespot[1925]: time="2026-06-02T20:27:22+04:00" level=trace msg="received accesspoint pong ack" Jun 02 20:27:22 volumio go-librespot[1925]: time="2026-06-02T20:27:22+04:00" level=debug msg="put connect state because NEW_DEVICE" Jun 02 20:27:27 volumio upmpdcli[2066]: writing RSA key Jun 02 20:27:33 volumio volumio[1245]: error: Failed LSINFO: Error: [50@0] {lsinfo} No such directory Jun 02 20:27:33 volumio volumio[1245]: info: go-librespot daemon successfully initialized Jun 02 20:27:35 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jun 02 20:27:35 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 20:27:35 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jun 02 20:27:35 volumio volumio[1245]: error: Plugin music_service ytcr failed to complete 'onStart' in a timely fashion Jun 02 20:27:35 volumio volumio[1245]: info: ------------------------------------------- Jun 02 20:27:35 volumio volumio[1245]: info: ----- MyVolumio plugins startup ---- Jun 02 20:27:35 volumio volumio[1245]: info: ------------------------------------------- Jun 02 20:27:35 volumio volumio[1245]: info: [MyVolumio PluginManager] Fetching plans data.... Jun 02 20:27:37 volumio volumio[1245]: info: Initializing connection to go-librespot Websocket Jun 02 20:27:46 volumio go-librespot[1925]: time="2026-06-02T20:27:46+04:00" level=debug msg="new websocket client" Jun 02 20:27:52 volumio go-librespot[1925]: time="2026-06-02T20:27:52+04:00" level=trace msg="sent dealer ping" Jun 02 20:27:52 volumio go-librespot[1925]: time="2026-06-02T20:27:52+04:00" level=trace msg="received dealer pong" Jun 02 20:27:54 volumio volumio[1245]: info: Connection to go-librespot Websocket established Jun 02 20:27:54 volumio volumio[1245]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jun 02 20:27:54 volumio volumio[1245]: LoungeScreenDisconnected { Jun 02 20:27:54 volumio volumio[1245]: AID: null, Jun 02 20:27:54 volumio volumio[1245]: name: 'loungeScreenDisconnected', Jun 02 20:27:54 volumio volumio[1245]: payload: {} Jun 02 20:27:54 volumio volumio[1245]: } Jun 02 20:27:54 volumio volumio[1245]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jun 02 20:27:54 volumio volumio[1245]: Error info: { missing: [ 'SID', 'gsessionid' ] } Jun 02 20:27:54 volumio volumio[1245]: Stack trace: Jun 02 20:27:54 volumio volumio[1245]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jun 02 20:27:54 volumio volumio[1245]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Jun 02 20:27:54 volumio volumio[1245]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Jun 02 20:27:54 volumio volumio[1245]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Jun 02 20:27:54 volumio volumio[1245]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Jun 02 20:27:54 volumio volumio[1245]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:39:23) Jun 02 20:27:54 volumio volumio[1245]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:345:67 Jun 02 20:27:54 volumio volumio[1245]: at new Promise () Jun 02 20:27:54 volumio volumio[1245]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:344:16) Jun 02 20:27:54 volumio volumio[1245]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:241:28) Jun 02 20:27:54 volumio volumio[1245]: at Session.begin (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:209:26) Jun 02 20:27:54 volumio volumio[1245]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 02 20:27:54 volumio volumio[1245]: at runNextTicks (node:internal/process/task_queues:64:3) Jun 02 20:27:54 volumio volumio[1245]: at process.processImmediate (node:internal/timers:449:9) Jun 02 20:27:54 volumio volumio[1245]: at async Promise.all (index 0) Jun 02 20:27:54 volumio volumio[1245]: at async YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:116:13) Jun 02 20:27:54 volumio volumio[1245]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/YouTubeCastReceiver.js:93:13) Jun 02 20:27:54 volumio volumio[1245]: error: [yt-cast-receiver] Failed to start receiver: Jun 02 20:27:54 volumio volumio[1245]: (AppError) Failed to start YouTubeApp Jun 02 20:27:54 volumio volumio[1245]: --->(SessionError) (YouTube) Failed to establish session Jun 02 20:27:54 volumio volumio[1245]: ------>(ConnectionError) (YouTube) Connection error in fetching session data Jun 02 20:27:54 volumio volumio[1245]: Error info: { Jun 02 20:27:54 volumio volumio[1245]: url: 'https://www.youtube.com/api/lounge/bc/bind?CVER=1&RID=46070&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&deviceInfo=%7B%22year%22%3A0%2C%22os%22%3A%22Windows%22%2C%22osVersion%22%3A%2210.0%22%2C%22chipset%22%3A%22%22%2C%22clientName%22%3A%22TVHTML5%22%2C%22dialAdditionalDataSupportLevel%22%3A%22unsupported%22%2C%22mdxDialServerType%22%3A%22MDX_DIAL_SERVER_TYPE_UNKNOWN%22%2C%22brand%22%3A%22Generic%22%2C%22model%22%3A%22SmartTV%22%7D&id=3393fa15-ae00-4b3e-b98b-88fd2fa22264&loungeIdToken=AGdO5p_Vgp030_UKthJ6nGEQmtFLayxrCNsxiNhIgejWLxVEkFUWcbpfFp5HJepqch23CILNZ-3rE_2PLnkLHg_Sr03qfLW9eDNudEKsdpqCL9B0ZWZvwJs&mdxVersion=2&name=YouTube%20on%20Volumio&obfuscatedGaiaId=&t=1&theme=cl&v=2&zx=5ffd3878df6d' Jun 02 20:27:54 volumio volumio[1245]: } Jun 02 20:27:54 volumio volumio[1245]: --------->(TypeError) fetch failed Jun 02 20:27:54 volumio volumio[1245]: Stack trace: Jun 02 20:27:54 volumio volumio[1245]: AppError: Failed to start YouTubeApp Jun 02 20:27:54 volumio volumio[1245]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:133:19) Jun 02 20:27:54 volumio volumio[1245]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 02 20:27:54 volumio volumio[1245]: at runNextTicks (node:internal/process/task_queues:64:3) Jun 02 20:27:54 volumio volumio[1245]: at process.processImmediate (node:internal/timers:449:9) Jun 02 20:27:54 volumio volumio[1245]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/YouTubeCastReceiver.js:93:13) Jun 02 20:27:54 volumio volumio[1245]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jun 02 20:27:54 volumio volumio[1245]: LoungeScreenDisconnected { Jun 02 20:27:54 volumio volumio[1245]: AID: null, Jun 02 20:27:54 volumio volumio[1245]: name: 'loungeScreenDisconnected', Jun 02 20:27:54 volumio volumio[1245]: payload: {} Jun 02 20:27:54 volumio volumio[1245]: } Jun 02 20:27:54 volumio volumio[1245]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jun 02 20:27:54 volumio volumio[1245]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jun 02 20:27:54 volumio volumio[1245]: Stack trace: Jun 02 20:27:54 volumio volumio[1245]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jun 02 20:27:54 volumio volumio[1245]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Jun 02 20:27:54 volumio volumio[1245]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Jun 02 20:27:54 volumio volumio[1245]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Jun 02 20:27:54 volumio volumio[1245]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Jun 02 20:27:54 volumio volumio[1245]: at AsyncTaskQueue.push (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:39:23) Jun 02 20:27:54 volumio volumio[1245]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:345:67 Jun 02 20:27:54 volumio volumio[1245]: at new Promise () Jun 02 20:27:54 volumio volumio[1245]: at Session.sendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:344:16) Jun 02 20:27:54 volumio volumio[1245]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:241:28) Jun 02 20:27:54 volumio volumio[1245]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:125:39 Jun 02 20:27:54 volumio volumio[1245]: at /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:130:19 Jun 02 20:27:54 volumio volumio[1245]: at Array.forEach () Jun 02 20:27:54 volumio volumio[1245]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:120:24) Jun 02 20:27:54 volumio volumio[1245]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 02 20:27:54 volumio volumio[1245]: at runNextTicks (node:internal/process/task_queues:64:3) Jun 02 20:27:54 volumio volumio[1245]: at process.processImmediate (node:internal/timers:449:9) Jun 02 20:27:54 volumio volumio[1245]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jun 02 20:27:54 volumio volumio[1245]: LoungeScreenDisconnected { Jun 02 20:27:54 volumio volumio[1245]: AID: null, Jun 02 20:27:54 volumio volumio[1245]: name: 'loungeScreenDisconnected', Jun 02 20:27:54 volumio volumio[1245]: payload: {} Jun 02 20:27:54 volumio volumio[1245]: } Jun 02 20:27:54 volumio volumio[1245]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jun 02 20:27:54 volumio volumio[1245]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jun 02 20:27:54 volumio volumio[1245]: Stack trace: Jun 02 20:27:54 volumio volumio[1245]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jun 02 20:27:54 volumio volumio[1245]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Jun 02 20:27:54 volumio volumio[1245]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Jun 02 20:27:54 volumio volumio[1245]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Jun 02 20:27:54 volumio volumio[1245]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Jun 02 20:27:54 volumio volumio[1245]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:242:71) Jun 02 20:27:54 volumio volumio[1245]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 02 20:27:54 volumio volumio[1245]: at runNextTicks (node:internal/process/task_queues:64:3) Jun 02 20:27:54 volumio volumio[1245]: at process.processImmediate (node:internal/timers:449:9) Jun 02 20:27:54 volumio volumio[1245]: error: [ytcr] Failed to start plugin: Jun 02 20:27:54 volumio volumio[1245]: (AppError) Failed to start YouTubeApp Jun 02 20:27:54 volumio volumio[1245]: --->(SessionError) (YouTube) Failed to establish session Jun 02 20:27:54 volumio volumio[1245]: ------>(ConnectionError) (YouTube) Connection error in fetching session data Jun 02 20:27:54 volumio volumio[1245]: Error info: { Jun 02 20:27:54 volumio volumio[1245]: url: 'https://www.youtube.com/api/lounge/bc/bind?CVER=1&RID=46070&VER=8&app=ytcr&capabilities=dsp%2Cmic%2Cdpa%2Cntb&cst=m&device=LOUNGE_SCREEN&deviceInfo=%7B%22year%22%3A0%2C%22os%22%3A%22Windows%22%2C%22osVersion%22%3A%2210.0%22%2C%22chipset%22%3A%22%22%2C%22clientName%22%3A%22TVHTML5%22%2C%22dialAdditionalDataSupportLevel%22%3A%22unsupported%22%2C%22mdxDialServerType%22%3A%22MDX_DIAL_SERVER_TYPE_UNKNOWN%22%2C%22brand%22%3A%22Generic%22%2C%22model%22%3A%22SmartTV%22%7D&id=3393fa15-ae00-4b3e-b98b-88fd2fa22264&loungeIdToken=AGdO5p_Vgp030_UKthJ6nGEQmtFLayxrCNsxiNhIgejWLxVEkFUWcbpfFp5HJepqch23CILNZ-3rE_2PLnkLHg_Sr03qfLW9eDNudEKsdpqCL9B0ZWZvwJs&mdxVersion=2&name=YouTube%20on%20Volumio&obfuscatedGaiaId=&t=1&theme=cl&v=2&zx=5ffd3878df6d' Jun 02 20:27:54 volumio volumio[1245]: } Jun 02 20:27:54 volumio volumio[1245]: --------->(TypeError) fetch failed Jun 02 20:27:54 volumio volumio[1245]: Stack trace: Jun 02 20:27:54 volumio volumio[1245]: AppError: Failed to start YouTubeApp Jun 02 20:27:54 volumio volumio[1245]: at YouTubeApp.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:133:19) Jun 02 20:27:54 volumio volumio[1245]: at processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 02 20:27:54 volumio volumio[1245]: at runNextTicks (node:internal/process/task_queues:64:3) Jun 02 20:27:54 volumio volumio[1245]: at process.processImmediate (node:internal/timers:449:9) Jun 02 20:27:54 volumio volumio[1245]: at async YouTubeCastReceiver.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/YouTubeCastReceiver.js:93:13) Jun 02 20:27:56 volumio volumio[1245]: error: MyVolumio Plugin failed to start in a timely fashion Jun 02 20:27:56 volumio volumio[1245]: [Metrics] CommandRouter: 79s 731.36ms Jun 02 20:27:56 volumio volumio[1245]: info: CoreCommandRouter::volumiosetStartupVolume Jun 02 20:27:56 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jun 02 20:27:56 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Jun 02 20:27:56 volumio volumio[1245]: info: CoreCommandRouter::Close All Modals sent Jun 02 20:27:56 volumio volumio[1245]: info: CoreCommandRouter::Close All Modals sent Jun 02 20:28:16 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , checkAudioDeviceAvailable Jun 02 20:28:16 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: i2s_dacs , getI2sStatus Jun 02 20:28:16 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: system , versionChangeDetect Jun 02 20:28:16 volumio volumio[1245]: info: Getting Spotify volume Jun 02 20:28:16 volumio volumio[1245]: info: BOOT COMPLETED Jun 02 20:28:22 volumio go-librespot[1925]: time="2026-06-02T20:28:22+04:00" level=trace msg="sent dealer ping" Jun 02 20:28:22 volumio go-librespot[1925]: time="2026-06-02T20:28:22+04:00" level=trace msg="received dealer pong" Jun 02 20:28:34 volumio volumio[1245]: error: [yt-cast-receiver] Caught error refreshing lounge token: Jun 02 20:28:34 volumio volumio[1245]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Jun 02 20:28:34 volumio volumio[1245]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Jun 02 20:28:34 volumio volumio[1245]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:377:16) Jun 02 20:28:34 volumio volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 02 20:28:34 volumio volumio[1245]: error: [yt-cast-receiver] (YouTube Music) Error occurred in SendMessageTask: Jun 02 20:28:34 volumio volumio[1245]: LoungeScreenDisconnected { Jun 02 20:28:34 volumio volumio[1245]: AID: null, Jun 02 20:28:34 volumio volumio[1245]: name: 'loungeScreenDisconnected', Jun 02 20:28:34 volumio volumio[1245]: payload: {} Jun 02 20:28:34 volumio volumio[1245]: } Jun 02 20:28:34 volumio volumio[1245]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jun 02 20:28:34 volumio volumio[1245]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jun 02 20:28:34 volumio volumio[1245]: Stack trace: Jun 02 20:28:34 volumio volumio[1245]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jun 02 20:28:34 volumio volumio[1245]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Jun 02 20:28:34 volumio volumio[1245]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Jun 02 20:28:34 volumio volumio[1245]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Jun 02 20:28:34 volumio volumio[1245]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Jun 02 20:28:34 volumio volumio[1245]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:242:71) Jun 02 20:28:34 volumio volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 02 20:28:34 volumio volumio[1245]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:125:25 Jun 02 20:28:34 volumio volumio[1245]: error: [yt-cast-receiver] Caught error refreshing lounge token: Jun 02 20:28:34 volumio volumio[1245]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Jun 02 20:28:34 volumio volumio[1245]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Jun 02 20:28:34 volumio volumio[1245]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:377:16) Jun 02 20:28:34 volumio volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 02 20:28:34 volumio volumio[1245]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jun 02 20:28:34 volumio volumio[1245]: LoungeScreenDisconnected { Jun 02 20:28:34 volumio volumio[1245]: AID: null, Jun 02 20:28:34 volumio volumio[1245]: name: 'loungeScreenDisconnected', Jun 02 20:28:34 volumio volumio[1245]: payload: {} Jun 02 20:28:34 volumio volumio[1245]: } Jun 02 20:28:34 volumio volumio[1245]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jun 02 20:28:34 volumio volumio[1245]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jun 02 20:28:34 volumio volumio[1245]: Stack trace: Jun 02 20:28:34 volumio volumio[1245]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jun 02 20:28:34 volumio volumio[1245]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Jun 02 20:28:34 volumio volumio[1245]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Jun 02 20:28:34 volumio volumio[1245]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Jun 02 20:28:34 volumio volumio[1245]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Jun 02 20:28:34 volumio volumio[1245]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:242:71) Jun 02 20:28:34 volumio volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 02 20:28:34 volumio volumio[1245]: at async /data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/YouTubeApp.js:125:25 Jun 02 20:28:34 volumio volumio[1245]: error: [yt-cast-receiver] Caught error refreshing lounge token: Jun 02 20:28:34 volumio volumio[1245]: (TypeError) Cannot read properties of undefined (reading 'removeAllListeners') Jun 02 20:28:34 volumio volumio[1245]: TypeError: Cannot read properties of undefined (reading 'removeAllListeners') Jun 02 20:28:34 volumio volumio[1245]: at Session._Session_refreshLoungeToken (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:377:16) Jun 02 20:28:34 volumio volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 02 20:28:34 volumio volumio[1245]: error: [yt-cast-receiver] (YouTube) Error occurred in SendMessageTask: Jun 02 20:28:34 volumio volumio[1245]: LoungeScreenDisconnected { Jun 02 20:28:34 volumio volumio[1245]: AID: null, Jun 02 20:28:34 volumio volumio[1245]: name: 'loungeScreenDisconnected', Jun 02 20:28:34 volumio volumio[1245]: payload: {} Jun 02 20:28:34 volumio volumio[1245]: } Jun 02 20:28:34 volumio volumio[1245]: (IncompleteAPIDataError) Missing data required to construct query string from bind params Jun 02 20:28:34 volumio volumio[1245]: Error info: { missing: [ 'loungeIdToken', 'SID', 'gsessionid' ] } Jun 02 20:28:34 volumio volumio[1245]: Stack trace: Jun 02 20:28:34 volumio volumio[1245]: IncompleteAPIDataError: Missing data required to construct query string from bind params Jun 02 20:28:34 volumio volumio[1245]: at BindParams.toQueryString (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/BindParams.js:120:19) Jun 02 20:28:34 volumio volumio[1245]: at Session._Session_doSendMessage (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:497:103) Jun 02 20:28:34 volumio volumio[1245]: at SendMessageTask.run (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:558:71) Jun 02 20:28:34 volumio volumio[1245]: at AsyncTaskQueue.start (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/utils/AsyncTaskQueue.js:52:32) Jun 02 20:28:34 volumio volumio[1245]: at Session.end (/data/plugins/music_service/ytcr/node_modules/yt-cast-receiver/dist/lib/app/Session.js:242:71) Jun 02 20:28:34 volumio volumio[1245]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jun 02 20:28:34 volumio volumio[1245]: info: CALLMETHOD: system_hardware pi_screen_setup saveOtaBehavior [object Object] Jun 02 20:28:34 volumio volumio[1245]: info: CoreCommandRouter::executeOnPlugin: pi_screen_setup , saveOtaBehavior Jun 02 20:28:34 volumio volumio[1245]: info: CoreCommandRouter::volumioGetState Jun 02 20:28:34 volumio volumio[1245]: info: CorePlayQueue::getTrack 0 Jun 02 20:28:35 volumio volumio[1245]: info: camilladsp respawn in 1000 ms (attempt 1/10) Jun 02 20:28:35 volumio volumio[1245]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 20:28:35 volumio volumio[1245]: Error [ERR_INTERNAL_ASSERTION]: This is caused by either a bug in Node.js or incorrect usage of Node.js internals. Jun 02 20:28:35 volumio volumio[1245]: Please open an issue with this stack trace at https://github.com/nodejs/node/issues Jun 02 20:28:35 volumio volumio[1245]: at new NodeError (node:internal/errors:405:5) Jun 02 20:28:35 volumio volumio[1245]: at assert (node:internal/assert:14:11) Jun 02 20:28:35 volumio volumio[1245]: at internalConnectMultiple (node:net:1118:3) Jun 02 20:28:35 volumio volumio[1245]: at Timeout.internalConnectMultipleTimeout (node:net:1687:3) Jun 02 20:28:35 volumio volumio[1245]: at listOnTimeout (node:internal/timers:575:11) Jun 02 20:28:35 volumio volumio[1245]: at process.processTimers (node:internal/timers:514:7) { Jun 02 20:28:35 volumio volumio[1245]: code: 'ERR_INTERNAL_ASSERTION' Jun 02 20:28:35 volumio volumio[1245]: } Jun 02 20:28:35 volumio volumio[1245]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jun 02 20:28:38 volumio sudo[2231]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-06-02 20:27' Jun 02 20:28:38 volumio sudo[2231]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"