-- Logs begin at Thu 2019-02-14 11:11:58 CET, end at Tue 2025-12-23 07:57:23 CET. -- Dec 23 07:56:06 streamer go-librespot[1574]: time="2025-12-23T07:56:06+01:00" level=trace msg="sent dealer ping" Dec 23 07:56:06 streamer go-librespot[1574]: time="2025-12-23T07:56:06+01:00" level=trace msg="received dealer pong" Dec 23 07:56:10 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 23 07:56:22 streamer volumio[1074]: info: [peppymeter] Killing last child of process tree (pids: 4526 4527 *4528*) Dec 23 07:56:22 streamer volumio[1074]: info: [peppymeter] (PID: 4704) Process spawned for cmd: /bin/kill -9 4528 Dec 23 07:56:22 streamer volumio[1074]: info: [peppymeter] (PID: 4704) Process closed - code: 0, signal: null Dec 23 07:56:22 streamer volumio[1074]: info: [peppymeter] (PID: 4526) Process stderr: /data/plugins/user_interface/peppymeter/dist/peppymeter/run_peppymeter.sh: line 12: 4528 Killed PYTHONUNBUFFERED=1 DISPLAY=:0 python3 volumio_peppymeter.py Dec 23 07:56:22 streamer volumio[1074]: info: [peppymeter] (PID: 4526) Process closed - code: 137, signal: null Dec 23 07:56:22 streamer volumio[1074]: info: Disabling plugin peppymeter Dec 23 07:56:22 streamer volumio[1074]: info: Done. Dec 23 07:56:23 streamer sudo[4707]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/chmod a+rw /etc/X11/xorg.conf.d/95-touch_display-plugin.conf Dec 23 07:56:23 streamer sudo[4707]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 07:56:23 streamer sudo[4707]: pam_unix(sudo:session): session closed for user root Dec 23 07:56:23 streamer sudo[4710]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl start getty@tty1.service Dec 23 07:56:23 streamer sudo[4710]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 07:56:23 streamer sudo[4713]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl enable getty@tty1.service Dec 23 07:56:23 streamer sudo[4713]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 07:56:23 streamer volumio[1074]: info: touch_display: File permissions for /etc/X11/xorg.conf.d/95-touch_display-plugin.conf set. Dec 23 07:56:23 streamer sudo[4716]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl stop volumio-kiosk.service Dec 23 07:56:23 streamer sudo[4716]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 07:56:23 streamer systemd[1]: Created slice system-getty.slice. Dec 23 07:56:23 streamer systemd[1]: Started Getty on tty1. Dec 23 07:56:23 streamer sudo[4710]: pam_unix(sudo:session): session closed for user root Dec 23 07:56:23 streamer volumio[1074]: info: touch_display: systemctl start getty@tty1.service succeeded. Dec 23 07:56:23 streamer systemd[1]: Reloading. Dec 23 07:56:24 streamer systemd[1]: /lib/systemd/system/winbind.service:8: PIDFile= references path below legacy directory /var/run/, updating /var/run/samba/winbindd.pid → /run/samba/winbindd.pid; please update the unit file accordingly. Dec 23 07:56:24 streamer systemd[1]: getty@tty1.service: Current command vanished from the unit file, execution of the command list won't be resumed. Dec 23 07:56:24 streamer sudo[4713]: pam_unix(sudo:session): session closed for user root Dec 23 07:56:24 streamer volumio[1074]: info: touch_display: systemctl enable getty@tty1.service succeeded. Dec 23 07:56:24 streamer startx[1582]: xinit: connection to X server lost Dec 23 07:56:24 streamer startx[1582]: [1B blob data] Dec 23 07:56:24 streamer systemd[1]: Stopping Volumio Kiosk... Dec 23 07:56:24 streamer startx[1582]: waiting for X server to shut down (II) Server terminated successfully (0). Closing log file. Dec 23 07:56:25 streamer startx[1582]: xinit: unexpected signal 15 Dec 23 07:56:25 streamer systemd[1]: volumio-kiosk.service: Main process exited, code=exited, status=1/FAILURE Dec 23 07:56:25 streamer systemd[1]: volumio-kiosk.service: Failed with result 'exit-code'. Dec 23 07:56:25 streamer systemd[1]: Stopped Volumio Kiosk. Dec 23 07:56:25 streamer sudo[4716]: pam_unix(sudo:session): session closed for user root Dec 23 07:56:25 streamer volumio[1074]: info: touch_display: systemctl stop volumio-kiosk.service succeeded. Dec 23 07:56:25 streamer volumio[1074]: info: Disabling plugin touch_display Dec 23 07:56:25 streamer volumio[1074]: info: Done. Dec 23 07:56:28 streamer volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 07:56:28 streamer volumio[1074]: info: [ASDebug] Setting UI defaults Dec 23 07:56:28 streamer volumio[1074]: info: [ASDebug] Port: 24 Dec 23 07:56:28 streamer volumio[1074]: info: [ASDebug] Inverted: false Dec 23 07:56:28 streamer volumio[1074]: info: [ASDebug] Latched: false Dec 23 07:56:28 streamer volumio[1074]: info: [ASDebug] On pulse width: 500 Dec 23 07:56:28 streamer volumio[1074]: info: [ASDebug] Off pulse width: 500 Dec 23 07:56:33 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 23 07:56:33 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioToken Dec 23 07:56:36 streamer go-librespot[1574]: time="2025-12-23T07:56:36+01:00" level=trace msg="received accesspoint ping" Dec 23 07:56:36 streamer go-librespot[1574]: time="2025-12-23T07:56:36+01:00" level=trace msg="received accesspoint pong ack" Dec 23 07:56:36 streamer volumio[1074]: info: [ASDebug] Port: 24 Dec 23 07:56:36 streamer volumio[1074]: info: [ASDebug] Inverted: false Dec 23 07:56:36 streamer volumio[1074]: info: [ASDebug] Delay: 720 Dec 23 07:56:36 streamer go-librespot[1574]: time="2025-12-23T07:56:36+01:00" level=trace msg="sent dealer ping" Dec 23 07:56:36 streamer go-librespot[1574]: time="2025-12-23T07:56:36+01:00" level=trace msg="received dealer pong" Dec 23 07:56:44 streamer volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 23 07:56:49 streamer volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 07:56:49 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 23 07:56:49 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 23 07:56:49 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: appearance , getConfigParam Dec 23 07:56:49 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: appearance , getAvailableUIs Dec 23 07:56:49 streamer volumio[1074]: info: Received Get System Version Dec 23 07:56:49 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getSystemVersion Dec 23 07:57:01 streamer volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 07:57:05 streamer volumio[1074]: info: Retrieving Cloud Streaming UI Dec 23 07:57:05 streamer volumio[1074]: info: Getting Tidal Cloud Configuration Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 07:57:05 streamer volumio[1074]: info: Getting Qobuz Cloud Configuration Dec 23 07:57:05 streamer volumio[1074]: info: Asking plugin for UI Config Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 07:57:05 streamer volumio[1074]: info: Getting Spotify Cloud Configuration Dec 23 07:57:05 streamer volumio[1074]: info: Asking plugin for UI Config Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 07:57:05 streamer volumio[1074]: info: Saving Spotify Acccount Dec 23 07:57:05 streamer volumio[1074]: info: Got Tidal Cloud Configuration Dec 23 07:57:05 streamer volumio[1074]: info: Got it Dec 23 07:57:05 streamer volumio[1074]: info: Got it Dec 23 07:57:05 streamer volumio[1074]: error: Could not retrieve Spotify Config from plugin Spotify: no section found Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::getUIConfigOnPlugin Dec 23 07:57:05 streamer volumio[1074]: error: Error retrieving Highresaudio conf: TypeError: Cannot read property 'onSave' of undefined Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: system , getAdvancedSettingsStatus Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: albumart , getConfigParam Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getConfigParam Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::volumioGetBrowseSources Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::volumioGetBrowseSources Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::volumioGetBrowseSources Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: mpd , getMyCollectionStats Dec 23 07:57:05 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: networkfs , listShares Dec 23 07:57:06 streamer go-librespot[1574]: time="2025-12-23T07:57:06+01:00" level=trace msg="sent dealer ping" Dec 23 07:57:06 streamer go-librespot[1574]: time="2025-12-23T07:57:06+01:00" level=trace msg="received dealer pong" Dec 23 07:57:10 streamer volumio[1074]: info: CoreCommandRouter::volumioGetState Dec 23 07:57:18 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: spop , handleBrowseUri Dec 23 07:57:18 streamer volumio[1074]: info: In handleBrowseUri, curUri=spotify Dec 23 07:57:18 streamer volumio[1074]: info: Preload queue cleared Dec 23 07:57:18 streamer volumio[1074]: info: Preload queue cleared Dec 23 07:57:18 streamer volumio[1074]: info: Preload queue cleared Dec 23 07:57:18 streamer volumio[1074]: info: Preload queue cleared Dec 23 07:57:21 streamer volumio[1074]: info: Preload queue cleared Dec 23 07:57:21 streamer volumio[1074]: info: CoreCommandRouter::volumioReplaceandPlayItems Dec 23 07:57:21 streamer volumio[1074]: info: CoreStateMachine::ClearQueue Dec 23 07:57:21 streamer volumio[1074]: info: CoreStateMachine::stop Dec 23 07:57:21 streamer volumio[1074]: info: CoreStateMachine::serviceStop Dec 23 07:57:21 streamer volumio[1074]: info: CoreCommandRouter::serviceStop Dec 23 07:57:21 streamer volumio[1074]: info: Airplay Stop Dec 23 07:57:21 streamer volumio[1074]: info: Stopping Airplay Playback and sending pause command to client via USR2 Dec 23 07:57:21 streamer volumio[1074]: info: CorePlayQueue::clearPlayQueue Dec 23 07:57:21 streamer volumio[1074]: info: CorePlayQueue::saveQueue Dec 23 07:57:21 streamer volumio[1074]: info: CoreCommandRouter::volumioPushQueue Dec 23 07:57:21 streamer volumio[1074]: info: CoreStateMachine::addQueueItems Dec 23 07:57:21 streamer volumio[1074]: info: CorePlayQueue::addQueueItems Dec 23 07:57:21 streamer volumio[1074]: info: Preload queue cleared Dec 23 07:57:21 streamer volumio[1074]: info: Adding Item to queue: spotify:playlist:37i9dQZF1DX0Yxoavh5qJV Dec 23 07:57:21 streamer volumio[1074]: info: Exploding uri spotify:playlist:37i9dQZF1DX0Yxoavh5qJV in service spop Dec 23 07:57:21 streamer volumio[1074]: SPOTIFY: EXPLODING URI:spotify:playlist:37i9dQZF1DX0Yxoavh5qJV Dec 23 07:57:21 streamer sudo[4892]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 1577 Dec 23 07:57:21 streamer sudo[4892]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 07:57:21 streamer sudo[4892]: pam_unix(sudo:session): session closed for user root Dec 23 07:57:21 streamer volumio[1074]: info: Shairport-Sync paused with USR2 Dec 23 07:57:21 streamer go-librespot[1574]: time="2025-12-23T07:57:21+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 23 07:57:21 streamer go-librespot[1574]: time="2025-12-23T07:57:21+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX0Yxoavh5qJV" Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX0Yxoavh5qJV" Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" Dec 23 07:57:22 streamer volumio[1074]: info: Restarting Shairport-Sync after stop Dec 23 07:57:22 streamer sudo[4895]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/systemctl restart shairport-sync Dec 23 07:57:22 streamer sudo[4895]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 07:57:22 streamer systemd[1]: Stopping Shairport Sync - AirPlay Audio Receiver... Dec 23 07:57:22 streamer shairport-sync[1577]: {"time":1766472904176,"response":"startAirplayPlayback Success"} Dec 23 07:57:22 streamer systemd[1]: shairport-sync.service: Succeeded. Dec 23 07:57:22 streamer systemd[1]: Stopped Shairport Sync - AirPlay Audio Receiver. Dec 23 07:57:22 streamer systemd[1]: Started Shairport Sync - AirPlay Audio Receiver. Dec 23 07:57:22 streamer sudo[4895]: pam_unix(sudo:session): session closed for user root Dec 23 07:57:22 streamer volumio[1074]: info: Shairport-Sync retarted Dec 23 07:57:22 streamer volumio[1074]: info: CoreCommandRouter::volumioPushQueue Dec 23 07:57:22 streamer volumio[1074]: info: CorePlayQueue::saveQueue Dec 23 07:57:22 streamer volumio[1074]: info: CoreStateMachine::updateTrackBlock Dec 23 07:57:22 streamer volumio[1074]: info: CorePlayQueue::getTrackBlock Dec 23 07:57:22 streamer volumio[1074]: info: CoreCommandRouter::volumioPlay Dec 23 07:57:22 streamer volumio[1074]: verbose: UNSET VOLATILE: Service: airplay_emulation Dec 23 07:57:22 streamer volumio[1074]: info: Stopping Airplay Playback and sending pause command to client via USR2 Dec 23 07:57:22 streamer volumio[1074]: info: CoreStateMachine::play index 0 Dec 23 07:57:22 streamer volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 07:57:22 streamer volumio[1074]: info: CoreStateMachine::stop Dec 23 07:57:22 streamer volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 07:57:22 streamer volumio[1074]: info: CoreStateMachine::stPlaybackTimer Dec 23 07:57:22 streamer volumio[1074]: info: CoreStateMachine::updateTrackBlock Dec 23 07:57:22 streamer volumio[1074]: info: CorePlayQueue::getTrackBlock Dec 23 07:57:22 streamer volumio[1074]: info: CoreStateMachine::pushState Dec 23 07:57:22 streamer volumio[1074]: info: CorePlayQueue::getTrack 1 Dec 23 07:57:22 streamer volumio[1074]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 07:57:22 streamer volumio[1074]: info: CoreCommandRouter::volumioPushState Dec 23 07:57:22 streamer volumio[1074]: info: CoreStateMachine::serviceStop Dec 23 07:57:22 streamer volumio[1074]: info: CorePlayQueue::getTrack 1 Dec 23 07:57:22 streamer volumio[1074]: info: CoreCommandRouter::serviceStop Dec 23 07:57:22 streamer volumio[1074]: info: Spotify Stop Dec 23 07:57:22 streamer volumio[1074]: SPOTIFY: SPOTIFY STOP Dec 23 07:57:22 streamer volumio[1074]: SPOTIFY: {"status":"play","title":"","artist":"","album":"","albumart":"/albumart?cacheid=990&web=//extralarge&path=&metadata=false","uri":"","trackType":"airplay","codec":"","seek":8000,"duration":0,"samplerate":"44.1 kHz","bitdepth":"16 bit","channels":2,"consume":false,"volume":100,"dbVolume":null,"mute":false,"disableVolumeControl":true,"stream":false,"volatile":true,"disableUiControls":true,"service":"airplay_emulation"} Dec 23 07:57:22 streamer volumio[1074]: info: Sending Spotify command to local API: /player/pause Dec 23 07:57:22 streamer volumio[1074]: info: CoreStateMachine::play index undefined Dec 23 07:57:22 streamer volumio[1074]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 07:57:22 streamer volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 23 07:57:22 streamer volumio[1074]: info: CoreStateMachine::startPlaybackTimer Dec 23 07:57:22 streamer volumio[1074]: info: CorePlayQueue::getTrack 0 Dec 23 07:57:22 streamer volumio[1074]: info: [1766473042336] ControllerSpotify::clearAddPlayTrack Dec 23 07:57:22 streamer volumio[1074]: info: Sending Spotify command with payload to local API: /player/play Dec 23 07:57:22 streamer sudo[4903]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/kill -USR2 4897 Dec 23 07:57:22 streamer sudo[4903]: pam_unix(sudo:session): session opened for user root by (uid=0) Dec 23 07:57:22 streamer volumio[1074]: info: [ASDebug] CurState: stop PrevState: play Dec 23 07:57:22 streamer volumio[1074]: info: [ASDebug] InitTimeout - Amp off in: 720 ms Dec 23 07:57:22 streamer sudo[4903]: pam_unix(sudo:session): session closed for user root Dec 23 07:57:22 streamer volumio[1074]: info: Shairport-Sync paused with USR2 Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=debug msg="resolved context of track" uri="spotify:track:2FRnf9qhLbvw8fu4IBXx78" Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=trace msg="fetched new page 0 with 1 items (list: 1)" uri="spotify:track:2FRnf9qhLbvw8fu4IBXx78" Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:2FRnf9qhLbvw8fu4IBXx78" Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=trace msg="emitting websocket event: will_play" Dec 23 07:57:22 streamer volumio[1074]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:track:2FRnf9qhLbvw8fu4IBXx78","uri":"spotify:track:2FRnf9qhLbvw8fu4IBXx78","play_origin":"go-librespot"}} Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=debug msg="selected format OGG_VORBIS_320 (bc4c46dec7eb7cf1e695a0c81c7c37331dd5b1df)" uri="spotify:track:2FRnf9qhLbvw8fu4IBXx78" Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=debug msg="requested aes key for file bc4c46dec7eb7cf1e695a0c81c7c37331dd5b1df, gid: 2FRnf9qhLbvw8fu4IBXx78" Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 310" Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=trace msg="found 2 cdn urls" uri="spotify:track:2FRnf9qhLbvw8fu4IBXx78" Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=debug msg="fetched first chunk of 19, total size is 9737884 bytes" uri="spotify:track:2FRnf9qhLbvw8fu4IBXx78" Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=debug msg="created new output device" Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=error msg="failed handling request play" error="failed loading context: failed loading current track (load context): failed setting stream for spotify:track:2FRnf9qhLbvw8fu4IBXx78: ALSA error at snd_pcm_open: Device or resource busy" Dec 23 07:57:22 streamer volumio[1074]: error: Failed to send command to Spotify local API: /player/play: Error: Internal Server Error Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=debug msg="fetched chunk 1/18, size: 524288" uri="spotify:track:2FRnf9qhLbvw8fu4IBXx78" Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=debug msg="fetched chunk 3/18, size: 524288" uri="spotify:track:2FRnf9qhLbvw8fu4IBXx78" Dec 23 07:57:22 streamer go-librespot[1574]: time="2025-12-23T07:57:22+01:00" level=debug msg="fetched chunk 2/18, size: 524288" uri="spotify:track:2FRnf9qhLbvw8fu4IBXx78" Dec 23 07:57:23 streamer volumio[1074]: info: [ASDebug] Togle GPIO: OFF Dec 23 07:57:23 streamer volumio[1074]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 07:57:23 streamer volumio[1074]: Error: EBADF: bad file descriptor, write Dec 23 07:57:23 streamer volumio[1074]: at Object.writeSync (fs.js:711:3) Dec 23 07:57:23 streamer volumio[1074]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) Dec 23 07:57:23 streamer volumio[1074]: at AmpSwitchController.off (/data/plugins/system_controller/ampswitch/index.js:221:23) Dec 23 07:57:23 streamer volumio[1074]: at Timeout._onTimeout (/data/plugins/system_controller/ampswitch/index.js:195:88) Dec 23 07:57:23 streamer volumio[1074]: at listOnTimeout (internal/timers.js:554:17) Dec 23 07:57:23 streamer volumio[1074]: at processTimers (internal/timers.js:497:7) { Dec 23 07:57:23 streamer volumio[1074]: errno: -9, Dec 23 07:57:23 streamer volumio[1074]: syscall: 'write', Dec 23 07:57:23 streamer volumio[1074]: code: 'EBADF' Dec 23 07:57:23 streamer volumio[1074]: } Dec 23 07:57:23 streamer volumio[1074]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 07:57:23 streamer sudo[4914]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-12-23 07:56 Dec 23 07:57:23 streamer sudo[4914]: pam_unix(sudo:session): session opened for user root by (uid=0) PRETTY_NAME="Raspbian GNU/Linux 10 (buster)" NAME="Raspbian GNU/Linux" VERSION_ID="10" VERSION="10 (buster)" VERSION_CODENAME=buster 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="b43c85cc0006d3f1efecba101311ec96e334d1fd" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu 11 Dec 2025 05:43:24 PM CET" VOLUMIO_VERSION="3.886" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="15d0241b0cd805792809f3ddf4a0fc4e"