Feb 12 22:41:00 volumio volumio[1188]: info: Building dependency tree... Feb 12 22:41:00 volumio volumio[1188]: info: Reading state information... Feb 12 22:41:00 volumio volumio[1188]: info: 26 packages can be upgraded. Run 'apt list --upgradable' to see them. Feb 12 22:41:00 volumio sudo[10030]: pam_unix(sudo:session): session closed for user root Feb 12 22:41:00 volumio sudo[10221]: root : PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/apt -y install --no-install-recommends python3-venv drc Feb 12 22:41:00 volumio sudo[10221]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 22:41:01 volumio volumio[1188]: info: Reading package lists... Feb 12 22:41:01 volumio volumio[1188]: info: Building dependency tree... Feb 12 22:41:01 volumio volumio[1188]: info: Reading state information... Feb 12 22:41:02 volumio volumio[1188]: info: The following additional packages will be installed: Feb 12 22:41:03 volumio volumio[1188]: info: python3-pip-whl python3-setuptools-whl python3.11-venv Feb 12 22:41:06 volumio volumio[1188]: info: The following NEW packages will be installed: Feb 12 22:41:06 volumio volumio[1188]: info: drc python3-pip-whl python3-setuptools-whl python3-venv python3.11-venv Feb 12 22:41:07 volumio volumio[1188]: info: 0 upgraded, 5 newly installed, 0 to remove and 26 not upgraded. Feb 12 22:41:07 volumio volumio[1188]: info: Need to get 2954 kB of archives. Feb 12 22:41:07 volumio volumio[1188]: info: After this operation, 3696 kB of additional disk space will be used. Feb 12 22:41:07 volumio volumio[1188]: info: Get:1 http://archive.raspberrypi.com/debian bookworm/main armhf python3-pip-whl all 23.0.1+dfsg-1+rpt1 [1718 kB] Feb 12 22:41:08 volumio volumio[1188]: info: Get:2 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf drc armhf 3.2.3~dfsg0-1 [118 kB] Feb 12 22:41:09 volumio volumio[1188]: info: Get:3 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-setuptools-whl all 66.1.1-1+deb12u2 [1112 kB] Feb 12 22:41:09 volumio volumio[1188]: info: Get:4 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3.11-venv armhf 3.11.2-6+deb12u6 [5896 B] Feb 12 22:41:10 volumio volumio[1188]: info: Get:5 http://raspbian.raspberrypi.com/raspbian bookworm/main armhf python3-venv armhf 3.11.2-1 [1188 B] Feb 12 22:41:11 volumio volumio[1188]: info: Fetched 2954 kB in 4s (800 kB/s) Feb 12 22:41:11 volumio volumio[1188]: info: Selecting previously unselected package drc. Feb 12 22:41:11 volumio volumio[1188]: [620B blob data] Feb 12 22:41:11 volumio volumio[1188]: info: Preparing to unpack .../drc_3.2.3~dfsg0-1_armhf.deb ... Feb 12 22:41:11 volumio volumio[1188]: info: Unpacking drc (3.2.3~dfsg0-1) ... Feb 12 22:41:13 volumio volumio[1188]: info: Selecting previously unselected package python3-pip-whl. Feb 12 22:41:13 volumio volumio[1188]: info: Preparing to unpack .../python3-pip-whl_23.0.1+dfsg-1+rpt1_all.deb ... Feb 12 22:41:13 volumio volumio[1188]: info: Unpacking python3-pip-whl (23.0.1+dfsg-1+rpt1) ... Feb 12 22:41:14 volumio volumio[1188]: info: Selecting previously unselected package python3-setuptools-whl. Feb 12 22:41:14 volumio volumio[1188]: info: Preparing to unpack .../python3-setuptools-whl_66.1.1-1+deb12u2_all.deb ... Feb 12 22:41:14 volumio volumio[1188]: info: Unpacking python3-setuptools-whl (66.1.1-1+deb12u2) ... Feb 12 22:41:14 volumio volumio[1188]: info: Selecting previously unselected package python3.11-venv. Feb 12 22:41:14 volumio volumio[1188]: info: Preparing to unpack .../python3.11-venv_3.11.2-6+deb12u6_armhf.deb ... Feb 12 22:41:14 volumio volumio[1188]: info: Unpacking python3.11-venv (3.11.2-6+deb12u6) ... Feb 12 22:41:15 volumio volumio[1188]: info: Selecting previously unselected package python3-venv. Feb 12 22:41:15 volumio volumio[1188]: info: Preparing to unpack .../python3-venv_3.11.2-1_armhf.deb ... Feb 12 22:41:15 volumio volumio[1188]: info: Unpacking python3-venv (3.11.2-1) ... Feb 12 22:41:15 volumio volumio[1188]: info: Setting up drc (3.2.3~dfsg0-1) ... Feb 12 22:41:15 volumio volumio[1188]: info: Setting up python3-setuptools-whl (66.1.1-1+deb12u2) ... Feb 12 22:41:15 volumio volumio[1188]: info: Setting up python3-pip-whl (23.0.1+dfsg-1+rpt1) ... Feb 12 22:41:15 volumio volumio[1188]: info: Setting up python3.11-venv (3.11.2-6+deb12u6) ... Feb 12 22:41:15 volumio volumio[1188]: info: Setting up python3-venv (3.11.2-1) ... Feb 12 22:41:15 volumio volumio[1188]: info: Processing triggers for man-db (2.11.2-2) ... Feb 12 22:41:18 volumio sudo[10221]: pam_unix(sudo:session): session closed for user root Feb 12 22:41:18 volumio volumio[1188]: info: creating sysytemd service Feb 12 22:41:18 volumio sudo[10346]: root : PWD=/data/plugins/audio_interface/fusiondsp ; USER=root ; COMMAND=/usr/bin/systemctl daemon-reload Feb 12 22:41:18 volumio sudo[10346]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 22:41:18 volumio systemd[1]: Reloading. Feb 12 22:41:19 volumio sudo[10346]: pam_unix(sudo:session): session closed for user root Feb 12 22:41:19 volumio volumio[1188]: info: copying hw detection script Feb 12 22:41:19 volumio volumio[1188]: info: Detected cpu architecture as armv7l Feb 12 22:41:21 volumio sudo[10376]: root : PWD=/tmp ; USER=root ; COMMAND=/usr/bin/cp /data/plugins/audio_interface/fusiondsp/c/hw_params_arm /data/plugins/audio_interface/fusiondsp/hw_params Feb 12 22:41:21 volumio sudo[10376]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 22:41:21 volumio sudo[10376]: pam_unix(sudo:session): session closed for user root Feb 12 22:41:21 volumio sudo[10378]: root : PWD=/tmp ; USER=root ; COMMAND=/usr/bin/chmod +x /data/plugins/audio_interface/fusiondsp/hw_params Feb 12 22:41:21 volumio sudo[10378]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 22:41:21 volumio sudo[10378]: pam_unix(sudo:session): session closed for user root Feb 12 22:41:21 volumio volumio[1188]: info: Downloading camillagui ... Please wait! Feb 12 22:41:34 volumio volumio[1188]: info: Extracting camillagui ... Feb 12 22:41:50 volumio sudo[10005]: pam_unix(sudo:session): session closed for user root Feb 12 22:41:50 volumio volumio[1188]: Plugin install end detected on script Feb 12 22:41:50 volumio volumio[1188]: info: Install script completed Feb 12 22:41:50 volumio volumio[1188]: info: Adding reference to registry Feb 12 22:41:50 volumio volumio[1188]: info: Done installing plugin. Feb 12 22:41:50 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 12 22:41:50 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 12 22:41:50 volumio volumio[1188]: info: Folder /tmp/plugins removed Feb 12 22:41:50 volumio volumio[1188]: info: Folder /tmp/downloaded_plugin.zip removed Feb 12 22:41:50 volumio volumio[1188]: info: Folder /data/temp removed Feb 12 22:42:04 volumio volumio[1188]: info: Enabling plugin peppymeterbasic Feb 12 22:42:04 volumio volumio[1188]: info: Loading plugin "peppymeterbasic"... Feb 12 22:42:06 volumio volumio[1188]: info: Preparing to generate the ALSA configuration file Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 12 22:42:07 volumio volumio[1188]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 12 22:42:07 volumio volumio[1188]: info: Reading ALSA contributions from plugins. Feb 12 22:42:07 volumio volumio[1188]: info: Asound.conf file written Feb 12 22:42:07 volumio sudo[10463]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 12 22:42:07 volumio sudo[10463]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:07 volumio sudo[10463]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:07 volumio volumio[1188]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 12 22:42:07 volumio volumio[1188]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 12 22:42:07 volumio volumio[1188]: info: Output device has changed, restarting MPD Feb 12 22:42:07 volumio volumio[1188]: info: Output device has changed, restarting Shairport Sync Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 22:42:07 volumio sudo[10470]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 22:42:07 volumio sudo[10470]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:07 volumio sudo[10470]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:07 volumio sudo[10472]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 22:42:07 volumio sudo[10472]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:07 volumio volumio[1188]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 22:42:07 volumio volumio[1188]: info: PLUGIN START: peppymeterbasic Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 12 22:42:07 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 12 22:42:07 volumio volumio[1188]: info: Preparing to generate the ALSA configuration file Feb 12 22:42:07 volumio volumio[1188]: info: Done. Feb 12 22:42:07 volumio volumio[1188]: info: The plugin peppymeterbasic has an ALSA contribution file peppy_in.peppy_out.6.conf Feb 12 22:42:07 volumio volumio[1188]: info: Reading ALSA contributions from plugins. Feb 12 22:42:07 volumio volumio[1188]: info: MPD Permissions set Feb 12 22:42:07 volumio sudo[10482]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/modprobe snd_aloop index=7 pcm_substreams=2 Feb 12 22:42:07 volumio sudo[10482]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:07 volumio volumio[1188]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 8 Feb 12 22:42:07 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 22:42:07 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 22:42:07 volumio systemd[1]: mpd.service: Consumed 2.034s CPU time. Feb 12 22:42:07 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 22:42:07 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 22:42:07 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:07 volumio volumio[1188]: info: Starting Shairport Sync Feb 12 22:42:07 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 22:42:07 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 22:42:07 volumio volumio[1188]: info: Asound.conf file unchanged, so no further update is needed Feb 12 22:42:07 volumio volumio[1188]: info: Output device has changed, restarting MPD Feb 12 22:42:07 volumio volumio[1188]: info: Output device has changed, restarting Shairport Sync Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:07 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 22:42:08 volumio sudo[10493]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 22:42:08 volumio sudo[10493]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:08 volumio sudo[10482]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:08 volumio sudo[10495]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 12 22:42:08 volumio sudo[10495]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:08 volumio sudo[10497]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 12 22:42:08 volumio sudo[10492]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 22:42:08 volumio sudo[10492]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 22:42:08 volumio volumio[1188]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 12 22:42:08 volumio sudo[10492]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:08 volumio sudo[10497]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:08 volumio (udev-worker)[10511]: controlC7: Process '/usr/sbin/alsactl -E HOME=/run/alsa -E XDG_RUNTIME_DIR=/run/alsa/runtime restore 7' failed with exit code 99. Feb 12 22:42:08 volumio sudo[10495]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:08 volumio volumio[1188]: info: snd_dummy loaded Feb 12 22:42:08 volumio volumio[1188]: info: MPD Permissions set Feb 12 22:42:08 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:08 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:08 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:08 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:08 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:08 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:08 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:08 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:08 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 12 22:42:08 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 12 22:42:08 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 12 22:42:08 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 12 22:42:08 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 22:42:08 volumio systemd[1]: shairport-sync.service: Consumed 7.839s CPU time. Feb 12 22:42:08 volumio volumio[1188]: info: Starting Shairport Sync Feb 12 22:42:08 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 22:42:08 volumio sudo[10493]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:08 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 12 22:42:08 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 12 22:42:08 volumio volumio[1188]: info: Shairport-Sync Started Feb 12 22:42:08 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 12 22:42:08 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 12 22:42:08 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 12 22:42:08 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 12 22:42:08 volumio sudo[10523]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 12 22:42:08 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 12 22:42:08 volumio sudo[10523]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:08 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 12 22:42:08 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 12 22:42:08 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 22:42:09 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 12 22:42:09 volumio sudo[10523]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:09 volumio volumio[1188]: info: Shairport-Sync Started Feb 12 22:42:09 volumio sudo[10525]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 12 22:42:09 volumio sudo[10525]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 12 22:42:09 volumio sudo[10525]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:11 volumio mpd[10556]: 2026-02-12T22:42:11 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 12 22:42:11 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 12 22:42:11 volumio sudo[10472]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:11 volumio sudo[10497]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:11 volumio volumio[1188]: error: MPD error: The expression evaluated to a falsy value: Feb 12 22:42:11 volumio volumio[1188]: assert.ok(self.idling) Feb 12 22:42:11 volumio volumio[1188]: error: The expression evaluated to a falsy value: Feb 12 22:42:11 volumio volumio[1188]: assert.ok(self.idling) Feb 12 22:42:11 volumio volumio[1188]: error: updateQueue error: null Feb 12 22:42:15 volumio volumio[1188]: info: CALLMETHOD: user_interface peppymeterbasic savepeppy [object Object] Feb 12 22:42:15 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , savepeppy Feb 12 22:42:15 volumio volumio[1188]: aaaaaaaaaaa 1280,400 Feb 12 22:42:15 volumio volumio[1188]: info: PeppyMeterBasic ---1280400 Feb 12 22:42:15 volumio volumio[1188]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Feb 12 22:42:15 volumio volumio[1188]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 12 22:42:15 volumio volumio[1188]: info: CoreCommandRouter::Close All Modals sent Feb 12 22:42:21 volumio volumio[1188]: info: CALLMETHOD: user_interface peppymeterbasic savepeppy1 [object Object] Feb 12 22:42:21 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , savepeppy1 Feb 12 22:42:21 volumio volumio[1188]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Feb 12 22:42:21 volumio volumio[1188]: info: PeppyMeterBasic -----------------------meterblack-white Feb 12 22:42:21 volumio volumio[1188]: info: PeppyMeterBasic -----------------------$basefolder Feb 12 22:42:21 volumio volumio[1188]: info: PeppyMeterBasic -----------------------screensize1280x400 Feb 12 22:42:21 volumio volumio[1188]: info: PeppyMeterBasic -----------------------screenwidth Feb 12 22:42:21 volumio volumio[1188]: info: PeppyMeterBasic -----------------------screenheight Feb 12 22:42:21 volumio volumio[1188]: info: PeppyMeterBasic -----------------------metersize30 Feb 12 22:42:21 volumio volumio[1188]: error: PeppyMeterBasic ---Error writing config null Feb 12 22:42:21 volumio sudo[10575]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppymeterbasic.service Feb 12 22:42:21 volumio sudo[10575]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:21 volumio volumio[1188]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 12 22:42:21 volumio volumio[1188]: info: CoreCommandRouter::Close All Modals sent Feb 12 22:42:21 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Feb 12 22:42:21 volumio sudo[10575]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:21 volumio volumio[1188]: info: peppymeterbasic Daemon Started Feb 12 22:42:24 volumio volumio[1188]: info: Preload queue cleared Feb 12 22:42:24 volumio volumio[1188]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::ClearQueue Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::stop Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::clearPlayQueue Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::saveQueue Feb 12 22:42:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::addQueueItems Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::addQueueItems Feb 12 22:42:24 volumio volumio[1188]: info: Preload queue cleared Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3 Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3 Feb 12 22:42:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::saveQueue Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Feb 12 22:42:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::play index 0 Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::addQueueItems Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::addQueueItems Feb 12 22:42:24 volumio volumio[1188]: info: Preload queue cleared Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3 Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3 Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Consuelo Velázquez - Bésame Mucho Cover Alexandra Ilieva & Family Thomann.mp3 Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Consuelo Velázquez - Bésame Mucho Cover Alexandra Ilieva & Family Thomann.mp3 Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Paul Mauriat - Butterfly.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Paul Mauriat - Butterfly.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Paul Mauriat - Emmanuelle.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Paul Mauriat - Emmanuelle.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Paul Mauriat - Isadora.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Paul Mauriat - Isadora.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Paul Mauriat - Mother of mine (1972).flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Paul Mauriat - Mother of mine (1972).flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Paul Mauriat - Rain and tears (1968).flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Paul Mauriat - Rain and tears (1968).flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Paul Mauriat-San Francisco.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Paul Mauriat-San Francisco.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Plein Soleil (1960_ 태양은가득히.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Plein Soleil (1960_ 태양은가득히.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Romeo et Juliette - Paul Mauriat.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Romeo et Juliette - Paul Mauriat.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/The Call of the Sea — Mystical Celtic Ballad of the Ocean.mp3 Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/The Call of the Sea — Mystical Celtic Ballad of the Ocean.mp3 Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/The Night Speaks Softly – A 1960s Italian Romance by the Sea.mp3 Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/The Night Speaks Softly – A 1960s Italian Romance by the Sea.mp3 Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Under the Lavender Sky – A 1960s Provence Chanson of Romantic France.mp3 Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Under the Lavender Sky – A 1960s Provence Chanson of Romantic France.mp3 Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/나자리노.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/나자리노.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/밤하늘에 트럼펫(1965).flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/밤하늘에 트럼펫(1965).flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/성민지 - 울리는 경부선 (남인수) song cover.mp3 Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/성민지 - 울리는 경부선 (남인수) song cover.mp3 Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/진혜림 a lovers concerto.flac.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/진혜림 a lovers concerto.flac.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/9. Can't Help Falling In Love.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/9. Can't Help Falling In Love.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/01. The Rolling Stones - Sympathy For The Devil.dsf Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/01. The Rolling Stones - Sympathy For The Devil.dsf Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/(01) Scarborough Fair.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/(01) Scarborough Fair.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/(02) Starry, Starry Night.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/(02) Starry, Starry Night.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/(07) You Raise Me Up.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/(07) You Raise Me Up.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/(09) No Matter What.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/(09) No Matter What.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/(12) Edelweiss.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/(12) Edelweiss.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/(13) The Sound Of Silence.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/(13) The Sound Of Silence.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/01. Donna Donna.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/01. Donna Donna.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/05. Somewhere My Love.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/05. Somewhere My Love.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/12. Moon River.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/12. Moon River.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/12. Without you.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/12. Without you.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/01.Speak softlylove.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/01.Speak softlylove.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/07.How deep is your love.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/07.How deep is your love.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/09.Hey jude.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/09.Hey jude.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/01 Eagles - Hotel California (2013 Remaster).flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/01 Eagles - Hotel California (2013 Remaster).flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/01. John Denver - Take Me Home, Country Roads.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/01. John Denver - Take Me Home, Country Roads.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/8. Brown Girls In The Ring.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/8. Brown Girls In The Ring.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/3. Sunny.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/3. Sunny.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/020. Paul Mauriat - Gone Is Love.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/020. Paul Mauriat - Gone Is Love.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/014. Paul Mauriat - El Condor Pasa.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/014. Paul Mauriat - El Condor Pasa.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/002. Bobby Vinton - Sealed With A Kiss.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/002. Bobby Vinton - Sealed With A Kiss.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/040. Paul Mauriat - Mamy Blue.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/040. Paul Mauriat - Mamy Blue.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/140. Paul Mauriat - Pour Un Flirt.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/140. Paul Mauriat - Pour Un Flirt.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/009. Paul Mauriat - Toccata.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/009. Paul Mauriat - Toccata.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/161. Paul Mauriat - Sayonara.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/161. Paul Mauriat - Sayonara.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/094. Paul Mauriat - Love Is Blue.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/094. Paul Mauriat - Love Is Blue.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Bach - Violinkonzert Nr. 2.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Bach - Violinkonzert Nr. 2.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Brahms - Ungarischer Tanz Nr. 5.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Brahms - Ungarischer Tanz Nr. 5.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Handel - Passacaglia.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Handel - Passacaglia.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Monti - Czardas.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Monti - Czardas.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Paganini - Caprice op. 1 Nr. 24.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Paganini - Caprice op. 1 Nr. 24.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Piazzolla - Libertango.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Piazzolla - Libertango.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Platochek - Russian Traditional.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Platochek - Russian Traditional.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Rimski-Korsakow - Hummelflung.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Rimski-Korsakow - Hummelflung.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Romanian Traditional - Song of Hope.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Romanian Traditional - Song of Hope.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Russian Traditional - Moscow Nights.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Russian Traditional - Moscow Nights.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Russian Traditional - Those Were the Days.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Russian Traditional - Those Were the Days.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Sarasate - Zigeunerweisen.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Sarasate - Zigeunerweisen.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Tchaikowsky - Walzer aus der Streicherserenade.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Tchaikowsky - Walzer aus der Streicherserenade.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Traditional - Hora Transsilvania.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Traditional - Hora Transsilvania.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Traditional - Moldavian Taracana.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Traditional - Moldavian Taracana.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Traditional - Roadhouse Gipsy.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Traditional - Roadhouse Gipsy.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Traditional - Russian Gipsyswing.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Traditional - Russian Gipsyswing.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/러시아 - 가을.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/러시아 - 가을.flac Feb 12 22:42:24 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/08. Starry starry night.flac Feb 12 22:42:24 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/08. Starry starry night.flac Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::stop Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 22:42:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::saveQueue Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::play index undefined Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::clearAddPlayTracks USB/1690-42B4/?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3 Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 22:42:24 volumio volumio[1188]: info: sendMpdCommand stop took 13 milliseconds Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 22:42:24 volumio volumio[1188]: info: Feb 12 22:42:24 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Feb 12 22:42:24 volumio volumio[1188]: info: Ignoring MPD Status Update Feb 12 22:42:24 volumio volumio[1188]: info: sendMpdCommand clear took 4 milliseconds Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "USB/1690-42B4/?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3" Feb 12 22:42:24 volumio volumio[1188]: info: Feb 12 22:42:24 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Feb 12 22:42:24 volumio volumio[1188]: info: Ignoring MPD Status Update Feb 12 22:42:24 volumio volumio[1188]: error: updateQueue error: null Feb 12 22:42:24 volumio volumio[1188]: info: Feb 12 22:42:24 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Feb 12 22:42:24 volumio volumio[1188]: info: Ignoring MPD Status Update Feb 12 22:42:24 volumio volumio[1188]: info: ------------------------------ 4ms Feb 12 22:42:24 volumio volumio[1188]: info: sendMpdCommand add "USB/1690-42B4/?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3" took 3 milliseconds Feb 12 22:42:24 volumio volumio[1188]: info: ------------------------------ 3ms Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Feb 12 22:42:24 volumio volumio[1188]: info: Feb 12 22:42:24 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Feb 12 22:42:24 volumio volumio[1188]: info: Ignoring MPD Status Update Feb 12 22:42:24 volumio volumio[1188]: info: ------------------------------ 6ms Feb 12 22:42:24 volumio volumio[1188]: info: sendMpdCommand play took 4 milliseconds Feb 12 22:42:24 volumio volumio[1188]: info: ------------------------------ 4ms Feb 12 22:42:24 volumio volumio[1188]: info: Feb 12 22:42:24 volumio volumio[1188]: ---------------------------- MPD announces state update: player Feb 12 22:42:24 volumio volumio[1188]: info: ControllerMpd::getState Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Feb 12 22:42:24 volumio volumio[1188]: info: Feb 12 22:42:24 volumio volumio[1188]: ---------------------------- MPD announces state update: player Feb 12 22:42:24 volumio volumio[1188]: info: ControllerMpd::getState Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 12 22:42:24 volumio volumio[1188]: info: sendMpdCommand status took 215 milliseconds Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::parseState Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 22:42:24 volumio volumio[1188]: info: Feb 12 22:42:24 volumio volumio[1188]: ---------------------------- MPD announces state update: player Feb 12 22:42:24 volumio volumio[1188]: info: ControllerMpd::getState Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 12 22:42:24 volumio volumio[1188]: info: sendMpdCommand status took 221 milliseconds Feb 12 22:42:24 volumio volumio[1188]: info: sendMpdCommand clearerror took 8 milliseconds Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::parseState Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 22:42:24 volumio volumio[1188]: info: Feb 12 22:42:24 volumio volumio[1188]: ---------------------------- MPD announces state update: player Feb 12 22:42:24 volumio volumio[1188]: info: ControllerMpd::getState Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Feb 12 22:42:24 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 16 milliseconds Feb 12 22:42:24 volumio volumio[1188]: info: sendMpdCommand status took 15 milliseconds Feb 12 22:42:24 volumio volumio[1188]: info: sendMpdCommand clearerror took 12 milliseconds Feb 12 22:42:24 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 12 22:42:24 volumio volumio[1188]: info: sendMpdCommand status took 9 milliseconds Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::parseState Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::parseState Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 22:42:24 volumio volumio[1188]: info: ControllerMpd::pushState Feb 12 22:42:24 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Feb 12 22:42:24 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3","artist":null,"album":null,"uri":"USB/1690-42B4/?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3","trackType":"mp3"} Feb 12 22:42:24 volumio volumio[1188]: verbose: CURRENT POSITION 0 Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::pushState Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Feb 12 22:42:24 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 22:42:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Feb 12 22:42:24 volumio volumio[1188]: info: ControllerMpd::pushState Feb 12 22:42:24 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Feb 12 22:42:24 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3","artist":null,"album":null,"uri":"USB/1690-42B4/?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3","trackType":"mp3"} Feb 12 22:42:24 volumio volumio[1188]: verbose: CURRENT POSITION 0 Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::pushState Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Feb 12 22:42:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Feb 12 22:42:24 volumio volumio[1188]: info: ------------------------------ 259ms Feb 12 22:42:24 volumio volumio[1188]: info: ------------------------------ 260ms Feb 12 22:42:24 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 25 milliseconds Feb 12 22:42:24 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 23 milliseconds Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Feb 12 22:42:24 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Feb 12 22:42:24 volumio volumio[1188]: info: ControllerMpd::pushState Feb 12 22:42:24 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Feb 12 22:42:24 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3","artist":null,"album":null,"uri":"USB/1690-42B4/?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3","trackType":"mp3"} Feb 12 22:42:24 volumio volumio[1188]: verbose: CURRENT POSITION 0 Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::pushState Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Feb 12 22:42:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Feb 12 22:42:24 volumio volumio[1188]: info: ControllerMpd::pushState Feb 12 22:42:24 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Feb 12 22:42:24 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":198,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3","artist":null,"album":null,"uri":"USB/1690-42B4/?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3","trackType":"mp3"} Feb 12 22:42:24 volumio volumio[1188]: verbose: CURRENT POSITION 0 Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Feb 12 22:42:24 volumio volumio[1188]: info: CoreStateMachine::pushState Feb 12 22:42:24 volumio volumio[1188]: info: CorePlayQueue::getTrack 0 Feb 12 22:42:24 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Feb 12 22:42:24 volumio volumio[1188]: info: ------------------------------ 67ms Feb 12 22:42:24 volumio volumio[1188]: info: ------------------------------ 61ms Feb 12 22:42:24 volumio volumio[1188]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 12 22:42:24 volumio volumio[1188]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 12 22:42:24 volumio volumio[1188]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 12 22:42:24 volumio volumio[1188]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 12 22:42:26 volumio sudo[10589]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 12 22:42:26 volumio sudo[10589]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:26 volumio sudo[10587]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 12 22:42:26 volumio sudo[10587]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:26 volumio sudo[10591]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 12 22:42:26 volumio sudo[10591]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:26 volumio sudo[10593]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 12 22:42:26 volumio sudo[10593]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:26 volumio systemd[1]: Stopping peppymeterbasic.service - peppymeterbasic Daemon... Feb 12 22:42:26 volumio systemd[1]: Starting setdatetime-helper.service - Time Synchronization Helper Service... Feb 12 22:42:27 volumio systemd[1]: peppymeterbasic.service: State 'final-sigterm' timed out. Killing. Feb 12 22:42:27 volumio systemd[1]: peppymeterbasic.service: Killing process 10580 (python3) with signal SIGKILL. Feb 12 22:42:28 volumio systemd[1]: peppymeterbasic.service: Processes still around after final SIGKILL. Entering failed mode. Feb 12 22:42:28 volumio systemd[1]: peppymeterbasic.service: Failed with result 'timeout'. Feb 12 22:42:28 volumio systemd[1]: peppymeterbasic.service: Unit process 10580 (python3) remains running after unit stopped. Feb 12 22:42:28 volumio systemd[1]: Stopped peppymeterbasic.service - peppymeterbasic Daemon. Feb 12 22:42:28 volumio systemd[1]: peppymeterbasic.service: Consumed 1.417s CPU time. Feb 12 22:42:28 volumio sudo[10591]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:28 volumio sudo[10593]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:28 volumio sudo[10587]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:28 volumio sudo[10589]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:28 volumio volumio[1188]: info: peppymeterbasic Daemon Stop Feb 12 22:42:28 volumio volumio[1188]: info: peppymeterbasic Daemon Stop Feb 12 22:42:28 volumio volumio[1188]: info: peppymeterbasic Daemon Stop Feb 12 22:42:28 volumio volumio[1188]: info: peppymeterbasic Daemon Stop Feb 12 22:42:29 volumio systemd[1]: setdatetime-helper.service: Deactivated successfully. Feb 12 22:42:29 volumio systemd[1]: Finished setdatetime-helper.service - Time Synchronization Helper Service. Feb 12 22:42:37 volumio volumio[1188]: info: Preload queue cleared Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::volumioReplaceandPlayItems Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::ClearQueue Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::stop Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::clearPlayQueue Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::saveQueue Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::addQueueItems Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::addQueueItems Feb 12 22:42:37 volumio volumio[1188]: info: Preload queue cleared Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3 Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/?? So This Is Love ?? A Tender Slow Bolero Female Vocalist.mp3 Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3 Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3 Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::saveQueue Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::volumioPlay Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::play index 1 Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::addQueueItems Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::addQueueItems Feb 12 22:42:37 volumio volumio[1188]: info: Preload queue cleared Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Consuelo Velázquez - Bésame Mucho Cover Alexandra Ilieva & Family Thomann.mp3 Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Consuelo Velázquez - Bésame Mucho Cover Alexandra Ilieva & Family Thomann.mp3 Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Paul Mauriat - Butterfly.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Paul Mauriat - Butterfly.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Paul Mauriat - Emmanuelle.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Paul Mauriat - Emmanuelle.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Paul Mauriat - Isadora.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Paul Mauriat - Isadora.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Paul Mauriat - Mother of mine (1972).flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Paul Mauriat - Mother of mine (1972).flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Paul Mauriat - Rain and tears (1968).flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Paul Mauriat - Rain and tears (1968).flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Paul Mauriat-San Francisco.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Paul Mauriat-San Francisco.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Plein Soleil (1960_ 태양은가득히.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Plein Soleil (1960_ 태양은가득히.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Romeo et Juliette - Paul Mauriat.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Romeo et Juliette - Paul Mauriat.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/The Call of the Sea — Mystical Celtic Ballad of the Ocean.mp3 Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/The Call of the Sea — Mystical Celtic Ballad of the Ocean.mp3 Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/The Night Speaks Softly – A 1960s Italian Romance by the Sea.mp3 Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/The Night Speaks Softly – A 1960s Italian Romance by the Sea.mp3 Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Under the Lavender Sky – A 1960s Provence Chanson of Romantic France.mp3 Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Under the Lavender Sky – A 1960s Provence Chanson of Romantic France.mp3 Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/나자리노.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/나자리노.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/밤하늘에 트럼펫(1965).flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/밤하늘에 트럼펫(1965).flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/성민지 - 울리는 경부선 (남인수) song cover.mp3 Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/성민지 - 울리는 경부선 (남인수) song cover.mp3 Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/진혜림 a lovers concerto.flac.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/진혜림 a lovers concerto.flac.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/9. Can't Help Falling In Love.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/9. Can't Help Falling In Love.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/01. The Rolling Stones - Sympathy For The Devil.dsf Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/01. The Rolling Stones - Sympathy For The Devil.dsf Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/(01) Scarborough Fair.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/(01) Scarborough Fair.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/(02) Starry, Starry Night.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/(02) Starry, Starry Night.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/(07) You Raise Me Up.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/(07) You Raise Me Up.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/(09) No Matter What.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/(09) No Matter What.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/(12) Edelweiss.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/(12) Edelweiss.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/(13) The Sound Of Silence.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/(13) The Sound Of Silence.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/01. Donna Donna.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/01. Donna Donna.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/05. Somewhere My Love.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/05. Somewhere My Love.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/12. Moon River.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/12. Moon River.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/12. Without you.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/12. Without you.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/01.Speak softlylove.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/01.Speak softlylove.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/07.How deep is your love.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/07.How deep is your love.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/09.Hey jude.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/09.Hey jude.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/01 Eagles - Hotel California (2013 Remaster).flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/01 Eagles - Hotel California (2013 Remaster).flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/01. John Denver - Take Me Home, Country Roads.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/01. John Denver - Take Me Home, Country Roads.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/8. Brown Girls In The Ring.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/8. Brown Girls In The Ring.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/3. Sunny.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/3. Sunny.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/020. Paul Mauriat - Gone Is Love.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/020. Paul Mauriat - Gone Is Love.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/014. Paul Mauriat - El Condor Pasa.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/014. Paul Mauriat - El Condor Pasa.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/002. Bobby Vinton - Sealed With A Kiss.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/002. Bobby Vinton - Sealed With A Kiss.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/040. Paul Mauriat - Mamy Blue.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/040. Paul Mauriat - Mamy Blue.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/140. Paul Mauriat - Pour Un Flirt.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/140. Paul Mauriat - Pour Un Flirt.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/009. Paul Mauriat - Toccata.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/009. Paul Mauriat - Toccata.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/161. Paul Mauriat - Sayonara.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/161. Paul Mauriat - Sayonara.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/094. Paul Mauriat - Love Is Blue.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/094. Paul Mauriat - Love Is Blue.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Bach - Violinkonzert Nr. 2.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Bach - Violinkonzert Nr. 2.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Brahms - Ungarischer Tanz Nr. 5.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Brahms - Ungarischer Tanz Nr. 5.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Handel - Passacaglia.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Handel - Passacaglia.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Monti - Czardas.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Monti - Czardas.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Paganini - Caprice op. 1 Nr. 24.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Paganini - Caprice op. 1 Nr. 24.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Piazzolla - Libertango.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Piazzolla - Libertango.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Platochek - Russian Traditional.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Platochek - Russian Traditional.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Rimski-Korsakow - Hummelflung.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Rimski-Korsakow - Hummelflung.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Romanian Traditional - Song of Hope.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Romanian Traditional - Song of Hope.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Russian Traditional - Moscow Nights.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Russian Traditional - Moscow Nights.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Russian Traditional - Those Were the Days.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Russian Traditional - Those Were the Days.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Sarasate - Zigeunerweisen.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Sarasate - Zigeunerweisen.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Tchaikowsky - Walzer aus der Streicherserenade.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Tchaikowsky - Walzer aus der Streicherserenade.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Traditional - Hora Transsilvania.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Traditional - Hora Transsilvania.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Traditional - Moldavian Taracana.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Traditional - Moldavian Taracana.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Traditional - Roadhouse Gipsy.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Traditional - Roadhouse Gipsy.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/Traditional - Russian Gipsyswing.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/Traditional - Russian Gipsyswing.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/러시아 - 가을.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/러시아 - 가을.flac Feb 12 22:42:37 volumio volumio[1188]: info: Adding Item to queue: music-library/USB/1690-42B4/08. Starry starry night.flac Feb 12 22:42:37 volumio volumio[1188]: info: Using cached record of: music-library/USB/1690-42B4/08. Starry starry night.flac Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::stop Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::volumioPushQueue Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::saveQueue Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::play index undefined Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::updateTrackBlock Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrackBlock Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::startPlaybackTimer Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::clearAddPlayTracks USB/1690-42B4/A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3 Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand stop Feb 12 22:42:37 volumio volumio[1188]: info: Feb 12 22:42:37 volumio volumio[1188]: ---------------------------- MPD announces state update: player Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand stop took 22 milliseconds Feb 12 22:42:37 volumio volumio[1188]: info: ControllerMpd::getState Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clear Feb 12 22:42:37 volumio volumio[1188]: info: Feb 12 22:42:37 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Feb 12 22:42:37 volumio volumio[1188]: info: Ignoring MPD Status Update Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand status took 5 milliseconds Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand clear took 6 milliseconds Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::parseState Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand add "USB/1690-42B4/A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3" Feb 12 22:42:37 volumio volumio[1188]: info: Feb 12 22:42:37 volumio volumio[1188]: ---------------------------- MPD announces state update: player Feb 12 22:42:37 volumio volumio[1188]: info: ControllerMpd::getState Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Feb 12 22:42:37 volumio volumio[1188]: error: updateQueue error: null Feb 12 22:42:37 volumio volumio[1188]: info: Feb 12 22:42:37 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Feb 12 22:42:37 volumio volumio[1188]: info: Ignoring MPD Status Update Feb 12 22:42:37 volumio volumio[1188]: info: ------------------------------ 7ms Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 6 milliseconds Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand add "USB/1690-42B4/A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3" took 6 milliseconds Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand status took 2 milliseconds Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand play Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::parseState Feb 12 22:42:37 volumio volumio[1188]: error: ControllerMpd::pushError: TypeError: Cannot read properties of undefined (reading 'split') Feb 12 22:42:37 volumio volumio[1188]: info: ControllerMpd::pushState Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::pushState Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Feb 12 22:42:37 volumio volumio[1188]: verbose: STATE SERVICE {"status":"stop","position":null,"seek":null,"duration":null,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":null,"artist":null,"album":null,"uri":null} Feb 12 22:42:37 volumio volumio[1188]: verbose: CURRENT POSITION 1 Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::syncState stateService stop Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::pushState Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Feb 12 22:42:37 volumio volumio[1188]: info: No code Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::pushState Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Feb 12 22:42:37 volumio volumio[1188]: info: ------------------------------ 29ms Feb 12 22:42:37 volumio volumio[1188]: info: ------------------------------ 33ms Feb 12 22:42:37 volumio volumio[1188]: info: Feb 12 22:42:37 volumio volumio[1188]: ---------------------------- MPD announces system playlist update Feb 12 22:42:37 volumio volumio[1188]: info: Ignoring MPD Status Update Feb 12 22:42:37 volumio volumio[1188]: info: Feb 12 22:42:37 volumio volumio[1188]: ---------------------------- MPD announces state update: player Feb 12 22:42:37 volumio volumio[1188]: info: ------------------------------ 41ms Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand play took 38 milliseconds Feb 12 22:42:37 volumio volumio[1188]: info: ControllerMpd::getState Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Feb 12 22:42:37 volumio volumio[1188]: info: ------------------------------ 10ms Feb 12 22:42:37 volumio volumio[1188]: info: Feb 12 22:42:37 volumio volumio[1188]: ---------------------------- MPD announces state update: player Feb 12 22:42:37 volumio volumio[1188]: info: ControllerMpd::getState Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Feb 12 22:42:37 volumio volumio[1188]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 12 22:42:37 volumio volumio[1188]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 12 22:42:37 volumio sudo[10654]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 12 22:42:37 volumio sudo[10654]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:37 volumio volumio[1188]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 12 22:42:37 volumio volumio[1188]: info: Feb 12 22:42:37 volumio volumio[1188]: ---------------------------- MPD announces state update: player Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand status took 135 milliseconds Feb 12 22:42:37 volumio volumio[1188]: info: ControllerMpd::getState Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand clearerror Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand status took 142 milliseconds Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::parseState Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::parseState Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 22:42:37 volumio volumio[1188]: info: Feb 12 22:42:37 volumio volumio[1188]: ---------------------------- MPD announces state update: player Feb 12 22:42:37 volumio volumio[1188]: info: ControllerMpd::getState Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand status Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand clearerror took 38 milliseconds Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand status took 37 milliseconds Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand clearerror took 27 milliseconds Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 17 milliseconds Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 10 milliseconds Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand status took 16 milliseconds Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::parseState Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::parseState Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::sendMpdCommand playlistinfo Feb 12 22:42:37 volumio volumio[1188]: info: ControllerMpd::pushState Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Feb 12 22:42:37 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3","artist":null,"album":null,"uri":"USB/1690-42B4/A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3","trackType":"mp3"} Feb 12 22:42:37 volumio volumio[1188]: verbose: CURRENT POSITION 1 Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::pushState Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Feb 12 22:42:37 volumio volumio[1188]: info: ControllerMpd::pushState Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Feb 12 22:42:37 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3","artist":null,"album":null,"uri":"USB/1690-42B4/A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3","trackType":"mp3"} Feb 12 22:42:37 volumio volumio[1188]: verbose: CURRENT POSITION 1 Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::pushState Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Feb 12 22:42:37 volumio sudo[10657]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 12 22:42:37 volumio sudo[10654]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:37 volumio sudo[10657]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:37 volumio sudo[10659]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 12 22:42:37 volumio sudo[10659]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:37 volumio volumio[1188]: info: ------------------------------ 245ms Feb 12 22:42:37 volumio volumio[1188]: info: ------------------------------ 241ms Feb 12 22:42:37 volumio volumio[1188]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 12 22:42:37 volumio volumio[1188]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 12 22:42:37 volumio sudo[10657]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 134 milliseconds Feb 12 22:42:37 volumio volumio[1188]: info: sendMpdCommand playlistinfo took 129 milliseconds Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Feb 12 22:42:37 volumio volumio[1188]: verbose: ControllerMpd::parseTrackInfo Feb 12 22:42:37 volumio volumio[1188]: info: ControllerMpd::pushState Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Feb 12 22:42:37 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3","artist":null,"album":null,"uri":"USB/1690-42B4/A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3","trackType":"mp3"} Feb 12 22:42:37 volumio volumio[1188]: verbose: CURRENT POSITION 1 Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::pushState Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Feb 12 22:42:37 volumio sudo[10664]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 12 22:42:37 volumio sudo[10664]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:37 volumio sudo[10659]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:37 volumio sudo[10665]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 12 22:42:37 volumio volumio[1188]: info: ControllerMpd::pushState Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::servicePushState Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Feb 12 22:42:37 volumio volumio[1188]: verbose: STATE SERVICE {"status":"pause","position":0,"seek":0,"duration":199,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3","artist":null,"album":null,"uri":"USB/1690-42B4/A Summer Kiss in Old Italy – Vintage Romance & 1960s Beauty.mp3","trackType":"mp3"} Feb 12 22:42:37 volumio volumio[1188]: verbose: CURRENT POSITION 1 Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::syncState stateService pause Feb 12 22:42:37 volumio sudo[10665]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::syncState currentStatus stop Feb 12 22:42:37 volumio volumio[1188]: info: CoreStateMachine::pushState Feb 12 22:42:37 volumio volumio[1188]: info: CorePlayQueue::getTrack 1 Feb 12 22:42:37 volumio volumio[1188]: info: CoreCommandRouter::volumioPushState Feb 12 22:42:37 volumio volumio[1188]: info: ------------------------------ 252ms Feb 12 22:42:37 volumio volumio[1188]: info: ------------------------------ 209ms Feb 12 22:42:37 volumio volumio[1188]: info: peppymeterbasic Daemon Stop Feb 12 22:42:37 volumio volumio[1188]: info: peppymeterbasic Daemon Stop Feb 12 22:42:37 volumio volumio[1188]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 12 22:42:37 volumio volumio[1188]: info: PeppyMeterBasic ---peppymeterbasic status stop Feb 12 22:42:37 volumio volumio[1188]: info: peppymeterbasic Daemon Stop Feb 12 22:42:37 volumio sudo[10665]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:37 volumio sudo[10669]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 12 22:42:37 volumio sudo[10669]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:37 volumio volumio[1188]: info: peppymeterbasic Daemon Stop Feb 12 22:42:37 volumio sudo[10664]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:37 volumio volumio[1188]: info: peppymeterbasic Daemon Stop Feb 12 22:42:37 volumio sudo[10671]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop peppymeterbasic.service Feb 12 22:42:37 volumio sudo[10671]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:37 volumio sudo[10671]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:37 volumio sudo[10669]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:37 volumio volumio[1188]: info: peppymeterbasic Daemon Stop Feb 12 22:42:37 volumio volumio[1188]: info: peppymeterbasic Daemon Stop Feb 12 22:42:44 volumio volumio[1188]: info: CALLMETHOD: user_interface peppymeterbasic savepeppy [object Object] Feb 12 22:42:44 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , savepeppy Feb 12 22:42:44 volumio volumio[1188]: aaaaaaaaaaa 1280,400 Feb 12 22:42:44 volumio volumio[1188]: info: PeppyMeterBasic ---1280400 Feb 12 22:42:44 volumio volumio[1188]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Feb 12 22:42:44 volumio volumio[1188]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 12 22:42:44 volumio volumio[1188]: info: CoreCommandRouter::Close All Modals sent Feb 12 22:42:46 volumio volumio[1188]: info: CALLMETHOD: user_interface peppymeterbasic savepeppy1 [object Object] Feb 12 22:42:46 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , savepeppy1 Feb 12 22:42:46 volumio volumio[1188]: error: Failed callmethod call: TypeError: Cannot read properties of undefined (reading 'then') Feb 12 22:42:46 volumio volumio[1188]: info: PeppyMeterBasic -----------------------meterrandom Feb 12 22:42:46 volumio volumio[1188]: info: PeppyMeterBasic -----------------------$basefolder Feb 12 22:42:46 volumio volumio[1188]: info: PeppyMeterBasic -----------------------screensize1280x400 Feb 12 22:42:46 volumio volumio[1188]: info: PeppyMeterBasic -----------------------screenwidth Feb 12 22:42:46 volumio volumio[1188]: info: PeppyMeterBasic -----------------------screenheight Feb 12 22:42:46 volumio volumio[1188]: info: PeppyMeterBasic -----------------------metersize30 Feb 12 22:42:46 volumio volumio[1188]: error: PeppyMeterBasic ---Error writing config null Feb 12 22:42:46 volumio sudo[10691]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart peppymeterbasic.service Feb 12 22:42:46 volumio sudo[10691]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 12 22:42:46 volumio volumio[1188]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 12 22:42:46 volumio volumio[1188]: info: CoreCommandRouter::Close All Modals sent Feb 12 22:42:46 volumio systemd[1]: Started peppymeterbasic.service - peppymeterbasic Daemon. Feb 12 22:42:46 volumio sudo[10691]: pam_unix(sudo:session): session closed for user root Feb 12 22:42:46 volumio volumio[1188]: info: peppymeterbasic Daemon Started Feb 12 22:42:49 volumio volumio[1188]: info: CALLMETHOD: user_interface peppymeterbasic dlmeter [object Object] Feb 12 22:42:49 volumio volumio[1188]: info: CoreCommandRouter::executeOnPlugin: peppymeterbasic , dlmeter Feb 12 22:42:50 volumio volumio[10696]: libpng warning: iCCP: known incorrect sRGB profile Feb 12 22:42:50 volumio volumio[10696]: libpng warning: iCCP: known incorrect sRGB profile Feb 12 22:42:50 volumio volumio[10696]: libpng warning: iCCP: known incorrect sRGB profile Feb 12 22:42:51 volumio volumio[1188]: --2026-02-12 22:42:49-- https://github.com/balbuze/Meter-peppymeter/raw/main/Zipped-folders/Choose Feb 12 22:42:51 volumio volumio[1188]: Resolving github.com (github.com)... 20.200.245.247 Feb 12 22:42:51 volumio volumio[1188]: Connecting to github.com (github.com)|20.200.245.247|:443... connected. Feb 12 22:42:51 volumio volumio[1188]: HTTP request sent, awaiting response... 404 Not Found Feb 12 22:42:51 volumio volumio[1188]: 2026-02-12 22:42:50 ERROR 404: Not Found. Feb 12 22:42:51 volumio volumio[1188]: --2026-02-12 22:42:50-- http://a/ Feb 12 22:42:51 volumio volumio[1188]: Resolving a (a)... failed: Device or resource busy. Feb 12 22:42:51 volumio volumio[1188]: wget: unable to resolve host address ‘a’ Feb 12 22:42:51 volumio volumio[1188]: --2026-02-12 22:42:50-- http://packet.zip/ Feb 12 22:42:51 volumio volumio[1188]: Resolving packet.zip (packet.zip)... 162.255.119.167 Feb 12 22:42:51 volumio volumio[1188]: Connecting to packet.zip (packet.zip)|162.255.119.167|:80... connected. Feb 12 22:42:51 volumio volumio[1188]: HTTP request sent, awaiting response... 302 Found Feb 12 22:42:51 volumio volumio[1188]: Location: http://www.packet.zip/ [following] Feb 12 22:42:51 volumio volumio[1188]: --2026-02-12 22:42:50-- http://www.packet.zip/ Feb 12 22:42:51 volumio volumio[1188]: Resolving www.packet.zip (www.packet.zip)... 91.195.240.19 Feb 12 22:42:51 volumio volumio[1188]: Connecting to www.packet.zip (www.packet.zip)|91.195.240.19|:80... connected. Feb 12 22:42:51 volumio volumio[1188]: HTTP request sent, awaiting response... 403 Forbidden Feb 12 22:42:51 volumio volumio[1188]: 2026-02-12 22:42:51 ERROR 403: Forbidden. Feb 12 22:42:51 volumio volumio[1188]: error: PeppyMeterBasic --- An error occurs while downloading or installing Meters Feb 12 22:42:51 volumio volumio[1188]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Feb 12 22:42:51 volumio volumio[1188]: /bin/rm: cannot remove 'a': No such file or directory Feb 12 22:42:51 volumio volumio[1188]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Feb 12 22:42:51 volumio volumio[1188]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 22:42:51 volumio volumio[1188]: Error: Command failed: /bin/rm /tmp/Choose a packet.zip* Feb 12 22:42:51 volumio volumio[1188]: /bin/rm: cannot remove '/tmp/Choose': No such file or directory Feb 12 22:42:51 volumio volumio[1188]: /bin/rm: cannot remove 'a': No such file or directory Feb 12 22:42:51 volumio volumio[1188]: /bin/rm: cannot remove 'packet.zip*': No such file or directory Feb 12 22:42:51 volumio volumio[1188]: at checkExecSyncError (node:child_process:887:11) Feb 12 22:42:51 volumio volumio[1188]: at execSync (node:child_process:959:15) Feb 12 22:42:51 volumio volumio[1188]: at /data/plugins/user_interface/peppymeterbasic/index.js:710:19 Feb 12 22:42:51 volumio volumio[1188]: at new Promise () Feb 12 22:42:51 volumio volumio[1188]: at peppymeterbasic.dlmeter (/data/plugins/user_interface/peppymeterbasic/index.js:685:12) Feb 12 22:42:51 volumio volumio[1188]: at CoreCommandRouter.executeOnPlugin (/volumio/app/index.js:1096:32) Feb 12 22:42:51 volumio volumio[1188]: at Socket. (/volumio/app/plugins/user_interface/websocket/index.js:337:42) Feb 12 22:42:51 volumio volumio[1188]: at Socket.emit (node:events:514:28) Feb 12 22:42:51 volumio volumio[1188]: at /volumio/node_modules/socket.io/lib/socket.js:503:12 Feb 12 22:42:51 volumio volumio[1188]: at process.processTicksAndRejections (node:internal/process/task_queues:77:11) { Feb 12 22:42:51 volumio volumio[1188]: status: 1, Feb 12 22:42:51 volumio volumio[1188]: signal: null, Feb 12 22:42:51 volumio volumio[1188]: output: [ Feb 12 22:42:51 volumio volumio[1188]: null, Feb 12 22:42:51 volumio volumio[1188]: , Feb 12 22:42:51 volumio volumio[1188]: Feb 12 22:42:51 volumio volumio[1188]: ], Feb 12 22:42:51 volumio volumio[1188]: pid: 10714, Feb 12 22:42:51 volumio volumio[1188]: stdout: , Feb 12 22:42:51 volumio volumio[1188]: stderr: Feb 12 22:42:51 volumio volumio[1188]: } Feb 12 22:42:51 volumio volumio[1188]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 12 22:42:51 volumio sudo[10730]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-12 22:41' Feb 12 22:42:51 volumio sudo[10730]: 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="b05a530ca81d05eb9a84d0acf82089f0a60b8ee6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="4ad85e2147d972f0b838d33c21382d2d813b2ae8" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Dec 25 09:13:19 UTC 2025" VOLUMIO_VERSION="4.084" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="792f09af7488531aecd1aadcaa9b175e"