Dec 12 12:25:07 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 12 12:25:07 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 12 12:25:11 volumiopi3 volumio[1172]: info: Enabling plugin peppymeterbasic Dec 12 12:25:11 volumiopi3 volumio[1172]: info: Loading plugin "peppymeterbasic"... Dec 12 12:25:11 volumiopi3 ntpd[892]: PROTO: 93.94.88.50 unlink local addr 192.168.88.49 -> Dec 12 12:25:11 volumiopi3 volumio[1172]: info: Preparing to generate the ALSA configuration file Dec 12 12:25:11 volumiopi3 volumio[1172]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 12 12:25:11 volumiopi3 volumio[1172]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 12 12:25:11 volumiopi3 volumio[1172]: info: Reading ALSA contributions from plugins. Dec 12 12:25:11 volumiopi3 volumio[1172]: info: Asound.conf file written Dec 12 12:25:11 volumiopi3 sudo[4213]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Dec 12 12:25:11 volumiopi3 sudo[4213]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 sudo[4213]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:12 volumiopi3 volumio[1172]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Dec 12 12:25:12 volumiopi3 volumio[1172]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Dec 12 12:25:12 volumiopi3 volumio[1172]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Dec 12 12:25:12 volumiopi3 volumio[1172]: info: Output device has changed, restarting MPD Dec 12 12:25:12 volumiopi3 volumio[1172]: info: Output device has changed, restarting Shairport Sync Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:25:12 volumiopi3 sudo[4222]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 12:25:12 volumiopi3 sudo[4222]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 sudo[4222]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:12 volumiopi3 sudo[4224]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 12 12:25:12 volumiopi3 sudo[4224]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 volumio[1172]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 12:25:12 volumiopi3 volumio[1172]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: QobuzConnect: setDeactiveState invoked Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:25:12 volumiopi3 vtcs[2060]: [2025-12-12 12:25:12.150] [tisoc] [warning] [SessionManagerImpl.cpp:240] Illegal State: IDLE Dec 12 12:25:12 volumiopi3 vtcs[2060]: [2025-12-12 12:25:12.152] [tisoc] [error] [SpkconServer.cpp:375] recv error. socket disconnected Dec 12 12:25:12 volumiopi3 vtcs[2060]: [2025-12-12 12:25:12.158] [tisoc] [error] [SpkconServer.cpp:472] socket write error. fd=3 errorno=32 error=Broken pipe Dec 12 12:25:12 volumiopi3 volumio[1172]: info: PLUGIN START: peppymeterbasic Dec 12 12:25:12 volumiopi3 systemd[1]: Stopping mpd.service - Music Player Daemon... Dec 12 12:25:12 volumiopi3 upmpdcli[1321]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Dec 12 12:25:12 volumiopi3 sudo[4234]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 12:25:12 volumiopi3 sudo[4234]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Dec 12 12:25:12 volumiopi3 volumio[1172]: info: Preparing to generate the ALSA configuration file Dec 12 12:25:12 volumiopi3 systemd[1]: Stopping vtcs.service - Volumio Tidal Connect Service... Dec 12 12:25:12 volumiopi3 sudo[4238]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Dec 12 12:25:12 volumiopi3 sudo[4238]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 systemd[1]: vtcs.service: Deactivated successfully. Dec 12 12:25:12 volumiopi3 volumio[1172]: info: Done. Dec 12 12:25:12 volumiopi3 systemd[1]: Stopped vtcs.service - Volumio Tidal Connect Service. Dec 12 12:25:12 volumiopi3 sudo[4234]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:12 volumiopi3 sudo[4241]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 12:25:12 volumiopi3 sudo[4241]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 volumio[1172]: info: The plugin multiroom has an ALSA contribution file volumioMultiRoomServer.postMultiRoom.1000.conf Dec 12 12:25:12 volumiopi3 volumio[1172]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Dec 12 12:25:12 volumiopi3 volumio[1172]: info: Reading ALSA contributions from plugins. Dec 12 12:25:12 volumiopi3 sudo[4241]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:12 volumiopi3 sudo[4249]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 12 12:25:12 volumiopi3 sudo[4249]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 volumio[1172]: info: MPD Permissions set Dec 12 12:25:12 volumiopi3 sudo[4238]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:12 volumiopi3 volumio[1172]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 sudo[4249]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 sudo[4259]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 12 12:25:12 volumiopi3 sudo[4259]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 volumio[1172]: info: snd_dummy loaded Dec 12 12:25:12 volumiopi3 volumio[1172]: info: Starting Shairport Sync Dec 12 12:25:12 volumiopi3 systemd[1]: mpd.service: Deactivated successfully. Dec 12 12:25:12 volumiopi3 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 12 12:25:12 volumiopi3 systemd[1]: mpd.service: Consumed 3.305s CPU time. Dec 12 12:25:12 volumiopi3 systemd[1]: mpd.socket: Deactivated successfully. Dec 12 12:25:12 volumiopi3 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 12 12:25:12 volumiopi3 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 12 12:25:12 volumiopi3 (udev-worker)[4265]: controlC7: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 7' failed with exit code 99. Dec 12 12:25:12 volumiopi3 volumio[1172]: info: Asound.conf file unchanged, so no further update is needed Dec 12 12:25:12 volumiopi3 volumio[1172]: info: Output device has changed, restarting MPD Dec 12 12:25:12 volumiopi3 sudo[4268]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 12:25:12 volumiopi3 sudo[4268]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 volumio[1172]: info: Output device has changed, restarting Shairport Sync Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:25:12 volumiopi3 systemd[2205]: Reached target sound.target - Sound Card. Dec 12 12:25:12 volumiopi3 sudo[4272]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Dec 12 12:25:12 volumiopi3 sudo[4272]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 sudo[4272]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:12 volumiopi3 sudo[4274]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Dec 12 12:25:12 volumiopi3 sudo[4274]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 12 12:25:12 volumiopi3 volumio[1172]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Dec 12 12:25:12 volumiopi3 volumio[1172]: info: MRS: Audio Device Changed, rebuilding Multiroom Configuration Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 12 12:25:12 volumiopi3 volumio[1172]: info: QobuzConnect: setDeactiveState invoked Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:25:12 volumiopi3 sudo[4285]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 12:25:12 volumiopi3 sudo[4285]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 systemd[1]: Stopping qobuz-connect.service - Volumio Qobuz Connect Service... Dec 12 12:25:12 volumiopi3 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 12:25:12 volumiopi3 systemd[1]: mpd.service: Deactivated successfully. Dec 12 12:25:12 volumiopi3 systemd[1]: Stopped mpd.service - Music Player Daemon. Dec 12 12:25:12 volumiopi3 systemd[1]: mpd.socket: Deactivated successfully. Dec 12 12:25:12 volumiopi3 systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Dec 12 12:25:12 volumiopi3 systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Dec 12 12:25:12 volumiopi3 qobuz-connect[1960]: 20251212 12:25:12.792 [1960.1960] INFO SampleApp: Stopping Local configuration server Dec 12 12:25:12 volumiopi3 sudo[4298]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl reset-failed qobuz-connect.service Dec 12 12:25:12 volumiopi3 sudo[4289]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop vtcs.service Dec 12 12:25:12 volumiopi3 volumio[1172]: info: MPD Permissions set Dec 12 12:25:12 volumiopi3 sudo[4289]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 sudo[4298]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Dec 12 12:25:12 volumiopi3 systemd[1]: Starting mpd.service - Music Player Daemon... Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Dec 12 12:25:12 volumiopi3 volumio[1172]: info: Starting Shairport Sync Dec 12 12:25:12 volumiopi3 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 12:25:12 volumiopi3 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 12:25:12 volumiopi3 systemd[1]: shairport-sync.service: Consumed 4.144s CPU time. Dec 12 12:25:12 volumiopi3 sudo[4310]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 12 12:25:12 volumiopi3 sudo[4310]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:12 volumiopi3 sudo[4298]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:12 volumiopi3 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 12:25:12 volumiopi3 sudo[4268]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:12 volumiopi3 sudo[4312]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart qobuz-connect.service Dec 12 12:25:12 volumiopi3 volumio[1172]: info: Shairport-Sync Started Dec 12 12:25:12 volumiopi3 sudo[4312]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:13 volumiopi3 sudo[4285]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:13 volumiopi3 sudo[4289]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:13 volumiopi3 systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Dec 12 12:25:13 volumiopi3 systemd[1]: shairport-sync.service: Deactivated successfully. Dec 12 12:25:13 volumiopi3 systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 12:25:13 volumiopi3 sudo[4307]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Dec 12 12:25:13 volumiopi3 sudo[4307]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Dec 12 12:25:13 volumiopi3 sudo[4307]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:13 volumiopi3 volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:25:13 volumiopi3 systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Dec 12 12:25:13 volumiopi3 sudo[4310]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:13 volumiopi3 volumio[1172]: info: Shairport-Sync Started Dec 12 12:25:13 volumiopi3 ntpd[892]: PROTO: 172.232.208.229 unlink local addr 192.168.88.49 -> Dec 12 12:25:14 volumiopi3 qobuz-connect[1960]: 20251212 12:25:14.173 [1960.1960] INFO SampleApp: shat down connection on UNIX socket Dec 12 12:25:14 volumiopi3 volumio[1172]: info: QobuzConnect: Qobuz Connect client [object Object] disconnected Dec 12 12:25:14 volumiopi3 volumio[1172]: info: QobuzConnect: setDeactiveState invoked Dec 12 12:25:14 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:25:14 volumiopi3 systemd[1]: qobuz-connect.service: Deactivated successfully. Dec 12 12:25:14 volumiopi3 systemd[1]: Stopped qobuz-connect.service - Volumio Qobuz Connect Service. Dec 12 12:25:14 volumiopi3 systemd[1]: Started qobuz-connect.service - Volumio Qobuz Connect Service. Dec 12 12:25:14 volumiopi3 sudo[4312]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:14 volumiopi3 sudo[4259]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:14 volumiopi3 volumio[1172]: info: Executing endpoint qc_getconfig Dec 12 12:25:14 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: qobuzconnect , onGetConfig Dec 12 12:25:14 volumiopi3 qobuz-connect[4337]: 20251212 12:25:14.364 [4337.4337] INFO SampleApp: Started connection to /tmp/qbz-connect.socket UNIX socket Dec 12 12:25:14 volumiopi3 volumio[1172]: info: QobuzConnect: Qobuz Connect socket /tmp/qbz-connect.socket connected to client [object Object] Dec 12 12:25:14 volumiopi3 volumio[1172]: info: QobuzConnect: QOBUZ Connect daemon connected Dec 12 12:25:14 volumiopi3 qobuz-connect[4337]: 20251212 12:25:14.375 [4337.4337] INFO VolumeManager: [0x1cca258]: Setting new playback volume: 75 Dec 12 12:25:14 volumiopi3 qobuz-connect[4337]: 20251212 12:25:14.375 [4337.4337] INFO VolumeManager: [0x1cca258]: Setting new mute state: 0 Dec 12 12:25:14 volumiopi3 qobuz-connect[4337]: 20251212 12:25:14.375 [4337.4337] INFO QobuzConnect: [0x1ccac28]: Client initialized! Dec 12 12:25:14 volumiopi3 qobuz-connect[4337]: 20251212 12:25:14.375 [4337.4337] INFO SampleApp: Starting Avahi advertising, name: VolumioPi3, service name: _qobuz-connect._tcp Dec 12 12:25:14 volumiopi3 qobuz-connect[4337]: 20251212 12:25:14.401 [4337.4337] INFO LocalConfigManager: [0x1cc9b38]: Starting Local Configuration server Dec 12 12:25:14 volumiopi3 qobuz-connect[4337]: 20251212 12:25:14.401 [4337.4337] INFO SampleApp: Starting Local configuration server Dec 12 12:25:14 volumiopi3 qobuz-connect[4337]: 20251212 12:25:14.402 [4337.4337] INFO SampleApp: Connected to UNIX socket client 0x1cb4818 Dec 12 12:25:14 volumiopi3 qobuz-connect[4337]: 20251212 12:25:14.515 [4337.4337] INFO SampleApp: Playback volume changed: 75 Dec 12 12:25:14 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: TidalConnect service stoped! Dec 12 12:25:15 volumiopi3 volumio[1172]: info: TidalConnect service stoped! Dec 12 12:25:15 volumiopi3 volumio[1172]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 12 12:25:15 volumiopi3 volumio[1172]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 12 12:25:15 volumiopi3 sudo[4352]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 12 12:25:15 volumiopi3 sudo[4352]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:15 volumiopi3 systemd[1]: Started vtcs.service - Volumio Tidal Connect Service. Dec 12 12:25:15 volumiopi3 sudo[4352]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:15 volumiopi3 volumio[1172]: info: Executing endpoint tc_getconfig Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onGetConfig Dec 12 12:25:15 volumiopi3 vtcs[4355]: STARTING TidalConnect services, version: 1.5.2.56 Dec 12 12:25:15 volumiopi3 vtcs[4355]: STARTED TidalConnect services. Dec 12 12:25:15 volumiopi3 volumio[1172]: info: Executing endpoint tc_connect Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: tidalconnect , onConnect Dec 12 12:25:15 volumiopi3 volumio[1172]: info: Connecting to TidalConnect Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::servicePushState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreStateMachine::pushState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Dec 12 12:25:15 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:25:15 volumiopi3 volumio[1172]: 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 12 12:25:15 volumiopi3 volumio[1172]: verbose: CURRENT POSITION 0 Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreStateMachine::syncState stateService stop Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreStateMachine::pushState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Dec 12 12:25:15 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: No code Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreStateMachine::pushState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Dec 12 12:25:15 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::servicePushState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreStateMachine::pushState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Dec 12 12:25:15 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:25:15 volumiopi3 volumio[1172]: 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 12 12:25:15 volumiopi3 volumio[1172]: verbose: CURRENT POSITION 0 Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreStateMachine::syncState stateService stop Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreStateMachine::pushState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Dec 12 12:25:15 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: No code Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreStateMachine::pushState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 12 12:25:15 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Dec 12 12:25:15 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output Dec 12 12:25:15 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:25:16 volumiopi3 volumio[1172]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.badK6kPI9dbBqlGxk5yfUmq9ze22.125b4c9c158ea974d99a810736d969e7.state.status' Dec 12 12:25:16 volumiopi3 volumio[1172]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.badK6kPI9dbBqlGxk5yfUmq9ze22.125b4c9c158ea974d99a810736d969e7.state.status' Dec 12 12:25:16 volumiopi3 volumio[1172]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.badK6kPI9dbBqlGxk5yfUmq9ze22.125b4c9c158ea974d99a810736d969e7.state.status' Dec 12 12:25:16 volumiopi3 volumio[1172]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.badK6kPI9dbBqlGxk5yfUmq9ze22.125b4c9c158ea974d99a810736d969e7.state.status' Dec 12 12:25:16 volumiopi3 volumio[1172]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.badK6kPI9dbBqlGxk5yfUmq9ze22.125b4c9c158ea974d99a810736d969e7.state.status' Dec 12 12:25:16 volumiopi3 volumio[1172]: error: Cannot update RT Device info: Error: Reference.set failed: First argument contains undefined in property 'user_devices.badK6kPI9dbBqlGxk5yfUmq9ze22.125b4c9c158ea974d99a810736d969e7.state.status' Dec 12 12:25:16 volumiopi3 volumio[1172]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 12 12:25:16 volumiopi3 volumio[1172]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 12 12:25:16 volumiopi3 volumio[1172]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 12 12:25:16 volumiopi3 volumio[1172]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 12 12:25:16 volumiopi3 volumio[1172]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 12 12:25:16 volumiopi3 volumio[1172]: info: PeppyMeterBasic ---peppymeterbasic status undefined Dec 12 12:25:16 volumiopi3 volumio[1172]: info: TidalConnect service stoped! Dec 12 12:25:16 volumiopi3 volumio[1172]: info: TidalConnect service stoped! Dec 12 12:25:16 volumiopi3 volumio[1172]: info: Updating tc_getconfig REST Endpoint for plugin: music_service/tidalconnect Dec 12 12:25:16 volumiopi3 volumio[1172]: info: Updating tc_connect REST Endpoint for plugin: music_service/tidalconnect Dec 12 12:25:16 volumiopi3 sudo[4394]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start vtcs.service Dec 12 12:25:16 volumiopi3 sudo[4394]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:16 volumiopi3 sudo[4394]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:16 volumiopi3 mpd[4320]: 2025-12-12T12:25:16 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Dec 12 12:25:16 volumiopi3 upmpdcli[1321]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Dec 12 12:25:16 volumiopi3 systemd[1]: Started mpd.service - Music Player Daemon. Dec 12 12:25:16 volumiopi3 sudo[4224]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:16 volumiopi3 sudo[4274]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:16 volumiopi3 upmpdcli[1321]: :2:../src/mpdcli.cxx:243::MPDCli::eventloop: mpd_run_idle_mask returned 0 Dec 12 12:25:16 volumiopi3 volumio[1172]: error: MPD error: The expression evaluated to a falsy value: Dec 12 12:25:16 volumiopi3 volumio[1172]: assert.ok(self.idling) Dec 12 12:25:16 volumiopi3 volumio[1172]: error: The expression evaluated to a falsy value: Dec 12 12:25:16 volumiopi3 volumio[1172]: assert.ok(self.idling) Dec 12 12:25:16 volumiopi3 volumio[1172]: error: updateQueue error: null Dec 12 12:25:18 volumiopi3 volumio[1172]: info: TidalConnect service started! Dec 12 12:25:18 volumiopi3 volumio[1172]: info: CALLMETHOD: user_interface peppymeterbasic savepeppy1 [object Object] Dec 12 12:25:18 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , savepeppy1 Dec 12 12:25:18 volumiopi3 volumio[1172]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 12 12:25:18 volumiopi3 volumio[1172]: info: PeppyMeterBasic -----------------------meterrandom Dec 12 12:25:18 volumiopi3 volumio[1172]: info: PeppyMeterBasic -----------------------$basefolder Dec 12 12:25:18 volumiopi3 volumio[1172]: info: PeppyMeterBasic -----------------------screensize320x240 Dec 12 12:25:18 volumiopi3 volumio[1172]: info: PeppyMeterBasic -----------------------screenwidth Dec 12 12:25:18 volumiopi3 volumio[1172]: info: PeppyMeterBasic -----------------------screenheight Dec 12 12:25:18 volumiopi3 volumio[1172]: info: PeppyMeterBasic -----------------------metersize30 Dec 12 12:25:18 volumiopi3 volumio[1172]: error: PeppyMeterBasic ---Error writing config null Dec 12 12:25:18 volumiopi3 sudo[4401]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppymeterbasic.service Dec 12 12:25:18 volumiopi3 sudo[4401]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:18 volumiopi3 volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:25:18 volumiopi3 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Dec 12 12:25:18 volumiopi3 systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 12 12:25:18 volumiopi3 sudo[4401]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:18 volumiopi3 volumio[1172]: info: peppymeterbasic Daemon Started Dec 12 12:25:19 volumiopi3 volumio[1172]: info: TidalConnect service started! Dec 12 12:25:19 volumiopi3 sudo[4407]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 12 12:25:19 volumiopi3 sudo[4407]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:19 volumiopi3 sudo[4410]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd_monitor.service Dec 12 12:25:19 volumiopi3 sudo[4410]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:19 volumiopi3 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 12 12:25:19 volumiopi3 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 12 12:25:19 volumiopi3 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 12 12:25:19 volumiopi3 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 12 12:25:19 volumiopi3 sudo[4407]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:19 volumiopi3 mpd_monitor.sh[4412]: MPD Monitor Service: Starting MPD Monitor Service Dec 12 12:25:19 volumiopi3 volumio[1172]: info: Successfully started MPD Monitor Dec 12 12:25:19 volumiopi3 systemd[1]: Stopping mpd_monitor.service - MPD Monitor Service... Dec 12 12:25:19 volumiopi3 systemd[1]: mpd_monitor.service: Deactivated successfully. Dec 12 12:25:19 volumiopi3 systemd[1]: Stopped mpd_monitor.service - MPD Monitor Service. Dec 12 12:25:19 volumiopi3 systemd[1]: Started mpd_monitor.service - MPD Monitor Service. Dec 12 12:25:19 volumiopi3 sudo[4410]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:19 volumiopi3 mpd_monitor.sh[4414]: MPD Monitor Service: Starting MPD Monitor Service Dec 12 12:25:19 volumiopi3 volumio[1172]: info: Successfully started MPD Monitor Dec 12 12:25:21 volumiopi3 ntpd[892]: PROTO: 89.46.74.148 unlink local addr 192.168.88.49 -> Dec 12 12:25:23 volumiopi3 volumio[4405]: error: XDG_RUNTIME_DIR is invalid or not set in the environment. Dec 12 12:25:23 volumiopi3 volumio[1172]: info: CALLMETHOD: user_interface peppymeterbasic updatelist Dec 12 12:25:23 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , updatelist Dec 12 12:25:23 volumiopi3 volumio[1172]: info: PeppyMeterBasic --- wget 'https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt' Dec 12 12:25:24 volumiopi3 ntpd[892]: PROTO: 172.232.209.103 unlink local addr 192.168.88.49 -> Dec 12 12:25:24 volumiopi3 volumio[1172]: --2025-12-12 12:25:23-- https://github.com/balbuze/Meter-peppymeter/raw/main/meterslist.txt Dec 12 12:25:24 volumiopi3 volumio[1172]: Resolving github.com (github.com)... 140.82.121.4 Dec 12 12:25:24 volumiopi3 volumio[1172]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Dec 12 12:25:24 volumiopi3 volumio[1172]: HTTP request sent, awaiting response... 302 Found Dec 12 12:25:24 volumiopi3 volumio[1172]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt [following] Dec 12 12:25:24 volumiopi3 volumio[1172]: --2025-12-12 12:25:24-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/meterslist.txt Dec 12 12:25:24 volumiopi3 volumio[1172]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.109.133, 185.199.108.133, 185.199.111.133, ... Dec 12 12:25:24 volumiopi3 volumio[1172]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.109.133|:443... connected. Dec 12 12:25:24 volumiopi3 volumio[1172]: HTTP request sent, awaiting response... 200 OK Dec 12 12:25:24 volumiopi3 volumio[1172]: Length: 362 [text/plain] Dec 12 12:25:24 volumiopi3 volumio[1172]: Saving to: ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ Dec 12 12:25:24 volumiopi3 volumio[1172]: 0K 100% 2.89M=0s Dec 12 12:25:24 volumiopi3 volumio[1172]: 2025-12-12 12:25:24 (2.89 MB/s) - ‘/data/plugins/user_interface/peppymeterbasic/meterslist.txt’ saved [362/362] Dec 12 12:25:24 volumiopi3 volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:25:24 volumiopi3 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Dec 12 12:25:25 volumiopi3 volumio[4405]: libpng warning: iCCP: known incorrect sRGB profile Dec 12 12:25:25 volumiopi3 volumio[4405]: libpng warning: iCCP: known incorrect sRGB profile Dec 12 12:25:25 volumiopi3 volumio[4405]: libpng warning: iCCP: known incorrect sRGB profile Dec 12 12:25:27 volumiopi3 volumio[1172]: info: CALLMETHOD: user_interface peppymeterbasic dlmeter [object Object] Dec 12 12:25:27 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , dlmeter Dec 12 12:25:30 volumiopi3 volumio[1172]: --2025-12-12 12:25:27-- https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/1024x600-Gelo5-BASIC_121.zip Dec 12 12:25:30 volumiopi3 volumio[1172]: Resolving github.com (github.com)... 140.82.121.4 Dec 12 12:25:30 volumiopi3 volumio[1172]: Connecting to github.com (github.com)|140.82.121.4|:443... connected. Dec 12 12:25:30 volumiopi3 volumio[1172]: HTTP request sent, awaiting response... 302 Found Dec 12 12:25:30 volumiopi3 volumio[1172]: Location: https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/Zipped-folders/1024x600-Gelo5-BASIC_121.zip [following] Dec 12 12:25:30 volumiopi3 volumio[1172]: --2025-12-12 12:25:28-- https://raw.githubusercontent.com/balbuze/Meter-peppymeter/main/Zipped-folders/1024x600-Gelo5-BASIC_121.zip Dec 12 12:25:30 volumiopi3 volumio[1172]: Resolving raw.githubusercontent.com (raw.githubusercontent.com)... 185.199.108.133, 185.199.111.133, 185.199.110.133, ... Dec 12 12:25:30 volumiopi3 volumio[1172]: Connecting to raw.githubusercontent.com (raw.githubusercontent.com)|185.199.108.133|:443... connected. Dec 12 12:25:30 volumiopi3 volumio[1172]: HTTP request sent, awaiting response... 200 OK Dec 12 12:25:30 volumiopi3 volumio[1172]: Length: 7807998 (7.4M) [application/zip] Dec 12 12:25:30 volumiopi3 volumio[1172]: Saving to: ‘/tmp/1024x600-Gelo5-BASIC_121.zip’ Dec 12 12:25:30 volumiopi3 volumio[1172]: 0K .......... .......... .......... .......... .......... 0% 1.11M 7s Dec 12 12:25:30 volumiopi3 volumio[1172]: 50K .......... .......... .......... .......... .......... 1% 4.10M 4s Dec 12 12:25:30 volumiopi3 volumio[1172]: 100K .......... .......... .......... .......... .......... 1% 2.01M 4s Dec 12 12:25:30 volumiopi3 volumio[1172]: 150K .......... .......... .......... .......... .......... 2% 6.26M 3s Dec 12 12:25:30 volumiopi3 volumio[1172]: 200K .......... .......... .......... .......... .......... 3% 7.45M 3s Dec 12 12:25:30 volumiopi3 volumio[1172]: 250K .......... .......... .......... .......... .......... 3% 2.64M 3s Dec 12 12:25:30 volumiopi3 volumio[1172]: 300K .......... .......... .......... .......... .......... 4% 11.4M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 350K .......... .......... .......... .......... .......... 5% 9.24M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 400K .......... .......... .......... .......... .......... 5% 1.17M 3s Dec 12 12:25:30 volumiopi3 volumio[1172]: 450K .......... .......... .......... .......... .......... 6% 10.3M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 500K .......... .......... .......... .......... .......... 7% 13.8M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 550K .......... .......... .......... .......... .......... 7% 3.80M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 600K .......... .......... .......... .......... .......... 8% 9.41M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 650K .......... .......... .......... .......... .......... 9% 13.8M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 700K .......... .......... .......... .......... .......... 9% 1.30M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 750K .......... .......... .......... .......... .......... 10% 10.7M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 800K .......... .......... .......... .......... .......... 11% 14.3M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 850K .......... .......... .......... .......... .......... 11% 14.1M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 900K .......... .......... .......... .......... .......... 12% 13.9M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 950K .......... .......... .......... .......... .......... 13% 14.5M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1000K .......... .......... .......... .......... .......... 13% 15.3M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1050K .......... .......... .......... .......... .......... 14% 15.6M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1100K .......... .......... .......... .......... .......... 15% 15.2M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1150K .......... .......... .......... .......... .......... 15% 401K 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1200K .......... .......... .......... .......... .......... 16% 14.0M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1250K .......... .......... .......... .......... .......... 17% 13.1M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1300K .......... .......... .......... .......... .......... 17% 14.2M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1350K .......... .......... .......... .......... .......... 18% 14.3M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1400K .......... .......... .......... .......... .......... 19% 15.3M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1450K .......... .......... .......... .......... .......... 19% 15.4M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1500K .......... .......... .......... .......... .......... 20% 8.30M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1550K .......... .......... .......... .......... .......... 20% 11.7M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1600K .......... .......... .......... .......... .......... 21% 14.6M 2s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1650K .......... .......... .......... .......... .......... 22% 14.4M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1700K .......... .......... .......... .......... .......... 22% 14.0M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1750K .......... .......... .......... .......... .......... 23% 14.3M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1800K .......... .......... .......... .......... .......... 24% 14.6M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1850K .......... .......... .......... .......... .......... 24% 14.7M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1900K .......... .......... .......... .......... .......... 25% 14.6M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 1950K .......... .......... .......... .......... .......... 26% 10.9M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2000K .......... .......... .......... .......... .......... 26% 15.0M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2050K .......... .......... .......... .......... .......... 27% 14.9M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2100K .......... .......... .......... .......... .......... 28% 14.2M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2150K .......... .......... .......... .......... .......... 28% 7.66M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2200K .......... .......... .......... .......... .......... 29% 13.5M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2250K .......... .......... .......... .......... .......... 30% 14.9M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2300K .......... .......... .......... .......... .......... 30% 14.9M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2350K .......... .......... .......... .......... .......... 31% 3.06M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2400K .......... .......... .......... .......... .......... 32% 5.62M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2450K .......... .......... .......... .......... .......... 32% 3.48M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2500K .......... .......... .......... .......... .......... 33% 2.23M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2550K .......... .......... .......... .......... .......... 34% 3.68M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2600K .......... .......... .......... .......... .......... 34% 2.39M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2650K .......... .......... .......... .......... .......... 35% 3.73M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2700K .......... .......... .......... .......... .......... 36% 3.77M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2750K .......... .......... .......... .......... .......... 36% 2.02M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2800K .......... .......... .......... .......... .......... 37% 3.77M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2850K .......... .......... .......... .......... .......... 38% 2.52M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2900K .......... .......... .......... .......... .......... 38% 3.88M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 2950K .......... .......... .......... .......... .......... 39% 2.40M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3000K .......... .......... .......... .......... .......... 40% 3.75M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3050K .......... .......... .......... .......... .......... 40% 2.74M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3100K .......... .......... .......... .......... .......... 41% 3.52M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3150K .......... .......... .......... .......... .......... 41% 2.23M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3200K .......... .......... .......... .......... .......... 42% 3.46M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3250K .......... .......... .......... .......... .......... 43% 3.73M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3300K .......... .......... .......... .......... .......... 43% 2.62M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3350K .......... .......... .......... .......... .......... 44% 3.61M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3400K .......... .......... .......... .......... .......... 45% 2.88M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3450K .......... .......... .......... .......... .......... 45% 3.43M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3500K .......... .......... .......... .......... .......... 46% 3.41M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3550K .......... .......... .......... .......... .......... 47% 2.35M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3600K .......... .......... .......... .......... .......... 47% 3.31M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3650K .......... .......... .......... .......... .......... 48% 2.87M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3700K .......... .......... .......... .......... .......... 49% 3.47M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3750K .......... .......... .......... .......... .......... 49% 2.90M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3800K .......... .......... .......... .......... .......... 50% 3.85M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3850K .......... .......... .......... .......... .......... 51% 3.02M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3900K .......... .......... .......... .......... .......... 51% 3.31M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 3950K .......... .......... .......... .......... .......... 52% 2.66M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 4000K .......... .......... .......... .......... .......... 53% 2.95M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 4050K .......... .......... .......... .......... .......... 53% 3.45M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 4100K .......... .......... .......... .......... .......... 54% 3.01M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 4150K .......... .......... .......... .......... .......... 55% 3.94M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 4200K .......... .......... .......... .......... .......... 55% 2.85M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 4250K .......... .......... .......... .......... .......... 56% 3.52M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 4300K .......... .......... .......... .......... .......... 57% 3.21M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 4350K .......... .......... .......... .......... .......... 57% 2.62M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 4400K .......... .......... .......... .......... .......... 58% 3.31M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 4450K .......... .......... .......... .......... .......... 59% 3.05M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 4500K .......... .......... .......... .......... .......... 59% 3.74M 1s Dec 12 12:25:30 volumiopi3 volumio[1172]: 4550K .......... .......... .......... .......... .......... 60% 3.10M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 4600K .......... .......... .......... .......... .......... 60% 3.39M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 4650K .......... .......... .......... .......... .......... 61% 3.75M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 4700K .......... .......... .......... .......... .......... 62% 3.12M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 4750K .......... .......... .......... .......... .......... 62% 2.87M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 4800K .......... .......... .......... .......... .......... 63% 2.99M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 4850K .......... .......... .......... .......... .......... 64% 3.54M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 4900K .......... .......... .......... .......... .......... 64% 3.42M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 4950K .......... .......... .......... .......... .......... 65% 2.81M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5000K .......... .......... .......... .......... .......... 66% 4.55M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5050K .......... .......... .......... .......... .......... 66% 3.22M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5100K .......... .......... .......... .......... .......... 67% 3.23M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5150K .......... .......... .......... .......... .......... 68% 2.74M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5200K .......... .......... .......... .......... .......... 68% 3.27M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5250K .......... .......... .......... .......... .......... 69% 3.90M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5300K .......... .......... .......... .......... .......... 70% 3.31M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5350K .......... .......... .......... .......... .......... 70% 3.33M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5400K .......... .......... .......... .......... .......... 71% 3.78M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5450K .......... .......... .......... .......... .......... 72% 3.13M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5500K .......... .......... .......... .......... .......... 72% 3.87M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5550K .......... .......... .......... .......... .......... 73% 2.72M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5600K .......... .......... .......... .......... .......... 74% 3.46M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5650K .......... .......... .......... .......... .......... 74% 3.30M 1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5700K .......... .......... .......... .......... .......... 75% 3.56M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5750K .......... .......... .......... .......... .......... 76% 3.40M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5800K .......... .......... .......... .......... .......... 76% 3.79M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5850K .......... .......... .......... .......... .......... 77% 3.83M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5900K .......... .......... .......... .......... .......... 78% 3.54M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 5950K .......... .......... .......... .......... .......... 78% 2.54M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6000K .......... .......... .......... .......... .......... 79% 3.75M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6050K .......... .......... .......... .......... .......... 80% 3.38M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6100K .......... .......... .......... .......... .......... 80% 3.08M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6150K .......... .......... .......... .......... .......... 81% 4.49M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6200K .......... .......... .......... .......... .......... 81% 3.35M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6250K .......... .......... .......... .......... .......... 82% 3.51M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6300K .......... .......... .......... .......... .......... 83% 3.92M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6350K .......... .......... .......... .......... .......... 83% 2.72M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6400K .......... .......... .......... .......... .......... 84% 4.00M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6450K .......... .......... .......... .......... .......... 85% 3.45M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6500K .......... .......... .......... .......... .......... 85% 1.02M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6550K .......... .......... .......... .......... .......... 86% 2.35M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6600K .......... .......... .......... .......... .......... 87% 3.53M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6650K .......... .......... .......... .......... .......... 87% 3.16M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6700K .......... .......... .......... .......... .......... 88% 4.85M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6750K .......... .......... .......... .......... .......... 89% 2.51M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6800K .......... .......... .......... .......... .......... 89% 4.03M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6850K .......... .......... .......... .......... .......... 90% 3.62M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6900K .......... .......... .......... .......... .......... 91% 3.56M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 6950K .......... .......... .......... .......... .......... 91% 4.17M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 7000K .......... .......... .......... .......... .......... 92% 3.26M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 7050K .......... .......... .......... .......... .......... 93% 3.96M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 7100K .......... .......... .......... .......... .......... 93% 4.23M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 7150K .......... .......... .......... .......... .......... 94% 2.56M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 7200K .......... .......... .......... .......... .......... 95% 4.35M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 7250K .......... .......... .......... .......... .......... 95% 3.72M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 7300K .......... .......... .......... .......... .......... 96% 3.33M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 7350K .......... .......... .......... .......... .......... 97% 4.16M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 7400K .......... .......... .......... .......... .......... 97% 3.45M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 7450K .......... .......... .......... .......... .......... 98% 3.92M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 7500K .......... .......... .......... .......... .......... 99% 4.22M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 7550K .......... .......... .......... .......... .......... 99% 2.57M 0s Dec 12 12:25:31 volumiopi3 volumio[1172]: 7600K .......... .......... .... 100% 4.40M=2.1s Dec 12 12:25:31 volumiopi3 volumio[1172]: 2025-12-12 12:25:30 (3.58 MB/s) - ‘/tmp/1024x600-Gelo5-BASIC_121.zip’ saved [7807998/7807998] Dec 12 12:25:31 volumiopi3 sudo[4457]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod -R 777 /data/INTERNAL/PeppyMeterBasic/Templates/ Dec 12 12:25:31 volumiopi3 sudo[4457]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:31 volumiopi3 sudo[4457]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:31 volumiopi3 volumio[1172]: info: PeppyMeterBasic ---message miniunzip -o /tmp/1024x600-Gelo5-BASIC_121.zip -d /data/INTERNAL/PeppyMeterBasic/Templates/ Dec 12 12:25:31 volumiopi3 volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:25:31 volumiopi3 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Dec 12 12:25:36 volumiopi3 volumio[1172]: info: CALLMETHOD: user_interface peppymeterbasic savepeppy [object Object] Dec 12 12:25:36 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , savepeppy Dec 12 12:25:36 volumiopi3 volumio[1172]: aaaaaaaaaaa 1024,600-Gelo5-BASIC_121 Dec 12 12:25:36 volumiopi3 volumio[1172]: info: PeppyMeterBasic ---1024600-Gelo5-BASIC_121 Dec 12 12:25:36 volumiopi3 volumio[1172]: info: PeppyMeterBasic ---1024600NaN Dec 12 12:25:36 volumiopi3 volumio[1172]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 12 12:25:36 volumiopi3 volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:25:36 volumiopi3 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Dec 12 12:25:38 volumiopi3 volumio[1172]: info: CALLMETHOD: user_interface peppymeterbasic savepeppy1 [object Object] Dec 12 12:25:38 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , savepeppy1 Dec 12 12:25:38 volumiopi3 volumio[1172]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Dec 12 12:25:38 volumiopi3 volumio[1172]: info: PeppyMeterBasic -----------------------meterrandom Dec 12 12:25:38 volumiopi3 sudo[4482]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppymeterbasic.service Dec 12 12:25:38 volumiopi3 volumio[1172]: info: PeppyMeterBasic -----------------------$basefolder/data/INTERNAL/PeppyMeterBasic/Templates Dec 12 12:25:38 volumiopi3 volumio[1172]: info: PeppyMeterBasic -----------------------screensize1024x600-Gelo5-BASIC_121 Dec 12 12:25:38 volumiopi3 volumio[1172]: info: PeppyMeterBasic -----------------------screenwidth1024 Dec 12 12:25:38 volumiopi3 volumio[1172]: info: PeppyMeterBasic -----------------------screenheight600 Dec 12 12:25:38 volumiopi3 volumio[1172]: info: PeppyMeterBasic -----------------------metersize30 Dec 12 12:25:38 volumiopi3 volumio[1172]: error: PeppyMeterBasic ---Error writing config null Dec 12 12:25:38 volumiopi3 sudo[4482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:25:38 volumiopi3 volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:25:38 volumiopi3 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Dec 12 12:25:39 volumiopi3 systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Dec 12 12:25:39 volumiopi3 systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Dec 12 12:25:40 volumiopi3 systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Dec 12 12:25:40 volumiopi3 systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Dec 12 12:25:40 volumiopi3 systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Dec 12 12:25:40 volumiopi3 systemd[1]: peppymeterbasic.service: State 'final-sigterm' timed out. Killing. Dec 12 12:25:40 volumiopi3 systemd[1]: peppymeterbasic.service: Killing process 4405 (python3) with signal SIGKILL. Dec 12 12:25:40 volumiopi3 systemd[1]: peppymeterbasic.service: Failed with result 'timeout'. Dec 12 12:25:40 volumiopi3 systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Dec 12 12:25:40 volumiopi3 systemd[1]: peppymeterbasic.service: Consumed 8.187s CPU time. Dec 12 12:25:40 volumiopi3 systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Dec 12 12:25:40 volumiopi3 sudo[4482]: pam_unix(sudo:session): session closed for user root Dec 12 12:25:40 volumiopi3 volumio[1172]: info: peppymeterbasic Daemon Started Dec 12 12:25:40 volumiopi3 kernel: hwmon hwmon1: Undervoltage detected! Dec 12 12:25:42 volumiopi3 volumio[4494]: error: XDG_RUNTIME_DIR is invalid or not set in the environment. Dec 12 12:25:44 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:25:44 volumiopi3 kernel: hwmon hwmon1: Voltage normalised Dec 12 12:25:46 volumiopi3 volumio[1172]: info: Executing endpoint metavolumio Dec 12 12:25:46 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 12:25:46 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 12 12:25:46 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 12 12:25:58 volumiopi3 volumio[1172]: info: Downloading plugin at https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/radio_paradise/volumio/bookworm/armhf Dec 12 12:25:59 volumiopi3 volumio[1172]: info: END DOWNLOAD: https://plugins.volumio.workers.dev/pluginsv2/downloadLatest/radio_paradise/volumio/bookworm/armhf Dec 12 12:25:59 volumiopi3 volumio[1172]: info: Folder /tmp/plugins removed Dec 12 12:25:59 volumiopi3 volumio[1172]: info: Check plugin dependencies Dec 12 12:25:59 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 12 12:25:59 volumiopi3 volumio[1172]: info: Checking if plugin already exists Dec 12 12:25:59 volumiopi3 volumio[1172]: info: Rename folder Dec 12 12:25:59 volumiopi3 volumio[1172]: info: Folder /tmp/downloaded_plugin.zip removed Dec 12 12:25:59 volumiopi3 volumio[1172]: info: Move to category Dec 12 12:26:01 volumiopi3 kernel: hwmon hwmon1: Undervoltage detected! Dec 12 12:26:04 volumiopi3 volumio[1172]: info: Checking if install.sh is present Dec 12 12:26:04 volumiopi3 volumio[1172]: info: Executing install.sh Dec 12 12:26:05 volumiopi3 sudo[4562]: volumio : PWD=/ ; USER=root ; COMMAND=/usr/bin/sh /data/plugins/music_service/radio_paradise/install.sh Dec 12 12:26:05 volumiopi3 sudo[4562]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:26:05 volumiopi3 sudo[4562]: pam_unix(sudo:session): session closed for user root Dec 12 12:26:05 volumiopi3 volumio[1172]: info: Install script completed Dec 12 12:26:05 volumiopi3 volumio[1172]: info: Adding reference to registry Dec 12 12:26:05 volumiopi3 volumio[1172]: info: Done installing plugin. Dec 12 12:26:05 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 12 12:26:05 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 12 12:26:05 volumiopi3 volumio[1172]: info: Folder /tmp/plugins removed Dec 12 12:26:05 volumiopi3 volumio[1172]: info: Folder /tmp/downloaded_plugin.zip removed Dec 12 12:26:05 volumiopi3 volumio[1172]: info: Folder /data/temp removed Dec 12 12:26:05 volumiopi3 kernel: hwmon hwmon1: Voltage normalised Dec 12 12:26:06 volumiopi3 volumio[1172]: info: Enabling plugin radio_paradise Dec 12 12:26:06 volumiopi3 volumio[1172]: info: Loading plugin "radio_paradise"... Dec 12 12:26:06 volumiopi3 volumio[1172]: info: [1765538766843] [RadioParadise] API delay: 5 Dec 12 12:26:06 volumiopi3 volumio[1172]: info: PLUGIN START: radio_paradise Dec 12 12:26:06 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 12 12:26:06 volumiopi3 volumio[1172]: info: [1765538766851] CoreMusicLibrary::Adding element Radio Paradise Dec 12 12:26:06 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 12:26:06 volumiopi3 volumio[1172]: Cannot find translation for source Radio Paradise Dec 12 12:26:06 volumiopi3 volumio[1172]: info: Done. Dec 12 12:26:21 volumiopi3 ntpd[892]: PROTO: 95.110.254.234 unlink local addr 192.168.88.49 -> Dec 12 12:26:22 volumiopi3 volumio[1172]: info: Enabling plugin radio_paradise Dec 12 12:26:22 volumiopi3 volumio[1172]: info: Loading plugin "radio_paradise"... Dec 12 12:26:22 volumiopi3 volumio[1172]: info: Applying required configuration parameters for plugin radio_paradise Dec 12 12:26:22 volumiopi3 volumio[1172]: info: [1765538782101] [RadioParadise] API delay: 5 Dec 12 12:26:22 volumiopi3 volumio[1172]: info: PLUGIN START: radio_paradise Dec 12 12:26:22 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Dec 12 12:26:22 volumiopi3 volumio[1172]: info: [1765538782105] CoreMusicLibrary::Adding element Radio Paradise Dec 12 12:26:22 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 12:26:22 volumiopi3 volumio[1172]: Cannot find translation for source Radio Paradise Dec 12 12:26:22 volumiopi3 volumio[1172]: info: Done. Dec 12 12:26:27 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:26:27 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetQueue Dec 12 12:26:27 volumiopi3 volumio[1172]: info: CoreStateMachine::getQueue Dec 12 12:26:27 volumiopi3 volumio[1172]: info: CorePlayQueue::getQueue Dec 12 12:26:28 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: radio_paradise , handleBrowseUri Dec 12 12:26:28 volumiopi3 volumio[1172]: info: Preload queue cleared Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Executing endpoint metavolumio Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Preload queue cleared Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::ClearQueue Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::stop Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::serviceStop Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Received STOP, but no service to execute it Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::clearPlayQueue Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::saveQueue Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioPushQueue Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::addQueueItems Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::addQueueItems Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Preload queue cleared Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Adding Item to queue: webrp/0 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Exploding uri webrp/0 in service radio_paradise Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioPushQueue Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::saveQueue Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::updateTrackBlock Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrackBlock Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: play , [object Object] Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioPlay Dec 12 12:26:29 volumiopi3 volumio[1172]: verbose: UNSET VOLATILE: Service: undefined Dec 12 12:26:29 volumiopi3 volumio[1172]: ------------------------------------ BT MESSAGE: [FUNC] detachBluetooth Dec 12 12:26:29 volumiopi3 volumio[1172]: ------------------------------------ BT MESSAGE: [FUNC] btAudioOutput Dec 12 12:26:29 volumiopi3 volumio[1172]: ------------------------------------ BT MESSAGE: [dbus-next] Disabling Bluetooth Audio Output Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::play index 0 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 12:26:29 volumiopi3 volumio[1172]: ------------------------------------ BT MESSAGE: Bluetooth audio output disabled. Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::stop Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::resetVolumioState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::getcurrentVolume Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioRetrievevolume Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::play index undefined Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::pushState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Dec 12 12:26:29 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioStop Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::stop Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::startPlaybackTimer Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetVisibleSources Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStatsObject Dec 12 12:26:29 volumiopi3 volumio[1172]: info: [RadioParadise] Matched station: Radio Paradise Main Mix (FLAC), chan=0 Dec 12 12:26:29 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand stop Dec 12 12:26:29 volumiopi3 volumio[1172]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 12 12:26:29 volumiopi3 volumio[1172]: info: sendMpdCommand stop took 90 milliseconds Dec 12 12:26:29 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand clear Dec 12 12:26:29 volumiopi3 sudo[4619]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 12 12:26:29 volumiopi3 sudo[4619]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:26:29 volumiopi3 upmpdcli[1321]: :3:../src/mpdcli.cxx:148::MPDCli::startEventLoop: already started Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Dec 12 12:26:29 volumiopi3 volumio[1172]: ---------------------------- MPD announces system playlist update Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Ignoring MPD Status Update Dec 12 12:26:29 volumiopi3 volumio[1172]: info: sendMpdCommand clear took 27 milliseconds Dec 12 12:26:29 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand consume 1 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Dec 12 12:26:29 volumiopi3 volumio[1172]: ---------------------------- MPD announces system playlist update Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Ignoring MPD Status Update Dec 12 12:26:29 volumiopi3 volumio[1172]: error: updateQueue error: null Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Dec 12 12:26:29 volumiopi3 volumio[1172]: ---------------------------- MPD announces state update: options Dec 12 12:26:29 volumiopi3 volumio[1172]: error: updateQueue error: null Dec 12 12:26:29 volumiopi3 volumio[1172]: info: ------------------------------ 24ms Dec 12 12:26:29 volumiopi3 volumio[1172]: info: sendMpdCommand consume 1 took 21 milliseconds Dec 12 12:26:29 volumiopi3 volumio[1172]: info: ControllerMpd::getState Dec 12 12:26:29 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Dec 12 12:26:29 volumiopi3 volumio[1172]: info: ------------------------------ 20ms Dec 12 12:26:29 volumiopi3 volumio[1172]: info: [1765538789395] [RadioParadise] set to consume mode, adding url: https://stream.radioparadise.com/flacm Dec 12 12:26:29 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand add "https://stream.radioparadise.com/flacm" Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Dec 12 12:26:29 volumiopi3 volumio[1172]: ---------------------------- MPD announces state update: options Dec 12 12:26:29 volumiopi3 volumio[1172]: info: ControllerMpd::getState Dec 12 12:26:29 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Dec 12 12:26:29 volumiopi3 volumio[1172]: ---------------------------- MPD announces system playlist update Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Ignoring MPD Status Update Dec 12 12:26:29 volumiopi3 volumio[1172]: info: sendMpdCommand status took 16 milliseconds Dec 12 12:26:29 volumiopi3 volumio[1172]: info: sendMpdCommand add "https://stream.radioparadise.com/flacm" took 15 milliseconds Dec 12 12:26:29 volumiopi3 volumio[1172]: info: sendMpdCommand status took 5 milliseconds Dec 12 12:26:29 volumiopi3 volumio[1172]: verbose: ControllerMpd::parseState Dec 12 12:26:29 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand play Dec 12 12:26:29 volumiopi3 volumio[1172]: verbose: ControllerMpd::parseState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: ControllerMpd::pushState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::servicePushState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::pushState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Dec 12 12:26:29 volumiopi3 systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Dec 12 12:26:29 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 12 12:26:29 volumiopi3 volumio[1172]: info: ControllerMpd::pushState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::servicePushState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreStateMachine::pushState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioPushState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output update for this device Dec 12 12:26:29 volumiopi3 volumio[1172]: info: MRS: Pushing multiroomSync output Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 12 12:26:29 volumiopi3 volumio[1172]: info: ------------------------------ 103ms Dec 12 12:26:29 volumiopi3 volumio[1172]: info: ------------------------------ 123ms Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Dec 12 12:26:29 volumiopi3 volumio[1172]: ---------------------------- MPD announces system playlist update Dec 12 12:26:29 volumiopi3 volumio[1172]: info: Ignoring MPD Status Update Dec 12 12:26:29 volumiopi3 volumio[1172]: info: ------------------------------ 127ms Dec 12 12:26:29 volumiopi3 volumio[1172]: info: sendMpdCommand play took 113 milliseconds Dec 12 12:26:29 volumiopi3 volumio[1172]: info: ------------------------------ 16ms Dec 12 12:26:29 volumiopi3 volumio[1172]: info: [RadioParadise] Using metadata URL: https://api.radioparadise.com/api/now_playing?chan=0 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: [1765538789538] [RadioParadise] getMetadata started with url https://api.radioparadise.com/api/now_playing?chan=0 Dec 12 12:26:29 volumiopi3 volumio[1172]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 12 12:26:29 volumiopi3 volumio[1172]: info: PeppyMeterBasic ---peppymeterbasic status stop Dec 12 12:26:29 volumiopi3 systemd[1]: peppymeterbasic.service: Deactivated successfully. Dec 12 12:26:29 volumiopi3 systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Dec 12 12:26:29 volumiopi3 systemd[1]: peppymeterbasic.service: Consumed 1min 877ms CPU time. Dec 12 12:26:29 volumiopi3 sudo[4625]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 12 12:26:29 volumiopi3 sudo[4625]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:26:29 volumiopi3 sudo[4619]: pam_unix(sudo:session): session closed for user root Dec 12 12:26:29 volumiopi3 volumio[1172]: info: peppymeterbasic Daemon Stop Dec 12 12:26:29 volumiopi3 sudo[4627]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Dec 12 12:26:29 volumiopi3 sudo[4627]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:26:29 volumiopi3 sudo[4625]: pam_unix(sudo:session): session closed for user root Dec 12 12:26:29 volumiopi3 volumio[1172]: info: peppymeterbasic Daemon Stop Dec 12 12:26:29 volumiopi3 sudo[4627]: pam_unix(sudo:session): session closed for user root Dec 12 12:26:29 volumiopi3 volumio[1172]: info: peppymeterbasic Daemon Stop Dec 12 12:26:30 volumiopi3 volumio[1172]: info: Dec 12 12:26:30 volumiopi3 volumio[1172]: ---------------------------- MPD announces state update: player Dec 12 12:26:30 volumiopi3 volumio[1172]: info: ControllerMpd::getState Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Dec 12 12:26:30 volumiopi3 volumio[1172]: info: Dec 12 12:26:30 volumiopi3 volumio[1172]: ---------------------------- MPD announces state update: player Dec 12 12:26:30 volumiopi3 volumio[1172]: info: ControllerMpd::getState Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Dec 12 12:26:30 volumiopi3 volumio[1172]: info: [1765538790321] [RadioParadise] received new metadata: {"time":50,"artist":"Travis","title":"Love Will Come Through","album":"12 Memories","year":"2003","cover":"https://img.radioparadise.com/covers/l/10340.jpg","cover_med":"https://img.radioparadise.com/covers/m/10340.jpg","cover_small":"https://img.radioparadise.com/covers/s/10340.jpg"} Dec 12 12:26:30 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:30 volumiopi3 volumio[1172]: info: CoreCommandRouter::servicePushState Dec 12 12:26:30 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: STATE SERVICE {"status":"play","service":"radio_paradise","type":"webradio","trackType":"flac","radioType":"","albumart":"https://img.radioparadise.com/covers/l/10340.jpg","uri":"https://stream.radioparadise.com/flacm","name":"Love Will Come Through","title":"Love Will Come Through","artist":"Travis","album":"12 Memories","streaming":true,"disableUiControls":true,"duration":55,"seek":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","channels":2} Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: CURRENT POSITION 0 Dec 12 12:26:30 volumiopi3 volumio[1172]: info: CoreStateMachine::syncState stateService play Dec 12 12:26:30 volumiopi3 volumio[1172]: info: CoreStateMachine::syncState currentStatus stop Dec 12 12:26:30 volumiopi3 volumio[1172]: info: [1765538790325] [RadioParadise] setting new timer with duration of 55000 seconds. Dec 12 12:26:30 volumiopi3 volumio[1172]: info: sendMpdCommand status took 428 milliseconds Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::parseState Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 12:26:30 volumiopi3 volumio[1172]: info: Dec 12 12:26:30 volumiopi3 volumio[1172]: ---------------------------- MPD announces state update: player Dec 12 12:26:30 volumiopi3 volumio[1172]: info: ControllerMpd::getState Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Dec 12 12:26:30 volumiopi3 volumio[1172]: info: Dec 12 12:26:30 volumiopi3 volumio[1172]: ---------------------------- MPD announces system playlist update Dec 12 12:26:30 volumiopi3 volumio[1172]: info: Ignoring MPD Status Update Dec 12 12:26:30 volumiopi3 volumio[1172]: info: Dec 12 12:26:30 volumiopi3 volumio[1172]: ---------------------------- MPD announces state update: player Dec 12 12:26:30 volumiopi3 volumio[1172]: info: ControllerMpd::getState Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Dec 12 12:26:30 volumiopi3 volumio[1172]: info: sendMpdCommand status took 432 milliseconds Dec 12 12:26:30 volumiopi3 volumio[1172]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::parseState Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Dec 12 12:26:30 volumiopi3 volumio[1172]: info: ControllerMpd::pushState Dec 12 12:26:30 volumiopi3 volumio[1172]: info: CoreCommandRouter::servicePushState Dec 12 12:26:30 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:30 volumiopi3 volumio[1172]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 12 12:26:30 volumiopi3 volumio[1172]: info: ------------------------------ 444ms Dec 12 12:26:30 volumiopi3 volumio[1172]: info: sendMpdCommand status took 12 milliseconds Dec 12 12:26:30 volumiopi3 volumio[1172]: info: ------------------------------ 11ms Dec 12 12:26:30 volumiopi3 volumio[1172]: info: sendMpdCommand status took 9 milliseconds Dec 12 12:26:30 volumiopi3 volumio[1172]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::parseState Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::parseState Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Dec 12 12:26:30 volumiopi3 volumio[1172]: info: ControllerMpd::pushState Dec 12 12:26:30 volumiopi3 volumio[1172]: info: CoreCommandRouter::servicePushState Dec 12 12:26:30 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:30 volumiopi3 volumio[1172]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 12 12:26:30 volumiopi3 volumio[1172]: info: ------------------------------ 450ms Dec 12 12:26:30 volumiopi3 volumio[1172]: info: Dec 12 12:26:30 volumiopi3 volumio[1172]: ---------------------------- MPD announces system playlist update Dec 12 12:26:30 volumiopi3 volumio[1172]: info: Ignoring MPD Status Update Dec 12 12:26:30 volumiopi3 volumio[1172]: info: sendMpdCommand playlistinfo took 7 milliseconds Dec 12 12:26:30 volumiopi3 volumio[1172]: info: sendMpdCommand playlistinfo took 6 milliseconds Dec 12 12:26:30 volumiopi3 volumio[1172]: info: ------------------------------ 4ms Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Dec 12 12:26:30 volumiopi3 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Dec 12 12:26:30 volumiopi3 volumio[1172]: info: ControllerMpd::pushState Dec 12 12:26:30 volumiopi3 volumio[1172]: info: CoreCommandRouter::servicePushState Dec 12 12:26:30 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:30 volumiopi3 volumio[1172]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 12 12:26:30 volumiopi3 volumio[1172]: info: ControllerMpd::pushState Dec 12 12:26:30 volumiopi3 volumio[1172]: info: CoreCommandRouter::servicePushState Dec 12 12:26:30 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:30 volumiopi3 volumio[1172]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 12 12:26:30 volumiopi3 volumio[1172]: info: ------------------------------ 28ms Dec 12 12:26:30 volumiopi3 volumio[1172]: info: ------------------------------ 25ms Dec 12 12:26:32 volumiopi3 volumio[1172]: info: CoreCommandRouter::volumioGetState Dec 12 12:26:32 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:34 volumiopi3 volumio[1172]: info: Executing endpoint metavolumio Dec 12 12:26:34 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 12:26:34 volumiopi3 volumio[1172]: info: Executing endpoint metavolumio Dec 12 12:26:34 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 12:26:34 volumiopi3 volumio[1172]: info: Executing endpoint metavolumio Dec 12 12:26:34 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: metavolumio , requestToMetaVolumio Dec 12 12:26:34 volumiopi3 volumio[1172]: info: Dec 12 12:26:34 volumiopi3 volumio[1172]: ---------------------------- MPD announces system playlist update Dec 12 12:26:34 volumiopi3 volumio[1172]: info: Ignoring MPD Status Update Dec 12 12:26:34 volumiopi3 volumio[1172]: info: Dec 12 12:26:34 volumiopi3 volumio[1172]: ---------------------------- MPD announces state update: player Dec 12 12:26:34 volumiopi3 volumio[1172]: info: ControllerMpd::getState Dec 12 12:26:34 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Dec 12 12:26:34 volumiopi3 volumio[1172]: info: Dec 12 12:26:34 volumiopi3 volumio[1172]: ---------------------------- MPD announces system playlist update Dec 12 12:26:34 volumiopi3 volumio[1172]: info: Ignoring MPD Status Update Dec 12 12:26:34 volumiopi3 volumio[1172]: info: Dec 12 12:26:34 volumiopi3 volumio[1172]: ---------------------------- MPD announces state update: player Dec 12 12:26:34 volumiopi3 volumio[1172]: info: ControllerMpd::getState Dec 12 12:26:34 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand status Dec 12 12:26:34 volumiopi3 volumio[1172]: info: ------------------------------ 7ms Dec 12 12:26:34 volumiopi3 volumio[1172]: info: sendMpdCommand status took 6 milliseconds Dec 12 12:26:34 volumiopi3 volumio[1172]: info: ------------------------------ 6ms Dec 12 12:26:34 volumiopi3 volumio[1172]: info: sendMpdCommand status took 3 milliseconds Dec 12 12:26:34 volumiopi3 volumio[1172]: verbose: ControllerMpd::parseState Dec 12 12:26:34 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 12:26:34 volumiopi3 volumio[1172]: verbose: ControllerMpd::parseState Dec 12 12:26:34 volumiopi3 volumio[1172]: verbose: ControllerMpd::sendMpdCommand playlistinfo Dec 12 12:26:34 volumiopi3 volumio[1172]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 12 12:26:34 volumiopi3 volumio[1172]: info: sendMpdCommand playlistinfo took 2 milliseconds Dec 12 12:26:34 volumiopi3 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Dec 12 12:26:34 volumiopi3 volumio[1172]: verbose: ControllerMpd::parseTrackInfo Dec 12 12:26:34 volumiopi3 volumio[1172]: info: ControllerMpd::pushState Dec 12 12:26:34 volumiopi3 volumio[1172]: info: CoreCommandRouter::servicePushState Dec 12 12:26:34 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:34 volumiopi3 volumio[1172]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 12 12:26:34 volumiopi3 volumio[1172]: info: ControllerMpd::pushState Dec 12 12:26:34 volumiopi3 volumio[1172]: info: CoreCommandRouter::servicePushState Dec 12 12:26:34 volumiopi3 volumio[1172]: info: CorePlayQueue::getTrack 0 Dec 12 12:26:34 volumiopi3 volumio[1172]: info: Received update from a service different from the one supposed to be playing music. Skipping notification.Current radio_paradise Received mpd Dec 12 12:26:34 volumiopi3 volumio[1172]: info: ------------------------------ 15ms Dec 12 12:26:34 volumiopi3 volumio[1172]: info: ------------------------------ 12ms Dec 12 12:26:35 volumiopi3 volumio[1172]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 12 12:26:35 volumiopi3 volumio[1172]: info: CoreCommandRouter::Close All Modals sent Dec 12 12:26:44 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 12 12:26:44 volumiopi3 volumio[1172]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 12 12:26:47 volumiopi3 volumio[1172]: info: touch_display: Un-/commenting gpu_mem settings in /boot/config.txt. Dec 12 12:26:47 volumiopi3 sudo[4667]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 12 12:26:47 volumiopi3 sudo[4667]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:26:47 volumiopi3 sudo[4669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Dec 12 12:26:47 volumiopi3 sudo[4669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:26:47 volumiopi3 volumio[1172]: info: touch_display: Using /boot/config.txt instead of /boot/userconfig.txt. Dec 12 12:26:47 volumiopi3 sudo[4671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Dec 12 12:26:47 volumiopi3 sudo[4671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:26:47 volumiopi3 sudo[4667]: pam_unix(sudo:session): session closed for user root Dec 12 12:26:47 volumiopi3 volumio[1172]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 12 12:26:47 volumiopi3 volumio[1172]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 12:26:47 volumiopi3 volumio[1172]: TypeError: Cannot read properties of undefined (reading 'split') Dec 12 12:26:47 volumiopi3 volumio[1172]: at /data/plugins/user_interface/touch_display/index.js:1274:37 Dec 12 12:26:47 volumiopi3 volumio[1172]: at ChildProcess.exithandler (node:child_process:413:7) Dec 12 12:26:47 volumiopi3 volumio[1172]: at ChildProcess.emit (node:events:514:28) Dec 12 12:26:47 volumiopi3 volumio[1172]: at maybeClose (node:internal/child_process:1105:16) Dec 12 12:26:47 volumiopi3 volumio[1172]: at ChildProcess._handle.onexit (node:internal/child_process:305:5) Dec 12 12:26:47 volumiopi3 volumio[1172]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 12 12:26:47 volumiopi3 sudo[4675]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Dec 12 12:26:47 volumiopi3 sudo[4675]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 12 12:26:47 volumiopi3 systemd[1]: Created slice system-getty.slice - Slice /system/getty. Dec 12 12:26:47 volumiopi3 systemd[1]: Started getty@tty1.service - Getty on tty1. Dec 12 12:26:47 volumiopi3 sudo[4669]: pam_unix(sudo:session): session closed for user root Dec 12 12:26:47 volumiopi3 systemd[1]: Reloading. Dec 12 12:26:48 volumiopi3 sudo[4710]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-12 12:25' Dec 12 12:26:48 volumiopi3 sudo[4710]: 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"