-- Logs begin at Thu 2019-02-14 12:11:58 EET, end at Tue 2026-05-26 13:47:31 EEST. -- May 26 13:46:26 oskamusic go-librespot[6659]: time="2026-05-26T13:46:26+03:00" level=trace msg="sent dealer ping" May 26 13:46:26 oskamusic go-librespot[6659]: time="2026-05-26T13:46:26+03:00" level=trace msg="received dealer pong" May 26 13:46:55 oskamusic go-librespot[6659]: time="2026-05-26T13:46:55+03:00" level=trace msg="received accesspoint ping" May 26 13:46:55 oskamusic go-librespot[6659]: time="2026-05-26T13:46:55+03:00" level=trace msg="received accesspoint pong ack" May 26 13:46:56 oskamusic go-librespot[6659]: time="2026-05-26T13:46:56+03:00" level=trace msg="sent dealer ping" May 26 13:46:56 oskamusic go-librespot[6659]: time="2026-05-26T13:46:56+03:00" level=trace msg="received dealer pong" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX4JAvHpjipBk" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZEVXdeg30Xj4pOE5" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZEVXdeg30Xj4pOE5" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EP6YuccBxUcC1" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EP6YuccBxUcC1" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZEVXbvGKBOqQjMih" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX9u7XXOp0l5L" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX9u7XXOp0l5L" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DXdPec7aLTmlC" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DXdPec7aLTmlC" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DWZ4YXAortWyq" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:07 oskamusic go-librespot[6659]: time="2026-05-26T13:47:07+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:08 oskamusic go-librespot[6659]: time="2026-05-26T13:47:08+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EIZzC6C8m4T9I" May 26 13:47:08 oskamusic go-librespot[6659]: time="2026-05-26T13:47:08+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:08 oskamusic go-librespot[6659]: time="2026-05-26T13:47:08+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX05r4Oy3Ln97" May 26 13:47:08 oskamusic go-librespot[6659]: time="2026-05-26T13:47:08+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:08 oskamusic go-librespot[6659]: time="2026-05-26T13:47:08+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DX05r4Oy3Ln97" May 26 13:47:08 oskamusic go-librespot[6659]: time="2026-05-26T13:47:08+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:08 oskamusic go-librespot[6659]: time="2026-05-26T13:47:08+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E8NxfuEgiyGlP" May 26 13:47:08 oskamusic go-librespot[6659]: time="2026-05-26T13:47:08+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:08 oskamusic go-librespot[6659]: time="2026-05-26T13:47:08+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EQnsJ0xmvpihE" May 26 13:47:08 oskamusic go-librespot[6659]: time="2026-05-26T13:47:08+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:08 oskamusic go-librespot[6659]: time="2026-05-26T13:47:08+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DWSf2RDTDayIx" May 26 13:47:08 oskamusic go-librespot[6659]: time="2026-05-26T13:47:08+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:08 oskamusic go-librespot[6659]: time="2026-05-26T13:47:08+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E4vfAbqYznzrX" May 26 13:47:08 oskamusic go-librespot[6659]: time="2026-05-26T13:47:08+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:09 oskamusic go-librespot[6659]: time="2026-05-26T13:47:09+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E4vfAbqYznzrX" May 26 13:47:09 oskamusic go-librespot[6659]: time="2026-05-26T13:47:09+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:09 oskamusic go-librespot[6659]: time="2026-05-26T13:47:09+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:09 oskamusic go-librespot[6659]: time="2026-05-26T13:47:09+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DXdPec7aLTmlC" May 26 13:47:09 oskamusic go-librespot[6659]: time="2026-05-26T13:47:09+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DXdPec7aLTmlC" May 26 13:47:09 oskamusic go-librespot[6659]: time="2026-05-26T13:47:09+03:00" level=debug msg="handling transfer player command from 79257207d5c8cc3b9f58f59a30dc6a50d61da8db" May 26 13:47:09 oskamusic go-librespot[6659]: time="2026-05-26T13:47:09+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:09 oskamusic go-librespot[6659]: time="2026-05-26T13:47:09+03:00" level=debug msg="renewing login5 access token" May 26 13:47:09 oskamusic go-librespot[6659]: time="2026-05-26T13:47:09+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:09 oskamusic go-librespot[6659]: time="2026-05-26T13:47:09+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:09 oskamusic go-librespot[6659]: time="2026-05-26T13:47:09+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 137" May 26 13:47:10 oskamusic go-librespot[6659]: time="2026-05-26T13:47:10+03:00" level=info msg="authenticated Login5" username="7y*********************x7" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1EIfgelqHfMLXX" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1EIfgelqHfMLXX" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="shuffled context with seed 14410439008107754920 (len: 50, keep: 44)" uri="spotify:playlist:37i9dQZF1EIfgelqHfMLXX" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="loading track (paused: true, position: 806ms)" uri="spotify:track:7pnVHbcqbmP7LA1ey3ydvu" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=trace msg="emitting websocket event: will_play" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="selected format OGG_VORBIS_320 (c2fb4cf6eb20956d65d18423a16c0a2ad08c8515)" uri="spotify:track:7pnVHbcqbmP7LA1ey3ydvu" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="requested aes key for file c2fb4cf6eb20956d65d18423a16c0a2ad08c8515, gid: 7pnVHbcqbmP7LA1ey3ydvu" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=trace msg="found 2 cdn urls" uri="spotify:track:7pnVHbcqbmP7LA1ey3ydvu" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1476" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="fetched first chunk of 25, total size is 12707088 bytes" uri="spotify:track:7pnVHbcqbmP7LA1ey3ydvu" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1241" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=trace msg="seek to 806ms (diff: 245ms, samples: 35544, bytes: 21102)" uri="spotify:track:7pnVHbcqbmP7LA1ey3ydvu" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="created new output device" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=info msg="loaded track \"Midnight\" (paused: true, position: 806ms, duration: 314683ms, prefetched: false)" uri="spotify:track:7pnVHbcqbmP7LA1ey3ydvu" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=trace msg="emitting websocket event: metadata" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=trace msg="emitting websocket event: active" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="sending successful reply for dealer request" May 26 13:47:11 oskamusic volumio[6475]: info: Aligning Spotify Volume to Volumio Volume May 26 13:47:11 oskamusic volumio[6475]: info: CoreCommandRouter::volumioGetState May 26 13:47:11 oskamusic volumio[6475]: info: CorePlayQueue::getTrack 0 May 26 13:47:11 oskamusic volumio[6475]: info: Setting Spotify Volume from Volumio: 86 May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1DWSf2RDTDayIx" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1EQnsJ0xmvpihE" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="skipping dealer message" uri="hm://playlist/v2/playlist/37i9dQZF1E4vfAbqYznzrX" May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/broadcast_status_update May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 26 13:47:11 oskamusic go-librespot[6659]: time="2026-05-26T13:47:11+03:00" level=debug msg="skipping dealer message" uri=social-connect/v2/session_update May 26 13:47:12 oskamusic go-librespot[6659]: time="2026-05-26T13:47:12+03:00" level=debug msg="fetched chunk 2/24, size: 524288" uri="spotify:track:7pnVHbcqbmP7LA1ey3ydvu" May 26 13:47:12 oskamusic go-librespot[6659]: time="2026-05-26T13:47:12+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 26 13:47:12 oskamusic go-librespot[6659]: time="2026-05-26T13:47:12+03:00" level=trace msg="emitting websocket event: paused" May 26 13:47:12 oskamusic volumio[6475]: info: Spotify is playing in volatile mode May 26 13:47:12 oskamusic volumio[6475]: info: CoreStateMachine::setConsumeUpdateService undefined May 26 13:47:12 oskamusic volumio[6475]: info: Setting Spotify stop after unset volatile call May 26 13:47:12 oskamusic volumio[6475]: info: CoreCommandRouter::servicePushState May 26 13:47:12 oskamusic volumio[6475]: info: CoreStateMachine::pushState May 26 13:47:12 oskamusic volumio[6475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 13:47:12 oskamusic volumio[6475]: info: CoreCommandRouter::volumioPushState May 26 13:47:12 oskamusic volumio[6475]: info: [ASDebug] CurState: pause PrevState: pause May 26 13:47:12 oskamusic go-librespot[6659]: time="2026-05-26T13:47:12+03:00" level=debug msg="fetched chunk 3/24, size: 524288" uri="spotify:track:7pnVHbcqbmP7LA1ey3ydvu" May 26 13:47:12 oskamusic go-librespot[6659]: time="2026-05-26T13:47:12+03:00" level=debug msg="fetched chunk 1/24, size: 524288" uri="spotify:track:7pnVHbcqbmP7LA1ey3ydvu" May 26 13:47:12 oskamusic volumio[6475]: info: Spotify Stop May 26 13:47:13 oskamusic volumio[6475]: info: Sending Spotify command with payload to local API: /player/volume May 26 13:47:13 oskamusic go-librespot[6659]: time="2026-05-26T13:47:13+03:00" level=debug msg="update volume requested to 56360/65535" May 26 13:47:13 oskamusic go-librespot[6659]: time="2026-05-26T13:47:13+03:00" level=debug msg="put connect state because VOLUME_CHANGED" May 26 13:47:13 oskamusic go-librespot[6659]: time="2026-05-26T13:47:13+03:00" level=trace msg="emitting websocket event: volume" May 26 13:47:26 oskamusic go-librespot[6659]: time="2026-05-26T13:47:26+03:00" level=trace msg="sent dealer ping" May 26 13:47:26 oskamusic go-librespot[6659]: time="2026-05-26T13:47:26+03:00" level=trace msg="received dealer pong" May 26 13:47:28 oskamusic go-librespot[6659]: time="2026-05-26T13:47:28+03:00" level=debug msg="handling update_context player command from 79257207d5c8cc3b9f58f59a30dc6a50d61da8db" May 26 13:47:28 oskamusic go-librespot[6659]: time="2026-05-26T13:47:28+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 26 13:47:28 oskamusic go-librespot[6659]: time="2026-05-26T13:47:28+03:00" level=debug msg="sending successful reply for dealer request" May 26 13:47:30 oskamusic go-librespot[6659]: time="2026-05-26T13:47:30+03:00" level=debug msg="handling play player command from 79257207d5c8cc3b9f58f59a30dc6a50d61da8db" May 26 13:47:30 oskamusic go-librespot[6659]: time="2026-05-26T13:47:30+03:00" level=debug msg="resolved context of track" uri="spotify:playlist:37i9dQZF1EP6YuccBxUcC1" May 26 13:47:30 oskamusic go-librespot[6659]: time="2026-05-26T13:47:30+03:00" level=trace msg="fetched new page 0 with 50 items (list: 50)" uri="spotify:playlist:37i9dQZF1EP6YuccBxUcC1" May 26 13:47:30 oskamusic go-librespot[6659]: time="2026-05-26T13:47:30+03:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:0wFxuRAncy2Qa298zQFC8c" May 26 13:47:30 oskamusic go-librespot[6659]: time="2026-05-26T13:47:30+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 26 13:47:30 oskamusic go-librespot[6659]: time="2026-05-26T13:47:30+03:00" level=trace msg="emitting websocket event: will_play" May 26 13:47:30 oskamusic go-librespot[6659]: time="2026-05-26T13:47:30+03:00" level=debug msg="selected format OGG_VORBIS_320 (5791677557da78b507d6ab389a8046bc44d274fe)" uri="spotify:track:0wFxuRAncy2Qa298zQFC8c" May 26 13:47:30 oskamusic go-librespot[6659]: time="2026-05-26T13:47:30+03:00" level=debug msg="requested aes key for file 5791677557da78b507d6ab389a8046bc44d274fe, gid: 0wFxuRAncy2Qa298zQFC8c" May 26 13:47:30 oskamusic go-librespot[6659]: time="2026-05-26T13:47:30+03:00" level=trace msg="found 2 cdn urls" uri="spotify:track:0wFxuRAncy2Qa298zQFC8c" May 26 13:47:31 oskamusic go-librespot[6659]: time="2026-05-26T13:47:31+03:00" level=debug msg="fetched first chunk of 20, total size is 10023184 bytes" uri="spotify:track:0wFxuRAncy2Qa298zQFC8c" May 26 13:47:31 oskamusic go-librespot[6659]: time="2026-05-26T13:47:31+03:00" level=debug msg="alsa driver configured, rate = 44100 bps, period time = 124988 us, period size = 5512 frames, buffer time = 500000 us, buffer size = 22050 frames, periods per buffer = 4 frames, PCM format = FLOAT_LE" May 26 13:47:31 oskamusic go-librespot[6659]: time="2026-05-26T13:47:31+03:00" level=info msg="loaded track \"Red Runner\" (paused: false, position: 0ms, duration: 247680ms, prefetched: false)" uri="spotify:track:0wFxuRAncy2Qa298zQFC8c" May 26 13:47:31 oskamusic go-librespot[6659]: time="2026-05-26T13:47:31+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 26 13:47:31 oskamusic go-librespot[6659]: time="2026-05-26T13:47:31+03:00" level=trace msg="scheduling prefetch in 217s" May 26 13:47:31 oskamusic go-librespot[6659]: time="2026-05-26T13:47:31+03:00" level=trace msg="emitting websocket event: metadata" May 26 13:47:31 oskamusic go-librespot[6659]: time="2026-05-26T13:47:31+03:00" level=debug msg="sending successful reply for dealer request" May 26 13:47:31 oskamusic go-librespot[6659]: time="2026-05-26T13:47:31+03:00" level=debug msg="fetched chunk 2/19, size: 524288" uri="spotify:track:0wFxuRAncy2Qa298zQFC8c" May 26 13:47:31 oskamusic go-librespot[6659]: time="2026-05-26T13:47:31+03:00" level=debug msg="fetched chunk 3/19, size: 524288" uri="spotify:track:0wFxuRAncy2Qa298zQFC8c" May 26 13:47:31 oskamusic go-librespot[6659]: time="2026-05-26T13:47:31+03:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" May 26 13:47:31 oskamusic go-librespot[6659]: time="2026-05-26T13:47:31+03:00" level=trace msg="emitting websocket event: playing" May 26 13:47:31 oskamusic volumio[6475]: info: CoreCommandRouter::servicePushState May 26 13:47:31 oskamusic volumio[6475]: info: CoreStateMachine::pushState May 26 13:47:31 oskamusic volumio[6475]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo May 26 13:47:31 oskamusic volumio[6475]: info: CoreCommandRouter::volumioPushState May 26 13:47:31 oskamusic volumio[6475]: info: [ASDebug] CurState: play PrevState: pause May 26 13:47:31 oskamusic volumio[6475]: info: [ASDebug] Togle GPIO: ON May 26 13:47:31 oskamusic volumio[6475]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 13:47:31 oskamusic volumio[6475]: Error: EBADF: bad file descriptor, write May 26 13:47:31 oskamusic volumio[6475]: at Object.writeSync (fs.js:711:3) May 26 13:47:31 oskamusic volumio[6475]: at Gpio.writeSync (/data/plugins/system_controller/ampswitch/node_modules/onoff/onoff.js:243:8) May 26 13:47:31 oskamusic volumio[6475]: at AmpSwitchController.on (/data/plugins/system_controller/ampswitch/index.js:207:23) May 26 13:47:31 oskamusic volumio[6475]: at AmpSwitchController.parseStatus (/data/plugins/system_controller/ampswitch/index.js:190:86) May 26 13:47:31 oskamusic volumio[6475]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) May 26 13:47:31 oskamusic volumio[6475]: at Socket.onevent (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:278:10) May 26 13:47:31 oskamusic volumio[6475]: at Socket.onpacket (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/socket.js:236:12) May 26 13:47:31 oskamusic volumio[6475]: at Manager. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) May 26 13:47:31 oskamusic volumio[6475]: at Manager.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) May 26 13:47:31 oskamusic volumio[6475]: at Manager.ondecoded (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:349:8) May 26 13:47:31 oskamusic volumio[6475]: at Decoder. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) May 26 13:47:31 oskamusic volumio[6475]: at Decoder.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) May 26 13:47:31 oskamusic volumio[6475]: at Decoder.add (/data/plugins/system_controller/ampswitch/node_modules/socket.io-parser/index.js:254:12) May 26 13:47:31 oskamusic volumio[6475]: at Manager.ondata (/data/plugins/system_controller/ampswitch/node_modules/socket.io-client/lib/manager.js:339:16) May 26 13:47:31 oskamusic volumio[6475]: at Socket. (/data/plugins/system_controller/ampswitch/node_modules/component-bind/index.js:21:15) May 26 13:47:31 oskamusic volumio[6475]: at Socket.Emitter.emit (/data/plugins/system_controller/ampswitch/node_modules/component-emitter/index.js:145:20) { May 26 13:47:31 oskamusic volumio[6475]: errno: -9, May 26 13:47:31 oskamusic volumio[6475]: syscall: 'write', May 26 13:47:31 oskamusic volumio[6475]: code: 'EBADF' May 26 13:47:31 oskamusic volumio[6475]: } May 26 13:47:31 oskamusic volumio[6475]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| May 26 13:47:31 oskamusic go-librespot[6659]: time="2026-05-26T13:47:31+03:00" level=debug msg="fetched chunk 1/19, size: 524288" uri="spotify:track:0wFxuRAncy2Qa298zQFC8c" May 26 13:47:31 oskamusic sudo[25454]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2026-05-26 13:46 May 26 13:47:31 oskamusic sudo[25454]: 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="e9612ec5034fb2e958508aaefbca2962fd6f6654" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="464fc672d77d3df6ee72b331d36cdf1fa936e1ec" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri 27 Feb 2026 10:59:40 AM CET" VOLUMIO_VERSION="3.912" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="37c6ab864cb114e1344d540995c69f86"