Aug 29 18:03:00 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:03:00 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:03:00 volumio4013 volumio[1518]: info: Discovery: Getting this device information Aug 29 18:03:00 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:00 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:03:01 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:01 volumio4013 volumio[1518]: verbose: New Socket.io Connection to 192.168.1.120:3000 from 192.168.1.139 UA: Dart/3.5 (dart:io) Engine version: 3 Transport: websocket Total Clients: 9 Aug 29 18:03:01 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:01 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: wizard , getShowWizard Aug 29 18:03:01 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getShowWizard Aug 29 18:03:01 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:01 volumio4013 sudo[2564]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 18:03:01 volumio4013 sudo[2564]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:01 volumio4013 sudo[2566]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 18:03:01 volumio4013 sudo[2566]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:01 volumio4013 sudo[2564]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:01 volumio4013 sudo[2566]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:01 volumio4013 sudo[2572]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 18:03:01 volumio4013 sudo[2572]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:01 volumio4013 sudo[2572]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:01 volumio4013 sudo[2574]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 18:03:01 volumio4013 sudo[2574]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:01 volumio4013 sudo[2574]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:02 volumio4013 volumio[1518]: verbose: New Socket.io Connection to 192.168.1.120 from 192.168.1.139 UA: Mozilla/5.0 (Linux; Android 15; SM-S938U1 Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.143 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 10 Aug 29 18:03:02 volumio4013 volumio[1518]: info: Received Get System Info Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:03:02 volumio4013 volumio[1518]: info: Discovery: Getting this device information Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 18:03:02 volumio4013 volumio[1518]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 29 18:03:02 volumio4013 volumio[1518]: info: Received Get System Info Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:03:02 volumio4013 volumio[1518]: info: Discovery: Getting this device information Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:02 volumio4013 volumio[1518]: info: Listing playlists Aug 29 18:03:02 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 18:03:02 volumio4013 volumio[1518]: verbose: New Socket.io Connection to 192.168.1.117 from 192.168.1.139 UA: Mozilla/5.0 (Linux; Android 15; SM-S938U1 Build/AP3A.240905.015.A2; wv) AppleWebKit/537.36 (KHTML, like Gecko) Version/4.0 Chrome/139.0.7258.143 Mobile Safari/537.36 Engine version: 3 Transport: polling Total Clients: 11 Aug 29 18:03:03 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:03:03 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:03:03 volumio4013 volumio[1518]: info: Discovery: Getting this device information Aug 29 18:03:03 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:03 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:03:03 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 18:03:03 volumio4013 volumio[1518]: info: Received Get System Info Aug 29 18:03:03 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:03:03 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:03:03 volumio4013 volumio[1518]: info: Discovery: Getting this device information Aug 29 18:03:03 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:03 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:03:04 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 18:03:04 volumio4013 volumio[1518]: info: Received Get System Info Aug 29 18:03:04 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:03:04 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:03:04 volumio4013 volumio[1518]: info: Discovery: Getting this device information Aug 29 18:03:04 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:04 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:03:08 volumio4013 volumio[1518]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 18:03:08 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Aug 29 18:03:08 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getInfoNetwork Aug 29 18:03:08 volumio4013 sudo[2597]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ethtool eth0 Aug 29 18:03:08 volumio4013 sudo[2597]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:08 volumio4013 sudo[2602]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 29 18:03:08 volumio4013 sudo[2602]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:08 volumio4013 sudo[2608]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 29 18:03:08 volumio4013 sudo[2608]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:08 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworksScanCache Aug 29 18:03:08 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getWirelessNetworks Aug 29 18:03:08 volumio4013 sudo[2614]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwconfig wlan0 Aug 29 18:03:08 volumio4013 sudo[2614]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:08 volumio4013 sudo[2597]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:08 volumio4013 sudo[2608]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:08 volumio4013 sudo[2602]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:08 volumio4013 sudo[2620]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 18:03:08 volumio4013 sudo[2620]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:08 volumio4013 sudo[2622]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 18:03:08 volumio4013 sudo[2622]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:08 volumio4013 sudo[2625]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/iwlist wlan0 scan Aug 29 18:03:08 volumio4013 sudo[2620]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:08 volumio4013 sudo[2625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:08 volumio4013 sudo[2622]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:08 volumio4013 sudo[2614]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:11 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 18:03:12 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 18:03:12 volumio4013 sudo[2625]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:20 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 18:03:20 volumio4013 volumio[1518]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 29 18:03:20 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 29 18:03:20 volumio4013 volumio[1518]: info: Received Get System Version Aug 29 18:03:20 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 18:03:20 volumio4013 volumio[1518]: info: Received Get System Info Aug 29 18:03:20 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:03:20 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:03:20 volumio4013 volumio[1518]: info: Discovery: Getting this device information Aug 29 18:03:20 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:20 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:03:21 volumio4013 go-librespot[1838]: time="2025-08-29T18:03:21-07:00" level=trace msg="received accesspoint ping" Aug 29 18:03:21 volumio4013 go-librespot[1838]: time="2025-08-29T18:03:21-07:00" level=trace msg="received accesspoint pong ack" Aug 29 18:03:22 volumio4013 go-librespot[1838]: time="2025-08-29T18:03:22-07:00" level=trace msg="sent dealer ping" Aug 29 18:03:22 volumio4013 go-librespot[1838]: time="2025-08-29T18:03:22-07:00" level=trace msg="received dealer pong" Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:03:29 volumio4013 volumio[1518]: info: Discovery: Getting this device information Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:03:29 volumio4013 sudo[2663]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 18:03:29 volumio4013 sudo[2663]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:29 volumio4013 sudo[2665]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 18:03:29 volumio4013 sudo[2663]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:29 volumio4013 sudo[2665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:29 volumio4013 sudo[2665]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:29 volumio4013 volumio[1518]: verbose: New Socket.io Connection to 192.168.1.117 from 192.168.1.100 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.0 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 12 Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 18:03:29 volumio4013 volumio[1518]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 29 18:03:29 volumio4013 volumio[1518]: info: Received Get System Info Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:03:29 volumio4013 volumio[1518]: info: Discovery: Getting this device information Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:29 volumio4013 volumio[1518]: info: Listing playlists Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 18:03:29 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 18:03:29 volumio4013 sudo[2669]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Aug 29 18:03:29 volumio4013 sudo[2669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:29 volumio4013 sudo[2669]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:29 volumio4013 sudo[2671]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Aug 29 18:03:29 volumio4013 sudo[2671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:29 volumio4013 sudo[2671]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:29 volumio4013 volumio[1518]: verbose: New Socket.io Connection to 192.168.1.117 from 192.168.1.100 UA: Mozilla/5.0 (Macintosh; Intel Mac OS X 10_15_7) AppleWebKit/605.1.15 (KHTML, like Gecko) Version/26.0 Safari/605.1.15 Engine version: 3 Transport: polling Total Clients: 13 Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetVisibleSources Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Aug 29 18:03:30 volumio4013 volumio[1518]: info: Error : CoreCommandRouter::executeOnPlugin: No method [getMultiroom] in plugin multiroom Aug 29 18:03:30 volumio4013 volumio[1518]: info: Received Get System Info Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:03:30 volumio4013 volumio[1518]: info: Discovery: Getting this device information Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:30 volumio4013 volumio[1518]: info: Listing playlists Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Aug 29 18:03:30 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:31 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Aug 29 18:03:31 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 18:03:31 volumio4013 volumio[1518]: info: Received Get System Info Aug 29 18:03:31 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:03:31 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:03:31 volumio4013 volumio[1518]: info: Discovery: Getting this device information Aug 29 18:03:31 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:31 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:03:31 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 29 18:03:32 volumio4013 volumio[1518]: info: Executing endpoint metavolumio Aug 29 18:03:32 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 18:03:33 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 18:03:33 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Aug 29 18:03:34 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Aug 29 18:03:34 volumio4013 volumio[1518]: info: Received Get System Info Aug 29 18:03:34 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:03:34 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:03:34 volumio4013 volumio[1518]: info: Discovery: Getting this device information Aug 29 18:03:34 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:34 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:03:39 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Aug 29 18:03:44 volumio4013 volumio[1518]: info: Enabling plugin peppymeterbasic Aug 29 18:03:44 volumio4013 volumio[1518]: info: Loading plugin "peppymeterbasic"... Aug 29 18:03:44 volumio4013 volumio[1518]: info: Preparing to generate the ALSA configuration file Aug 29 18:03:44 volumio4013 volumio[1518]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 29 18:03:44 volumio4013 volumio[1518]: info: Reading ALSA contributions from plugins. Aug 29 18:03:44 volumio4013 volumio[1518]: info: Asound.conf file written Aug 29 18:03:44 volumio4013 sudo[2700]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Aug 29 18:03:44 volumio4013 sudo[2700]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 sudo[2700]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:44 volumio4013 volumio[1518]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Aug 29 18:03:44 volumio4013 volumio[1518]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Aug 29 18:03:44 volumio4013 volumio[1518]: info: Output device has changed, restarting MPD Aug 29 18:03:44 volumio4013 volumio[1518]: info: Output device has changed, restarting Shairport Sync Aug 29 18:03:44 volumio4013 sudo[2706]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 18:03:44 volumio4013 sudo[2706]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 18:03:44 volumio4013 sudo[2706]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:44 volumio4013 sudo[2708]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 18:03:44 volumio4013 sudo[2708]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 volumio[1518]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 18:03:44 volumio4013 volumio[1518]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: QobuzConnect: setDeactiveState invoked Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:44 volumio4013 vtcs[2375]: [2025-08-29 18:03:44.582] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Aug 29 18:03:44 volumio4013 vtcs[2375]: [2025-08-29 18:03:44.582] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Aug 29 18:03:44 volumio4013 volumio[1518]: info: PLUGIN START: peppymeterbasic Aug 29 18:03:44 volumio4013 systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Aug 29 18:03:44 volumio4013 systemd[1]: Stopping mpd.service - Music Player Daemon... Aug 29 18:03:44 volumio4013 sudo[2719]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 29 18:03:44 volumio4013 sudo[2719]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Aug 29 18:03:44 volumio4013 volumio[1518]: info: Preparing to generate the ALSA configuration file Aug 29 18:03:44 volumio4013 dbus-daemon[910]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.45' (uid=0 pid=2720 comm="timedatectl show --property=NTPSynchronized --valu") Aug 29 18:03:44 volumio4013 volumio[1518]: info: Done. Aug 29 18:03:44 volumio4013 systemd[1]: Starting systemd-timedated.service - Time & Date Service... Aug 29 18:03:44 volumio4013 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Aug 29 18:03:44 volumio4013 systemd[1]: vtcs.service: Deactivated successfully. Aug 29 18:03:44 volumio4013 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Aug 29 18:03:44 volumio4013 sudo[2725]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 29 18:03:44 volumio4013 sudo[2725]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 sudo[2719]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:44 volumio4013 volumio[1518]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Aug 29 18:03:44 volumio4013 volumio[1518]: info: Reading ALSA contributions from plugins. Aug 29 18:03:44 volumio4013 volumio[1518]: info: MPD Permissions set Aug 29 18:03:44 volumio4013 volumio[1518]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 14 Aug 29 18:03:44 volumio4013 sudo[2725]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:44 volumio4013 sudo[2733]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 29 18:03:44 volumio4013 sudo[2733]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 sudo[2733]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:44 volumio4013 volumio[1518]: info: Starting Shairport Sync Aug 29 18:03:44 volumio4013 volumio[1518]: info: Asound.conf file unchanged, so no further update is needed Aug 29 18:03:44 volumio4013 volumio[1518]: info: Output device has changed, restarting MPD Aug 29 18:03:44 volumio4013 dbus-daemon[910]: [system] Successfully activated service 'org.freedesktop.timedate1' Aug 29 18:03:44 volumio4013 sudo[2741]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 29 18:03:44 volumio4013 sudo[2741]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 systemd[1]: mpd.service: Deactivated successfully. Aug 29 18:03:44 volumio4013 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 18:03:44 volumio4013 systemd[1]: mpd.service: Consumed 1.029s CPU time. Aug 29 18:03:44 volumio4013 systemd[1]: Started systemd-timedated.service - Time & Date Service. Aug 29 18:03:44 volumio4013 systemd[1]: mpd.socket: Deactivated successfully. Aug 29 18:03:44 volumio4013 setdatetime-helper.sh[2716]: Time is not synchronized. Attempting to sync... Aug 29 18:03:44 volumio4013 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 18:03:44 volumio4013 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 18:03:44 volumio4013 sudo[2743]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 18:03:44 volumio4013 sudo[2743]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 volumio[1518]: info: Output device has changed, restarting Shairport Sync Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 18:03:44 volumio4013 sudo[2746]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Aug 29 18:03:44 volumio4013 sudo[2746]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 sudo[2746]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:44 volumio4013 sudo[2754]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Aug 29 18:03:44 volumio4013 sudo[2754]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 volumio[1518]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Aug 29 18:03:44 volumio4013 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 18:03:44 volumio4013 volumio[1518]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 18:03:44 volumio4013 volumio[1518]: info: QobuzConnect: setDeactiveState invoked Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:44 volumio4013 sudo[2766]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 29 18:03:44 volumio4013 sudo[2766]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 sudo[2773]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Aug 29 18:03:44 volumio4013 sudo[2773]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 volumio[1518]: info: MPD Permissions set Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Aug 29 18:03:44 volumio4013 qobuz-connect[2306]: 20250829 18:03:44.827 [2306.2306] INFO SampleApp: Stopping Local configuration server Aug 29 18:03:44 volumio4013 sudo[2779]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Aug 29 18:03:44 volumio4013 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Aug 29 18:03:44 volumio4013 sudo[2779]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 systemd[1]: shairport-sync.service: Deactivated successfully. Aug 29 18:03:44 volumio4013 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 18:03:44 volumio4013 systemd[1]: shairport-sync.service: Consumed 1.619s CPU time. Aug 29 18:03:44 volumio4013 systemd[1]: mpd.service: Deactivated successfully. Aug 29 18:03:44 volumio4013 systemd[1]: Stopped mpd.service - Music Player Daemon. Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Aug 29 18:03:44 volumio4013 volumio[1518]: info: Starting Shairport Sync Aug 29 18:03:44 volumio4013 sudo[2766]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:44 volumio4013 sudo[2787]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Aug 29 18:03:44 volumio4013 sudo[2787]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 sudo[2773]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:44 volumio4013 sudo[2779]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:44 volumio4013 systemd[1]: mpd.socket: Deactivated successfully. Aug 29 18:03:44 volumio4013 sudo[2791]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Aug 29 18:03:44 volumio4013 sudo[2791]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:44 volumio4013 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Aug 29 18:03:44 volumio4013 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Aug 29 18:03:44 volumio4013 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Aug 29 18:03:44 volumio4013 systemd[1]: Starting mpd.service - Music Player Daemon... Aug 29 18:03:44 volumio4013 sudo[2794]: root : PWD=/ ; USER=root ; COMMAND=/usr/bin/date -s 'Sat, 30 Aug 2025 01:03:45 GMT#015' Aug 29 18:03:44 volumio4013 sudo[2794]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 18:03:45 volumio4013 setdatetime-helper.sh[2796]: Fri Aug 29 18:03:45 PDT 2025 Aug 29 18:03:45 volumio4013 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Aug 29 18:03:45 volumio4013 sudo[2794]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:45 volumio4013 sudo[2743]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:45 volumio4013 setdatetime-helper.sh[2716]: Time synchronized successfully. Aug 29 18:03:45 volumio4013 volumio[1518]: info: Shairport-Sync Started Aug 29 18:03:45 volumio4013 systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Aug 29 18:03:45 volumio4013 systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Aug 29 18:03:45 volumio4013 sudo[2787]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:45 volumio4013 volumio[1518]: info: Shairport-Sync Started Aug 29 18:03:45 volumio4013 sudo[2795]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Aug 29 18:03:45 volumio4013 sudo[2795]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Aug 29 18:03:45 volumio4013 sudo[2795]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:45 volumio4013 mpd[2813]: 2025-08-29T18:03:45 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Aug 29 18:03:45 volumio4013 volumio[1518]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 18:03:46 volumio4013 volumio[1518]: info: PeppyMeterBasic ---list is random,01G5_McIntosh Hybrid,02G5_Naim,03G5_NAD HD,04G5_Old braun,06G5_Dark Spotify,09G5_Sansui Aug 29 18:03:46 volumio4013 volumio[1518]: info: PeppyMeterBasic ---800x480-test Aug 29 18:03:46 volumio4013 volumio[1518]: info: PeppyMeterBasic ---800x480-balbuze Aug 29 18:03:46 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1024x600-Gelo5-BASIC_121 Aug 29 18:03:46 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1280x400-Gelo5-BASIC_221 Aug 29 18:03:46 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1280x800-Gelo5-BASIC_421 Aug 29 18:03:46 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1480x320-Gelo5-BASIC_521 Aug 29 18:03:46 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1600x720-balbuze Aug 29 18:03:46 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1920x480-Gelo5-BASIC_521 Aug 29 18:03:46 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1920x515-Gelo5-BASIC_921 Aug 29 18:03:46 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1920x1080-Gelo5-BASIC_721 Aug 29 18:03:46 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1920x1080-balbuze Aug 29 18:03:46 volumio4013 volumio[1518]: info: PeppyMeterBasic ---2560x720-Gelo5-BASIC_921 Aug 29 18:03:46 volumio4013 systemd[1]: Started mpd.service - Music Player Daemon. Aug 29 18:03:46 volumio4013 sudo[2708]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:46 volumio4013 sudo[2754]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:46 volumio4013 volumio[1518]: error: updateQueue error: null Aug 29 18:03:46 volumio4013 volumio[1518]: error: updateQueue error: null Aug 29 18:03:46 volumio4013 qobuz-connect[2306]: 20250829 18:03:46.572 [2306.2306] INFO SampleApp: shat down connection on UNIX socket Aug 29 18:03:46 volumio4013 volumio[1518]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Aug 29 18:03:46 volumio4013 volumio[1518]: info: QobuzConnect: setDeactiveState invoked Aug 29 18:03:46 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:46 volumio4013 systemd[1]: qobuz-connect.service: Deactivated successfully. Aug 29 18:03:46 volumio4013 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Aug 29 18:03:46 volumio4013 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Aug 29 18:03:46 volumio4013 sudo[2791]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:46 volumio4013 sudo[2741]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:46 volumio4013 volumio[1518]: info: Executing endpoint qc_getconfig Aug 29 18:03:46 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Aug 29 18:03:46 volumio4013 qobuz-connect[2817]: 20250829 18:03:46.626 [2817.2817] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Aug 29 18:03:46 volumio4013 volumio[1518]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Aug 29 18:03:46 volumio4013 volumio[1518]: info: QobuzConnect: QOBUZ Connect daemon connected Aug 29 18:03:46 volumio4013 qobuz-connect[2817]: 20250829 18:03:46.628 [2817.2817] INFO VolumeManager: [0x1ff13c8]: Setting new playback volume: 75 Aug 29 18:03:46 volumio4013 qobuz-connect[2817]: 20250829 18:03:46.628 [2817.2817] INFO VolumeManager: [0x1ff13c8]: Setting new mute state: 0 Aug 29 18:03:46 volumio4013 qobuz-connect[2817]: 20250829 18:03:46.628 [2817.2817] INFO QobuzConnect: [0x1ff1d98]: Client initialized! Aug 29 18:03:46 volumio4013 qobuz-connect[2817]: 20250829 18:03:46.628 [2817.2817] INFO SampleApp: Starting Avahi advertising, name: Volumio4013, service name: _qobuz-connect._tcp Aug 29 18:03:46 volumio4013 qobuz-connect[2817]: 20250829 18:03:46.634 [2817.2817] INFO LocalConfigManager: [0x1ff0ca8]: Starting Local Configuration server Aug 29 18:03:46 volumio4013 qobuz-connect[2817]: 20250829 18:03:46.634 [2817.2817] INFO SampleApp: Starting Local configuration server Aug 29 18:03:46 volumio4013 qobuz-connect[2817]: 20250829 18:03:46.634 [2817.2817] INFO SampleApp: Connected to UNIX socket client 0x1fdb818 Aug 29 18:03:46 volumio4013 qobuz-connect[2817]: 20250829 18:03:46.776 [2817.2817] INFO SampleApp: Playback volume changed: 75 Aug 29 18:03:46 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:47 volumio4013 volumio[1518]: info: TidalConnect service stoped! Aug 29 18:03:47 volumio4013 volumio[1518]: info: TidalConnect service stoped! Aug 29 18:03:47 volumio4013 volumio[1518]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 29 18:03:47 volumio4013 volumio[1518]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 29 18:03:47 volumio4013 sudo[2829]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 29 18:03:47 volumio4013 sudo[2829]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:47 volumio4013 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Aug 29 18:03:47 volumio4013 sudo[2829]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:47 volumio4013 volumio[1518]: info: Executing endpoint tc_getconfig Aug 29 18:03:47 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Aug 29 18:03:47 volumio4013 vtcs[2832]: STARTING TidalConnect services, version: 1.5.1-beta1 Aug 29 18:03:47 volumio4013 vtcs[2832]: STARTED TidalConnect services. Aug 29 18:03:47 volumio4013 volumio[1518]: info: TidalConnect service stoped! Aug 29 18:03:47 volumio4013 volumio[1518]: info: Executing endpoint tc_connect Aug 29 18:03:47 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Aug 29 18:03:47 volumio4013 volumio[1518]: info: Connecting to TidalConnect Aug 29 18:03:47 volumio4013 volumio[1518]: info: CoreCommandRouter::servicePushState Aug 29 18:03:47 volumio4013 volumio[1518]: info: CoreStateMachine::pushState Aug 29 18:03:47 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioPushState Aug 29 18:03:47 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:47 volumio4013 volumio[1518]: info: CorePlayQueue::getTrack 0 Aug 29 18:03:47 volumio4013 volumio[1518]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Aug 29 18:03:47 volumio4013 volumio[1518]: info: CoreCommandRouter::servicePushState Aug 29 18:03:47 volumio4013 volumio[1518]: info: CoreStateMachine::pushState Aug 29 18:03:47 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioPushState Aug 29 18:03:47 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:47 volumio4013 volumio[1518]: info: CorePlayQueue::getTrack 0 Aug 29 18:03:47 volumio4013 volumio[1518]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current mpd Received tidalconnect Aug 29 18:03:47 volumio4013 volumio[1518]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Y8AfN8GQEshVvajlU8wGBNeXTCf2.413e1b808dca51b76f0f810b3fd0cfdc.state.status' Aug 29 18:03:47 volumio4013 volumio[1518]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.Y8AfN8GQEshVvajlU8wGBNeXTCf2.413e1b808dca51b76f0f810b3fd0cfdc.state.status' Aug 29 18:03:47 volumio4013 volumio[1518]: info: PeppyMeterBasic ---peppymeterbasic status undefined Aug 29 18:03:47 volumio4013 volumio[1518]: info: PeppyMeterBasic ---peppymeterbasic status undefined Aug 29 18:03:47 volumio4013 volumio[1518]: info: TidalConnect service stoped! Aug 29 18:03:47 volumio4013 volumio[1518]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Aug 29 18:03:47 volumio4013 volumio[1518]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Aug 29 18:03:47 volumio4013 sudo[2859]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Aug 29 18:03:47 volumio4013 sudo[2859]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Aug 29 18:03:48 volumio4013 sudo[2859]: pam_unix(sudo:session): session closed for user root Aug 29 18:03:48 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Aug 29 18:03:48 volumio4013 volumio[1518]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Aug 29 18:03:48 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Aug 29 18:03:48 volumio4013 volumio[1518]: info: Received Get System Version Aug 29 18:03:48 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Aug 29 18:03:48 volumio4013 volumio[1518]: info: Received Get System Info Aug 29 18:03:48 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:03:48 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:03:48 volumio4013 volumio[1518]: info: Discovery: Getting this device information Aug 29 18:03:48 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:48 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:03:49 volumio4013 volumio[1518]: info: Executing endpoint metavolumio Aug 29 18:03:49 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Aug 29 18:03:50 volumio4013 volumio[1518]: info: TidalConnect service started! Aug 29 18:03:51 volumio4013 volumio[1518]: info: TidalConnect service started! Aug 29 18:03:52 volumio4013 go-librespot[1838]: time="2025-08-29T18:03:52-07:00" level=trace msg="sent dealer ping" Aug 29 18:03:52 volumio4013 go-librespot[1838]: time="2025-08-29T18:03:52-07:00" level=trace msg="received dealer pong" Aug 29 18:03:58 volumio4013 volumio[1518]: info: CALLMETHOD: user_interface peppymeterbasic updatelist Aug 29 18:03:58 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , updatelist Aug 29 18:03:58 volumio4013 volumio[1518]: info: PeppyMeterBasic --- wget 'https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt' Aug 29 18:03:59 volumio4013 volumio[1518]: --2025-08-29 18:03:58-- https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt Aug 29 18:03:59 volumio4013 volumio[1518]: Resolving github.com (github.com)... 140.82.116.3 Aug 29 18:03:59 volumio4013 volumio[1518]: Connecting to github.com (github.com)|140.82.116.3|:443... connected. Aug 29 18:03:59 volumio4013 volumio[1518]: HTTP request sent, awaiting response... 302 Found Aug 29 18:03:59 volumio4013 volumio[1518]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt [following] Aug 29 18:03:59 volumio4013 volumio[1518]: --2025-08-29 18:03:58-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt Aug 29 18:03:59 volumio4013 volumio[1518]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.108.133, 185.199.109.133, 185.199.110.133, ... Aug 29 18:03:59 volumio4013 volumio[1518]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.108.133|:443... connected. Aug 29 18:03:59 volumio4013 volumio[1518]: HTTP request sent, awaiting response... 200 OK Aug 29 18:03:59 volumio4013 volumio[1518]: Length: 312 [text/plain] Aug 29 18:03:59 volumio4013 volumio[1518]: Saving to: ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ Aug 29 18:03:59 volumio4013 volumio[1518]: 0K 100% 9.89M=0s Aug 29 18:03:59 volumio4013 volumio[1518]: 2025-08-29 18:03:59 (9.89 MB/s) - ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ saved [312/312] Aug 29 18:03:59 volumio4013 volumio[1518]: info: CoreCommandRouter::getUIConfigOnPlugin Aug 29 18:03:59 volumio4013 volumio[1518]: info: CoreCommandRouter::Close All Modals sent Aug 29 18:03:59 volumio4013 volumio[1518]: info: PeppyMeterBasic ---list is random,01G5_McIntosh Hybrid,02G5_Naim,03G5_NAD HD,04G5_Old braun,06G5_Dark Spotify,09G5_Sansui Aug 29 18:03:59 volumio4013 volumio[1518]: info: PeppyMeterBasic ---800x480-test Aug 29 18:03:59 volumio4013 volumio[1518]: info: PeppyMeterBasic ---800x480-balbuze Aug 29 18:03:59 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1024x600-Gelo5-BASIC_121 Aug 29 18:03:59 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1280x400-Gelo5-BASIC_221 Aug 29 18:03:59 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1280x800-Gelo5-BASIC_421 Aug 29 18:03:59 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1480x320-Gelo5-BASIC_521 Aug 29 18:03:59 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1600x720-balbuze Aug 29 18:03:59 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1920x480-Gelo5-BASIC_521 Aug 29 18:03:59 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1920x515-Gelo5-BASIC_921 Aug 29 18:03:59 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1920x1080-Gelo5-BASIC_721 Aug 29 18:03:59 volumio4013 volumio[1518]: info: PeppyMeterBasic ---1920x1080-balbuze Aug 29 18:03:59 volumio4013 volumio[1518]: info: PeppyMeterBasic ---2560x720-Gelo5-BASIC_921 Aug 29 18:03:59 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Aug 29 18:03:59 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Aug 29 18:03:59 volumio4013 volumio[1518]: info: Discovery: Getting this device information Aug 29 18:03:59 volumio4013 volumio[1518]: info: CoreCommandRouter::volumioGetState Aug 29 18:03:59 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Aug 29 18:04:05 volumio4013 volumio[1518]: info: CALLMETHOD: user_interface peppymeterbasic dlmeter [object Object] Aug 29 18:04:05 volumio4013 volumio[1518]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , dlmeter Aug 29 18:04:06 volumio4013 volumio[1518]: --2025-08-29 18:04:05-- https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/Choose Aug 29 18:04:06 volumio4013 volumio[1518]: Resolving github.com (github.com)... 140.82.116.3 Aug 29 18:04:06 volumio4013 volumio[1518]: Connecting to github.com (github.com)|140.82.116.3|:443... connected. Aug 29 18:04:06 volumio4013 volumio[1518]: HTTP request sent, awaiting response... 404 Not Found Aug 29 18:04:06 volumio4013 volumio[1518]: 2025-08-29 18:04:05 ERROR 404: Not Found. Aug 29 18:04:06 volumio4013 volumio[1518]: --2025-08-29 18:04:05-- http://a/ Aug 29 18:04:06 volumio4013 volumio[1518]: Resolving a (a)... failed: Device or resource busy. Aug 29 18:04:06 volumio4013 volumio[1518]: wget: unable to resolve host address ‘a’ Aug 29 18:04:06 volumio4013 volumio[1518]: --2025-08-29 18:04:06-- http://packet.zip/ Aug 29 18:04:06 volumio4013 volumio[1518]: Resolving packet.zip (packet.zip)... 162.255.119.167 Aug 29 18:04:06 volumio4013 volumio[1518]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Aug 29 18:04:06 volumio4013 volumio[1518]: HTTP request sent, awaiting response... 302 Found Aug 29 18:04:06 volumio4013 volumio[1518]: Location: http://www.packet.zip/ [following] Aug 29 18:04:06 volumio4013 volumio[1518]: --2025-08-29 18:04:06-- http://www.packet.zip/ Aug 29 18:04:06 volumio4013 volumio[1518]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Aug 29 18:04:06 volumio4013 volumio[1518]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Aug 29 18:04:06 volumio4013 volumio[1518]: HTTP request sent, awaiting response... 403 Forbidden Aug 29 18:04:06 volumio4013 volumio[1518]: 2025-08-29 18:04:06 ERROR 403: Forbidden. Aug 29 18:04:06 volumio4013 volumio[1518]: error: PeppyMeterBasic --- An error occurs while downloading or installing Meters Aug 29 18:04:06 volumio4013 volumio[1518]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Aug 29 18:04:06 volumio4013 volumio[1518]: /bin/rm: cannot remove 'a': No such file or directory Aug 29 18:04:06 volumio4013 volumio[1518]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Aug 29 18:04:06 volumio4013 volumio[1518]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 18:04:06 volumio4013 volumio[1518]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Aug 29 18:04:06 volumio4013 volumio[1518]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Aug 29 18:04:06 volumio4013 volumio[1518]: /bin/rm: cannot remove 'a': No such file or directory Aug 29 18:04:06 volumio4013 volumio[1518]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Aug 29 18:04:06 volumio4013 volumio[1518]: at checkExecSyncError (node:child_process:887:11) Aug 29 18:04:06 volumio4013 volumio[1518]: at execSync (node:child_process:959:15) Aug 29 18:04:06 volumio4013 volumio[1518]: at /data/plugins/user_interface/peppymeterbasic/index.js:700:17 Aug 29 18:04:06 volumio4013 volumio[1518]: at new Promise () Aug 29 18:04:06 volumio4013 volumio[1518]: at peppymeterbasic.dlmeter (/data/plugins/user_interface/peppymeterbasic/index.js:675:12) Aug 29 18:04:06 volumio4013 volumio[1518]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Aug 29 18:04:06 volumio4013 volumio[1518]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Aug 29 18:04:06 volumio4013 volumio[1518]: at Socket.emit (node:events:514:28) Aug 29 18:04:06 volumio4013 volumio[1518]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Aug 29 18:04:06 volumio4013 volumio[1518]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Aug 29 18:04:06 volumio4013 volumio[1518]: status: 1, Aug 29 18:04:06 volumio4013 volumio[1518]: signal: null, Aug 29 18:04:06 volumio4013 volumio[1518]: output: [ Aug 29 18:04:06 volumio4013 volumio[1518]: null, Aug 29 18:04:06 volumio4013 volumio[1518]: , Aug 29 18:04:06 volumio4013 volumio[1518]: Aug 29 18:04:06 volumio4013 volumio[1518]: ], Aug 29 18:04:06 volumio4013 volumio[1518]: pid: 2902, Aug 29 18:04:06 volumio4013 volumio[1518]: stdout: , Aug 29 18:04:06 volumio4013 volumio[1518]: stderr: Aug 29 18:04:06 volumio4013 volumio[1518]: } Aug 29 18:04:06 volumio4013 volumio[1518]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Aug 29 18:04:06 volumio4013 sudo[2918]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-08-29 18:03' Aug 29 18:04:06 volumio4013 sudo[2918]: 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="477d19cd2b4ebf4b4c802be78e00bbb657e83aea" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="fc4c820ddd3b5fc1a7a6b54ef112273ebd122010" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Aug 2 17:34:53 UTC 2025" VOLUMIO_VERSION="4.021" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="6199706cbb86ad1289fc7190b8a00601"