Dec 08 16:20:01 volumiop3 volumio[2480]: info: Processing triggers for fontconfig (2.14.1-4) ... Dec 08 16:20:06 volumiop3 sudo[3936]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:06 volumiop3 sudo[4647]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Dec 08 16:20:06 volumiop3 sudo[4647]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 08 16:20:06 volumiop3 systemd[1]: Reloading. Dec 08 16:20:11 volumiop3 systemd[1]: /lib/systemd/system/sshtunnel.service:6: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 08 16:20:11 volumiop3 systemd[1]: /lib/systemd/system/sshtunnel.service:7: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Dec 08 16:20:11 volumiop3 sudo[4647]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:11 volumiop3 volumio[2480]: info: Linked /data/plugins/user_interface/peppymeterbasic/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppymeterbasic/alsa-lib/libpeppyalsa.so Dec 08 16:20:11 volumiop3 volumio[2480]: info: Linked /data/plugins/user_interface/peppymeterbasic/alsa-lib/armhf/libpeppyalsa.so.0.0.0 to /data/plugins/user_interface/peppymeterbasic/alsa-lib/libpeppyalsa.so.0 Dec 08 16:20:18 volumiop3 sudo[4697]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/user_interface/peppymeterbasic/startpeppymeterbasic.sh Dec 08 16:20:18 volumiop3 sudo[4697]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 08 16:20:18 volumiop3 sudo[4697]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:18 volumiop3 sudo[3618]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:18 volumiop3 volumio[2480]: Plugin install end detected on script Dec 08 16:20:18 volumiop3 volumio[2480]: info: Install script completed Dec 08 16:20:18 volumiop3 volumio[2480]: info: Adding reference to registry Dec 08 16:20:18 volumiop3 volumio[2480]: info: Done installing plugin. Dec 08 16:20:18 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 08 16:20:18 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 08 16:20:18 volumiop3 volumio[2480]: info: Folder /tmp/plugins removed Dec 08 16:20:18 volumiop3 volumio[2480]: info: Folder /tmp/downloaded_plugin.zip removed Dec 08 16:20:18 volumiop3 volumio[2480]: info: Folder /data/temp removed Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 16:20:21 volumiop3 volumio[2480]: info: Discovery: Getting this device information Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 16:20:21 volumiop3 volumio[2480]: verbose: New Socket.io Connection to 192.168.88.95:3000 from 192.168.88.102 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 08 16:20:21 volumiop3 volumio[2480]: verbose: New Socket.io Connection to 192.168.88.95:3000 from 192.168.88.102 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 7 Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 16:20:21 volumiop3 volumio[2480]: info: Discovery: Getting this device information Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 16:20:21 volumiop3 volumio[2480]: info: Discovery: Getting this device information Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 16:20:21 volumiop3 volumio[2480]: verbose: New Socket.io Connection to 192.168.88.95:3000 from 192.168.88.102 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 08 16:20:21 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 16:20:25 volumiop3 volumio[2480]: info: Discovery: Getting this device information Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableLanguages Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getCurrentTimezone Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Dec 08 16:20:25 volumiop3 sudo[4706]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Dec 08 16:20:25 volumiop3 sudo[4706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:25 volumiop3 sudo[4711]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 08 16:20:25 volumiop3 sudo[4711]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:25 volumiop3 sudo[4722]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 08 16:20:25 volumiop3 sudo[4722]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:25 volumiop3 sudo[4711]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:25 volumiop3 sudo[4706]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:25 volumiop3 sudo[4722]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:25 volumiop3 sudo[4717]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Dec 08 16:20:25 volumiop3 sudo[4717]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:25 volumiop3 sudo[4717]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:25 volumiop3 sudo[4728]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 08 16:20:25 volumiop3 sudo[4728]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:25 volumiop3 sudo[4731]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 08 16:20:25 volumiop3 sudo[4731]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:25 volumiop3 sudo[4731]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:25 volumiop3 sudo[4728]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 16:20:25 volumiop3 volumio[2480]: info: Discovery: Getting this device information Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 08 16:20:25 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 08 16:20:26 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 16:20:26 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 16:20:26 volumiop3 volumio[2480]: info: Discovery: Getting this device information Dec 08 16:20:26 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:26 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 16:20:26 volumiop3 volumio[2480]: verbose: New Socket.io Connection to 192.168.88.95:3000 from 192.168.88.102 UA: Dart/3.9 (dart:io) Engine version: 3 Transport: websocket Total Clients: 8 Dec 08 16:20:26 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Dec 08 16:20:26 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Dec 08 16:20:27 volumiop3 sudo[4756]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 08 16:20:27 volumiop3 sudo[4756]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:27 volumiop3 sudo[4758]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 08 16:20:27 volumiop3 sudo[4758]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:27 volumiop3 sudo[4756]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:27 volumiop3 sudo[4758]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:27 volumiop3 volumio[2480]: verbose: New Socket.io Connection to 192.168.88.95 from 192.168.88.102 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Dec 08 16:20:27 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 08 16:20:27 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetVisibleSources Dec 08 16:20:27 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 08 16:20:27 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:27 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 08 16:20:27 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 08 16:20:27 volumiop3 volumio[2480]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 08 16:20:27 volumiop3 volumio[2480]: info: Received Get System Info Dec 08 16:20:27 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 16:20:27 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 16:20:27 volumiop3 volumio[2480]: info: Discovery: Getting this device information Dec 08 16:20:27 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:27 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 16:20:27 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:27 volumiop3 volumio[2480]: info: Listing playlists Dec 08 16:20:27 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 08 16:20:27 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 08 16:20:28 volumiop3 sudo[4764]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 08 16:20:28 volumiop3 sudo[4764]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:28 volumiop3 sudo[4764]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:28 volumiop3 sudo[4766]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 08 16:20:28 volumiop3 sudo[4766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:28 volumiop3 sudo[4766]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:28 volumiop3 volumio[2480]: verbose: New Socket.io Connection to 192.168.88.95 from 192.168.88.102 UA: Mozilla/5.0 (iPhone; CPU iPhone OS 18_7 like Mac OS X) AppleWebKit/605.1.15 (KHTML, like Gecko) Mobile/15E148 Engine version: 3 Transport: polling Total Clients: 8 Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetVisibleSources Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 08 16:20:28 volumiop3 volumio[2480]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Dec 08 16:20:28 volumiop3 volumio[2480]: info: Received Get System Info Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 16:20:28 volumiop3 volumio[2480]: info: Discovery: Getting this device information Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:28 volumiop3 volumio[2480]: info: Listing playlists Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 08 16:20:28 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 08 16:20:29 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 08 16:20:29 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 08 16:20:29 volumiop3 volumio[2480]: info: Received Get System Info Dec 08 16:20:29 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 16:20:29 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 16:20:29 volumiop3 volumio[2480]: info: Discovery: Getting this device information Dec 08 16:20:29 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:29 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 16:20:30 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 08 16:20:31 volumiop3 volumio[2480]: info: Enabling plugin peppymeterbasic Dec 08 16:20:31 volumiop3 volumio[2480]: info: Loading plugin "peppymeterbasic"... Dec 08 16:20:32 volumiop3 volumio[2480]: info: Preparing to generate the ALSA configuration file Dec 08 16:20:32 volumiop3 volumio[2480]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 08 16:20:32 volumiop3 volumio[2480]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 08 16:20:32 volumiop3 volumio[2480]: info: Reading ALSA contributions from plugins. Dec 08 16:20:32 volumiop3 volumio[2480]: info: Asound.conf file written Dec 08 16:20:32 volumiop3 sudo[4772]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 08 16:20:32 volumiop3 sudo[4772]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:32 volumiop3 sudo[4772]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:32 volumiop3 volumio[2480]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 08 16:20:32 volumiop3 volumio[2480]: No state is present for card b1 Dec 08 16:20:32 volumiop3 volumio[2480]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 08 16:20:32 volumiop3 volumio[2480]: /usr/share/alsa/init/bcm2835:14: control element not found Dec 08 16:20:32 volumiop3 volumio[2480]: No state is present for card b1 Dec 08 16:20:32 volumiop3 volumio[2480]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 08 16:20:32 volumiop3 volumio[2480]: No state is present for card Headphones Dec 08 16:20:32 volumiop3 volumio[2480]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 08 16:20:32 volumiop3 volumio[2480]: /usr/share/alsa/init/bcm2835:20: control element not found Dec 08 16:20:32 volumiop3 volumio[2480]: No state is present for card Headphones Dec 08 16:20:32 volumiop3 volumio[2480]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 08 16:20:32 volumiop3 volumio[2480]: No state is present for card sndrpihifiberry Dec 08 16:20:32 volumiop3 volumio[2480]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 08 16:20:32 volumiop3 volumio[2480]: Found hardware: "HifiberryDacp" "" "" "" "" Dec 08 16:20:32 volumiop3 volumio[2480]: Hardware is initialized using a generic method Dec 08 16:20:32 volumiop3 volumio[2480]: No state is present for card sndrpihifiberry Dec 08 16:20:32 volumiop3 volumio[2480]: info: Output device has changed, restarting MPD Dec 08 16:20:32 volumiop3 sudo[4778]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 08 16:20:32 volumiop3 sudo[4778]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:32 volumiop3 sudo[4778]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:32 volumiop3 volumio[2480]: info: Output device has changed, restarting Shairport Sync Dec 08 16:20:32 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:32 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 16:20:33 volumiop3 sudo[4782]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 08 16:20:33 volumiop3 sudo[4782]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:33 volumiop3 volumio[2480]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 08 16:20:33 volumiop3 volumio[2480]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:33 volumiop3 volumio[2480]: info: QobuzConnect: setDeactiveState invoked Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:33 volumiop3 vtcs[3502]: [2025-12-08 16:20:33.117] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 08 16:20:33 volumiop3 vtcs[3502]: [2025-12-08 16:20:33.117] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 08 16:20:33 volumiop3 volumio[2480]: info: PLUGIN START: peppymeterbasic Dec 08 16:20:33 volumiop3 sudo[4791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 08 16:20:33 volumiop3 systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Dec 08 16:20:33 volumiop3 sudo[4791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:33 volumiop3 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 08 16:20:33 volumiop3 volumio[2480]: info: Preparing to generate the ALSA configuration file Dec 08 16:20:33 volumiop3 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 08 16:20:33 volumiop3 systemd[1]: vtcs.service: Killing process 3509 (vtcs) with signal SIGKILL. Dec 08 16:20:33 volumiop3 systemd[1]: vtcs.service: Deactivated successfully. Dec 08 16:20:33 volumiop3 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 08 16:20:33 volumiop3 volumio[2480]: info: Done. Dec 08 16:20:33 volumiop3 sudo[4796]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Dec 08 16:20:33 volumiop3 sudo[4796]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:33 volumiop3 sudo[4791]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:33 volumiop3 sudo[4805]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 08 16:20:33 volumiop3 sudo[4805]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:33 volumiop3 volumio[2480]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 08 16:20:33 volumiop3 volumio[2480]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 08 16:20:33 volumiop3 volumio[2480]: info: Reading ALSA contributions from plugins. Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 08 16:20:33 volumiop3 volumio[2480]: info: Received Get System Info Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 16:20:33 volumiop3 volumio[2480]: info: Discovery: Getting this device information Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 16:20:33 volumiop3 volumio[2480]: info: MPD Permissions set Dec 08 16:20:33 volumiop3 sudo[4816]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 08 16:20:33 volumiop3 sudo[4816]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 08 16:20:33 volumiop3 volumio[2480]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 9 Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:33 volumiop3 systemd[1]: mpd.service: Deactivated successfully. Dec 08 16:20:33 volumiop3 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 08 16:20:33 volumiop3 systemd[1]: mpd.service: Consumed 6.666s CPU time. Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 16:20:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:33 volumiop3 systemd[1]: mpd.socket: Deactivated successfully. Dec 08 16:20:33 volumiop3 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 08 16:20:33 volumiop3 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 08 16:20:33 volumiop3 sudo[4816]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:33 volumiop3 sudo[4796]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:33 volumiop3 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 08 16:20:33 volumiop3 sudo[4828]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 08 16:20:33 volumiop3 sudo[4828]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:33 volumiop3 volumio[2480]: info: snd_dummy loaded Dec 08 16:20:33 volumiop3 (udev-worker)[4834]: controlC7: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 7' failed with exit code 99. Dec 08 16:20:33 volumiop3 volumio[2480]: info: Starting Shairport Sync Dec 08 16:20:33 volumiop3 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 08 16:20:34 volumiop3 sudo[4839]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 08 16:20:34 volumiop3 sudo[4839]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:34 volumiop3 volumio[2480]: info: Asound.conf file unchanged, so no further update is needed Dec 08 16:20:34 volumiop3 volumio[2480]: info: Output device has changed, restarting MPD Dec 08 16:20:34 volumiop3 sudo[4805]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:34 volumiop3 qobuz-connect[3484]: 20251208 16:20:34.243 [3484.3484] INFO SampleApp: Stopping Local configuration server Dec 08 16:20:34 volumiop3 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 08 16:20:34 volumiop3 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 08 16:20:34 volumiop3 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 08 16:20:34 volumiop3 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 08 16:20:34 volumiop3 systemd[1]: shairport-sync.service: Consumed 4.590s CPU time. Dec 08 16:20:34 volumiop3 qobuz-connect[3484]: 20251208 16:20:34.323 [3484.3484] INFO SampleApp: shat down connection on UNIX socket Dec 08 16:20:34 volumiop3 volumio[2480]: info: Output device has changed, restarting Shairport Sync Dec 08 16:20:34 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:34 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 16:20:34 volumiop3 sudo[4843]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 08 16:20:34 volumiop3 sudo[4843]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:34 volumiop3 sudo[4843]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:34 volumiop3 sudo[4846]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 08 16:20:34 volumiop3 sudo[4846]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:34 volumiop3 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 08 16:20:34 volumiop3 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 08 16:20:34 volumiop3 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 08 16:20:34 volumiop3 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 08 16:20:34 volumiop3 sudo[4839]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:34 volumiop3 sudo[4828]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:34 volumiop3 sudo[4836]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 08 16:20:34 volumiop3 sudo[4836]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 08 16:20:34 volumiop3 volumio[2480]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 08 16:20:34 volumiop3 volumio[2480]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 08 16:20:34 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:34 volumiop3 sudo[4836]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:34 volumiop3 volumio[2480]: info: QobuzConnect: setDeactiveState invoked Dec 08 16:20:34 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:34 volumiop3 sudo[4875]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 08 16:20:34 volumiop3 sudo[4875]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:34 volumiop3 systemd[1]: mpd.service: Deactivated successfully. Dec 08 16:20:34 volumiop3 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 08 16:20:34 volumiop3 systemd[1]: mpd.socket: Deactivated successfully. Dec 08 16:20:34 volumiop3 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 08 16:20:34 volumiop3 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 08 16:20:34 volumiop3 sudo[4882]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 08 16:20:34 volumiop3 sudo[4882]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:34 volumiop3 sudo[4888]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 08 16:20:34 volumiop3 sudo[4888]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:35 volumiop3 volumio[2480]: info: MPD Permissions set Dec 08 16:20:35 volumiop3 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 08 16:20:35 volumiop3 volumio[2480]: info: Shairport-Sync Started Dec 08 16:20:35 volumiop3 volumio[2480]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 08 16:20:35 volumiop3 volumio[2480]: info: QobuzConnect: setDeactiveState invoked Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:35 volumiop3 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 08 16:20:35 volumiop3 volumio[2480]: info: Received Get System Info Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 16:20:35 volumiop3 volumio[2480]: info: Discovery: Getting this device information Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 16:20:35 volumiop3 volumio[2480]: info: Executing endpoint qc_getconfig Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 08 16:20:35 volumiop3 qobuz-connect[4853]: 20251208 16:20:35.195 [4853.4853] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 08 16:20:35 volumiop3 qobuz-connect[4853]: 20251208 16:20:35.219 [4853.4853] INFO VolumeManager: [0x24b258]: Setting new playback volume: 75 Dec 08 16:20:35 volumiop3 qobuz-connect[4853]: 20251208 16:20:35.219 [4853.4853] INFO VolumeManager: [0x24b258]: Setting new mute state: 0 Dec 08 16:20:35 volumiop3 qobuz-connect[4853]: 20251208 16:20:35.220 [4853.4853] INFO QobuzConnect: [0x24bc28]: Client initialized! Dec 08 16:20:35 volumiop3 qobuz-connect[4853]: 20251208 16:20:35.220 [4853.4853] INFO SampleApp: Starting Avahi advertising, name: VolumioP3, service name: _qobuz-connect._tcp Dec 08 16:20:35 volumiop3 volumio[2480]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 08 16:20:35 volumiop3 volumio[2480]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 08 16:20:35 volumiop3 sudo[4888]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:35 volumiop3 qobuz-connect[4853]: 20251208 16:20:35.315 [4853.4853] INFO LocalConfigManager: [0x24ab38]: Starting Local Configuration server Dec 08 16:20:35 volumiop3 qobuz-connect[4853]: 20251208 16:20:35.315 [4853.4853] INFO SampleApp: Starting Local configuration server Dec 08 16:20:35 volumiop3 qobuz-connect[4853]: 20251208 16:20:35.317 [4853.4853] INFO SampleApp: Connected to UNIX socket client 0x235818 Dec 08 16:20:35 volumiop3 qobuz-connect[4853]: 20251208 16:20:35.345 [4853.4853] INFO SampleApp: Playback volume changed: 75 Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:35 volumiop3 sudo[4875]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:35 volumiop3 sudo[4896]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 08 16:20:35 volumiop3 sudo[4896]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:35 volumiop3 sudo[4882]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 08 16:20:35 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:35 volumiop3 qobuz-connect[4853]: 20251208 16:20:35.593 [4853.4853] INFO SampleApp: Stopping Local configuration server Dec 08 16:20:35 volumiop3 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 08 16:20:35 volumiop3 volumio[2480]: info: Starting Shairport Sync Dec 08 16:20:35 volumiop3 sudo[4908]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 08 16:20:35 volumiop3 sudo[4908]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:35 volumiop3 sudo[4892]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 08 16:20:35 volumiop3 sudo[4892]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 08 16:20:35 volumiop3 sudo[4892]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:35 volumiop3 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 08 16:20:35 volumiop3 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 08 16:20:35 volumiop3 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 08 16:20:35 volumiop3 systemd[1]: shairport-sync.service: Consumed 1.242s CPU time. Dec 08 16:20:35 volumiop3 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 08 16:20:35 volumiop3 sudo[4908]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:35 volumiop3 volumio[2480]: info: Shairport-Sync Started Dec 08 16:20:35 volumiop3 volumio[2480]: (node:2480) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 08 16:20:35 volumiop3 volumio[2480]: (Use `node --trace-warnings ...` to show where the warning was created) Dec 08 16:20:35 volumiop3 volumio[2480]: (node:2480) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 08 16:20:35 volumiop3 volumio[2480]: (node:2480) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 08 16:20:35 volumiop3 volumio[2480]: (node:2480) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 08 16:20:35 volumiop3 volumio[2480]: (node:2480) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Dec 08 16:20:36 volumiop3 volumio[2480]: info: TidalConnect service stoped! Dec 08 16:20:37 volumiop3 volumio[2480]: info: TidalConnect service stoped! Dec 08 16:20:37 volumiop3 volumio[2480]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 08 16:20:37 volumiop3 volumio[2480]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 08 16:20:37 volumiop3 qobuz-connect[4853]: 20251208 16:20:37.336 [4853.4853] INFO SampleApp: shat down connection on UNIX socket Dec 08 16:20:37 volumiop3 volumio[2480]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 08 16:20:37 volumiop3 volumio[2480]: info: QobuzConnect: setDeactiveState invoked Dec 08 16:20:37 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:37 volumiop3 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 08 16:20:37 volumiop3 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 08 16:20:37 volumiop3 sudo[4950]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 08 16:20:37 volumiop3 sudo[4950]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:37 volumiop3 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 08 16:20:37 volumiop3 sudo[4896]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:37 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 08 16:20:37 volumiop3 volumio[2480]: info: Received Get System Info Dec 08 16:20:37 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 16:20:37 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 16:20:37 volumiop3 volumio[2480]: info: Discovery: Getting this device information Dec 08 16:20:37 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:37 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 16:20:37 volumiop3 volumio[2480]: info: Executing endpoint qc_getconfig Dec 08 16:20:37 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 08 16:20:37 volumiop3 qobuz-connect[4951]: 20251208 16:20:37.650 [4951.4951] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 08 16:20:37 volumiop3 volumio[2480]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 08 16:20:37 volumiop3 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 08 16:20:37 volumiop3 volumio[2480]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 08 16:20:37 volumiop3 qobuz-connect[4951]: 20251208 16:20:37.673 [4951.4951] INFO VolumeManager: [0x985258]: Setting new playback volume: 75 Dec 08 16:20:37 volumiop3 qobuz-connect[4951]: 20251208 16:20:37.673 [4951.4951] INFO VolumeManager: [0x985258]: Setting new mute state: 0 Dec 08 16:20:37 volumiop3 qobuz-connect[4951]: 20251208 16:20:37.673 [4951.4951] INFO QobuzConnect: [0x985c28]: Client initialized! Dec 08 16:20:37 volumiop3 qobuz-connect[4951]: 20251208 16:20:37.673 [4951.4951] INFO SampleApp: Starting Avahi advertising, name: VolumioP3, service name: _qobuz-connect._tcp Dec 08 16:20:37 volumiop3 sudo[4950]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:37 volumiop3 qobuz-connect[4951]: 20251208 16:20:37.748 [4951.4951] INFO LocalConfigManager: [0x984b38]: Starting Local Configuration server Dec 08 16:20:37 volumiop3 qobuz-connect[4951]: 20251208 16:20:37.748 [4951.4951] INFO SampleApp: Starting Local configuration server Dec 08 16:20:37 volumiop3 qobuz-connect[4951]: 20251208 16:20:37.749 [4951.4951] INFO SampleApp: Connected to UNIX socket client 0x96f818 Dec 08 16:20:37 volumiop3 qobuz-connect[4951]: 20251208 16:20:37.804 [4951.4951] INFO SampleApp: Playback volume changed: 75 Dec 08 16:20:37 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 08 16:20:38 volumiop3 volumio[2480]: info: Received Get System Info Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 16:20:38 volumiop3 volumio[2480]: info: Discovery: Getting this device information Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 16:20:38 volumiop3 volumio[2480]: info: Executing endpoint tc_getconfig Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 08 16:20:38 volumiop3 vtcs[4957]: STARTING TidalConnect services, version: 1.5.2.56 Dec 08 16:20:38 volumiop3 vtcs[4957]: STARTED TidalConnect services. Dec 08 16:20:38 volumiop3 volumio[2480]: info: Executing endpoint tc_connect Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 08 16:20:38 volumiop3 volumio[2480]: info: Connecting to TidalConnect Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::servicePushState Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreStateMachine::pushState Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioPushState Dec 08 16:20:38 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output update for this device Dec 08 16:20:38 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:38 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:20:38 volumiop3 volumio[2480]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 08 16:20:38 volumiop3 volumio[2480]: verbose: CURRENT POSITION 0 Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreStateMachine::syncState stateService stop Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreStateMachine::syncState currentStatus stop Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreStateMachine::pushState Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioPushState Dec 08 16:20:38 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output update for this device Dec 08 16:20:38 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:38 volumiop3 volumio[2480]: info: No code Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreStateMachine::pushState Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioPushState Dec 08 16:20:38 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output update for this device Dec 08 16:20:38 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::servicePushState Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreStateMachine::pushState Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioPushState Dec 08 16:20:38 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output update for this device Dec 08 16:20:38 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:38 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:20:38 volumiop3 volumio[2480]: verbose: STATE SERVICE {"status":"stop","service":"tidalconnect","title":"","artist":"","album":"","albumart":"/albumart","uri":"","icon":"fa fa-spinner","trackType":"tidal","codec":"","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"","channels":2,"random":false,"repeat":false,"repeatSingle":false} Dec 08 16:20:38 volumiop3 volumio[2480]: verbose: CURRENT POSITION 0 Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreStateMachine::syncState stateService stop Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreStateMachine::syncState currentStatus stop Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreStateMachine::pushState Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioPushState Dec 08 16:20:38 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output update for this device Dec 08 16:20:38 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:38 volumiop3 volumio[2480]: info: No code Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreStateMachine::pushState Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioPushState Dec 08 16:20:38 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output update for this device Dec 08 16:20:38 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output Dec 08 16:20:38 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:38 volumiop3 volumio[2480]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 08 16:20:38 volumiop3 volumio[2480]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 08 16:20:38 volumiop3 volumio[2480]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 08 16:20:38 volumiop3 volumio[2480]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 08 16:20:38 volumiop3 volumio[2480]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 08 16:20:38 volumiop3 volumio[2480]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 08 16:20:38 volumiop3 volumio[2480]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.badK6kPI9dbBqlGxk5yfUmq9ze22.125b4c9c158ea974d99a810736d969e7.state.status' Dec 08 16:20:38 volumiop3 volumio[2480]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.badK6kPI9dbBqlGxk5yfUmq9ze22.125b4c9c158ea974d99a810736d969e7.state.status' Dec 08 16:20:38 volumiop3 volumio[2480]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.badK6kPI9dbBqlGxk5yfUmq9ze22.125b4c9c158ea974d99a810736d969e7.state.status' Dec 08 16:20:38 volumiop3 volumio[2480]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.badK6kPI9dbBqlGxk5yfUmq9ze22.125b4c9c158ea974d99a810736d969e7.state.status' Dec 08 16:20:38 volumiop3 volumio[2480]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.badK6kPI9dbBqlGxk5yfUmq9ze22.125b4c9c158ea974d99a810736d969e7.state.status' Dec 08 16:20:38 volumiop3 volumio[2480]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.badK6kPI9dbBqlGxk5yfUmq9ze22.125b4c9c158ea974d99a810736d969e7.state.status' Dec 08 16:20:38 volumiop3 volumio[2480]: info: TidalConnect service stoped! Dec 08 16:20:38 volumiop3 volumio[2480]: info: TidalConnect service stoped! Dec 08 16:20:38 volumiop3 volumio[2480]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 08 16:20:38 volumiop3 volumio[2480]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 08 16:20:39 volumiop3 sudo[4984]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 08 16:20:39 volumiop3 sudo[4984]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:39 volumiop3 sudo[4984]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:40 volumiop3 volumio[2480]: info: TidalConnect service started! Dec 08 16:20:40 volumiop3 volumio[2480]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/radio_paradise/volumio/bookworm/armhf Dec 08 16:20:42 volumiop3 volumio[2480]: info: TidalConnect service started! Dec 08 16:20:42 volumiop3 mpd[4911]: 2025-12-08T16:20:42 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 08 16:20:42 volumiop3 systemd[1]: Started mpd.service - Music Player Daemon. Dec 08 16:20:42 volumiop3 sudo[4846]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:42 volumiop3 sudo[4782]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:42 volumiop3 volumio[2480]: error: MPD error: The expression evaluated to a falsy value: Dec 08 16:20:42 volumiop3 volumio[2480]: assert.ok(self.idling) Dec 08 16:20:42 volumiop3 volumio[2480]: error: The expression evaluated to a falsy value: Dec 08 16:20:42 volumiop3 volumio[2480]: assert.ok(self.idling) Dec 08 16:20:42 volumiop3 volumio[2480]: error: updateQueue error: null Dec 08 16:20:43 volumiop3 volumio[2480]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatestStable/radio_paradise/volumio/bookworm/armhf Dec 08 16:20:43 volumiop3 volumio[2480]: info: Folder /tmp/plugins removed Dec 08 16:20:43 volumiop3 systemd[1]: setdatetime-helper.service: Deactivated successfully. Dec 08 16:20:43 volumiop3 systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Dec 08 16:20:43 volumiop3 systemd[1]: setdatetime-helper.service: Consumed 2.427s CPU time. Dec 08 16:20:43 volumiop3 volumio[2480]: info: Check plugin dependencies Dec 08 16:20:43 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 08 16:20:43 volumiop3 volumio[2480]: info: Checking if plugin already exists Dec 08 16:20:43 volumiop3 volumio[2480]: info: Rename folder Dec 08 16:20:43 volumiop3 volumio[2480]: info: Folder /tmp/downloaded_plugin.zip removed Dec 08 16:20:43 volumiop3 volumio[2480]: info: Move to category Dec 08 16:20:44 volumiop3 volumio[2480]: info: Checking if install.sh is present Dec 08 16:20:44 volumiop3 volumio[2480]: info: Executing install.sh Dec 08 16:20:44 volumiop3 sudo[5017]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/radio_paradise/install.sh Dec 08 16:20:44 volumiop3 sudo[5017]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:44 volumiop3 sudo[5017]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:44 volumiop3 volumio[2480]: info: Installing radio paradise Dependencies Dec 08 16:20:44 volumiop3 volumio[2480]: Plugin install end detected on script Dec 08 16:20:44 volumiop3 volumio[2480]: info: Install script completed Dec 08 16:20:44 volumiop3 volumio[2480]: info: Adding reference to registry Dec 08 16:20:44 volumiop3 volumio[2480]: info: Done installing plugin. Dec 08 16:20:44 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 08 16:20:44 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 08 16:20:44 volumiop3 volumio[2480]: info: Folder /tmp/plugins removed Dec 08 16:20:44 volumiop3 volumio[2480]: info: Folder /tmp/downloaded_plugin.zip removed Dec 08 16:20:44 volumiop3 volumio[2480]: info: Folder /data/temp removed Dec 08 16:20:45 volumiop3 sudo[5020]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 08 16:20:45 volumiop3 sudo[5020]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:45 volumiop3 sudo[5023]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 08 16:20:45 volumiop3 sudo[5023]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:45 volumiop3 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 08 16:20:45 volumiop3 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 08 16:20:45 volumiop3 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 08 16:20:45 volumiop3 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 08 16:20:45 volumiop3 sudo[5020]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:45 volumiop3 volumio[2480]: info: Successfully started MPD Monitor Dec 08 16:20:45 volumiop3 mpd_monitor.sh[5025]: MPD Monitor Service: Starting MPD Monitor Service Dec 08 16:20:45 volumiop3 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 08 16:20:45 volumiop3 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 08 16:20:45 volumiop3 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 08 16:20:45 volumiop3 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 08 16:20:45 volumiop3 sudo[5023]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:45 volumiop3 mpd_monitor.sh[5030]: MPD Monitor Service: Starting MPD Monitor Service Dec 08 16:20:45 volumiop3 volumio[2480]: info: Successfully started MPD Monitor Dec 08 16:20:46 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 08 16:20:46 volumiop3 volumio[2480]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Dec 08 16:20:46 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Dec 08 16:20:46 volumiop3 volumio[2480]: info: Received Get System Version Dec 08 16:20:46 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 08 16:20:46 volumiop3 volumio[2480]: info: Received Get System Info Dec 08 16:20:46 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 08 16:20:46 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 08 16:20:46 volumiop3 volumio[2480]: info: Discovery: Getting this device information Dec 08 16:20:46 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:46 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 08 16:20:46 volumiop3 volumio[2480]: info: Enabling plugin radio_paradise Dec 08 16:20:46 volumiop3 volumio[2480]: info: Loading plugin "radio_paradise"... Dec 08 16:20:47 volumiop3 volumio[2480]: info: [1765207247396] [RadioParadise] API delay: 5 Dec 08 16:20:47 volumiop3 volumio[2480]: info: PLUGIN START: radio_paradise Dec 08 16:20:47 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 08 16:20:47 volumiop3 volumio[2480]: info: [1765207247409] CoreMusicLibrary::Adding element Radio Paradise Dec 08 16:20:47 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 08 16:20:47 volumiop3 volumio[2480]: Cannot find translation for source Radio Paradise Dec 08 16:20:47 volumiop3 volumio[2480]: info: Done. Dec 08 16:20:56 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:57 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetQueue Dec 08 16:20:57 volumiop3 volumio[2480]: info: CoreStateMachine::getQueue Dec 08 16:20:57 volumiop3 volumio[2480]: info: CorePlayQueue::getQueue Dec 08 16:20:57 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Dec 08 16:20:57 volumiop3 volumio[2480]: info: Preload queue cleared Dec 08 16:20:58 volumiop3 volumio[2480]: info: Executing endpoint metavolumio Dec 08 16:20:58 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 08 16:20:59 volumiop3 volumio[2480]: info: Preload queue cleared Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::ClearQueue Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::stop Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::serviceStop Dec 08 16:20:59 volumiop3 volumio[2480]: info: Received STOP, but no service to execute it Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::clearPlayQueue Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::saveQueue Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioPushQueue Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::addQueueItems Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::addQueueItems Dec 08 16:20:59 volumiop3 volumio[2480]: info: Preload queue cleared Dec 08 16:20:59 volumiop3 volumio[2480]: info: Adding Item to queue: webrp/0 Dec 08 16:20:59 volumiop3 volumio[2480]: info: Exploding uri webrp/0 in service radio_paradise Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioPushQueue Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::saveQueue Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::updateTrackBlock Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::getTrackBlock Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioPlay Dec 08 16:20:59 volumiop3 volumio[2480]: verbose: UNSET VOLATILE: Service: undefined Dec 08 16:20:59 volumiop3 volumio[2480]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Dec 08 16:20:59 volumiop3 volumio[2480]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Dec 08 16:20:59 volumiop3 volumio[2480]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::play index 0 Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 08 16:20:59 volumiop3 volumio[2480]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::stop Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::resetVolumioState Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::getcurrentVolume Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioRetrievevolume Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::play index undefined Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioStop Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::stop Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::startPlaybackTimer Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetVisibleSources Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 08 16:20:59 volumiop3 volumio[2480]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0 Dec 08 16:20:59 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand stop Dec 08 16:20:59 volumiop3 volumio[2480]: info: sendMpdCommand stop took 19 milliseconds Dec 08 16:20:59 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand clear Dec 08 16:20:59 volumiop3 volumio[2480]: info: Dec 08 16:20:59 volumiop3 volumio[2480]: ---------------------------- MPD announces system playlist update Dec 08 16:20:59 volumiop3 volumio[2480]: info: Ignoring MPD Status Update Dec 08 16:20:59 volumiop3 volumio[2480]: info: sendMpdCommand clear took 8 milliseconds Dec 08 16:20:59 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 08 16:20:59 volumiop3 volumio[2480]: info: Dec 08 16:20:59 volumiop3 volumio[2480]: ---------------------------- MPD announces system playlist update Dec 08 16:20:59 volumiop3 volumio[2480]: info: Ignoring MPD Status Update Dec 08 16:20:59 volumiop3 volumio[2480]: error: updateQueue error: null Dec 08 16:20:59 volumiop3 volumio[2480]: info: Dec 08 16:20:59 volumiop3 volumio[2480]: ---------------------------- MPD announces state update: options Dec 08 16:20:59 volumiop3 volumio[2480]: error: updateQueue error: null Dec 08 16:20:59 volumiop3 volumio[2480]: info: ------------------------------ 41ms Dec 08 16:20:59 volumiop3 volumio[2480]: info: sendMpdCommand consume 1 took 34 milliseconds Dec 08 16:20:59 volumiop3 volumio[2480]: info: ControllerMpd::getState Dec 08 16:20:59 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand status Dec 08 16:20:59 volumiop3 volumio[2480]: info: ------------------------------ 36ms Dec 08 16:20:59 volumiop3 volumio[2480]: info: [1765207259334] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flacm Dec 08 16:20:59 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flacm" Dec 08 16:20:59 volumiop3 volumio[2480]: info: Dec 08 16:20:59 volumiop3 volumio[2480]: ---------------------------- MPD announces state update: options Dec 08 16:20:59 volumiop3 volumio[2480]: info: ControllerMpd::getState Dec 08 16:20:59 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand status Dec 08 16:20:59 volumiop3 volumio[2480]: info: Dec 08 16:20:59 volumiop3 volumio[2480]: ---------------------------- MPD announces system playlist update Dec 08 16:20:59 volumiop3 volumio[2480]: info: Ignoring MPD Status Update Dec 08 16:20:59 volumiop3 volumio[2480]: info: sendMpdCommand status took 18 milliseconds Dec 08 16:20:59 volumiop3 volumio[2480]: info: sendMpdCommand add "https://stream.radioparadise.com/flacm" took 16 milliseconds Dec 08 16:20:59 volumiop3 volumio[2480]: info: sendMpdCommand status took 11 milliseconds Dec 08 16:20:59 volumiop3 volumio[2480]: verbose: ControllerMpd::parseState Dec 08 16:20:59 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand play Dec 08 16:20:59 volumiop3 volumio[2480]: verbose: ControllerMpd::parseState Dec 08 16:20:59 volumiop3 volumio[2480]: info: ControllerMpd::pushState Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::servicePushState Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::pushState Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioPushState Dec 08 16:20:59 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output update for this device Dec 08 16:20:59 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:20:59 volumiop3 volumio[2480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 08 16:20:59 volumiop3 volumio[2480]: info: ControllerMpd::pushState Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::servicePushState Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::pushState Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioPushState Dec 08 16:20:59 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output update for this device Dec 08 16:20:59 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:20:59 volumiop3 volumio[2480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 08 16:20:59 volumiop3 volumio[2480]: info: ------------------------------ 158ms Dec 08 16:20:59 volumiop3 volumio[2480]: info: ------------------------------ 194ms Dec 08 16:20:59 volumiop3 volumio[2480]: info: Dec 08 16:20:59 volumiop3 volumio[2480]: ---------------------------- MPD announces system playlist update Dec 08 16:20:59 volumiop3 volumio[2480]: info: Ignoring MPD Status Update Dec 08 16:20:59 volumiop3 volumio[2480]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 08 16:20:59 volumiop3 volumio[2480]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 08 16:20:59 volumiop3 volumio[2480]: info: ------------------------------ 255ms Dec 08 16:20:59 volumiop3 volumio[2480]: info: sendMpdCommand play took 226 milliseconds Dec 08 16:20:59 volumiop3 volumio[2480]: info: ------------------------------ 68ms Dec 08 16:20:59 volumiop3 volumio[2480]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=0 Dec 08 16:20:59 volumiop3 volumio[2480]: info: [1765207259606] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Dec 08 16:20:59 volumiop3 sudo[5070]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 08 16:20:59 volumiop3 sudo[5070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:59 volumiop3 sudo[5072]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 08 16:20:59 volumiop3 sudo[5072]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:20:59 volumiop3 volumio[2480]: info: VolumeController:: Volume=100 Mute =false Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::pushState Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioPushState Dec 08 16:20:59 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output update for this device Dec 08 16:20:59 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreStateMachine::updateTrackBlock Dec 08 16:20:59 volumiop3 volumio[2480]: info: CorePlayQueue::getTrackBlock Dec 08 16:20:59 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioRetrievevolume Dec 08 16:20:59 volumiop3 sudo[5072]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:59 volumiop3 sudo[5070]: pam_unix(sudo:session): session closed for user root Dec 08 16:20:59 volumiop3 volumio[2480]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 08 16:21:00 volumiop3 sudo[5077]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 08 16:21:00 volumiop3 sudo[5077]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:21:00 volumiop3 volumio[2480]: info: peppymeterbasic Daemon Stop Dec 08 16:21:00 volumiop3 volumio[2480]: info: peppymeterbasic Daemon Stop Dec 08 16:21:00 volumiop3 volumio[2480]: info: VolumeController:: Volume=100 Mute =false Dec 08 16:21:00 volumiop3 volumio[2480]: info: CoreStateMachine::pushState Dec 08 16:21:00 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:21:00 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 08 16:21:00 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioPushState Dec 08 16:21:00 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output update for this device Dec 08 16:21:00 volumiop3 volumio[2480]: info: MRS: Pushing multiroomSync output Dec 08 16:21:00 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:21:00 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:21:00 volumiop3 sudo[5077]: pam_unix(sudo:session): session closed for user root Dec 08 16:21:00 volumiop3 volumio[2480]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 08 16:21:00 volumiop3 sudo[5081]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 08 16:21:00 volumiop3 volumio[2480]: info: peppymeterbasic Daemon Stop Dec 08 16:21:00 volumiop3 sudo[5081]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 08 16:21:00 volumiop3 sudo[5081]: pam_unix(sudo:session): session closed for user root Dec 08 16:21:00 volumiop3 volumio[2480]: info: peppymeterbasic Daemon Stop Dec 08 16:21:00 volumiop3 volumio[2480]: info: [1765207260637] [RadioParadise] received new metadata: {"time":126,"artist":"Red Hot Chili Peppers","title":"Otherside","album":"Californication","year":"1999","cover":"https://img.radioparadise.com/covers/l/B00000J7JO.jpg","cover_med":"https://img.radioparadise.com/covers/m/B00000J7JO.jpg","cover_small":"https://img.radioparadise.com/covers/s/B00000J7JO.jpg"} Dec 08 16:21:00 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:21:00 volumiop3 volumio[2480]: info: CoreCommandRouter::servicePushState Dec 08 16:21:00 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:21:00 volumiop3 volumio[2480]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/B00000J7JO.jpg","uri":"https://stream.radioparadise.com/flacm","name":"Otherside","title":"Otherside","artist":"Red Hot Chili Peppers","album":"Californication","streaming":true,"disableUiControls":true,"duration":131,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 08 16:21:00 volumiop3 volumio[2480]: verbose: CURRENT POSITION 0 Dec 08 16:21:00 volumiop3 volumio[2480]: info: CoreStateMachine::syncState stateService play Dec 08 16:21:00 volumiop3 volumio[2480]: info: CoreStateMachine::syncState currentStatus stop Dec 08 16:21:00 volumiop3 volumio[2480]: info: [1765207260650] [RadioParadise] setting new timer with duration of 131000 seconds. Dec 08 16:21:00 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:21:00 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 1 Dec 08 16:21:01 volumiop3 volumio[2480]: info: Dec 08 16:21:01 volumiop3 volumio[2480]: ---------------------------- MPD announces state update: player Dec 08 16:21:01 volumiop3 volumio[2480]: info: ControllerMpd::getState Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand status Dec 08 16:21:01 volumiop3 volumio[2480]: info: Dec 08 16:21:01 volumiop3 volumio[2480]: ---------------------------- MPD announces state update: player Dec 08 16:21:01 volumiop3 volumio[2480]: info: ControllerMpd::getState Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand status Dec 08 16:21:01 volumiop3 volumio[2480]: info: sendMpdCommand status took 230 milliseconds Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::parseState Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 08 16:21:01 volumiop3 volumio[2480]: info: Dec 08 16:21:01 volumiop3 volumio[2480]: ---------------------------- MPD announces state update: player Dec 08 16:21:01 volumiop3 volumio[2480]: info: ControllerMpd::getState Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand status Dec 08 16:21:01 volumiop3 volumio[2480]: info: sendMpdCommand status took 237 milliseconds Dec 08 16:21:01 volumiop3 volumio[2480]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::parseState Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::parseTrackInfo Dec 08 16:21:01 volumiop3 volumio[2480]: info: ControllerMpd::pushState Dec 08 16:21:01 volumiop3 volumio[2480]: info: CoreCommandRouter::servicePushState Dec 08 16:21:01 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:21:01 volumiop3 volumio[2480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 08 16:21:01 volumiop3 volumio[2480]: info: ------------------------------ 254ms Dec 08 16:21:01 volumiop3 volumio[2480]: info: Dec 08 16:21:01 volumiop3 volumio[2480]: ---------------------------- MPD announces state update: player Dec 08 16:21:01 volumiop3 volumio[2480]: info: ControllerMpd::getState Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand status Dec 08 16:21:01 volumiop3 volumio[2480]: info: sendMpdCommand status took 15 milliseconds Dec 08 16:21:01 volumiop3 volumio[2480]: info: sendMpdCommand playlistinfo took 12 milliseconds Dec 08 16:21:01 volumiop3 volumio[2480]: info: sendMpdCommand status took 3 milliseconds Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::parseState Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::parseTrackInfo Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::parseState Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 08 16:21:01 volumiop3 volumio[2480]: info: ControllerMpd::pushState Dec 08 16:21:01 volumiop3 volumio[2480]: info: CoreCommandRouter::servicePushState Dec 08 16:21:01 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:21:01 volumiop3 volumio[2480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 08 16:21:01 volumiop3 volumio[2480]: info: ------------------------------ 261ms Dec 08 16:21:01 volumiop3 volumio[2480]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 08 16:21:01 volumiop3 volumio[2480]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::parseTrackInfo Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::parseTrackInfo Dec 08 16:21:01 volumiop3 volumio[2480]: info: ControllerMpd::pushState Dec 08 16:21:01 volumiop3 volumio[2480]: info: CoreCommandRouter::servicePushState Dec 08 16:21:01 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:21:01 volumiop3 volumio[2480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 08 16:21:01 volumiop3 volumio[2480]: info: ControllerMpd::pushState Dec 08 16:21:01 volumiop3 volumio[2480]: info: CoreCommandRouter::servicePushState Dec 08 16:21:01 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:21:01 volumiop3 volumio[2480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 08 16:21:01 volumiop3 volumio[2480]: info: ------------------------------ 34ms Dec 08 16:21:01 volumiop3 volumio[2480]: info: ------------------------------ 19ms Dec 08 16:21:01 volumiop3 volumio[2480]: info: Dec 08 16:21:01 volumiop3 volumio[2480]: ---------------------------- MPD announces system playlist update Dec 08 16:21:01 volumiop3 volumio[2480]: info: Ignoring MPD Status Update Dec 08 16:21:01 volumiop3 volumio[2480]: info: Dec 08 16:21:01 volumiop3 volumio[2480]: ---------------------------- MPD announces state update: player Dec 08 16:21:01 volumiop3 volumio[2480]: info: ControllerMpd::getState Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand status Dec 08 16:21:01 volumiop3 volumio[2480]: info: Dec 08 16:21:01 volumiop3 volumio[2480]: ---------------------------- MPD announces system playlist update Dec 08 16:21:01 volumiop3 volumio[2480]: info: Ignoring MPD Status Update Dec 08 16:21:01 volumiop3 volumio[2480]: info: Dec 08 16:21:01 volumiop3 volumio[2480]: ---------------------------- MPD announces state update: player Dec 08 16:21:01 volumiop3 volumio[2480]: info: ControllerMpd::getState Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand status Dec 08 16:21:01 volumiop3 volumio[2480]: info: ------------------------------ 8ms Dec 08 16:21:01 volumiop3 volumio[2480]: info: sendMpdCommand status took 5 milliseconds Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::parseState Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 08 16:21:01 volumiop3 volumio[2480]: info: ------------------------------ 8ms Dec 08 16:21:01 volumiop3 volumio[2480]: info: sendMpdCommand status took 6 milliseconds Dec 08 16:21:01 volumiop3 volumio[2480]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::parseState Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::parseTrackInfo Dec 08 16:21:01 volumiop3 volumio[2480]: info: ControllerMpd::pushState Dec 08 16:21:01 volumiop3 volumio[2480]: info: CoreCommandRouter::servicePushState Dec 08 16:21:01 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:21:01 volumiop3 volumio[2480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 08 16:21:01 volumiop3 volumio[2480]: info: ------------------------------ 17ms Dec 08 16:21:01 volumiop3 volumio[2480]: info: sendMpdCommand playlistinfo took 4 milliseconds Dec 08 16:21:01 volumiop3 volumio[2480]: verbose: ControllerMpd::parseTrackInfo Dec 08 16:21:01 volumiop3 volumio[2480]: info: ControllerMpd::pushState Dec 08 16:21:01 volumiop3 volumio[2480]: info: CoreCommandRouter::servicePushState Dec 08 16:21:01 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:21:01 volumiop3 volumio[2480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 08 16:21:01 volumiop3 volumio[2480]: info: ------------------------------ 16ms Dec 08 16:21:05 volumiop3 volumio[2480]: info: Dec 08 16:21:05 volumiop3 volumio[2480]: ---------------------------- MPD announces system playlist update Dec 08 16:21:05 volumiop3 volumio[2480]: info: Ignoring MPD Status Update Dec 08 16:21:05 volumiop3 volumio[2480]: info: Dec 08 16:21:05 volumiop3 volumio[2480]: ---------------------------- MPD announces state update: player Dec 08 16:21:05 volumiop3 volumio[2480]: info: ControllerMpd::getState Dec 08 16:21:05 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand status Dec 08 16:21:05 volumiop3 volumio[2480]: info: Dec 08 16:21:05 volumiop3 volumio[2480]: ---------------------------- MPD announces system playlist update Dec 08 16:21:05 volumiop3 volumio[2480]: info: Ignoring MPD Status Update Dec 08 16:21:05 volumiop3 volumio[2480]: info: Dec 08 16:21:05 volumiop3 volumio[2480]: ---------------------------- MPD announces state update: player Dec 08 16:21:05 volumiop3 volumio[2480]: info: ControllerMpd::getState Dec 08 16:21:05 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand status Dec 08 16:21:05 volumiop3 volumio[2480]: info: ------------------------------ 11ms Dec 08 16:21:05 volumiop3 volumio[2480]: info: sendMpdCommand status took 8 milliseconds Dec 08 16:21:05 volumiop3 volumio[2480]: verbose: ControllerMpd::parseState Dec 08 16:21:05 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 08 16:21:05 volumiop3 volumio[2480]: info: ------------------------------ 12ms Dec 08 16:21:05 volumiop3 volumio[2480]: info: sendMpdCommand status took 10 milliseconds Dec 08 16:21:05 volumiop3 volumio[2480]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 08 16:21:05 volumiop3 volumio[2480]: verbose: ControllerMpd::parseState Dec 08 16:21:05 volumiop3 volumio[2480]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 08 16:21:05 volumiop3 volumio[2480]: verbose: ControllerMpd::parseTrackInfo Dec 08 16:21:05 volumiop3 volumio[2480]: info: ControllerMpd::pushState Dec 08 16:21:05 volumiop3 volumio[2480]: info: CoreCommandRouter::servicePushState Dec 08 16:21:05 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:21:05 volumiop3 volumio[2480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 08 16:21:05 volumiop3 volumio[2480]: info: ------------------------------ 19ms Dec 08 16:21:05 volumiop3 volumio[2480]: info: sendMpdCommand playlistinfo took 5 milliseconds Dec 08 16:21:05 volumiop3 volumio[2480]: verbose: ControllerMpd::parseTrackInfo Dec 08 16:21:05 volumiop3 volumio[2480]: info: ControllerMpd::pushState Dec 08 16:21:05 volumiop3 volumio[2480]: info: CoreCommandRouter::servicePushState Dec 08 16:21:05 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:21:05 volumiop3 volumio[2480]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 08 16:21:05 volumiop3 volumio[2480]: info: ------------------------------ 22ms Dec 08 16:21:06 volumiop3 volumio[2480]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 16:21:06 volumiop3 volumio[2480]: info: CoreCommandRouter::Close All Modals sent Dec 08 16:21:09 volumiop3 volumio[2480]: info: MRS: Found cast device: Google-Nest-Hub-3335d5713a5ba681c1d5540259550c63 Dec 08 16:21:09 volumiop3 volumio[2480]: info: MRS: Found cast device: Google-Nest-Hub-41e246e6e0ebfbd48e861ce964a5678a Dec 08 16:21:09 volumiop3 volumio[2480]: info: MRS: Found cast device: Google-Home-4d14245a36ab51ab1d708eef0f2570cb Dec 08 16:21:09 volumiop3 volumio[2480]: info: MRS: Found cast device: Google-Home-71e141483b292d7057c226b70510ad6b Dec 08 16:21:09 volumiop3 volumio[2480]: info: MRS: Found cast device: Google-Cast-Group-55CB098B0B764B22B7D108BA1B44446A-1 Dec 08 16:21:09 volumiop3 volumio[2480]: info: MRS: Found cast device: TIMVISION-VSB-e680b76f52f1a499a0a7238d45e5b25f Dec 08 16:21:09 volumiop3 volumio[2480]: info: CoreCommandRouter::volumioGetState Dec 08 16:21:09 volumiop3 volumio[2480]: info: CorePlayQueue::getTrack 0 Dec 08 16:21:11 volumiop3 volumio[2480]: info: Executing endpoint metavolumio Dec 08 16:21:11 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 08 16:21:11 volumiop3 volumio[2480]: info: Executing endpoint metavolumio Dec 08 16:21:11 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 08 16:21:11 volumiop3 volumio[2480]: info: Executing endpoint metavolumio Dec 08 16:21:11 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 08 16:21:14 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 08 16:21:14 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 08 16:21:18 volumiop3 volumio[2480]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 16:21:26 volumiop3 volumio[2480]: info: CALLMETHOD: user_interface peppymeterbasic savepeppy [object Object] Dec 08 16:21:26 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , savepeppy Dec 08 16:21:26 volumiop3 volumio[2480]: aaaaaaaaaaa 800,480 Dec 08 16:21:26 volumiop3 volumio[2480]: info: PeppyMeterBasic ---800480 Dec 08 16:21:26 volumiop3 volumio[2480]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 08 16:21:26 volumiop3 volumio[2480]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 16:21:26 volumiop3 volumio[2480]: info: CoreCommandRouter::Close All Modals sent Dec 08 16:21:33 volumiop3 ntpd[829]: PROTO: 93.94.88.51 unlink local addr 192.168.88.95 -> Dec 08 16:21:33 volumiop3 volumio[2480]: info: CALLMETHOD: user_interface peppymeterbasic updatelist Dec 08 16:21:33 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , updatelist Dec 08 16:21:33 volumiop3 volumio[2480]: info: PeppyMeterBasic --- wget 'https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt' Dec 08 16:21:34 volumiop3 volumio[2480]: --2025-12-08 16:21:33-- https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt Dec 08 16:21:34 volumiop3 volumio[2480]: Resolving github.com (github.com)... 140.82.121.3 Dec 08 16:21:34 volumiop3 volumio[2480]: Connecting to github.com (github.com)|140.82.121.3|:443... connected. Dec 08 16:21:34 volumiop3 volumio[2480]: HTTP request sent, awaiting response... 302 Found Dec 08 16:21:34 volumiop3 volumio[2480]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt [following] Dec 08 16:21:34 volumiop3 volumio[2480]: --2025-12-08 16:21:34-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt Dec 08 16:21:34 volumiop3 volumio[2480]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.108.133, 185.199.111.133, 185.199.110.133, ... Dec 08 16:21:34 volumiop3 volumio[2480]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.108.133|:443... connected. Dec 08 16:21:34 volumiop3 volumio[2480]: HTTP request sent, awaiting response... 200 OK Dec 08 16:21:34 volumiop3 volumio[2480]: Length: 362 [text/plain] Dec 08 16:21:34 volumiop3 volumio[2480]: Saving to: ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ Dec 08 16:21:34 volumiop3 volumio[2480]: 0K 100% 1.37M=0s Dec 08 16:21:34 volumiop3 volumio[2480]: 2025-12-08 16:21:34 (1.37 MB/s) - ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ saved [362/362] Dec 08 16:21:35 volumiop3 volumio[2480]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 16:21:35 volumiop3 volumio[2480]: info: CoreCommandRouter::Close All Modals sent Dec 08 16:21:41 volumiop3 volumio[2480]: info: CALLMETHOD: user_interface peppymeterbasic updatelist Dec 08 16:21:41 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , updatelist Dec 08 16:21:41 volumiop3 volumio[2480]: info: PeppyMeterBasic --- wget 'https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt' Dec 08 16:21:42 volumiop3 volumio[2480]: --2025-12-08 16:21:41-- https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt Dec 08 16:21:42 volumiop3 volumio[2480]: Resolving github.com (github.com)... 140.82.121.4 Dec 08 16:21:42 volumiop3 volumio[2480]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Dec 08 16:21:42 volumiop3 volumio[2480]: HTTP request sent, awaiting response... 302 Found Dec 08 16:21:42 volumiop3 volumio[2480]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt [following] Dec 08 16:21:42 volumiop3 volumio[2480]: --2025-12-08 16:21:42-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt Dec 08 16:21:42 volumiop3 volumio[2480]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.111.133, 185.199.110.133, 185.199.109.133, ... Dec 08 16:21:42 volumiop3 volumio[2480]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.111.133|:443... connected. Dec 08 16:21:42 volumiop3 volumio[2480]: HTTP request sent, awaiting response... 200 OK Dec 08 16:21:42 volumiop3 volumio[2480]: Length: 362 [text/plain] Dec 08 16:21:42 volumiop3 volumio[2480]: Saving to: ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ Dec 08 16:21:42 volumiop3 volumio[2480]: 0K 100% 1.86M=0s Dec 08 16:21:42 volumiop3 volumio[2480]: 2025-12-08 16:21:42 (1.86 MB/s) - ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ saved [362/362] Dec 08 16:21:42 volumiop3 volumio[2480]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 08 16:21:42 volumiop3 volumio[2480]: info: CoreCommandRouter::Close All Modals sent Dec 08 16:21:46 volumiop3 volumio[2480]: info: CALLMETHOD: user_interface peppymeterbasic dlmeter [object Object] Dec 08 16:21:46 volumiop3 volumio[2480]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , dlmeter Dec 08 16:21:48 volumiop3 volumio[2480]: --2025-12-08 16:21:46-- https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/Choose Dec 08 16:21:48 volumiop3 volumio[2480]: Resolving github.com (github.com)... 140.82.121.4 Dec 08 16:21:48 volumiop3 volumio[2480]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Dec 08 16:21:48 volumiop3 volumio[2480]: HTTP request sent, awaiting response... 404 Not Found Dec 08 16:21:48 volumiop3 volumio[2480]: 2025-12-08 16:21:47 ERROR 404: Not Found. Dec 08 16:21:48 volumiop3 volumio[2480]: --2025-12-08 16:21:47-- http://a/ Dec 08 16:21:48 volumiop3 volumio[2480]: Resolving a (a)... failed: Device or resource busy. Dec 08 16:21:48 volumiop3 volumio[2480]: wget: unable to resolve host address ‘a’ Dec 08 16:21:48 volumiop3 volumio[2480]: --2025-12-08 16:21:47-- http://packet.zip/ Dec 08 16:21:48 volumiop3 volumio[2480]: Resolving packet.zip (packet.zip)... 162.255.119.167 Dec 08 16:21:48 volumiop3 volumio[2480]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Dec 08 16:21:48 volumiop3 volumio[2480]: HTTP request sent, awaiting response... 302 Found Dec 08 16:21:48 volumiop3 volumio[2480]: Location: http://www.packet.zip/ [following] Dec 08 16:21:48 volumiop3 volumio[2480]: --2025-12-08 16:21:48-- http://www.packet.zip/ Dec 08 16:21:48 volumiop3 volumio[2480]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Dec 08 16:21:48 volumiop3 volumio[2480]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Dec 08 16:21:48 volumiop3 volumio[2480]: HTTP request sent, awaiting response... 403 Forbidden Dec 08 16:21:48 volumiop3 volumio[2480]: 2025-12-08 16:21:48 ERROR 403: Forbidden. Dec 08 16:21:48 volumiop3 volumio[2480]: error: PeppyMeterBasic --- An error occurs while downloading or installing Meters Dec 08 16:21:48 volumiop3 volumio[2480]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 08 16:21:48 volumiop3 volumio[2480]: /bin/rm: cannot remove 'a': No such file or directory Dec 08 16:21:48 volumiop3 volumio[2480]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 08 16:21:48 volumiop3 volumio[2480]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 16:21:48 volumiop3 volumio[2480]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Dec 08 16:21:48 volumiop3 volumio[2480]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Dec 08 16:21:48 volumiop3 volumio[2480]: /bin/rm: cannot remove 'a': No such file or directory Dec 08 16:21:48 volumiop3 volumio[2480]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Dec 08 16:21:48 volumiop3 volumio[2480]: at checkExecSyncError (node:child_process:887:11) Dec 08 16:21:48 volumiop3 volumio[2480]: at execSync (node:child_process:959:15) Dec 08 16:21:48 volumiop3 volumio[2480]: at /data/plugins/user_interface/peppymeterbasic/index.js:710:19 Dec 08 16:21:48 volumiop3 volumio[2480]: at new Promise () Dec 08 16:21:48 volumiop3 volumio[2480]: at peppymeterbasic.dlmeter (/data/plugins/user_interface/peppymeterbasic/index.js:685:12) Dec 08 16:21:48 volumiop3 volumio[2480]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Dec 08 16:21:48 volumiop3 volumio[2480]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Dec 08 16:21:48 volumiop3 volumio[2480]: at Socket.emit (node:events:514:28) Dec 08 16:21:48 volumiop3 volumio[2480]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Dec 08 16:21:48 volumiop3 volumio[2480]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Dec 08 16:21:48 volumiop3 volumio[2480]: status: 1, Dec 08 16:21:48 volumiop3 volumio[2480]: signal: null, Dec 08 16:21:48 volumiop3 volumio[2480]: output: [ Dec 08 16:21:48 volumiop3 volumio[2480]: null, Dec 08 16:21:48 volumiop3 volumio[2480]: , Dec 08 16:21:48 volumiop3 volumio[2480]: Dec 08 16:21:48 volumiop3 volumio[2480]: ], Dec 08 16:21:48 volumiop3 volumio[2480]: pid: 5190, Dec 08 16:21:48 volumiop3 volumio[2480]: stdout: , Dec 08 16:21:48 volumiop3 volumio[2480]: stderr: Dec 08 16:21:48 volumiop3 volumio[2480]: } Dec 08 16:21:48 volumiop3 volumio[2480]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 08 16:21:50 volumiop3 sudo[5207]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-08 16:20' Dec 08 16:21:50 volumiop3 sudo[5207]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"