Feb 24 02:10:00 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:00 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:00 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:00 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:00 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:00 volumio volumio[1265]: import socketio Feb 24 02:10:00 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:01 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:01 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:01 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:01 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:01 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:01 volumio volumio[1265]: import socketio Feb 24 02:10:01 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:02 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:02 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:02 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:02 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:02 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:02 volumio volumio[1265]: import socketio Feb 24 02:10:02 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:03 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:03 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:03 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:03 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:03 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:03 volumio volumio[1265]: import socketio Feb 24 02:10:03 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:03 volumio volumio[1265]: info: CoreCommandRouter::volumioGetState Feb 24 02:10:03 volumio volumio[1265]: info: CorePlayQueue::getTrack 1 Feb 24 02:10:04 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:04 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:04 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:04 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:04 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:04 volumio volumio[1265]: import socketio Feb 24 02:10:04 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:05 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:05 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:05 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:05 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:05 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:05 volumio volumio[1265]: import socketio Feb 24 02:10:05 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 02:10:05 volumio volumio[1265]: info: Retrieving Cloud Streaming UI Feb 24 02:10:05 volumio volumio[1265]: info: Getting Tidal Cloud Configuration Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 02:10:05 volumio volumio[1265]: info: Getting Qobuz Cloud Configuration Feb 24 02:10:05 volumio volumio[1265]: info: Asking plugin for UI Config Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 02:10:05 volumio volumio[1265]: info: Getting Spotify Cloud Configuration Feb 24 02:10:05 volumio volumio[1265]: info: Asking plugin for UI Config Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 02:10:05 volumio volumio[1265]: info: Saving Spotify Acccount Feb 24 02:10:05 volumio volumio[1265]: info: Got Tidal Cloud Configuration Feb 24 02:10:05 volumio volumio[1265]: info: Got it Feb 24 02:10:05 volumio volumio[1265]: info: Got it Feb 24 02:10:05 volumio volumio[1265]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 02:10:05 volumio volumio[1265]: error: Error retrieving Highresaudio conf: TypeError: Cannot read properties of undefined (reading 'onSave') Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 02:10:05 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Feb 24 02:10:06 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:06 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:06 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:06 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:06 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:06 volumio volumio[1265]: import socketio Feb 24 02:10:06 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:07 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:07 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:07 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:07 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:07 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:07 volumio volumio[1265]: import socketio Feb 24 02:10:07 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:08 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:08 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:08 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:08 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:08 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:08 volumio volumio[1265]: import socketio Feb 24 02:10:08 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:08 volumio go-librespot[5630]: time="2026-02-24T02:10:08+07:00" level=trace msg="sent dealer ping" Feb 24 02:10:08 volumio go-librespot[5630]: time="2026-02-24T02:10:08+07:00" level=trace msg="received dealer pong" Feb 24 02:10:09 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:09 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:09 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:09 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:09 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:09 volumio volumio[1265]: import socketio Feb 24 02:10:09 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:09 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 02:10:10 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:10 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:10 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:10 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:10 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:10 volumio volumio[1265]: import socketio Feb 24 02:10:10 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:11 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:11 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:11 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:11 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:11 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:11 volumio volumio[1265]: import socketio Feb 24 02:10:11 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:12 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:12 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:12 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:12 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:12 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:12 volumio volumio[1265]: import socketio Feb 24 02:10:12 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:13 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:13 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:13 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:13 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:13 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:13 volumio volumio[1265]: import socketio Feb 24 02:10:13 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:13 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 02:10:14 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:14 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:14 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:14 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:14 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:14 volumio volumio[1265]: import socketio Feb 24 02:10:14 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:15 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:15 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:15 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:15 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:15 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:15 volumio volumio[1265]: import socketio Feb 24 02:10:15 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:16 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:16 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:16 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:16 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:16 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:16 volumio volumio[1265]: import socketio Feb 24 02:10:16 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:17 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:17 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:17 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:17 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:17 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:17 volumio volumio[1265]: import socketio Feb 24 02:10:17 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:17 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 02:10:18 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:18 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:18 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:18 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:18 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:18 volumio volumio[1265]: import socketio Feb 24 02:10:18 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:19 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:19 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:19 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:19 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:19 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:19 volumio volumio[1265]: import socketio Feb 24 02:10:19 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:20 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:20 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:20 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:20 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:20 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:20 volumio volumio[1265]: import socketio Feb 24 02:10:20 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:21 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:21 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:21 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:21 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:21 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:21 volumio volumio[1265]: import socketio Feb 24 02:10:21 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:21 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 02:10:22 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:22 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:22 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:22 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:22 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:22 volumio volumio[1265]: import socketio Feb 24 02:10:22 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:23 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:23 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:23 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:23 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:23 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:23 volumio volumio[1265]: import socketio Feb 24 02:10:23 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:24 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:24 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:24 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:24 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:24 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:24 volumio volumio[1265]: import socketio Feb 24 02:10:24 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:25 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:25 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:25 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:25 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:25 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:25 volumio volumio[1265]: import socketio Feb 24 02:10:25 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:25 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 02:10:26 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:26 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:26 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:26 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:26 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:26 volumio volumio[1265]: import socketio Feb 24 02:10:26 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:27 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:27 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:27 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:27 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:27 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:27 volumio volumio[1265]: import socketio Feb 24 02:10:27 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:28 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:28 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:28 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:28 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:28 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:28 volumio volumio[1265]: import socketio Feb 24 02:10:28 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:29 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:29 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:29 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:29 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:29 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:29 volumio volumio[1265]: import socketio Feb 24 02:10:29 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:29 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 02:10:30 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:30 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:30 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:30 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:30 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:30 volumio volumio[1265]: import socketio Feb 24 02:10:30 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:31 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:31 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:31 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:31 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:31 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:31 volumio volumio[1265]: import socketio Feb 24 02:10:31 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:32 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:32 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:32 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:32 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:32 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:32 volumio volumio[1265]: import socketio Feb 24 02:10:32 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:33 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:33 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:33 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:33 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:33 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:33 volumio volumio[1265]: import socketio Feb 24 02:10:33 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:33 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 02:10:34 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:34 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:34 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:34 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:34 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:34 volumio volumio[1265]: import socketio Feb 24 02:10:34 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:35 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:35 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:35 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:35 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:35 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:35 volumio volumio[1265]: import socketio Feb 24 02:10:35 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:36 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:36 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:36 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:36 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:36 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:36 volumio volumio[1265]: import socketio Feb 24 02:10:36 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:37 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:37 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:37 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:37 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:37 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:37 volumio volumio[1265]: import socketio Feb 24 02:10:37 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:37 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 02:10:38 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:38 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:38 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:38 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:38 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:38 volumio volumio[1265]: import socketio Feb 24 02:10:38 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:38 volumio go-librespot[5630]: time="2026-02-24T02:10:38+07:00" level=trace msg="sent dealer ping" Feb 24 02:10:38 volumio go-librespot[5630]: time="2026-02-24T02:10:38+07:00" level=trace msg="received dealer pong" Feb 24 02:10:38 volumio volumio[1265]: info: CALLMETHOD: miscellanea my_music updateMusicLibrarySupersearchSources [object Object] Feb 24 02:10:38 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_music , updateMusicLibrarySupersearchSources Feb 24 02:10:39 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:39 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:39 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:39 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:39 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:39 volumio volumio[1265]: import socketio Feb 24 02:10:39 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:39 volumio volumio[1265]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 02:10:39 volumio volumio[1265]: info: CoreCommandRouter::volumioGetBrowseSources Feb 24 02:10:39 volumio volumio[1265]: info: CoreCommandRouter::updateGlobalSearchableBrowseSources Feb 24 02:10:40 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:40 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:40 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:40 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:40 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:40 volumio volumio[1265]: import socketio Feb 24 02:10:40 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:41 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:41 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:41 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:41 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:41 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:41 volumio volumio[1265]: import socketio Feb 24 02:10:41 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:41 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Feb 24 02:10:42 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:42 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:42 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:42 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:42 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:42 volumio volumio[1265]: import socketio Feb 24 02:10:42 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:43 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:43 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:43 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:43 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:43 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:43 volumio volumio[1265]: import socketio Feb 24 02:10:43 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:44 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:44 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:44 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:44 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:44 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:44 volumio volumio[1265]: import socketio Feb 24 02:10:44 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:44 volumio volumio[1265]: info: CoreCommandRouter::volumioGetState Feb 24 02:10:44 volumio volumio[1265]: info: CorePlayQueue::getTrack 1 Feb 24 02:10:45 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:45 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:45 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:45 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:45 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:45 volumio volumio[1265]: import socketio Feb 24 02:10:45 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:46 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:46 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:46 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:46 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:46 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:46 volumio volumio[1265]: import socketio Feb 24 02:10:46 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:47 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Feb 24 02:10:47 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Feb 24 02:10:47 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:47 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:47 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:47 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:47 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:47 volumio volumio[1265]: import socketio Feb 24 02:10:47 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:48 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:48 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:48 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:48 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:48 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:48 volumio volumio[1265]: import socketio Feb 24 02:10:48 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:49 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:49 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:49 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:49 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:49 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:49 volumio volumio[1265]: import socketio Feb 24 02:10:49 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:50 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:50 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:50 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:50 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:50 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:50 volumio volumio[1265]: import socketio Feb 24 02:10:50 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:51 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:51 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:51 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:51 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:51 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:51 volumio volumio[1265]: import socketio Feb 24 02:10:51 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:52 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:52 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:52 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:52 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:52 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:52 volumio volumio[1265]: import socketio Feb 24 02:10:52 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:52 volumio volumio[1265]: info: CoreCommandRouter::getUIConfigOnPlugin Feb 24 02:10:52 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 02:10:53 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:53 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:53 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:53 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:53 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:53 volumio volumio[1265]: import socketio Feb 24 02:10:53 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:54 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:54 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:54 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:54 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:54 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:54 volumio volumio[1265]: import socketio Feb 24 02:10:54 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:55 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:55 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:55 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:55 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:55 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:55 volumio volumio[1265]: import socketio Feb 24 02:10:55 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:56 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:56 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:56 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:56 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:56 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:56 volumio volumio[1265]: import socketio Feb 24 02:10:56 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:57 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:57 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:57 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:57 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:57 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:57 volumio volumio[1265]: import socketio Feb 24 02:10:57 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:58 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:58 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:58 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:58 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:58 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:58 volumio volumio[1265]: import socketio Feb 24 02:10:58 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:10:59 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:10:59 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:10:59 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:10:59 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:10:59 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:10:59 volumio volumio[1265]: import socketio Feb 24 02:10:59 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:00 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:00 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:00 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:00 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:00 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:00 volumio volumio[1265]: import socketio Feb 24 02:11:00 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:01 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:01 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:01 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:01 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:01 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:01 volumio volumio[1265]: import socketio Feb 24 02:11:01 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:02 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:02 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:02 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:02 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:02 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:02 volumio volumio[1265]: import socketio Feb 24 02:11:02 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:03 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:03 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:03 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:03 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:03 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:03 volumio volumio[1265]: import socketio Feb 24 02:11:03 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:04 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:04 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:04 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:04 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:04 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:04 volumio volumio[1265]: import socketio Feb 24 02:11:04 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:05 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:05 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:05 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:05 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:05 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:05 volumio volumio[1265]: import socketio Feb 24 02:11:05 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:06 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:06 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:06 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:06 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:06 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:06 volumio volumio[1265]: import socketio Feb 24 02:11:06 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:07 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:07 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:07 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:07 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:07 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:07 volumio volumio[1265]: import socketio Feb 24 02:11:07 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:08 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:08 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:08 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:08 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:08 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:08 volumio volumio[1265]: import socketio Feb 24 02:11:08 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:08 volumio go-librespot[5630]: time="2026-02-24T02:11:08+07:00" level=trace msg="sent dealer ping" Feb 24 02:11:08 volumio go-librespot[5630]: time="2026-02-24T02:11:08+07:00" level=trace msg="received dealer pong" Feb 24 02:11:09 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:09 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:09 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:09 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:09 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:09 volumio volumio[1265]: import socketio Feb 24 02:11:09 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:10 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:10 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:10 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:10 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:10 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:10 volumio volumio[1265]: import socketio Feb 24 02:11:10 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:11 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:11 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:11 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:11 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:11 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:11 volumio volumio[1265]: import socketio Feb 24 02:11:11 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:11 volumio volumio[1265]: info: CALLMETHOD: user_interface peppy_screensaver savePeppyMeterConf [object Object] Feb 24 02:11:11 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: peppy_screensaver , savePeppyMeterConf Feb 24 02:11:11 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 02:11:11 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 02:11:11 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 02:11:11 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 02:11:11 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: spop , initializeLibrespotDaemon Feb 24 02:11:11 volumio volumio[1265]: info: Creating Spotify config file Feb 24 02:11:11 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 02:11:11 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , updateALSAConfigFile Feb 24 02:11:11 volumio volumio[1265]: info: Preparing to generate the ALSA configuration file Feb 24 02:11:11 volumio volumio[1265]: info: The plugin alsa_controller has an ALSA contribution file softvolume.postVolume.conf Feb 24 02:11:11 volumio volumio[1265]: info: The plugin peppy_screensaver has an ALSA contribution file Peppyalsa.postPeppyalsa.5.conf Feb 24 02:11:11 volumio volumio[1265]: info: Reading ALSA contributions from plugins. Feb 24 02:11:11 volumio volumio[1265]: info: Spotify config file written Feb 24 02:11:12 volumio volumio[1265]: info: Asound.conf file written Feb 24 02:11:12 volumio sudo[6070]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart go-librespot-daemon.service Feb 24 02:11:12 volumio sudo[6070]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 02:11:12 volumio sudo[6073]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/mv /home/volumio/.asoundrc /etc/asound.conf Feb 24 02:11:12 volumio sudo[6073]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 02:11:12 volumio sudo[6073]: pam_unix(sudo:session): session closed for user root Feb 24 02:11:12 volumio systemd[1]: Stopping go-librespot-daemon.service - go-librespot Daemon... Feb 24 02:11:12 volumio systemd[1]: go-librespot-daemon.service: Deactivated successfully. Feb 24 02:11:12 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 24 02:11:12 volumio volumio[1265]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:0 use case configuration -2 Feb 24 02:11:12 volumio volumio[1265]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:1 use case configuration -2 Feb 24 02:11:12 volumio volumio[1265]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:2 use case configuration -2 Feb 24 02:11:12 volumio volumio[1265]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:3 use case configuration -2 Feb 24 02:11:12 volumio volumio[1265]: alsa-lib main.c:1541:(snd_use_case_mgr_open) error: failed to import hw:7 use case configuration -2 Feb 24 02:11:12 volumio volumio[1265]: info: Output device has changed, restarting MPD Feb 24 02:11:12 volumio volumio[1265]: info: Output device has changed, restarting Shairport Sync Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 02:11:12 volumio sudo[6083]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/chmod 777 /etc/mpd.conf Feb 24 02:11:12 volumio sudo[6083]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 02:11:12 volumio sudo[6085]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 02:11:12 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 24 02:11:12 volumio sudo[6085]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 02:11:12 volumio sudo[6070]: pam_unix(sudo:session): session closed for user root Feb 24 02:11:12 volumio sudo[6083]: pam_unix(sudo:session): session closed for user root Feb 24 02:11:12 volumio go-librespot[6080]: go-librespot daemon starting... Feb 24 02:11:12 volumio volumio[1265]: info: Could not detect Primo: Error: Command failed: aplay -l | grep es90x8q2m-dac-dai-0 Feb 24 02:11:12 volumio volumio[1265]: info: Connection to go-librespot Websocket closed Feb 24 02:11:12 volumio volumio[1265]: info: MPD Permissions set Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 02:11:12 volumio systemd[1]: Stopping mpd.service - Music Player Daemon... Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=info msg="running go-librespot 0.7.0" Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=debug msg="app state loaded" Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: system , getConfigParam Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 02:11:12 volumio volumio[1265]: info: Starting Shairport Sync Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 24 02:11:12 volumio sudo[6106]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Feb 24 02:11:12 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:12 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:12 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:12 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:12 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:12 volumio volumio[1265]: import socketio Feb 24 02:11:12 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:12 volumio sudo[6106]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 02:11:12 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 24 02:11:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 24 02:11:12 volumio systemd[1]: mpd.service: Consumed 1min 258ms CPU time. Feb 24 02:11:12 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 24 02:11:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 24 02:11:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 24 02:11:12 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 24 02:11:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 24 02:11:12 volumio systemd[1]: Stopping shairport-sync.service - Shairport Sync - AirPlay Audio Receiver... Feb 24 02:11:12 volumio systemd[1]: shairport-sync.service: Deactivated successfully. Feb 24 02:11:12 volumio systemd[1]: Stopped shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 02:11:12 volumio systemd[1]: shairport-sync.service: Consumed 5.654s CPU time. Feb 24 02:11:12 volumio systemd[1]: Started shairport-sync.service - Shairport Sync - AirPlay Audio Receiver. Feb 24 02:11:12 volumio sudo[6106]: pam_unix(sudo:session): session closed for user root Feb 24 02:11:12 volumio volumio[1265]: info: Shairport-Sync Started Feb 24 02:11:12 volumio volumio[1265]: (node:1265) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pbeg listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 24 02:11:12 volumio volumio[1265]: (Use `node --trace-warnings ...` to show where the warning was created) Feb 24 02:11:12 volumio volumio[1265]: (node:1265) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 meta listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 24 02:11:12 volumio volumio[1265]: (node:1265) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 prgr listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 24 02:11:12 volumio volumio[1265]: (node:1265) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pvol listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 24 02:11:12 volumio volumio[1265]: (node:1265) MaxListenersExceededWarning: Possible EventEmitter memory leak detected. 11 pend listeners added to [ShairportSyncReaderUDP]. Use emitter.setMaxListeners() to increase limit Feb 24 02:11:12 volumio sudo[6109]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 02:11:12 volumio sudo[6109]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 24 02:11:12 volumio sudo[6109]: pam_unix(sudo:session): session closed for user root Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=info msg="zeroconf server listening on port 36991" Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: mpd , restartMpd Feb 24 02:11:12 volumio sudo[6146]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart mpd.service Feb 24 02:11:12 volumio sudo[6146]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 02:11:12 volumio systemd[1]: mpd.service: Deactivated successfully. Feb 24 02:11:12 volumio systemd[1]: Stopped mpd.service - Music Player Daemon. Feb 24 02:11:12 volumio systemd[1]: mpd.socket: Deactivated successfully. Feb 24 02:11:12 volumio systemd[1]: Closed mpd.socket - Music Player Daemon Socket. Feb 24 02:11:12 volumio systemd[1]: Stopping mpd.socket - Music Player Daemon Socket... Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=debug msg="obtained new client token: AAAYF8nQna0Fmr+pxLMqadYFhCBJgnzj5roVcG4LjLeTvJKl99CfH9SxPj7TU0BhrhonG8g7kbvOObkSL0E67ywmVHrit9ncefHmBOjQy3+WVcbHxg8o4Oj/WE8C/TlGXYY6qZKUmOX6H1dpfp6I6CMWpYLehRptluIzB8ptAv5KkkLgwjuFNEPZIx5wyBZUrhFn+W8pomq+cWusdAvFVAbq8Ws9GYx+mFWnekl9OUH+i04r6+A8qNS4" Feb 24 02:11:12 volumio systemd[1]: Listening on mpd.socket - Music Player Daemon Socket. Feb 24 02:11:12 volumio systemd[1]: Starting mpd.service - Music Player Daemon... Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp 104.199.241.202:4070: connect: connection refused" Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 02:11:12 volumio volumio[1265]: info: CoreCommandRouter::executeOnPlugin: alsa_controller , getConfigParam Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=debug msg="connected to ap-gae2.spotify.com:443" Feb 24 02:11:12 volumio sudo[6149]: root : PWD=/ ; USER=root ; COMMAND=/bin/chown mpd:audio /var/log/mpd.log Feb 24 02:11:12 volumio sudo[6149]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=0) Feb 24 02:11:12 volumio sudo[6149]: pam_unix(sudo:session): session closed for user root Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=debug msg="completed keyexchange" Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=debug msg="completed challenge" Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=info msg="authenticated AP" username="31************************oa" Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=info msg="authenticated Login5" username="31************************oa" Feb 24 02:11:12 volumio go-librespot[6092]: time="2026-02-24T02:11:12+07:00" level=debug msg="initializing zeroconf session" username="31************************oa" Feb 24 02:11:13 volumio go-librespot[6092]: time="2026-02-24T02:11:13+07:00" level=debug msg="dealer connection opened" Feb 24 02:11:13 volumio go-librespot[6092]: time="2026-02-24T02:11:13+07:00" level=trace msg="starting accesspoint recv loop" Feb 24 02:11:13 volumio go-librespot[6092]: time="2026-02-24T02:11:13+07:00" level=trace msg="received accesspoint ping" Feb 24 02:11:13 volumio go-librespot[6092]: time="2026-02-24T02:11:13+07:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336" Feb 24 02:11:13 volumio go-librespot[6092]: time="2026-02-24T02:11:13+07:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2" Feb 24 02:11:13 volumio go-librespot[6092]: time="2026-02-24T02:11:13+07:00" level=trace msg="starting dealer recv loop" Feb 24 02:11:13 volumio go-librespot[6092]: time="2026-02-24T02:11:13+07:00" level=debug msg="received connection id: ZjdkMWExOWUtMGUy...RTY5NDA0NTlBNQ==" Feb 24 02:11:13 volumio go-librespot[6092]: time="2026-02-24T02:11:13+07:00" level=debug msg="put connect state because NEW_DEVICE" Feb 24 02:11:13 volumio go-librespot[6092]: time="2026-02-24T02:11:13+07:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17" Feb 24 02:11:13 volumio go-librespot[6092]: time="2026-02-24T02:11:13+07:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0" Feb 24 02:11:13 volumio go-librespot[6092]: time="2026-02-24T02:11:13+07:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481" Feb 24 02:11:13 volumio go-librespot[6092]: time="2026-02-24T02:11:13+07:00" level=trace msg="received accesspoint pong ack" Feb 24 02:11:13 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:13 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:13 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:13 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:13 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:13 volumio volumio[1265]: import socketio Feb 24 02:11:13 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:14 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:14 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:14 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:14 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:14 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:14 volumio volumio[1265]: import socketio Feb 24 02:11:14 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:15 volumio mpd[6151]: 2026-02-24T02:11:15 decoder: Decoder plugin "wildmidi" is unavailable: configuration file does not exist: /etc/timidity/timidity.cfg Feb 24 02:11:15 volumio systemd[1]: Started mpd.service - Music Player Daemon. Feb 24 02:11:15 volumio sudo[6085]: pam_unix(sudo:session): session closed for user root Feb 24 02:11:15 volumio sudo[6146]: pam_unix(sudo:session): session closed for user root Feb 24 02:11:15 volumio volumio[1265]: error: MPD error: The expression evaluated to a falsy value: Feb 24 02:11:15 volumio volumio[1265]: assert.ok(self.idling) Feb 24 02:11:15 volumio volumio[1265]: error: The expression evaluated to a falsy value: Feb 24 02:11:15 volumio volumio[1265]: assert.ok(self.idling) Feb 24 02:11:15 volumio volumio[1265]: error: updateQueue error: null Feb 24 02:11:15 volumio volumio[1265]: info: Initializing connection to go-librespot Websocket Feb 24 02:11:15 volumio volumio[1265]: info: go-librespot daemon successfully initialized Feb 24 02:11:15 volumio go-librespot[6092]: time="2026-02-24T02:11:15+07:00" level=debug msg="new websocket client" Feb 24 02:11:15 volumio volumio[1265]: info: Connection to go-librespot Websocket established Feb 24 02:11:15 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:15 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:15 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:15 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:15 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:15 volumio volumio[1265]: import socketio Feb 24 02:11:15 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:16 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:16 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:16 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:16 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:16 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:16 volumio volumio[1265]: import socketio Feb 24 02:11:16 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:17 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:17 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:17 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:17 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:17 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:17 volumio volumio[1265]: import socketio Feb 24 02:11:17 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:18 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:18 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:18 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:18 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:18 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:18 volumio volumio[1265]: import socketio Feb 24 02:11:18 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:18 volumio volumio[1265]: info: Initializing connection to go-librespot Websocket Feb 24 02:11:18 volumio go-librespot[6092]: time="2026-02-24T02:11:18+07:00" level=debug msg="new websocket client" Feb 24 02:11:18 volumio volumio[1265]: info: Connection to go-librespot Websocket established Feb 24 02:11:18 volumio volumio[1265]: info: Getting Spotify volume Feb 24 02:11:18 volumio volumio[1265]: info: Spotify volume: 100 Feb 24 02:11:18 volumio volumio[1265]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 13 Feb 24 02:11:18 volumio volumio[1265]: info: CoreCommandRouter::volumioGetState Feb 24 02:11:18 volumio volumio[1265]: info: CorePlayQueue::getTrack 1 Feb 24 02:11:18 volumio volumio[1265]: SPOTIFY: RECEIVED VOLUMIO VOLUME 72 Feb 24 02:11:18 volumio volumio[1265]: SPOTIFY: SPOTIFY VOLUME 100 Feb 24 02:11:18 volumio volumio[1265]: SPOTIFY: VOLUMIO VOLUME 72 Feb 24 02:11:18 volumio volumio[1265]: SPOTIFY: DELTA VOLUME ENOUGH: true Feb 24 02:11:18 volumio volumio[1265]: info: Setting Spotify Volume from Volumio: 72 Feb 24 02:11:18 volumio volumio[1265]: info: CoreCommandRouter::volumioNext Feb 24 02:11:18 volumio volumio[1265]: info: CoreStateMachine::next Feb 24 02:11:18 volumio volumio[1265]: info: CoreStateMachine::stop Feb 24 02:11:18 volumio volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 02:11:18 volumio volumio[1265]: info: CoreStateMachine::play index undefined Feb 24 02:11:18 volumio volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 02:11:18 volumio volumio[1265]: info: CorePlayQueue::getTrack 2 Feb 24 02:11:18 volumio volumio[1265]: info: CoreStateMachine::startPlaybackTimer Feb 24 02:11:18 volumio volumio[1265]: info: CorePlayQueue::getTrack 2 Feb 24 02:11:18 volumio volumio[1265]: info: [1771873878406] ControllerSpotify::clearAddPlayTrack Feb 24 02:11:18 volumio volumio[1265]: info: Sending Spotify command with payload to local API: /player/play Feb 24 02:11:18 volumio volumio[1265]: info: CoreStateMachine::updateTrackBlock Feb 24 02:11:18 volumio volumio[1265]: info: CorePlayQueue::getTrackBlock Feb 24 02:11:18 volumio go-librespot[6092]: time="2026-02-24T02:11:18+07:00" level=debug msg="resolved context of track" uri="spotify:track:5XfGQZA0ioQAWUjlyJRcHc" Feb 24 02:11:18 volumio go-librespot[6092]: panic: runtime error: invalid memory address or nil pointer dereference Feb 24 02:11:18 volumio go-librespot[6092]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x74 pc=0x6b5430] Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 70 gp=0x1969e68 m=7 mp=0x1c62008 [running]: Feb 24 02:11:18 volumio go-librespot[6092]: panic({0x841c90, 0xfa0250}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/panic.go:802 +0x148 fp=0x183777c sp=0x1837728 pc=0xa92ec Feb 24 02:11:18 volumio go-librespot[6092]: runtime.panicmem(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/panic.go:262 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.sigpanic() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/signal_unix.go:925 +0x384 fp=0x18377ac sp=0x183777c pc=0xabca4 Feb 24 02:11:18 volumio go-librespot[6092]: main.(*AppPlayer).loadContext(0x1abe460, {0xa3d914, 0x19d0230}, 0x19c87c0, 0x0, 0x0, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/controls.go:243 +0x21c fp=0x183786c sp=0x18377b0 pc=0x6b5430 Feb 24 02:11:18 volumio go-librespot[6092]: main.(*AppPlayer).handleApiRequest(0x1abe460, {0xa3d8a4, 0x1005090}, {{0x919763, 0x4}, {0x85e5e0, 0x18b20c0}, 0x19c8780}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/player.go:512 +0x700 fp=0x18379e0 sp=0x183786c pc=0x6c313c Feb 24 02:11:18 volumio go-librespot[6092]: main.(*AppPlayer).Run(0x1abe460, {0xa3d8a4, 0x1005090}, 0x1a00180, 0x1a001c0) Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/player.go:697 +0x5b0 fp=0x1837fd4 sp=0x18379e0 pc=0x6c5a9c Feb 24 02:11:18 volumio go-librespot[6092]: main.(*App).withAppPlayer.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/main.go:274 +0x48 fp=0x1837fec sp=0x1837fd4 pc=0x6bd8f4 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1837fec sp=0x1837fec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by main.(*App).withAppPlayer in goroutine 1 Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/main.go:274 +0x46c Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 1 gp=0x1802128 m=nil [select]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x977334, 0x0, 0x9, 0x3, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x19abce0 sp=0x19abccc pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.selectgo(0x19abdf0, 0x19abdbc, 0x0, 0x0, 0x2, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x19abd90 sp=0x19abce0 pc=0x85000 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x189ed20, 0x19abe90) Feb 24 02:11:18 volumio go-librespot[6092]: /src/zeroconf/zeroconf.go:300 +0x228 fp=0x19abe38 sp=0x19abd90 pc=0x62f43c Feb 24 02:11:18 volumio go-librespot[6092]: main.(*App).withAppPlayer(0x18ce800, {0xa3d8a4, 0x1005090}, 0x18ad8f0) Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/main.go:340 +0x5dc fp=0x19abed0 sp=0x19abe38 pc=0x6bcb64 Feb 24 02:11:18 volumio go-librespot[6092]: main.(*App).withCredentials(...) Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/main.go:209 Feb 24 02:11:18 volumio go-librespot[6092]: main.(*App).SpotifyToken(0x18ce800, {0xa3d8a4, 0x1005090}, {0x18ca9c0, 0x1c}, {0x18a03c0, 0x138}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x19abf00 sp=0x19abed0 pc=0x6bbf30 Feb 24 02:11:18 volumio go-librespot[6092]: main.main() Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/main.go:620 +0x660 fp=0x19abfa8 sp=0x19abf00 pc=0x6bf470 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.main() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x19abfec sp=0x19abfa8 pc=0x6f390 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x19abfec sp=0x19abfec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 2 gp=0x18027e8 m=nil [force gc (idle)]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x977300, 0xff8fa8, 0x11, 0xa, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1848fd4 sp=0x1848fc0 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goparkunlock(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:466 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.forcegchelper() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x1848fec sp=0x1848fd4 pc=0x6f7f4 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1848fec sp=0x1848fec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by runtime.init.6 in goroutine 1 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:361 +0x1c Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 3 gp=0x1802b48 m=nil [GC sweep wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x977300, 0xff9850, 0xc, 0x9, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x18497c4 sp=0x18497b0 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goparkunlock(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:466 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.bgsweep(0x182e050) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x18497e4 sp=0x18497c4 pc=0x5768c Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gcenable.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x18497ec sp=0x18497e4 pc=0x46c3c Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x18497ec sp=0x18497ec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by runtime.gcenable in goroutine 1 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 4 gp=0x1802c68 m=nil [GC scavenge wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x977300, 0xffa170, 0xd, 0xa, 0x2) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1849fb4 sp=0x1849fa0 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goparkunlock(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:466 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.(*scavengerState).park(0xffa170) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x1849fc8 sp=0x1849fb4 pc=0x54a28 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.bgscavenge(0x182e050) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x1849fe4 sp=0x1849fc8 pc=0x5516c Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gcenable.gowrap2() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x1849fec sp=0x1849fe4 pc=0x46be8 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1849fec sp=0x1849fec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by runtime.gcenable in goroutine 1 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 18 gp=0x1882248 m=nil [GOMAXPROCS updater (idle)]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x977300, 0xff95e8, 0x12, 0xa, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x18447a4 sp=0x1844790 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goparkunlock(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:466 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.updateMaxProcsGoroutine() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x18447ec sp=0x18447a4 pc=0x7f174 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x18447ec sp=0x18447ec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 19 gp=0x18825a8 m=nil [finalizer wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9771a8, 0x1005180, 0x10, 0xa, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1844f88 sp=0x1844f74 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.runFinalizers() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x1844fec sp=0x1844f88 pc=0x45888 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1844fec sp=0x1844fec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by runtime.createfing in goroutine 1 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 20 gp=0x1969b08 m=nil [cleanup wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x977300, 0xffa348, 0x2e, 0xa, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x18487a4 sp=0x1848790 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goparkunlock(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:466 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.(*cleanupQueue).dequeue(0xffa2e8) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x18487c4 sp=0x18487a4 pc=0x41cb0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.runCleanups() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x18487ec sp=0x18487c4 pc=0x4284c Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x18487ec sp=0x18487ec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 23 gp=0x199cd88 m=nil [IO wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9772f0, 0xb50be010, 0x2, 0x2, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x185bcf0 sp=0x185bcdc pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.netpollblock(0xb50be000, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x185bd08 sp=0x185bcf0 pc=0x675a0 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.runtime_pollWait(0xb50be000, 0x72) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x185bd1c sp=0x185bd08 pc=0xa8864 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).wait(0x18a5468, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x185bd30 sp=0x185bd1c pc=0x136044 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).waitRead(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*FD).Accept(0x18a5450) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x185bd78 sp=0x185bd30 pc=0x13a800 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*netFD).accept(0x18a5450) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x185bde0 sp=0x185bd78 pc=0x1abc28 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*TCPListener).accept(0x18b0660) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x185be34 sp=0x185bde0 pc=0x1c3554 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*TCPListener).Accept(0x18b0660) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x185be50 sp=0x185be34 pc=0x1c259c Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*onceCloseListener).Accept(0x186e0a0) Feb 24 02:11:18 volumio go-librespot[6092]: :1 +0x34 fp=0x185be68 sp=0x185be50 pc=0x367f9c Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*Server).Serve(0x1a0c008, {0xa3d0a0, 0x18b0660}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x185bf00 sp=0x185be68 pc=0x341154 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.Serve(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:2971 Feb 24 02:11:18 volumio go-librespot[6092]: main.(*ConcreteApiServer).serve(0x189e840) Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x185bfe4 sp=0x185bf00 pc=0x6b17bc Feb 24 02:11:18 volumio go-librespot[6092]: main.NewApiServer.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x185bfec sp=0x185bfe4 pc=0x6b04f4 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x185bfec sp=0x185bfec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by main.NewApiServer in goroutine 1 Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/api_server.go:332 +0x310 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 32 gp=0x199d0e8 m=nil [chan receive]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x977188, 0x1c43278, 0xe, 0x7, 0x2) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1845778 sp=0x1845764 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.chanrecv(0x1c43240, 0x0, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x18457c0 sp=0x1845778 pc=0x3414c Feb 24 02:11:18 volumio go-librespot[6092]: runtime.chanrecv1(0x1c43240, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/chan.go:509 +0x20 fp=0x18457d4 sp=0x18457c0 pc=0x33bcc Feb 24 02:11:18 volumio go-librespot[6092]: github.com/godbus/dbus/v5.newConn.func1() Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:303 +0x40 fp=0x18457ec sp=0x18457d4 pc=0x4aa8ec Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x18457ec sp=0x18457ec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by github.com/godbus/dbus/v5.newConn in goroutine 1 Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:302 +0x52c Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 30 gp=0x199d208 m=nil [IO wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9772f0, 0xb50bde10, 0x2, 0x2, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x185caf4 sp=0x185cae0 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.netpollblock(0xb50bde00, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x185cb0c sp=0x185caf4 pc=0x675a0 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.runtime_pollWait(0xb50bde00, 0x72) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x185cb20 sp=0x185cb0c pc=0xa8864 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).wait(0x18a56e8, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x185cb34 sp=0x185cb20 pc=0x136044 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).waitRead(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*FD).Read(0x18a56d0, {0x1a36000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x185cb7c sp=0x185cb34 pc=0x1371c4 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*netFD).Read(0x18a56d0, {0x1a36000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x185cba8 sp=0x185cb7c pc=0x1a9e78 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*conn).Read(0x1832218, {0x1a36000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x185cbd4 sp=0x185cba8 pc=0x1b967c Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*atLeastReader).Read(0x1810090, {0x1a36000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x185cc00 sp=0x185cbd4 pc=0x2674c0 Feb 24 02:11:18 volumio go-librespot[6092]: bytes.(*Buffer).ReadFrom(0x1a1018c, {0xa39ef0, 0x1810090}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x185cc3c sp=0x185cc00 pc=0x162058 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readFromUntil(0x1a10008, {0xa38e30, 0x1832218}, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x185cc64 sp=0x185cc3c pc=0x26771c Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readRecordOrCCS(0x1a10008, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x185cdc4 sp=0x185cc64 pc=0x264e40 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readRecord(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:589 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).Read(0x1a10008, {0x1913000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x185cdf4 sp=0x185cdc4 pc=0x26ac04 Feb 24 02:11:18 volumio go-librespot[6092]: bufio.(*Reader).Read(0x19cb6e0, {0x187a0a4, 0x9, 0x9}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x185ce18 sp=0x185cdf4 pc=0x2b72a0 Feb 24 02:11:18 volumio go-librespot[6092]: io.ReadAtLeast({0xa39080, 0x19cb6e0}, {0x187a0a4, 0x9, 0x9}, 0x9) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x185ce44 sp=0x185ce18 pc=0xee954 Feb 24 02:11:18 volumio go-librespot[6092]: io.ReadFull(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/io/io.go:354 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.http2readFrameHeader({0x187a0a4, 0x9, 0x9}, {0xa39080, 0x19cb6e0}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x185ce6c sp=0x185ce44 pc=0x2f86ac Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2Framer).ReadFrame(0x187a080) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x185cef4 sp=0x185ce6c pc=0x2f8e04 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2clientConnReadLoop).run(0x185cfdc) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x185cfa4 sp=0x185cef4 pc=0x31d33c Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2ClientConn).readLoop(0x1803208) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x185cfe4 sp=0x185cfa4 pc=0x31c604 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2Transport).newClientConn.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x185cfec sp=0x185cfe4 pc=0x316960 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x185cfec sp=0x185cfec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by net/http.(*http2Transport).newClientConn in goroutine 29 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 34 gp=0x1c58008 m=nil [GC worker (idle)]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9771b4, 0x1bb8400, 0x1c, 0xa, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c5e788 sp=0x1c5e774 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gcBgMarkWorker(0x1c42400) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x1c5e7e4 sp=0x1c5e788 pc=0x49f34 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gcBgMarkStartWorkers.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x1c5e7ec sp=0x1c5e7e4 pc=0x49e14 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c5e7ec sp=0x1c5e7ec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 35 gp=0x1c58128 m=nil [GC worker (idle)]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9771b4, 0x1bb8600, 0x1c, 0xa, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c5ef88 sp=0x1c5ef74 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gcBgMarkWorker(0x1c42400) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x1c5efe4 sp=0x1c5ef88 pc=0x49f34 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gcBgMarkStartWorkers.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x1c5efec sp=0x1c5efe4 pc=0x49e14 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c5efec sp=0x1c5efec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 36 gp=0x1c58248 m=nil [GC worker (idle)]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9771b4, 0x1bb8800, 0x1c, 0xa, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c5f788 sp=0x1c5f774 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gcBgMarkWorker(0x1c42400) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x1c5f7e4 sp=0x1c5f788 pc=0x49f34 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gcBgMarkStartWorkers.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x1c5f7ec sp=0x1c5f7e4 pc=0x49e14 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c5f7ec sp=0x1c5f7ec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 37 gp=0x1c58368 m=nil [GC worker (idle)]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9771b4, 0x1bb8a00, 0x1c, 0xa, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x184af88 sp=0x184af74 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gcBgMarkWorker(0x1c42400) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x184afe4 sp=0x184af88 pc=0x49f34 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gcBgMarkStartWorkers.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x184afec sp=0x184afe4 pc=0x49e14 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x184afec sp=0x184afec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by runtime.gcBgMarkStartWorkers in goroutine 5 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 7 gp=0x199c008 m=nil [IO wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9772f0, 0xb50bda10, 0x2, 0x2, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1d04cbc sp=0x1d04ca8 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.netpollblock(0xb50bda00, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1d04cd4 sp=0x1d04cbc pc=0x675a0 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.runtime_pollWait(0xb50bda00, 0x72) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1d04ce8 sp=0x1d04cd4 pc=0xa8864 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).wait(0x19c1b48, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1d04cfc sp=0x1d04ce8 pc=0x136044 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).waitRead(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*FD).ReadMsg(0x19c1b30, {0x1b1a0b0, 0x10, 0x10}, {0x1914018, 0x1000, 0x1000}, 0x40000000) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_unix.go:295 +0x2c0 fp=0x1d04d6c sp=0x1d04cfc pc=0x1381bc Feb 24 02:11:18 volumio go-librespot[6092]: net.(*netFD).readMsg(0x19c1b30, {0x1b1a0b0, 0x10, 0x10}, {0x1914018, 0x1000, 0x1000}, 0x40000000) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/fd_posix.go:91 +0x58 fp=0x1d04dc8 sp=0x1d04d6c pc=0x1aa2c8 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*UnixConn).readMsg(0x1c89248, {0x1b1a0b0, 0x10, 0x10}, {0x1914018, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/unixsock_posix.go:115 +0x58 fp=0x1d04e28 sp=0x1d04dc8 pc=0x1c9d80 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*UnixConn).ReadMsgUnix(0x1c89248, {0x1b1a0b0, 0x10, 0x10}, {0x1914018, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/unixsock.go:143 +0x58 fp=0x1d04e78 sp=0x1d04e28 pc=0x1c820c Feb 24 02:11:18 volumio go-librespot[6092]: github.com/godbus/dbus/v5.(*oobReader).Read(0x1914008, {0x1b1a0b0, 0x10, 0x10}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:41 +0x50 fp=0x1d04ec8 sp=0x1d04e78 pc=0x4c2af0 Feb 24 02:11:18 volumio go-librespot[6092]: io.ReadAtLeast({0xa39be0, 0x1914008}, {0x1b1a0b0, 0x10, 0x10}, 0x10) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1d04ef4 sp=0x1d04ec8 pc=0xee954 Feb 24 02:11:18 volumio go-librespot[6092]: io.ReadFull(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/io/io.go:354 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/godbus/dbus/v5.(*unixTransport).ReadMessage(0x1c83970) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:124 +0x224 fp=0x1d04fa0 sp=0x1d04ef4 pc=0x4c32c4 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/godbus/dbus/v5.(*Conn).inWorker(0x187a280) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:390 +0x44 fp=0x1d04fe4 sp=0x1d04fa0 pc=0x4aaef4 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/godbus/dbus/v5.(*Conn).Auth.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x28 fp=0x1d04fec sp=0x1d04fe4 pc=0x4a8318 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1d04fec sp=0x1d04fec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by github.com/godbus/dbus/v5.(*Conn).Auth in goroutine 1 Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x77c Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 109 gp=0x1803448 m=nil [IO wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9772f0, 0xb50bd210, 0x2, 0x2, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1d07998 sp=0x1d07984 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.netpollblock(0xb50bd200, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1d079b0 sp=0x1d07998 pc=0x675a0 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.runtime_pollWait(0xb50bd200, 0x72) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1d079c4 sp=0x1d079b0 pc=0xa8864 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).wait(0x1c517d8, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1d079d8 sp=0x1d079c4 pc=0x136044 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).waitRead(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*FD).Read(0x1c517c0, {0x1c79000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1d07a20 sp=0x1d079d8 pc=0x1371c4 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*netFD).Read(0x1c517c0, {0x1c79000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1d07a4c sp=0x1d07a20 pc=0x1a9e78 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*conn).Read(0x1832448, {0x1c79000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1d07a78 sp=0x1d07a4c pc=0x1b967c Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*atLeastReader).Read(0x1c540d0, {0x1c79000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x1d07aa4 sp=0x1d07a78 pc=0x2674c0 Feb 24 02:11:18 volumio go-librespot[6092]: bytes.(*Buffer).ReadFrom(0x19ae18c, {0xa39ef0, 0x1c540d0}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x1d07ae0 sp=0x1d07aa4 pc=0x162058 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readFromUntil(0x19ae008, {0xa38e30, 0x1832448}, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x1d07b08 sp=0x1d07ae0 pc=0x26771c Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readRecordOrCCS(0x19ae008, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x1d07c68 sp=0x1d07b08 pc=0x264e40 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readRecord(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:589 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).Read(0x19ae008, {0x1819000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x1d07c98 sp=0x1d07c68 pc=0x26ac04 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*readWriteCloserBody).Read(0x1810830, {0x1819000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/transport.go:2578 +0xec fp=0x1d07cb8 sp=0x1d07c98 pc=0x358048 Feb 24 02:11:18 volumio go-librespot[6092]: bufio.(*Reader).fill(0x1c6f350) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x1d07cdc sp=0x1d07cb8 pc=0x2b6c1c Feb 24 02:11:18 volumio go-librespot[6092]: bufio.(*Reader).ReadByte(0x1c6f350) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x1d07ce8 sp=0x1d07cdc pc=0x2b7498 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.readFrameHeader(0x1c6f350, {0x190ac38, 0x8, 0x8}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x1d07d28 sp=0x1d07ce8 pc=0x372a08 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).readFrameHeader(0x190ac08, {0xa3d888, 0x1005090}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x1d07dac sp=0x1d07d28 pc=0x375bd4 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).readLoop(0x190ac08, {0xa3d888, 0x1005090}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x1d07e70 sp=0x1d07dac pc=0x375390 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).reader(0x190ac08, {0xa3d888, 0x1005090}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x1d07ef4 sp=0x1d07e70 pc=0x376798 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).Reader(...) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).Read(0x190ac08, {0xa3d888, 0x1005090}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x1d07f1c sp=0x1d07ef4 pc=0x374944 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x1aba168) Feb 24 02:11:18 volumio go-librespot[6092]: /src/dealer/dealer.go:188 +0x58 fp=0x1d07fe4 sp=0x1d07f1c pc=0x5a222c Feb 24 02:11:18 volumio go-librespot[6092]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /src/dealer/dealer.go:125 +0x28 fp=0x1d07fec sp=0x1d07fe4 pc=0x5a3d28 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1d07fec sp=0x1d07fec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 70 Feb 24 02:11:18 volumio go-librespot[6092]: /src/dealer/dealer.go:125 +0x98 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 69 gp=0x199c248 m=nil [select]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x977334, 0x0, 0x9, 0x3, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c5ce74 sp=0x1c5ce60 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.selectgo(0x1c5cf80, 0x1c5cf4c, 0x0, 0x0, 0x3, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1c5cf24 sp=0x1c5ce74 pc=0x85000 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x1bb1680) Feb 24 02:11:18 volumio go-librespot[6092]: /src/player/player.go:215 +0x1f4 fp=0x1c5cfe4 sp=0x1c5cf24 pc=0x582954 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /src/player/player.go:197 +0x28 fp=0x1c5cfec sp=0x1c5cfe4 pc=0x58253c Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c5cfec sp=0x1c5cfec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Feb 24 02:11:18 volumio go-librespot[6092]: /src/player/player.go:197 +0x220 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 71 gp=0x199c368 m=nil [chan receive]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x977188, 0x1890238, 0xe, 0x7, 0x2) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1b7373c sp=0x1b73728 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.chanrecv(0x1890200, 0x1b737d0, 0x1) Feb 24 02:11:18 volumio volumio[1265]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Feb 24 02:11:18 volumio volumio[1265]: info: Connection to go-librespot Websocket closed Feb 24 02:11:18 volumio volumio[1265]: info: Connection to go-librespot Websocket closed Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x1b73784 sp=0x1b7373c pc=0x3414c Feb 24 02:11:18 volumio go-librespot[6092]: runtime.chanrecv2(0x1890200, 0x1b737d0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x1b73798 sp=0x1b73784 pc=0x33bf0 Feb 24 02:11:18 volumio go-librespot[6092]: main.(*App).withAppPlayer.func1() Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/main.go:284 +0x68 fp=0x1b737ec sp=0x1b73798 pc=0x6bd81c Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1b737ec sp=0x1b737ec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by main.(*App).withAppPlayer in goroutine 1 Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/main.go:281 +0x4f8 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 72 gp=0x18827e8 m=nil [chan receive]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x977188, 0x18901f8, 0xe, 0x7, 0x2) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c5d740 sp=0x1c5d72c pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.chanrecv(0x18901c0, 0x1c5d7e0, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x1c5d788 sp=0x1c5d740 pc=0x3414c Feb 24 02:11:18 volumio go-librespot[6092]: runtime.chanrecv2(0x18901c0, 0x1c5d7e0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x1c5d79c sp=0x1c5d788 pc=0x33bf0 Feb 24 02:11:18 volumio go-librespot[6092]: main.(*App).withAppPlayer.func2() Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/main.go:300 +0x6c fp=0x1c5d7ec sp=0x1c5d79c pc=0x6bd380 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c5d7ec sp=0x1c5d7ec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by main.(*App).withAppPlayer in goroutine 1 Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/main.go:297 +0x598 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 56 gp=0x1969c28 m=nil [IO wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9772f0, 0xb50bd810, 0x2, 0x2, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1856af4 sp=0x1856ae0 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.netpollblock(0xb50bd800, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1856b0c sp=0x1856af4 pc=0x675a0 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.runtime_pollWait(0xb50bd800, 0x72) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1856b20 sp=0x1856b0c pc=0xa8864 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).wait(0x1b3c298, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1856b34 sp=0x1856b20 pc=0x136044 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).waitRead(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*FD).Read(0x1b3c280, {0x1b58000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1856b7c sp=0x1856b34 pc=0x1371c4 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*netFD).Read(0x1b3c280, {0x1b58000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1856ba8 sp=0x1856b7c pc=0x1a9e78 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*conn).Read(0x189ccc0, {0x1b58000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1856bd4 sp=0x1856ba8 pc=0x1b967c Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*atLeastReader).Read(0x1ab7860, {0x1b58000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x1856c00 sp=0x1856bd4 pc=0x2674c0 Feb 24 02:11:18 volumio go-librespot[6092]: bytes.(*Buffer).ReadFrom(0x18c684c, {0xa39ef0, 0x1ab7860}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x1856c3c sp=0x1856c00 pc=0x162058 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readFromUntil(0x18c66c8, {0xa38e30, 0x189ccc0}, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x1856c64 sp=0x1856c3c pc=0x26771c Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readRecordOrCCS(0x18c66c8, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x1856dc4 sp=0x1856c64 pc=0x264e40 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readRecord(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:589 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).Read(0x18c66c8, {0x1b68000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x1856df4 sp=0x1856dc4 pc=0x26ac04 Feb 24 02:11:18 volumio go-librespot[6092]: bufio.(*Reader).Read(0x1b24c60, {0x1b064a4, 0x9, 0x9}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x1856e18 sp=0x1856df4 pc=0x2b72a0 Feb 24 02:11:18 volumio go-librespot[6092]: io.ReadAtLeast({0xa39080, 0x1b24c60}, {0x1b064a4, 0x9, 0x9}, 0x9) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1856e44 sp=0x1856e18 pc=0xee954 Feb 24 02:11:18 volumio go-librespot[6092]: io.ReadFull(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/io/io.go:354 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.http2readFrameHeader({0x1b064a4, 0x9, 0x9}, {0xa39080, 0x1b24c60}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x1856e6c sp=0x1856e44 pc=0x2f86ac Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2Framer).ReadFrame(0x1b06480) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x1856ef4 sp=0x1856e6c pc=0x2f8e04 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2clientConnReadLoop).run(0x1856fdc) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x1856fa4 sp=0x1856ef4 pc=0x31d33c Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2ClientConn).readLoop(0x1882908) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x1856fe4 sp=0x1856fa4 pc=0x31c604 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2Transport).newClientConn.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x1856fec sp=0x1856fe4 pc=0x316960 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1856fec sp=0x1856fec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by net/http.(*http2Transport).newClientConn in goroutine 55 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 83 gp=0x1c76128 m=nil [IO wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9772f0, 0xb50bd410, 0x2, 0x2, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1bbaaf4 sp=0x1bbaae0 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.netpollblock(0xb50bd400, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1bbab0c sp=0x1bbaaf4 pc=0x675a0 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.runtime_pollWait(0xb50bd400, 0x72) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1bbab20 sp=0x1bbab0c pc=0xa8864 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).wait(0x1c516e8, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1bbab34 sp=0x1bbab20 pc=0x136044 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).waitRead(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*FD).Read(0x1c516d0, {0x1c70000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1bbab7c sp=0x1bbab34 pc=0x1371c4 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*netFD).Read(0x1c516d0, {0x1c70000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1bbaba8 sp=0x1bbab7c pc=0x1a9e78 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*conn).Read(0x189d0a8, {0x1c70000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1bbabd4 sp=0x1bbaba8 pc=0x1b967c Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*atLeastReader).Read(0x1ab6030, {0x1c70000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x1bbac00 sp=0x1bbabd4 pc=0x2674c0 Feb 24 02:11:18 volumio go-librespot[6092]: bytes.(*Buffer).ReadFrom(0x18c714c, {0xa39ef0, 0x1ab6030}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x1bbac3c sp=0x1bbac00 pc=0x162058 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readFromUntil(0x18c6fc8, {0xa38e30, 0x189d0a8}, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x1bbac64 sp=0x1bbac3c pc=0x26771c Feb 24 02:11:18 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readRecordOrCCS(0x18c6fc8, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x1bbadc4 sp=0x1bbac64 pc=0x264e40 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readRecord(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:589 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).Read(0x18c6fc8, {0x1c78000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x1bbadf4 sp=0x1bbadc4 pc=0x26ac04 Feb 24 02:11:18 volumio go-librespot[6092]: bufio.(*Reader).Read(0x1c6e990, {0x1b068a4, 0x9, 0x9}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x1bbae18 sp=0x1bbadf4 pc=0x2b72a0 Feb 24 02:11:18 volumio go-librespot[6092]: io.ReadAtLeast({0xa39080, 0x1c6e990}, {0x1b068a4, 0x9, 0x9}, 0x9) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1bbae44 sp=0x1bbae18 pc=0xee954 Feb 24 02:11:18 volumio go-librespot[6092]: io.ReadFull(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/io/io.go:354 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.http2readFrameHeader({0x1b068a4, 0x9, 0x9}, {0xa39080, 0x1c6e990}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x1bbae6c sp=0x1bbae44 pc=0x2f86ac Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2Framer).ReadFrame(0x1b06880) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x1bbaef4 sp=0x1bbae6c pc=0x2f8e04 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2clientConnReadLoop).run(0x1bbafdc) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x1bbafa4 sp=0x1bbaef4 pc=0x31d33c Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2ClientConn).readLoop(0x1c76008) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x1bbafe4 sp=0x1bbafa4 pc=0x31c604 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2Transport).newClientConn.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x1bbafec sp=0x1bbafe4 pc=0x316960 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1bbafec sp=0x1bbafec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by net/http.(*http2Transport).newClientConn in goroutine 82 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 108 gp=0x199c488 m=nil [select]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x977334, 0x0, 0x9, 0x3, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c616a4 sp=0x1c61690 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.selectgo(0x1c617d4, 0x1c61778, 0x0, 0x0, 0x2, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1c61754 sp=0x1c616a4 pc=0x85000 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x1abad18) Feb 24 02:11:18 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 24 02:11:18 volumio go-librespot[6092]: /src/ap/ap.go:368 +0x8c fp=0x1c617e4 sp=0x1c61754 pc=0x4f7cf8 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Feb 24 02:11:18 volumio go-librespot[6092]: /src/ap/ap.go:277 +0x28 fp=0x1c617ec sp=0x1c617e4 pc=0x4f73c4 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c617ec sp=0x1c617ec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 70 Feb 24 02:11:18 volumio go-librespot[6092]: /src/ap/ap.go:277 +0x15c Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 107 gp=0x199c908 m=nil [IO wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9772f0, 0xb50bd610, 0x2, 0x2, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1859d04 sp=0x1859cf0 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.netpollblock(0xb50bd600, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1859d1c sp=0x1859d04 pc=0x675a0 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.runtime_pollWait(0xb50bd600, 0x72) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1859d30 sp=0x1859d1c pc=0xa8864 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).wait(0x1b3cfb8, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1859d44 sp=0x1859d30 pc=0x136044 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).waitRead(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*FD).Read(0x1b3cfa0, {0x1ac9580, 0x3, 0x3}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1859d8c sp=0x1859d44 pc=0x1371c4 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*netFD).Read(0x1b3cfa0, {0x1ac9580, 0x3, 0x3}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1859db8 sp=0x1859d8c pc=0x1a9e78 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*conn).Read(0x189cd98, {0x1ac9580, 0x3, 0x3}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1859de4 sp=0x1859db8 pc=0x1b967c Feb 24 02:11:18 volumio go-librespot[6092]: io.ReadAtLeast({0xa38e30, 0x189cd98}, {0x1ac9580, 0x3, 0x3}, 0x3) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1859e10 sp=0x1859de4 pc=0xee954 Feb 24 02:11:18 volumio go-librespot[6092]: io.ReadFull(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/io/io.go:354 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/devgianlu/go-librespot/ap.(*shannonConn).receivePacket(0x18afe40, {0xa3d8a4, 0x1005090}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/ap/shannon.go:124 +0x370 fp=0x1859ed4 sp=0x1859e10 pc=0x4fac0c Feb 24 02:11:18 volumio go-librespot[6092]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x1abad18) Feb 24 02:11:18 volumio go-librespot[6092]: /src/ap/ap.go:289 +0x68 fp=0x1859fe4 sp=0x1859ed4 pc=0x4f74ac Feb 24 02:11:18 volumio go-librespot[6092]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /src/ap/ap.go:273 +0x28 fp=0x1859fec sp=0x1859fe4 pc=0x4f7418 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1859fec sp=0x1859fec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 70 Feb 24 02:11:18 volumio go-librespot[6092]: /src/ap/ap.go:273 +0x98 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 73 gp=0x1c587e8 m=nil [IO wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9772f0, 0xb50bdc10, 0x2, 0x2, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1845dac sp=0x1845d98 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.netpollblock(0xb50bdc00, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1845dc4 sp=0x1845dac pc=0x675a0 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.runtime_pollWait(0xb50bdc00, 0x72) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1845dd8 sp=0x1845dc4 pc=0xa8864 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).wait(0x19c1af8, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1845dec sp=0x1845dd8 pc=0x136044 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).waitRead(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*FD).Accept(0x19c1ae0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x1845e34 sp=0x1845dec pc=0x13a800 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*netFD).accept(0x19c1ae0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x1845e9c sp=0x1845e34 pc=0x1abc28 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*TCPListener).accept(0x19cb7a0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x1845ef0 sp=0x1845e9c pc=0x1c3554 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*TCPListener).Accept(0x19cb7a0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x1845f0c sp=0x1845ef0 pc=0x1c259c Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*onceCloseListener).Accept(0x1bb6640) Feb 24 02:11:18 volumio go-librespot[6092]: :1 +0x34 fp=0x1845f24 sp=0x1845f0c pc=0x367f9c Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*Server).Serve(0x1aba218, {0xa3d0a0, 0x19cb7a0}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x1845fbc sp=0x1845f24 pc=0x341154 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.Serve(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:2971 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Feb 24 02:11:18 volumio go-librespot[6092]: /src/zeroconf/zeroconf.go:297 +0x8c fp=0x1845fec sp=0x1845fbc pc=0x62f538 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1845fec sp=0x1845fec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Feb 24 02:11:18 volumio go-librespot[6092]: /src/zeroconf/zeroconf.go:297 +0x160 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 110 gp=0x199cb48 m=nil [select]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x977334, 0x0, 0x9, 0x3, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c5fe84 sp=0x1c5fe70 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.selectgo(0x1c5ffd4, 0x1c5ff5c, 0x0, 0x0, 0x2, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x1c5ff34 sp=0x1c5fe84 pc=0x85000 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x1aba168) Feb 24 02:11:18 volumio go-librespot[6092]: /src/dealer/dealer.go:138 +0x8c fp=0x1c5ffe4 sp=0x1c5ff34 pc=0x5a1d90 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Feb 24 02:11:18 volumio go-librespot[6092]: /src/dealer/dealer.go:129 +0x28 fp=0x1c5ffec sp=0x1c5ffe4 pc=0x5a3cd4 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c5ffec sp=0x1c5ffec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 70 Feb 24 02:11:18 volumio go-librespot[6092]: /src/dealer/dealer.go:129 +0x15c Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 86 gp=0x1c58a28 m=nil [IO wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9772f0, 0xb3acb210, 0x2, 0x2, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1d02af4 sp=0x1d02ae0 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.netpollblock(0xb3acb200, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1d02b0c sp=0x1d02af4 pc=0x675a0 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.runtime_pollWait(0xb3acb200, 0x72) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1d02b20 sp=0x1d02b0c pc=0xa8864 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).wait(0x1bb18c8, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1d02b34 sp=0x1d02b20 pc=0x136044 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).waitRead(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*FD).Read(0x1bb18b0, {0x1a54000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1d02b7c sp=0x1d02b34 pc=0x1371c4 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*netFD).Read(0x1bb18b0, {0x1a54000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1d02ba8 sp=0x1d02b7c pc=0x1a9e78 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*conn).Read(0x1833158, {0x1a54000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1d02bd4 sp=0x1d02ba8 pc=0x1b967c Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*atLeastReader).Read(0x1c82710, {0x1a54000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x1d02c00 sp=0x1d02bd4 pc=0x2674c0 Feb 24 02:11:18 volumio go-librespot[6092]: bytes.(*Buffer).ReadFrom(0x19aef0c, {0xa39ef0, 0x1c82710}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x1d02c3c sp=0x1d02c00 pc=0x162058 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readFromUntil(0x19aed88, {0xa38e30, 0x1833158}, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x1d02c64 sp=0x1d02c3c pc=0x26771c Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readRecordOrCCS(0x19aed88, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x1d02dc4 sp=0x1d02c64 pc=0x264e40 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).readRecord(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:589 Feb 24 02:11:18 volumio go-librespot[6092]: crypto/tls.(*Conn).Read(0x19aed88, {0x1a70000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x1d02df4 sp=0x1d02dc4 pc=0x26ac04 Feb 24 02:11:18 volumio go-librespot[6092]: bufio.(*Reader).Read(0x1b24ab0, {0x187a124, 0x9, 0x9}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x1d02e18 sp=0x1d02df4 pc=0x2b72a0 Feb 24 02:11:18 volumio go-librespot[6092]: io.ReadAtLeast({0xa39080, 0x1b24ab0}, {0x187a124, 0x9, 0x9}, 0x9) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x1d02e44 sp=0x1d02e18 pc=0xee954 Feb 24 02:11:18 volumio go-librespot[6092]: io.ReadFull(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/io/io.go:354 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.http2readFrameHeader({0x187a124, 0x9, 0x9}, {0xa39080, 0x1b24ab0}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x1d02e6c sp=0x1d02e44 pc=0x2f86ac Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2Framer).ReadFrame(0x187a100) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x1d02ef4 sp=0x1d02e6c pc=0x2f8e04 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2clientConnReadLoop).run(0x1d02fdc) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x1d02fa4 sp=0x1d02ef4 pc=0x31d33c Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2ClientConn).readLoop(0x1c76248) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x1d02fe4 sp=0x1d02fa4 pc=0x31c604 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*http2Transport).newClientConn.gowrap1() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x1d02fec sp=0x1d02fe4 pc=0x316960 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1d02fec sp=0x1d02fec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by net/http.(*http2Transport).newClientConn in goroutine 85 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 116 gp=0x199dc28 m=nil [IO wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9772f0, 0xb3acb010, 0x2, 0x2, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x19ad908 sp=0x19ad8f4 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.netpollblock(0xb3acb000, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x19ad920 sp=0x19ad908 pc=0x675a0 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.runtime_pollWait(0xb3acb000, 0x72) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x19ad934 sp=0x19ad920 pc=0xa8864 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).wait(0x1c19828, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x19ad948 sp=0x19ad934 pc=0x136044 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).waitRead(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*FD).Read(0x1c19810, {0x199f000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x19ad990 sp=0x19ad948 pc=0x1371c4 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*netFD).Read(0x1c19810, {0x199f000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x19ad9bc sp=0x19ad990 pc=0x1a9e78 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*conn).Read(0x1833230, {0x199f000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x19ad9e8 sp=0x19ad9bc pc=0x1b967c Feb 24 02:11:18 volumio go-librespot[6092]: io.(*multiReader).Read(0x1c82620, {0x199f000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x19ada14 sp=0x19ad9e8 pc=0xefb88 Feb 24 02:11:18 volumio go-librespot[6092]: bufio.(*Reader).fill(0x1abd3e0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x19ada38 sp=0x19ada14 pc=0x2b6c1c Feb 24 02:11:18 volumio go-librespot[6092]: bufio.(*Reader).ReadByte(0x1abd3e0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x19ada44 sp=0x19ada38 pc=0x2b7498 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.readFrameHeader(0x1abd3e0, {0x190adb8, 0x8, 0x8}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x19ada84 sp=0x19ada44 pc=0x372a08 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).readFrameHeader(0x190ad88, {0xa3d888, 0x1005090}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x19adb08 sp=0x19ada84 pc=0x375bd4 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).readLoop(0x190ad88, {0xa3d888, 0x1005090}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x19adbcc sp=0x19adb08 pc=0x375390 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).reader(0x190ad88, {0xa3d888, 0x1005090}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x19adc50 sp=0x19adbcc pc=0x376798 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).Reader(...) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).Read(0x190ad88, {0xa3d888, 0x1005090}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x19adc78 sp=0x19adc50 pc=0x374944 Feb 24 02:11:18 volumio go-librespot[6092]: main.(*ConcreteApiServer).serve.func17({0xa3d160, 0x1b3fc38}, 0x1b3fad8) Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/api_server.go:636 +0x3b4 fp=0x19adcfc sp=0x19adc78 pc=0x6b2040 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.HandlerFunc.ServeHTTP(0x18321a8, {0xa3d160, 0x1b3fc38}, 0x1b3fad8) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x19add0c sp=0x19adcfc pc=0x33d7d8 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*ServeMux).ServeHTTP(0x187a000, {0xa3d160, 0x1b3fc38}, 0x1b3fad8) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x19add4c sp=0x19add0c pc=0x33f6c8 Feb 24 02:11:18 volumio go-librespot[6092]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d160, 0x1b3fc38}, 0x1b3fad8) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x19add74 sp=0x19add4c pc=0x6b19b8 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.HandlerFunc.ServeHTTP(0x1810170, {0xa3d160, 0x1b3fc38}, 0x1b3fad8) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x19add84 sp=0x19add74 pc=0x33d7d8 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.serverHandler.ServeHTTP({0x1a0c008}, {0xa3d160, 0x1b3fc38}, 0x1b3fad8) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x19adda0 sp=0x19add84 pc=0x35cb44 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*conn).serve(0x1b6a840, {0xa3d8c0, 0x18281f8}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x19adfdc sp=0x19adda0 pc=0x33b894 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*Server).Serve.gowrap3() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x19adfec sp=0x19adfdc pc=0x3415cc Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x19adfec sp=0x19adfec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by net/http.(*Server).Serve in goroutine 23 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:3493 +0x42c Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 118 gp=0x199dd48 m=nil [IO wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9772f0, 0xb3acae10, 0x2, 0x2, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1bc9908 sp=0x1bc98f4 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.netpollblock(0xb3acae00, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1bc9920 sp=0x1bc9908 pc=0x675a0 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.runtime_pollWait(0xb3acae00, 0x72) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1bc9934 sp=0x1bc9920 pc=0xa8864 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).wait(0x19d00b8, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1bc9948 sp=0x1bc9934 pc=0x136044 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).waitRead(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*FD).Read(0x19d00a0, {0x1a71000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1bc9990 sp=0x1bc9948 pc=0x1371c4 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*netFD).Read(0x19d00a0, {0x1a71000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1bc99bc sp=0x1bc9990 pc=0x1a9e78 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*conn).Read(0x1833320, {0x1a71000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1bc99e8 sp=0x1bc99bc pc=0x1b967c Feb 24 02:11:18 volumio go-librespot[6092]: io.(*multiReader).Read(0x1b36fd0, {0x1a71000, 0x1000, 0x1000}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x1bc9a14 sp=0x1bc99e8 pc=0xefb88 Feb 24 02:11:18 volumio go-librespot[6092]: bufio.(*Reader).fill(0x1b24e70) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x1bc9a38 sp=0x1bc9a14 pc=0x2b6c1c Feb 24 02:11:18 volumio go-librespot[6092]: bufio.(*Reader).ReadByte(0x1b24e70) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x1bc9a44 sp=0x1bc9a38 pc=0x2b7498 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.readFrameHeader(0x1b24e70, {0x1b4e038, 0x8, 0x8}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x1bc9a84 sp=0x1bc9a44 pc=0x372a08 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).readFrameHeader(0x1b4e008, {0xa3d888, 0x1005090}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x1bc9b08 sp=0x1bc9a84 pc=0x375bd4 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).readLoop(0x1b4e008, {0xa3d888, 0x1005090}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x1bc9bcc sp=0x1bc9b08 pc=0x375390 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).reader(0x1b4e008, {0xa3d888, 0x1005090}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x1bc9c50 sp=0x1bc9bcc pc=0x376798 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).Reader(...) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Feb 24 02:11:18 volumio go-librespot[6092]: github.com/coder/websocket.(*Conn).Read(0x1b4e008, {0xa3d888, 0x1005090}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x1bc9c78 sp=0x1bc9c50 pc=0x374944 Feb 24 02:11:18 volumio go-librespot[6092]: main.(*ConcreteApiServer).serve.func17({0xa3d160, 0x1a0c638}, 0x1a0c4d8) Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/api_server.go:636 +0x3b4 fp=0x1bc9cfc sp=0x1bc9c78 pc=0x6b2040 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.HandlerFunc.ServeHTTP(0x18321a8, {0xa3d160, 0x1a0c638}, 0x1a0c4d8) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x1bc9d0c sp=0x1bc9cfc pc=0x33d7d8 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*ServeMux).ServeHTTP(0x187a000, {0xa3d160, 0x1a0c638}, 0x1a0c4d8) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x1bc9d4c sp=0x1bc9d0c pc=0x33f6c8 Feb 24 02:11:18 volumio go-librespot[6092]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d160, 0x1a0c638}, 0x1a0c4d8) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x1bc9d74 sp=0x1bc9d4c pc=0x6b19b8 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.HandlerFunc.ServeHTTP(0x1810170, {0xa3d160, 0x1a0c638}, 0x1a0c4d8) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x1bc9d84 sp=0x1bc9d74 pc=0x33d7d8 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.serverHandler.ServeHTTP({0x1a0c008}, {0xa3d160, 0x1a0c638}, 0x1a0c4d8) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x1bc9da0 sp=0x1bc9d84 pc=0x35cb44 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*conn).serve(0x1b6a8a0, {0xa3d8c0, 0x18281f8}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x1bc9fdc sp=0x1bc9da0 pc=0x33b894 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*Server).Serve.gowrap3() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x1bc9fec sp=0x1bc9fdc pc=0x3415cc Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1bc9fec sp=0x1bc9fec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by net/http.(*Server).Serve in goroutine 23 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:3493 +0x42c Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 119 gp=0x199de68 m=nil [chan receive]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x977188, 0x19c87b8, 0xe, 0x7, 0x2) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1bbbbd8 sp=0x1bbbbc4 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.chanrecv(0x19c8780, 0x1bbbcac, 0x1) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x1bbbc20 sp=0x1bbbbd8 pc=0x3414c Feb 24 02:11:18 volumio go-librespot[6092]: runtime.chanrecv1(0x19c8780, 0x1bbbcac) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/chan.go:509 +0x20 fp=0x1bbbc34 sp=0x1bbbc20 pc=0x33bcc Feb 24 02:11:18 volumio go-librespot[6092]: main.(*ConcreteApiServer).handleRequest(0x189e840, {{0x919763, 0x4}, {0x85e5e0, 0x18b20c0}, 0x19c8780}, {0xa3d160, 0x1b3fef8}) Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/api_server.go:355 +0x74 fp=0x1bbbcbc sp=0x1bbbc34 pc=0x6b06a4 Feb 24 02:11:18 volumio go-librespot[6092]: main.(*ConcreteApiServer).serve.func4({0xa3d160, 0x1b3fef8}, 0x1a0cdc8) Feb 24 02:11:18 volumio go-librespot[6092]: /src/cmd/daemon/api_server.go:448 +0x148 fp=0x1bbbcfc sp=0x1bbbcbc pc=0x6b31ec Feb 24 02:11:18 volumio go-librespot[6092]: net/http.HandlerFunc.ServeHTTP(0x1832058, {0xa3d160, 0x1b3fef8}, 0x1a0cdc8) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x1bbbd0c sp=0x1bbbcfc pc=0x33d7d8 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*ServeMux).ServeHTTP(0x187a000, {0xa3d160, 0x1b3fef8}, 0x1a0cdc8) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x1bbbd4c sp=0x1bbbd0c pc=0x33f6c8 Feb 24 02:11:18 volumio go-librespot[6092]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d160, 0x1b3fef8}, 0x1a0cdc8) Feb 24 02:11:18 volumio go-librespot[6092]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x1bbbd74 sp=0x1bbbd4c pc=0x6b19b8 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.HandlerFunc.ServeHTTP(0x1810170, {0xa3d160, 0x1b3fef8}, 0x1a0cdc8) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x1bbbd84 sp=0x1bbbd74 pc=0x33d7d8 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.serverHandler.ServeHTTP({0x1a0c008}, {0xa3d160, 0x1b3fef8}, 0x1a0cdc8) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x1bbbda0 sp=0x1bbbd84 pc=0x35cb44 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*conn).serve(0x1b6a900, {0xa3d8c0, 0x18281f8}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x1bbbfdc sp=0x1bbbda0 pc=0x33b894 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*Server).Serve.gowrap3() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x1bbbfec sp=0x1bbbfdc pc=0x3415cc Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1bbbfec sp=0x1bbbfec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by net/http.(*Server).Serve in goroutine 23 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:3493 +0x42c Feb 24 02:11:18 volumio go-librespot[6092]: goroutine 120 gp=0x19d6008 m=nil [IO wait]: Feb 24 02:11:18 volumio go-librespot[6092]: runtime.gopark(0x9772f0, 0xb3acac10, 0x2, 0x2, 0x5) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x1c5b6d8 sp=0x1c5b6c4 pc=0xa97c0 Feb 24 02:11:18 volumio go-librespot[6092]: runtime.netpollblock(0xb3acac00, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x1c5b6f0 sp=0x1c5b6d8 pc=0x675a0 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.runtime_pollWait(0xb3acac00, 0x72) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x1c5b704 sp=0x1c5b6f0 pc=0xa8864 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).wait(0x19d0108, 0x72, 0x0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x1c5b718 sp=0x1c5b704 pc=0x136044 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*pollDesc).waitRead(...) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 24 02:11:18 volumio go-librespot[6092]: internal/poll.(*FD).Read(0x19d00f0, {0x1b251b5, 0x1, 0x1}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x1c5b760 sp=0x1c5b718 pc=0x1371c4 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*netFD).Read(0x19d00f0, {0x1b251b5, 0x1, 0x1}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x1c5b78c sp=0x1c5b760 pc=0x1a9e78 Feb 24 02:11:18 volumio go-librespot[6092]: net.(*conn).Read(0x1833328, {0x1b251b5, 0x1, 0x1}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x1c5b7b8 sp=0x1c5b78c pc=0x1b967c Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*connReader).backgroundRead(0x1b251a0) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:702 +0x3c fp=0x1c5b7e4 sp=0x1c5b7b8 pc=0x335ac0 Feb 24 02:11:18 volumio go-librespot[6092]: net/http.(*connReader).startBackgroundRead.gowrap2() Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:698 +0x28 fp=0x1c5b7ec sp=0x1c5b7e4 pc=0x3359fc Feb 24 02:11:18 volumio go-librespot[6092]: runtime.goexit({}) Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x1c5b7ec sp=0x1c5b7ec pc=0xb0874 Feb 24 02:11:18 volumio go-librespot[6092]: created by net/http.(*connReader).startBackgroundRead in goroutine 119 Feb 24 02:11:18 volumio go-librespot[6092]: /usr/local/go/src/net/http/server.go:698 +0xd4 Feb 24 02:11:18 volumio go-librespot[6080]: Aborted Feb 24 02:11:19 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:19 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:19 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:19 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:19 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:19 volumio volumio[1265]: import socketio Feb 24 02:11:19 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:19 volumio volumio[1265]: SPOTIFY: SETTING SPOTIFY VOLUME 72 Feb 24 02:11:19 volumio volumio[1265]: info: Sending Spotify command with payload to local API: /player/volume Feb 24 02:11:19 volumio volumio[1265]: error: Failed to send command to Spotify local API: /player/volume: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 24 02:11:20 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:20 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:20 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:20 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:20 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:20 volumio volumio[1265]: import socketio Feb 24 02:11:20 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:20 volumio volumio[1265]: info: CoreCommandRouter::volumioPlay Feb 24 02:11:20 volumio volumio[1265]: info: CoreStateMachine::play index undefined Feb 24 02:11:20 volumio volumio[1265]: info: CoreStateMachine::setConsumeUpdateService undefined Feb 24 02:11:20 volumio volumio[1265]: info: CorePlayQueue::getTrack 2 Feb 24 02:11:20 volumio volumio[1265]: info: CoreStateMachine::startPlaybackTimer Feb 24 02:11:20 volumio volumio[1265]: info: CorePlayQueue::getTrack 2 Feb 24 02:11:20 volumio volumio[1265]: info: [1771873880736] ControllerSpotify::clearAddPlayTrack Feb 24 02:11:20 volumio volumio[1265]: info: Sending Spotify command with payload to local API: /player/play Feb 24 02:11:20 volumio volumio[1265]: error: Failed to send command to Spotify local API: /player/play: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 24 02:11:21 volumio volumio[1265]: error: peppy_screensaver: Error start PeppyMeter: Error: Command failed: /data/plugins/user_interface/peppy_screensaver/run_peppymeter.sh Feb 24 02:11:21 volumio volumio[1265]: Traceback (most recent call last): Feb 24 02:11:21 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/./screensaver/volumio_peppymeter.py", line 29, in Feb 24 02:11:21 volumio volumio[1265]: from volumio_albumart import AlbumartAnimator Feb 24 02:11:21 volumio volumio[1265]: File "/data/plugins/user_interface/peppy_screensaver/screensaver/volumio_albumart.py", line 12, in Feb 24 02:11:21 volumio volumio[1265]: import socketio Feb 24 02:11:21 volumio volumio[1265]: ModuleNotFoundError: No module named 'socketio' Feb 24 02:11:21 volumio volumio[1265]: info: Getting Spotify volume Feb 24 02:11:21 volumio volumio[1265]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 02:11:21 volumio volumio[1265]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 24 02:11:21 volumio volumio[1265]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 24 02:11:21 volumio volumio[1265]: errno: -111, Feb 24 02:11:21 volumio volumio[1265]: code: 'ECONNREFUSED', Feb 24 02:11:21 volumio volumio[1265]: syscall: 'connect', Feb 24 02:11:21 volumio volumio[1265]: address: '127.0.0.1', Feb 24 02:11:21 volumio volumio[1265]: port: 9879, Feb 24 02:11:21 volumio volumio[1265]: response: undefined Feb 24 02:11:21 volumio volumio[1265]: } Feb 24 02:11:21 volumio volumio[1265]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 24 02:11:21 volumio sudo[6193]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-24 02:10' Feb 24 02:11:21 volumio sudo[6193]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Feb 24 02:11:21 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 24 02:11:21 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:07:07 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="5e37060f9823263a753eb5e84030104f"