Jan 11 15:47:00 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:00 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:00 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:00 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:00 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:01 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:01 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:01 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:01 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:01 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:02 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:02 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:02 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:02 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:02 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:03 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:03 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:03 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:03 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:03 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:04 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:04 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:04 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:04 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:04 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:05 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:05 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:05 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:05 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:05 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:06 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:06 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:06 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:06 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:06 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:07 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:07 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:07 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:07 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:07 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:08 volumio sudo[6599]: volumio : TTY=pts/0 ; PWD=/home/volumio ; USER=root ; COMMAND=/usr/bin/nano /var/lib/alsa/asound.state Jan 11 15:47:08 volumio sudo[6599]: pam_unix(sudo:session): session opened for user root(uid=0) by volumio(uid=1000) Jan 11 15:47:08 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:08 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:08 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:08 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:08 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:09 volumio volumio[1216]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jan 11 15:47:09 volumio volumio[1216]: Traceback (most recent call last): Jan 11 15:47:09 volumio volumio[1216]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 10, in Jan 11 15:47:09 volumio volumio[1216]: import pygame as pg Jan 11 15:47:09 volumio volumio[1216]: ModuleNotFoundError: No module named 'pygame' Jan 11 15:47:09 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:09 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:09 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:09 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:09 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:10 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:10 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:10 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:10 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:10 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:11 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:11 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:11 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:11 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:11 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:12 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:12 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:12 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:12 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:12 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:13 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:13 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:13 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:13 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:13 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:14 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:14 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:14 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:14 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:14 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:15 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:15 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:15 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:15 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:15 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:16 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:16 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:16 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:16 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:16 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:17 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:17 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:17 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:17 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:17 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:18 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:18 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:18 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:18 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:18 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:19 volumio volumio[1216]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jan 11 15:47:19 volumio volumio[1216]: Traceback (most recent call last): Jan 11 15:47:19 volumio volumio[1216]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 10, in Jan 11 15:47:19 volumio volumio[1216]: import pygame as pg Jan 11 15:47:19 volumio volumio[1216]: ModuleNotFoundError: No module named 'pygame' Jan 11 15:47:19 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:19 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:19 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:19 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:19 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:20 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:20 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:20 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:20 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:20 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:21 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:21 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:21 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:21 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:21 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:22 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:22 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:22 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:22 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:22 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:23 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:23 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:23 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:23 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:24 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:24 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:24 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:24 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:25 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:25 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:25 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:25 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:25 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:26 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:26 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:26 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:26 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:26 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:27 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:27 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:27 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:27 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:27 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:28 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:28 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:28 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:28 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:28 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:29 volumio volumio[1216]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jan 11 15:47:29 volumio volumio[1216]: Traceback (most recent call last): Jan 11 15:47:29 volumio volumio[1216]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 10, in Jan 11 15:47:29 volumio volumio[1216]: import pygame as pg Jan 11 15:47:29 volumio volumio[1216]: ModuleNotFoundError: No module named 'pygame' Jan 11 15:47:29 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:29 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:29 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:29 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:29 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:30 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:30 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:30 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:30 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:30 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:31 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:31 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:31 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:31 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:31 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:32 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:32 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:32 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:32 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:32 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:33 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:33 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:33 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:33 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:33 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:34 volumio systemd[1]: Starting systemd-tmpfiles-clean.service - Cleanup of Temporary Directories... Jan 11 15:47:34 volumio systemd[1]: systemd-tmpfiles-clean.service: Deactivated successfully. Jan 11 15:47:34 volumio systemd[1]: Finished systemd-tmpfiles-clean.service - Cleanup of Temporary Directories. Jan 11 15:47:34 volumio systemd[1]: run-credentials-systemd\x2dtmpfiles\x2dclean.service.mount: Deactivated successfully. Jan 11 15:47:34 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:34 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:34 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:34 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:34 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:35 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:35 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:35 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:35 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:35 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:36 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:36 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:36 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:36 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:36 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:37 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:37 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:37 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:37 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:37 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:38 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:38 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:38 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:38 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:38 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:39 volumio volumio[1216]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jan 11 15:47:39 volumio volumio[1216]: Traceback (most recent call last): Jan 11 15:47:39 volumio volumio[1216]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 10, in Jan 11 15:47:39 volumio volumio[1216]: import pygame as pg Jan 11 15:47:39 volumio volumio[1216]: ModuleNotFoundError: No module named 'pygame' Jan 11 15:47:39 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:39 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:39 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:39 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:39 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:40 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:40 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:40 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:40 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:40 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:41 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:41 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:41 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:41 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:41 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:42 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:42 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:42 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:42 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:42 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:43 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:43 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:43 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:43 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:43 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:44 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:44 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:44 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:44 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:44 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:45 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:45 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:45 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:45 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:45 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:46 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:46 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:46 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:46 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:46 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:47 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:47 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:47 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:47 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:47 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:48 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:48 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:49 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:49 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:49 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:49 volumio volumio[1216]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jan 11 15:47:49 volumio volumio[1216]: Traceback (most recent call last): Jan 11 15:47:49 volumio volumio[1216]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 10, in Jan 11 15:47:49 volumio volumio[1216]: import pygame as pg Jan 11 15:47:49 volumio volumio[1216]: ModuleNotFoundError: No module named 'pygame' Jan 11 15:47:49 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:49 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:49 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:49 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:49 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:50 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:50 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:50 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:50 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:50 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:51 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:51 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:51 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:51 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:51 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:52 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:52 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:52 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:52 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:52 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:54 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:54 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:54 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:54 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:54 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:55 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:55 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:55 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:55 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:55 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:56 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:56 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:56 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:56 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:56 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:57 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:57 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:57 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:57 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:57 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:58 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:58 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:58 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:58 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:58 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:59 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:47:59 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:47:59 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:47:59 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:47:59 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:47:59 volumio sudo[6599]: pam_unix(sudo:session): session closed for user root Jan 11 15:47:59 volumio volumio[1216]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jan 11 15:47:59 volumio volumio[1216]: Traceback (most recent call last): Jan 11 15:47:59 volumio volumio[1216]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 10, in Jan 11 15:47:59 volumio volumio[1216]: import pygame as pg Jan 11 15:47:59 volumio volumio[1216]: ModuleNotFoundError: No module named 'pygame' Jan 11 15:48:00 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:00 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:00 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:00 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:00 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:01 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:01 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:01 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:01 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:01 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:02 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:02 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:02 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:02 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:02 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:03 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:03 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:03 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:03 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:03 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:04 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:04 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:04 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:04 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:04 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:05 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:05 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:05 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:05 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:05 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:06 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:06 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:06 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:06 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:06 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:07 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:07 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:07 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:07 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:07 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:08 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:08 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:08 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:08 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:08 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:09 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:09 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:09 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:09 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:09 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:09 volumio volumio[1216]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jan 11 15:48:09 volumio volumio[1216]: Traceback (most recent call last): Jan 11 15:48:09 volumio volumio[1216]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 10, in Jan 11 15:48:09 volumio volumio[1216]: import pygame as pg Jan 11 15:48:09 volumio volumio[1216]: ModuleNotFoundError: No module named 'pygame' Jan 11 15:48:10 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:10 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:10 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:10 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:10 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:11 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:11 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:11 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:11 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:11 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:12 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:12 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:12 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:12 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:12 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:13 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:13 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:13 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:13 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:13 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:14 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:14 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:14 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:14 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:14 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:15 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:15 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:15 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:15 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:15 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:16 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:16 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:16 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:16 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:16 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:17 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:17 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:17 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:17 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:17 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:18 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:18 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:18 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:18 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:18 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:18 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:18 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:18 volumio volumio[1216]: info: Prefetching next song Jan 11 15:48:18 volumio volumio[1216]: info: DOING PREFETCH IN MPD Jan 11 15:48:18 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand add "USB/FLORIN_32GB/1NEW DANCE/antonio-jose-te-parece-audio.mp4" Jan 11 15:48:18 volumio volumio[1216]: info: Jan 11 15:48:18 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Jan 11 15:48:18 volumio volumio[1216]: info: Ignoring MPD Status Update Jan 11 15:48:18 volumio volumio[1216]: info: sendMpdCommand add "USB/FLORIN_32GB/1NEW DANCE/antonio-jose-te-parece-audio.mp4" took 1 milliseconds Jan 11 15:48:18 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand consume 1 Jan 11 15:48:18 volumio volumio[1216]: info: Jan 11 15:48:18 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Jan 11 15:48:18 volumio volumio[1216]: info: Ignoring MPD Status Update Jan 11 15:48:18 volumio volumio[1216]: info: ------------------------------ 2ms Jan 11 15:48:18 volumio volumio[1216]: info: sendMpdCommand consume 1 took 1 milliseconds Jan 11 15:48:18 volumio volumio[1216]: info: ------------------------------ 1ms Jan 11 15:48:19 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:19 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:19 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:19 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:19 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:19 volumio volumio[1216]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jan 11 15:48:19 volumio volumio[1216]: Traceback (most recent call last): Jan 11 15:48:19 volumio volumio[1216]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 10, in Jan 11 15:48:19 volumio volumio[1216]: import pygame as pg Jan 11 15:48:19 volumio volumio[1216]: ModuleNotFoundError: No module named 'pygame' Jan 11 15:48:20 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:20 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:20 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:20 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:20 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:21 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:21 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:21 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:21 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:21 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:22 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:22 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:22 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:22 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:22 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 6 Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::startPlaybackTimer Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:23 volumio volumio[1216]: info: Jan 11 15:48:23 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Jan 11 15:48:23 volumio volumio[1216]: info: Ignoring MPD Status Update Jan 11 15:48:23 volumio volumio[1216]: info: Jan 11 15:48:23 volumio volumio[1216]: ---------------------------- MPD announces state update: player Jan 11 15:48:23 volumio volumio[1216]: info: ControllerMpd::getState Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:48:23 volumio volumio[1216]: info: Jan 11 15:48:23 volumio volumio[1216]: ---------------------------- MPD announces system playlist update Jan 11 15:48:23 volumio volumio[1216]: info: Ignoring MPD Status Update Jan 11 15:48:23 volumio volumio[1216]: info: Jan 11 15:48:23 volumio volumio[1216]: ---------------------------- MPD announces state update: player Jan 11 15:48:23 volumio volumio[1216]: info: ControllerMpd::getState Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:48:23 volumio volumio[1216]: info: Jan 11 15:48:23 volumio volumio[1216]: ---------------------------- MPD announces state update: player Jan 11 15:48:23 volumio volumio[1216]: info: ControllerMpd::getState Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:48:23 volumio volumio[1216]: info: Jan 11 15:48:23 volumio volumio[1216]: ---------------------------- MPD announces state update: player Jan 11 15:48:23 volumio volumio[1216]: info: ------------------------------ 3ms Jan 11 15:48:23 volumio volumio[1216]: info: sendMpdCommand status took 3 milliseconds Jan 11 15:48:23 volumio volumio[1216]: info: ------------------------------ 3ms Jan 11 15:48:23 volumio volumio[1216]: info: ControllerMpd::getState Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand status Jan 11 15:48:23 volumio volumio[1216]: info: sendMpdCommand status took 2 milliseconds Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::parseState Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::parseState Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:48:23 volumio volumio[1216]: info: sendMpdCommand status took 3 milliseconds Jan 11 15:48:23 volumio volumio[1216]: info: sendMpdCommand status took 2 milliseconds Jan 11 15:48:23 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 11 15:48:23 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 1 milliseconds Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::parseState Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::parseState Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::sendMpdCommand playlistinfo Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:48:23 volumio volumio[1216]: info: ControllerMpd::pushState Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:23 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":155,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Te Parece","artist":"Antonio Jos","album":null,"uri":"USB/FLORIN_32GB/1NEW DANCE/antonio-jose-te-parece-audio.mp4","trackType":"mp4"} Jan 11 15:48:23 volumio volumio[1216]: verbose: CURRENT POSITION 7 Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:48:23 volumio volumio[1216]: info: Received an update from plugin. extracting info from payload Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::pushState Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::pushState Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Jan 11 15:48:23 volumio volumio[1216]: info: ControllerMpd::pushState Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:23 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":155,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Te Parece","artist":"Antonio Jos","album":null,"uri":"USB/FLORIN_32GB/1NEW DANCE/antonio-jose-te-parece-audio.mp4","trackType":"mp4"} Jan 11 15:48:23 volumio volumio[1216]: verbose: CURRENT POSITION 7 Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:48:23 volumio volumio[1216]: info: Received an update from plugin. extracting info from payload Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::pushState Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::pushState Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Jan 11 15:48:23 volumio volumio[1216]: info: ------------------------------ 20ms Jan 11 15:48:23 volumio volumio[1216]: info: ------------------------------ 20ms Jan 11 15:48:23 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 17 milliseconds Jan 11 15:48:23 volumio volumio[1216]: info: sendMpdCommand playlistinfo took 16 milliseconds Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:48:23 volumio volumio[1216]: verbose: ControllerMpd::parseTrackInfo Jan 11 15:48:23 volumio volumio[1216]: info: ControllerMpd::pushState Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:23 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":155,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Te Parece","artist":"Antonio Jos","album":null,"uri":"USB/FLORIN_32GB/1NEW DANCE/antonio-jose-te-parece-audio.mp4","trackType":"mp4"} Jan 11 15:48:23 volumio volumio[1216]: verbose: CURRENT POSITION 7 Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:48:23 volumio volumio[1216]: info: Received an update from plugin. extracting info from payload Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::pushState Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::pushState Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Jan 11 15:48:23 volumio volumio[1216]: info: ControllerMpd::pushState Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::servicePushState Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:23 volumio volumio[1216]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":155,"samplerate":44.1,"bitdepth":"32 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"127 Kbps","isStreaming":false,"title":"Te Parece","artist":"Antonio Jos","album":null,"uri":"USB/FLORIN_32GB/1NEW DANCE/antonio-jose-te-parece-audio.mp4","trackType":"mp4"} Jan 11 15:48:23 volumio volumio[1216]: verbose: CURRENT POSITION 7 Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::syncState stateService play Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::syncState currentStatus play Jan 11 15:48:23 volumio volumio[1216]: info: Received an update from plugin. extracting info from payload Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::pushState Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Jan 11 15:48:23 volumio volumio[1216]: info: CoreStateMachine::pushState Jan 11 15:48:23 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:23 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Jan 11 15:48:23 volumio volumio[1216]: info: ------------------------------ 50ms Jan 11 15:48:23 volumio volumio[1216]: info: ------------------------------ 49ms Jan 11 15:48:24 volumio volumio[1216]: info: FusionDsp - Volumio is playing Jan 11 15:48:24 volumio volumio[1216]: info: FusionDsp - Volumio is playing Jan 11 15:48:24 volumio volumio[1216]: info: FusionDsp - Volumio is playing Jan 11 15:48:24 volumio volumio[1216]: info: FusionDsp - Volumio is playing Jan 11 15:48:24 volumio volumio[1216]: info: FusionDsp - Volumio is playing Jan 11 15:48:24 volumio volumio[1216]: info: FusionDsp - Volumio is playing Jan 11 15:48:24 volumio volumio[1216]: info: FusionDsp - Volumio is playing Jan 11 15:48:24 volumio volumio[1216]: info: FusionDsp - Volumio is playing Jan 11 15:48:24 volumio volumio[1216]: info: CoreStateMachine::pushState Jan 11 15:48:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:24 volumio volumio[1216]: info: CoreCommandRouter::volumioPushState Jan 11 15:48:24 volumio volumio[1216]: info: FusionDsp - Volumio is playing Jan 11 15:48:24 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:24 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:24 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:24 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:24 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:24 volumio volumio[1216]: info: Enabling plugin spop Jan 11 15:48:24 volumio volumio[1216]: info: Loading plugin "spop"... Jan 11 15:48:25 volumio volumio[1216]: info: PLUGIN START: spop Jan 11 15:48:25 volumio volumio[1216]: info: Creating Spotify config file Jan 11 15:48:25 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 15:48:25 volumio volumio[1216]: info: Done. Jan 11 15:48:25 volumio volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 15:48:25 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:25 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:25 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:25 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:25 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:25 volumio volumio[1216]: info: Spotify config file written Jan 11 15:48:25 volumio volumio[1216]: info: No need to fix Spotify hosts Jan 11 15:48:25 volumio sudo[6967]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 15:48:25 volumio sudo[6967]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:48:25 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:9: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 15:48:25 volumio systemd[1]: /lib/systemd/system/go-librespot-daemon.service:10: Standard output type syslog is obsolete, automatically updating to journal. Please update your unit file, and consider removing the setting altogether. Jan 11 15:48:25 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 15:48:25 volumio sudo[6967]: pam_unix(sudo:session): session closed for user root Jan 11 15:48:25 volumio go-librespot[6969]: go-librespot daemon starting... Jan 11 15:48:25 volumio go-librespot[6970]: time="2026-01-11T15:48:25+01:00" level=info msg="running go-librespot 0.6.2" Jan 11 15:48:25 volumio go-librespot[6970]: time="2026-01-11T15:48:25+01:00" level=debug msg="no app state found" Jan 11 15:48:25 volumio go-librespot[6970]: time="2026-01-11T15:48:25+01:00" level=debug msg="stored credentials not found" Jan 11 15:48:25 volumio go-librespot[6970]: time="2026-01-11T15:48:25+01:00" level=info msg="generated new device id: fb5711e5ac6a462858d14f01d4a2b297551f0628" Jan 11 15:48:25 volumio go-librespot[6970]: time="2026-01-11T15:48:25+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 15:48:26 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:26 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:26 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:26 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:26 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:26 volumio go-librespot[6970]: time="2026-01-11T15:48:26+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 11 15:48:26 volumio go-librespot[6970]: time="2026-01-11T15:48:26+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 11 15:48:26 volumio go-librespot[6970]: time="2026-01-11T15:48:26+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 11 15:48:26 volumio go-librespot[6970]: time="2026-01-11T15:48:26+01:00" level=info msg="zeroconf server listening on port 40403" Jan 11 15:48:26 volumio volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 15:48:27 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:27 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:27 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:27 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:27 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:28 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:28 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:28 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:28 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:28 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:28 volumio volumio[1216]: info: go-librespot daemon successfully initialized Jan 11 15:48:29 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:29 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:29 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:29 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:29 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:29 volumio volumio[1216]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jan 11 15:48:29 volumio volumio[1216]: Traceback (most recent call last): Jan 11 15:48:29 volumio volumio[1216]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 10, in Jan 11 15:48:29 volumio volumio[1216]: import pygame as pg Jan 11 15:48:29 volumio volumio[1216]: ModuleNotFoundError: No module named 'pygame' Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:30 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:30 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:30 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:30 volumio volumio[1216]: info: Received OAUTH Data Jan 11 15:48:30 volumio volumio[1216]: info: Executing Spotify Oauth Login Jan 11 15:48:30 volumio volumio[1216]: info: Saving Spotify Refresh Token Jan 11 15:48:30 volumio sudo[7009]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Jan 11 15:48:30 volumio sudo[7009]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:48:30 volumio sudo[7009]: pam_unix(sudo:session): session closed for user root Jan 11 15:48:30 volumio sudo[7011]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Jan 11 15:48:30 volumio sudo[7011]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:48:30 volumio volumio[1216]: info: New Spotify access tokenBQA7svHjsI... Jan 11 15:48:30 volumio sudo[7011]: pam_unix(sudo:session): session closed for user root Jan 11 15:48:30 volumio volumio[1216]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 11 15:48:30 volumio volumio[1216]: verbose: New Socket.io Connection to 192.168.1.176 from 192.168.1.148 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64; rv:146.0) Gecko/20100101 Firefox/146.0 Engine version: 3 Transport: polling Total Clients: 9 Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::volumioGetVisibleSources Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:30 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Jan 11 15:48:30 volumio volumio[1216]: info: Received Get System Info Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 15:48:30 volumio volumio[1216]: info: Discovery: Getting this device information Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:30 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:30 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:30 volumio volumio[1216]: info: Listing playlists Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 15:48:30 volumio volumio[1216]: SPOTIFY: User informations: {"country":"ES","display_name":"Florin","email":"florin_1220@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/116522351"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/116522351","id":"116522351","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85b9a5eda28584389bc4e7d494","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82b9a5eda28584389bc4e7d494","width":64}],"product":"premium","type":"user","uri":"spotify:user:116522351"} Jan 11 15:48:30 volumio volumio[1216]: info: Creating Spotify config file Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::getUIConfigOnPlugin Jan 11 15:48:30 volumio volumio[1216]: info: Spotify config file written Jan 11 15:48:30 volumio sudo[7015]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 15:48:30 volumio sudo[7015]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:48:30 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 11 15:48:30 volumio systemd[1]: go-librespot-daemon.service: Killing process 6974 (go-librespot) with signal SIGKILL. Jan 11 15:48:30 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 11 15:48:30 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 15:48:30 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 15:48:30 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Jan 11 15:48:30 volumio go-librespot[7018]: go-librespot daemon starting... Jan 11 15:48:30 volumio sudo[7015]: pam_unix(sudo:session): session closed for user root Jan 11 15:48:30 volumio go-librespot[7019]: time="2026-01-11T15:48:30+01:00" level=info msg="running go-librespot 0.6.2" Jan 11 15:48:30 volumio go-librespot[7019]: time="2026-01-11T15:48:30+01:00" level=debug msg="app state loaded" Jan 11 15:48:30 volumio go-librespot[7019]: time="2026-01-11T15:48:30+01:00" level=debug msg="stored credentials not found" Jan 11 15:48:31 volumio go-librespot[7019]: time="2026-01-11T15:48:31+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 15:48:31 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:31 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:31 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:31 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:31 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:31 volumio volumio[1216]: info: New Spotify access tokenBQB4UoQfGr... Jan 11 15:48:31 volumio volumio[1216]: info: Spotify credentials grant success - running version from March 24, 2019 Jan 11 15:48:31 volumio go-librespot[7019]: time="2026-01-11T15:48:31+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gew4.spotify.com:443 ap-gae2.spotify.com:80]" Jan 11 15:48:31 volumio go-librespot[7019]: time="2026-01-11T15:48:31+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gew4-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 11 15:48:31 volumio go-librespot[7019]: time="2026-01-11T15:48:31+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gew4-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 11 15:48:31 volumio go-librespot[7019]: time="2026-01-11T15:48:31+01:00" level=info msg="zeroconf server listening on port 34329" Jan 11 15:48:31 volumio volumio[1216]: SPOTIFY: User informations: {"country":"ES","display_name":"Florin","email":"florin_1220@hotmail.com","explicit_content":{"filter_enabled":false,"filter_locked":false},"external_urls":{"spotify":"https://open.spotify.com/user/116522351"},"followers":{"href":null,"total":2},"href":"https://api.spotify.com/v1/users/116522351","id":"116522351","images":[{"height":300,"url":"https://i.scdn.co/image/ab6775700000ee85b9a5eda28584389bc4e7d494","width":300},{"height":64,"url":"https://i.scdn.co/image/ab67757000003b82b9a5eda28584389bc4e7d494","width":64}],"product":"premium","type":"user","uri":"spotify:user:116522351"} Jan 11 15:48:31 volumio volumio[1216]: info: Spotify Successfully logged in Jan 11 15:48:31 volumio volumio[1216]: info: CoreCommandRouter::volumioAddToBrowseSources[object Object] Jan 11 15:48:31 volumio volumio[1216]: info: [1768142911274] CoreMusicLibrary::Adding element Spotify Jan 11 15:48:31 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Jan 11 15:48:31 volumio volumio[1216]: Cannot find translation for source Spotify Jan 11 15:48:31 volumio go-librespot[7019]: time="2026-01-11T15:48:31+01:00" level=debug msg="obtained new client token: AABdb6zgugSKoAwm9xyXVU2+YHpWvScXQkbnVeaU27Oskwyq3oODpd9QjIo2+6cdjUaJvyAGK5y3OwX4i92QWOKeJiAxO5iqbvJG8OhlhCF7wRPvK0O+4kzbwmzE4UwrUm92dHO4r4yJvPR3In29NSW3BqcLHLUdBT9Qf19FXHGBlSIOLejuzMcRIQ4z2VKWgcBxQiOsNsamRZfS+w8E1N8NzVxrotxq/s1j1UVWO/FQv7K5tSac1bc=" Jan 11 15:48:31 volumio go-librespot[7019]: time="2026-01-11T15:48:31+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" Jan 11 15:48:31 volumio go-librespot[7019]: time="2026-01-11T15:48:31+01:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: EOF" Jan 11 15:48:31 volumio volumio[1216]: info: Initializing connection to go-librespot Websocket Jan 11 15:48:31 volumio go-librespot[7019]: time="2026-01-11T15:48:31+01:00" level=debug msg="new websocket client" Jan 11 15:48:31 volumio volumio[1216]: info: Connection to go-librespot Websocket established Jan 11 15:48:31 volumio go-librespot[7019]: time="2026-01-11T15:48:31+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Jan 11 15:48:31 volumio go-librespot[7019]: time="2026-01-11T15:48:31+01:00" level=debug msg="completed keyexchange" Jan 11 15:48:31 volumio go-librespot[7019]: time="2026-01-11T15:48:31+01:00" level=debug msg="completed challenge" Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=info msg="authenticated AP" username="11*****51" Jan 11 15:48:32 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:32 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:32 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:32 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:32 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=info msg="authenticated Login5" username="11*****51" Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=debug msg="stored credentials" username="11*****51" Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=debug msg="initializing zeroconf session" username="11*****51" Jan 11 15:48:32 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=debug msg="dealer connection opened" Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=trace msg="starting accesspoint recv loop" Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=trace msg="starting dealer recv loop" Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=trace msg="received accesspoint ping" Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=debug msg="received connection id: ZDY3OTcwODUtODdk...NzlDMDc4OUU0OQ==" Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=trace msg="received accesspoint pong ack" Jan 11 15:48:32 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 15:48:32 volumio volumio[1216]: info: Received Get System Info Jan 11 15:48:32 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 15:48:32 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 15:48:32 volumio volumio[1216]: info: Discovery: Getting this device information Jan 11 15:48:32 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:32 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:32 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 15:48:32 volumio go-librespot[7019]: time="2026-01-11T15:48:32+01:00" level=debug msg="put connect state because NEW_DEVICE" Jan 11 15:48:33 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:33 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:33 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:33 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:33 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:33 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Jan 11 15:48:33 volumio volumio[1216]: info: Received Get System Info Jan 11 15:48:33 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 15:48:33 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 15:48:33 volumio volumio[1216]: info: Discovery: Getting this device information Jan 11 15:48:33 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:33 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:33 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 15:48:33 volumio volumio[1216]: info: go-librespot daemon successfully initialized Jan 11 15:48:34 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:34 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:34 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:34 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:34 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:34 volumio volumio[1216]: info: Getting Spotify volume Jan 11 15:48:34 volumio volumio[1216]: info: Spotify volume: 100 Jan 11 15:48:34 volumio volumio[1216]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jan 11 15:48:34 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:34 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:34 volumio volumio[1216]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jan 11 15:48:34 volumio volumio[1216]: SPOTIFY: SPOTIFY VOLUME 100 Jan 11 15:48:34 volumio volumio[1216]: SPOTIFY: VOLUMIO VOLUME 57 Jan 11 15:48:34 volumio volumio[1216]: SPOTIFY: DELTA VOLUME ENOUGH: true Jan 11 15:48:34 volumio volumio[1216]: info: Setting Spotify Volume from Volumio: 57 Jan 11 15:48:35 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:35 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:35 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:35 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:35 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:36 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:36 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:36 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:36 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:36 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:36 volumio volumio[1216]: SPOTIFY: SETTING SPOTIFY VOLUME 57 Jan 11 15:48:36 volumio volumio[1216]: info: Sending Spotify command with payload to local API: /player/volume Jan 11 15:48:36 volumio go-librespot[7019]: time="2026-01-11T15:48:36+01:00" level=debug msg="update volume requested to 37354/65535" Jan 11 15:48:36 volumio go-librespot[7019]: time="2026-01-11T15:48:36+01:00" level=debug msg="put connect state because VOLUME_CHANGED" Jan 11 15:48:36 volumio go-librespot[7019]: time="2026-01-11T15:48:36+01:00" level=trace msg="emitting websocket event: volume" Jan 11 15:48:36 volumio volumio[1216]: SPOTIFY: received: {"type":"volume","data":{"value":57,"max":100}} Jan 11 15:48:36 volumio volumio[1216]: SPOTIFY: RECEIVED SPOTIFY VOLUME 57 Jan 11 15:48:36 volumio volumio[1216]: info: Initializing connection to go-librespot Websocket Jan 11 15:48:36 volumio go-librespot[7019]: time="2026-01-11T15:48:36+01:00" level=debug msg="new websocket client" Jan 11 15:48:36 volumio volumio[1216]: info: Connection to go-librespot Websocket established Jan 11 15:48:37 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:37 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:37 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:37 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:37 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:38 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:38 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:38 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:38 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:38 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:39 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:39 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:39 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:39 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:39 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:39 volumio volumio[1216]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jan 11 15:48:39 volumio volumio[1216]: Traceback (most recent call last): Jan 11 15:48:39 volumio volumio[1216]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 10, in Jan 11 15:48:39 volumio volumio[1216]: import pygame as pg Jan 11 15:48:39 volumio volumio[1216]: ModuleNotFoundError: No module named 'pygame' Jan 11 15:48:39 volumio volumio[1216]: info: Getting Spotify volume Jan 11 15:48:39 volumio volumio[1216]: info: Spotify volume: 57 Jan 11 15:48:40 volumio volumio[1216]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jan 11 15:48:40 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:40 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:40 volumio volumio[1216]: SPOTIFY: RECEIVED VOLUMIO VOLUME 57 Jan 11 15:48:40 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:40 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:40 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:40 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:40 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:40 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: appearance , isLatestTOSAccepted Jan 11 15:48:41 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:41 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:41 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:41 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:41 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:42 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:42 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:42 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:42 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:42 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:43 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:43 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:43 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:43 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:43 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:44 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:44 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:44 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:44 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:44 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:44 volumio volumio[1216]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Jan 11 15:48:44 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Jan 11 15:48:44 volumio volumio[1216]: info: Creating Spotify config file Jan 11 15:48:44 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 15:48:44 volumio volumio[1216]: info: Spotify config file written Jan 11 15:48:44 volumio sudo[7093]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 15:48:44 volumio sudo[7093]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:48:44 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 11 15:48:44 volumio systemd[1]: go-librespot-daemon.service: Killing process 7029 (go-librespot) with signal SIGKILL. Jan 11 15:48:44 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 11 15:48:44 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 15:48:44 volumio volumio[1216]: info: Connection to go-librespot Websocket closed Jan 11 15:48:44 volumio volumio[1216]: info: Connection to go-librespot Websocket closed Jan 11 15:48:44 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 15:48:44 volumio go-librespot[7095]: go-librespot daemon starting... Jan 11 15:48:44 volumio sudo[7093]: pam_unix(sudo:session): session closed for user root Jan 11 15:48:44 volumio go-librespot[7097]: time="2026-01-11T15:48:44+01:00" level=info msg="running go-librespot 0.6.2" Jan 11 15:48:44 volumio go-librespot[7097]: time="2026-01-11T15:48:44+01:00" level=debug msg="app state loaded" Jan 11 15:48:44 volumio go-librespot[7097]: time="2026-01-11T15:48:44+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 15:48:44 volumio go-librespot[7097]: time="2026-01-11T15:48:44+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gae2.spotify.com:80]" Jan 11 15:48:44 volumio go-librespot[7097]: time="2026-01-11T15:48:44+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Jan 11 15:48:44 volumio go-librespot[7097]: time="2026-01-11T15:48:44+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Jan 11 15:48:44 volumio go-librespot[7097]: time="2026-01-11T15:48:44+01:00" level=info msg="zeroconf server listening on port 33601" Jan 11 15:48:44 volumio go-librespot[7097]: time="2026-01-11T15:48:44+01:00" level=debug msg="obtained new client token: AAA0+Lz6mkPA9WKiyCgNNsyoqAROjdWPoCL25j9QMdfyApwJX6dOAQYJmBcFDzPnsw8egwF8otcJrdiYct6EtXQSMW5yGX1kxuJb/qqh2JRpaKIlJnrC+DuE5/84bRfuTyqk9rOC3GyeN5J7GfH5oCTViVpBn2HMgdtoTP9C8gg4svx5TrelJyFxIpwpj4hrIYghgOTi625jQUXQhQ1jW3ol4aSg5lTsVZNZdsK+CWBixHEXCyH9DGw=" Jan 11 15:48:44 volumio go-librespot[7097]: time="2026-01-11T15:48:44+01:00" level=warning msg="failed to connect to AP ap-gew1.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.65.9:4070: connect: connection refused" Jan 11 15:48:44 volumio go-librespot[7097]: time="2026-01-11T15:48:44+01:00" level=debug msg="connected to ap-gew1.spotify.com:443" Jan 11 15:48:44 volumio go-librespot[7097]: time="2026-01-11T15:48:44+01:00" level=debug msg="completed keyexchange" Jan 11 15:48:44 volumio go-librespot[7097]: time="2026-01-11T15:48:44+01:00" level=debug msg="completed challenge" Jan 11 15:48:44 volumio go-librespot[7097]: time="2026-01-11T15:48:44+01:00" level=info msg="authenticated AP" username="11*****51" Jan 11 15:48:45 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:45 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:45 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:45 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:45 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:45 volumio go-librespot[7097]: time="2026-01-11T15:48:45+01:00" level=info msg="authenticated Login5" username="11*****51" Jan 11 15:48:45 volumio go-librespot[7097]: time="2026-01-11T15:48:45+01:00" level=debug msg="initializing zeroconf session" username="11*****51" Jan 11 15:48:45 volumio go-librespot[7097]: time="2026-01-11T15:48:45+01:00" level=debug msg="dealer connection opened" Jan 11 15:48:45 volumio go-librespot[7097]: time="2026-01-11T15:48:45+01:00" level=trace msg="starting accesspoint recv loop" Jan 11 15:48:45 volumio go-librespot[7097]: time="2026-01-11T15:48:45+01:00" level=trace msg="starting dealer recv loop" Jan 11 15:48:45 volumio go-librespot[7097]: time="2026-01-11T15:48:45+01:00" level=trace msg="received accesspoint ping" Jan 11 15:48:45 volumio go-librespot[7097]: time="2026-01-11T15:48:45+01:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Jan 11 15:48:45 volumio go-librespot[7097]: time="2026-01-11T15:48:45+01:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Jan 11 15:48:45 volumio go-librespot[7097]: time="2026-01-11T15:48:45+01:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Jan 11 15:48:45 volumio go-librespot[7097]: time="2026-01-11T15:48:45+01:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Jan 11 15:48:45 volumio go-librespot[7097]: time="2026-01-11T15:48:45+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Jan 11 15:48:45 volumio go-librespot[7097]: time="2026-01-11T15:48:45+01:00" level=debug msg="received connection id: NmQ5YTZlZTItMmNi...RUZBRDk5NEE5Mg==" Jan 11 15:48:45 volumio go-librespot[7097]: time="2026-01-11T15:48:45+01:00" level=trace msg="received accesspoint pong ack" Jan 11 15:48:45 volumio go-librespot[7097]: time="2026-01-11T15:48:45+01:00" level=debug msg="put connect state because NEW_DEVICE" Jan 11 15:48:46 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:46 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:46 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:46 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:46 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:47 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:47 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:47 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:47 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:47 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:47 volumio volumio[1216]: info: Initializing connection to go-librespot Websocket Jan 11 15:48:47 volumio go-librespot[7097]: time="2026-01-11T15:48:47+01:00" level=debug msg="new websocket client" Jan 11 15:48:47 volumio volumio[1216]: info: Connection to go-librespot Websocket established Jan 11 15:48:47 volumio volumio[1216]: info: go-librespot daemon successfully initialized Jan 11 15:48:48 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:48 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:48 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:48 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:48 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:48 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Jan 11 15:48:48 volumio volumio[1216]: info: CALLMETHOD: system_controller my_volumio retreiveBackendEventStates undefined Jan 11 15:48:48 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: my_volumio , retreiveBackendEventStates Jan 11 15:48:48 volumio volumio[1216]: info: Received Get System Version Jan 11 15:48:48 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Jan 11 15:48:48 volumio volumio[1216]: info: Received Get System Info Jan 11 15:48:48 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Jan 11 15:48:48 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Jan 11 15:48:48 volumio volumio[1216]: info: Discovery: Getting this device information Jan 11 15:48:48 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:48 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:48 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Jan 11 15:48:49 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:49 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:49 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:49 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:49 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:49 volumio volumio[1216]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Jan 11 15:48:49 volumio volumio[1216]: Traceback (most recent call last): Jan 11 15:48:49 volumio volumio[1216]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 10, in Jan 11 15:48:49 volumio volumio[1216]: import pygame as pg Jan 11 15:48:49 volumio volumio[1216]: ModuleNotFoundError: No module named 'pygame' Jan 11 15:48:50 volumio volumio[1216]: info: CoreCommandRouter::volumioGetState Jan 11 15:48:50 volumio volumio[1216]: info: CorePlayQueue::getTrack 7 Jan 11 15:48:50 volumio volumio[1216]: info: CoreCommandRouter::volumioGetQueue Jan 11 15:48:50 volumio volumio[1216]: info: CoreStateMachine::getQueue Jan 11 15:48:50 volumio volumio[1216]: info: CorePlayQueue::getQueue Jan 11 15:48:50 volumio volumio[1216]: info: CALLMETHOD: music_service spop saveGoLibrespotSettings [object Object] Jan 11 15:48:50 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: spop , saveGoLibrespotSettings Jan 11 15:48:50 volumio volumio[1216]: info: Creating Spotify config file Jan 11 15:48:50 volumio volumio[1216]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Jan 11 15:48:50 volumio volumio[1216]: info: Spotify config file written Jan 11 15:48:50 volumio sudo[7151]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Jan 11 15:48:50 volumio sudo[7151]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:48:50 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Jan 11 15:48:50 volumio systemd[1]: go-librespot-daemon.service: Killing process 7100 (go-librespot) with signal SIGKILL. Jan 11 15:48:50 volumio volumio[1216]: info: Connection to go-librespot Websocket closed Jan 11 15:48:50 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Jan 11 15:48:50 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jan 11 15:48:50 volumio volumio[1216]: info: Getting Spotify volume Jan 11 15:48:50 volumio volumio[1216]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 15:48:50 volumio volumio[1216]: Error: connect ECONNREFUSED 127.0.0.1:9879 Jan 11 15:48:50 volumio volumio[1216]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Jan 11 15:48:50 volumio volumio[1216]: errno: -111, Jan 11 15:48:50 volumio volumio[1216]: code: 'ECONNREFUSED', Jan 11 15:48:50 volumio volumio[1216]: syscall: 'connect', Jan 11 15:48:50 volumio volumio[1216]: address: '127.0.0.1', Jan 11 15:48:50 volumio volumio[1216]: port: 9879, Jan 11 15:48:50 volumio volumio[1216]: response: undefined Jan 11 15:48:50 volumio volumio[1216]: } Jan 11 15:48:50 volumio volumio[1216]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jan 11 15:48:50 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jan 11 15:48:50 volumio go-librespot[7153]: go-librespot daemon starting... Jan 11 15:48:50 volumio sudo[7151]: pam_unix(sudo:session): session closed for user root Jan 11 15:48:50 volumio go-librespot[7165]: time="2026-01-11T15:48:50+01:00" level=info msg="running go-librespot 0.6.2" Jan 11 15:48:50 volumio go-librespot[7165]: time="2026-01-11T15:48:50+01:00" level=debug msg="app state loaded" Jan 11 15:48:50 volumio go-librespot[7165]: time="2026-01-11T15:48:50+01:00" level=info msg="api server listening on 127.0.0.1:9879" Jan 11 15:48:50 volumio go-librespot[7165]: time="2026-01-11T15:48:50+01:00" level=debug msg="fetched new accesspoints: [ap-gew1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gew1.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]" Jan 11 15:48:50 volumio go-librespot[7165]: time="2026-01-11T15:48:50+01:00" level=debug msg="fetched new dealers: [gew1-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]" Jan 11 15:48:50 volumio go-librespot[7165]: time="2026-01-11T15:48:50+01:00" level=debug msg="fetched new spclients: [gew1-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]" Jan 11 15:48:50 volumio go-librespot[7165]: time="2026-01-11T15:48:50+01:00" level=info msg="zeroconf server listening on port 41437" Jan 11 15:48:50 volumio go-librespot[7165]: time="2026-01-11T15:48:50+01:00" level=debug msg="obtained new client token: AAA4qDcsZIb8wB/8tvNx4f3Ldarxa7qtsbxwVMN0ZayKvohlBIrjvWQEmH2BGCepgrHR5RSDlgY5ciKT8P2rDe6i7ERvfIpl01TBaDz03NgUYGxFmFZ9D1N+tU60cKJQSxZTJmDN8ALryuM2BodGV6CIEDZlzSRkjwe5cmN9S3C0o7Xw/ueHI/Zgha1wgmClM9+y952/DLYZeqfNekztrQwY3NHfstCseKUDCkVxFR1mGt3l1G7Veh4=" Jan 11 15:48:50 volumio sudo[7178]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-01-11 15:47' Jan 11 15:48:50 volumio sudo[7178]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jan 11 15:48:50 volumio go-librespot[7165]: time="2026-01-11T15:48:50+01:00" level=debug msg="connected to ap-gew1.spotify.com:4070" PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"