Dec 23 19:19:08 volumio go-librespot[1771]: time="2025-12-23T19:19:08+09:00" level=debug msg="fetched chunk 28/34, size: 524288" uri="spotify:track:6WTaPOmjmElVLG7HD8nHkb" Dec 23 19:19:17 volumio go-librespot[1771]: time="2025-12-23T19:19:17+09:00" level=trace msg="sent dealer ping" Dec 23 19:19:17 volumio go-librespot[1771]: time="2025-12-23T19:19:17+09:00" level=trace msg="received dealer pong" Dec 23 19:19:24 volumio go-librespot[1771]: time="2025-12-23T19:19:24+09:00" level=debug msg="fetched chunk 29/34, size: 524288" uri="spotify:track:6WTaPOmjmElVLG7HD8nHkb" Dec 23 19:19:38 volumio go-librespot[1771]: time="2025-12-23T19:19:38+09:00" level=debug msg="handling skip_next player command from 666d08b9cce204732d8b70563765454c8d78350a" Dec 23 19:19:38 volumio go-librespot[1771]: time="2025-12-23T19:19:38+09:00" level=debug msg="loading track (paused: false, position: 0ms)" uri="spotify:track:3fruFhpDq6h8j49UDcXwQY" Dec 23 19:19:41 volumio go-librespot[1771]: time="2025-12-23T19:19:41+09:00" level=debug msg="fetched chunk 30/34, size: 524288" uri="spotify:track:6WTaPOmjmElVLG7HD8nHkb" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 323" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1348" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=trace msg="emitting websocket event: will_play" Dec 23 19:19:43 volumio volumio[1366]: SPOTIFY: received: {"type":"will_play","data":{"context_uri":"spotify:playlist:37i9dQZF1DZ06evO3qZO9x","uri":"spotify:track:3fruFhpDq6h8j49UDcXwQY","play_origin":"playlist"}} Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="selected format OGG_VORBIS_320 (1e071dfbb0888dff28bf3a3c27838096966b4649)" uri="spotify:track:3fruFhpDq6h8j49UDcXwQY" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="requested aes key for file 1e071dfbb0888dff28bf3a3c27838096966b4649, gid: 3fruFhpDq6h8j49UDcXwQY" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=trace msg="found 3 cdn urls" uri="spotify:track:3fruFhpDq6h8j49UDcXwQY" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="fetched first chunk of 18, total size is 9152692 bytes" uri="spotify:track:3fruFhpDq6h8j49UDcXwQY" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=warning msg="reducing track normalisation factor to prevent clipping, please add negative pregain to avoid" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=info msg="loaded track \"Anna\" (paused: false, position: 0ms, duration: 235553ms, prefetched: false)" uri="spotify:track:3fruFhpDq6h8j49UDcXwQY" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="fetched chunk 2/17, size: 524288" uri="spotify:track:3fruFhpDq6h8j49UDcXwQY" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=trace msg="scheduling prefetch in 205s" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=trace msg="emitting websocket event: metadata" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="sending successful reply for dealer request" Dec 23 19:19:43 volumio volumio[1366]: SPOTIFY: received: {"type":"metadata","data":{"uri":"spotify:track:3fruFhpDq6h8j49UDcXwQY","name":"Anna","artist_names":["Charlie Haden","Antonio Forcione"],"album_name":"Heartplay","album_cover_url":"https://i.scdn.co/image/ab67616d00001e028acb9d792c352ee954026451","position":0,"duration":235553,"release_date":"year:2006","track_number":1,"disc_number":1}} Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="fetched chunk 3/17, size: 524288" uri="spotify:track:3fruFhpDq6h8j49UDcXwQY" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="put connect state because PLAYER_STATE_CHANGED" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=trace msg="emitting websocket event: playing" Dec 23 19:19:43 volumio volumio[1366]: SPOTIFY: received: {"type":"playing","data":{"context_uri":"spotify:playlist:37i9dQZF1DZ06evO3qZO9x","uri":"spotify:track:3fruFhpDq6h8j49UDcXwQY","resume":false,"play_origin":"playlist"}} Dec 23 19:19:43 volumio volumio[1366]: SPOTIFY: PUSH STATE SPOTIFY Dec 23 19:19:43 volumio volumio[1366]: SPOTIFY: {"status":"play","service":"spop","title":"Anna","artist":"Charlie Haden, Antonio Forcione","album":"Heartplay","albumart":"https://i.scdn.co/image/ab67616d00001e028acb9d792c352ee954026451","uri":"spotify:track:3fruFhpDq6h8j49UDcXwQY","trackType":"spotify","seek":0,"duration":235,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 23 19:19:43 volumio volumio[1366]: info: CoreCommandRouter::servicePushState Dec 23 19:19:43 volumio volumio[1366]: info: CoreStateMachine::pushState Dec 23 19:19:43 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 19:19:43 volumio volumio[1366]: info: CoreCommandRouter::volumioPushState Dec 23 19:19:43 volumio volumio[1366]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=info msg="playback was transferred to Web Player (Chrome)" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=trace msg="closed output device because of stop command" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 411" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="fetched chunk 1/17, size: 524288" uri="spotify:track:3fruFhpDq6h8j49UDcXwQY" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="put connect state inactive" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=trace msg="emitting websocket event: inactive" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=trace msg="emitting websocket event: stopped" Dec 23 19:19:43 volumio volumio[1366]: SPOTIFY: received: {"type":"inactive","data":null} Dec 23 19:19:43 volumio volumio[1366]: error: Failed to decode event: inactive Dec 23 19:19:43 volumio volumio[1366]: SPOTIFY: received: {"type":"stopped","data":{"play_origin":""}} Dec 23 19:19:43 volumio volumio[1366]: SPOTIFY: PUSH STATE SPOTIFY Dec 23 19:19:43 volumio volumio[1366]: SPOTIFY: {"status":"stop","service":"spop","title":"Anna","artist":"Charlie Haden, Antonio Forcione","album":"Heartplay","albumart":"https://i.scdn.co/image/ab67616d00001e028acb9d792c352ee954026451","uri":"spotify:track:3fruFhpDq6h8j49UDcXwQY","trackType":"spotify","seek":0,"duration":235,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 23 19:19:43 volumio volumio[1366]: info: CoreCommandRouter::servicePushState Dec 23 19:19:43 volumio volumio[1366]: info: CoreStateMachine::pushState Dec 23 19:19:43 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo Dec 23 19:19:43 volumio volumio[1366]: info: CoreCommandRouter::volumioPushState Dec 23 19:19:43 volumio volumio[1366]: info: CorePlayQueue::getTrack 1 Dec 23 19:19:43 volumio volumio[1366]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"Anna","artist":"Charlie Haden, Antonio Forcione","album":"Heartplay","albumart":"https://i.scdn.co/image/ab67616d00001e028acb9d792c352ee954026451","uri":"spotify:track:3fruFhpDq6h8j49UDcXwQY","trackType":"spotify","seek":0,"duration":235,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null,"stream":false,"repeatMode":"all"} Dec 23 19:19:43 volumio volumio[1366]: verbose: CURRENT POSITION 1 Dec 23 19:19:43 volumio volumio[1366]: info: CoreStateMachine::syncState stateService stop Dec 23 19:19:43 volumio volumio[1366]: info: CoreStateMachine::syncState currentStatus play Dec 23 19:19:43 volumio volumio[1366]: info: CoreStateMachine::play index undefined Dec 23 19:19:43 volumio volumio[1366]: info: CoreStateMachine::setConsumeUpdateService undefined Dec 23 19:19:43 volumio volumio[1366]: info: CoreStateMachine::pushState Dec 23 19:19:43 volumio volumio[1366]: info: CoreCommandRouter::volumioPushState Dec 23 19:19:43 volumio volumio[1366]: info: CorePlayQueue::getTrack 2 Dec 23 19:19:43 volumio volumio[1366]: info: CoreStateMachine::startPlaybackTimer Dec 23 19:19:43 volumio volumio[1366]: info: CorePlayQueue::getTrack 2 Dec 23 19:19:43 volumio volumio[1366]: info: [1766485183821] ControllerSpotify::clearAddPlayTrack Dec 23 19:19:43 volumio volumio[1366]: info: Sending Spotify command with payload to local API: /player/play Dec 23 19:19:43 volumio volumio[1366]: info: CoreStateMachine::pushState Dec 23 19:19:43 volumio volumio[1366]: info: CoreCommandRouter::volumioPushState Dec 23 19:19:43 volumio volumio[1366]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Dec 23 19:19:43 volumio volumio[1366]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Dec 23 19:19:43 volumio volumio[1366]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 1364" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="dealer connection closed" Dec 23 19:19:43 volumio go-librespot[1771]: time="2025-12-23T19:19:43+09:00" level=debug msg="dealer recv loop stopped" Dec 23 19:19:43 volumio go-librespot[1771]: panic: send on closed channel Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 69 gp=0x22347e8 m=4 mp=0x2049088 [running]: Dec 23 19:19:43 volumio go-librespot[1771]: panic({0x6cfbf0, 0x881b80}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/panic.go:779 +0x12c fp=0x24b7f48 sp=0x24b7ef4 pc=0x58bf8 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.chansend(0x24802c0, 0x24b7fa8, 0x1, 0x63e7c8) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/chan.go:283 +0x5e4 fp=0x24b7f80 sp=0x24b7f48 pc=0x1f474 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.chansend1(0x24802c0, 0x24b7fa8) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/chan.go:145 +0x28 fp=0x24b7f98 sp=0x24b7f80 pc=0x1ee8c Dec 23 19:19:43 volumio go-librespot[1771]: main.(*App).withAppPlayer.func1() Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/main.go:275 +0x9c fp=0x24b7fec sp=0x24b7f98 pc=0x63e7c8 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24b7fec sp=0x24b7fec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by main.(*App).withAppPlayer in goroutine 1 Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/main.go:265 +0x464 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 1 gp=0x2002128 m=nil [select, 1380 minutes]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x251bd00 sp=0x251bcec pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.selectgo(0x251be00, 0x21dbdcc, 0x0, 0x0, 0x2, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x251bda0 sp=0x251bd00 pc=0x70e78 Dec 23 19:19:43 volumio go-librespot[1771]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x24a7e60, 0x251be90) Dec 23 19:19:43 volumio go-librespot[1771]: /src/zeroconf/zeroconf.go:283 +0x228 fp=0x251be44 sp=0x251bda0 pc=0x5a8088 Dec 23 19:19:43 volumio go-librespot[1771]: main.(*App).withAppPlayer(0x20de780, {0x8874f0, 0xd0ad00}, 0x200e290) Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/main.go:321 +0x554 fp=0x251bed0 sp=0x251be44 pc=0x63db54 Dec 23 19:19:43 volumio go-librespot[1771]: main.(*App).withCredentials(...) Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/main.go:193 Dec 23 19:19:43 volumio go-librespot[1771]: main.(*App).SpotifyToken(0x20de780, {0x8874f0, 0xd0ad00}, {0x22c65e0, 0x1c}, {0x209a140, 0x13c}) Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/main.go:185 +0xd8 fp=0x251bf00 sp=0x251bed0 pc=0x63cfa8 Dec 23 19:19:43 volumio go-librespot[1771]: main.main() Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/main.go:557 +0x588 fp=0x251bfa8 sp=0x251bf00 pc=0x63feb4 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.main() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x251bfec sp=0x251bfa8 pc=0x5cb30 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x251bfec sp=0x251bfec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 18 gp=0x2084248 m=nil [force gc (idle), 106 minutes]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e4100, 0xcdf428, 0x11, 0xa, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20407d4 sp=0x20407c0 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goparkunlock(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:408 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.forcegchelper() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x20407ec sp=0x20407d4 pc=0x5cefc Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20407ec sp=0x20407ec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by runtime.init.5 in goroutine 1 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:314 +0x1c Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 19 gp=0x2084368 m=nil [GC sweep wait]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e4100, 0xcdfbe0, 0xc, 0x9, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2040fc4 sp=0x2040fb0 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goparkunlock(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:408 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.bgsweep(0x2092000) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x2040fe4 sp=0x2040fc4 pc=0x45848 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gcenable.gowrap1() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x2040fec sp=0x2040fe4 pc=0x35968 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2040fec sp=0x2040fec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by runtime.gcenable in goroutine 1 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 20 gp=0x2084488 m=nil [GC scavenge wait]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e4100, 0xce0208, 0xd, 0xa, 0x2) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x20417b4 sp=0x20417a0 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goparkunlock(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:408 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.(*scavengerState).park(0xce0208) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x20417c8 sp=0x20417b4 pc=0x42b68 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.bgscavenge(0x2092000) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x20417e4 sp=0x20417c8 pc=0x432a0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gcenable.gowrap2() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x20417ec sp=0x20417e4 pc=0x35914 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20417ec sp=0x20417ec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by runtime.gcenable in goroutine 1 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 2 gp=0x2002ea8 m=nil [finalizer wait]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e3fac, 0xd0ad9c, 0x10, 0xa, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2044f8c sp=0x2044f78 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.runfinq() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x2044fec sp=0x2044f8c pc=0x34754 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2044fec sp=0x2044fec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by runtime.createfing in goroutine 1 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 23 gp=0x2085688 m=nil [IO wait]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e40f0, 0xb4f7ef18, 0x2, 0x2, 0x5) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x205ad28 sp=0x205ad14 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.netpollblock(0xb4f7ef08, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x205ad40 sp=0x205ad28 pc=0x548cc Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.runtime_pollWait(0xb4f7ef08, 0x72) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x205ad54 sp=0x205ad40 pc=0x8f4a8 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).wait(0x209d198, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x205ad68 sp=0x205ad54 pc=0x10a318 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).waitRead(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*FD).Accept(0x209d180) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x205adb0 sp=0x205ad68 pc=0x10eb84 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*netFD).accept(0x209d180) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x205ae18 sp=0x205adb0 pc=0x17e4c4 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*TCPListener).accept(0x20da5e8) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x205ae30 sp=0x205ae18 pc=0x196900 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*TCPListener).Accept(0x20da5e8) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x205ae4c sp=0x205ae30 pc=0x19596c Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*onceCloseListener).Accept(0x2097640) Dec 23 19:19:43 volumio go-librespot[1771]: :1 +0x34 fp=0x205ae64 sp=0x205ae4c pc=0x362b54 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Server).Serve(0x22bb908, {0x886cb8, 0x20da5e8}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x205aefc sp=0x205ae64 pc=0x341210 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.Serve(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:2794 Dec 23 19:19:43 volumio go-librespot[1771]: main.(*ConcreteApiServer).serve(0x20a0420) Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/api_server.go:630 +0x974 fp=0x205afe4 sp=0x205aefc pc=0x6330b8 Dec 23 19:19:43 volumio go-librespot[1771]: main.NewApiServer.gowrap1() Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/api_server.go:296 +0x28 fp=0x205afec sp=0x205afe4 pc=0x631de4 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x205afec sp=0x205afec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by main.NewApiServer in goroutine 1 Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/api_server.go:296 +0x310 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 10 gp=0x20857a8 m=nil [IO wait]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e40f0, 0xb4f7ed38, 0x2, 0x2, 0x5) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x22e8e84 sp=0x22e8e70 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.netpollblock(0xb4f7ed28, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x22e8e9c sp=0x22e8e84 pc=0x548cc Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.runtime_pollWait(0xb4f7ed28, 0x72) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x22e8eb0 sp=0x22e8e9c pc=0x8f4a8 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).wait(0x217b2d8, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x22e8ec4 sp=0x22e8eb0 pc=0x10a318 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).waitRead(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*FD).RawRead(0x217b2c0, 0x2529770) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x22e8ef0 sp=0x22e8ec4 pc=0x10f788 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*rawConn).Read(0x22152d0, 0x2529770) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x22e8f10 sp=0x22e8ef0 pc=0x190d28 Dec 23 19:19:43 volumio go-librespot[1771]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x240cad0, 0x2529740, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x22e8f40 sp=0x22e8f10 pc=0x548824 Dec 23 19:19:43 volumio go-librespot[1771]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Dec 23 19:19:43 volumio go-librespot[1771]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x21c1298, {0x2310000, 0x10000, 0x10000}) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x22e8f9c sp=0x22e8f40 pc=0x54c80c Dec 23 19:19:43 volumio go-librespot[1771]: github.com/grandcat/zeroconf.(*Server).recv4(0x24c7ec0, 0x21c1290) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x22e8fe0 sp=0x22e8f9c pc=0x5a309c Dec 23 19:19:43 volumio go-librespot[1771]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x22e8fec sp=0x22e8fe0 pc=0x5a2d54 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x22e8fec sp=0x22e8fec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 9 Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 3750647 gp=0x2003448 m=nil [IO wait]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e40f0, 0xb4f7e6f8, 0x2, 0x2, 0x5) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x251cafc sp=0x251cae8 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.netpollblock(0xb4f7e6e8, 0x72, 0x0) Dec 23 19:19:43 volumio volumio[1366]: info: touch_display: Setting screensaver timeout to 120 seconds. Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x251cb14 sp=0x251cafc pc=0x548cc Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.runtime_pollWait(0xb4f7e6e8, 0x72) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x251cb28 sp=0x251cb14 pc=0x8f4a8 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).wait(0x25555a8, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x251cb3c sp=0x251cb28 pc=0x10a318 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).waitRead(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*FD).Read(0x2555590, {0x2608000, 0xa000, 0xa000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x251cb84 sp=0x251cb3c pc=0x10b504 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*netFD).Read(0x2555590, {0x2608000, 0xa000, 0xa000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x251cbb0 sp=0x251cb84 pc=0x17c5d0 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*conn).Read(0x20943f0, {0x2608000, 0xa000, 0xa000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x251cbdc sp=0x251cbb0 pc=0x18caec Dec 23 19:19:43 volumio go-librespot[1771]: net.(*TCPConn).Read(0x20943f0, {0x2608000, 0xa000, 0xa000}) Dec 23 19:19:43 volumio go-librespot[1771]: :1 +0x44 fp=0x251cbfc sp=0x251cbdc pc=0x19fd40 Dec 23 19:19:43 volumio go-librespot[1771]: crypto/tls.(*atLeastReader).Read(0x2091ba0, {0x2608000, 0xa000, 0xa000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x251cc28 sp=0x251cbfc pc=0x27c680 Dec 23 19:19:43 volumio go-librespot[1771]: bytes.(*Buffer).ReadFrom(0x2138ccc, {0x883674, 0x2091ba0}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x251cc64 sp=0x251cc28 pc=0x139bd0 Dec 23 19:19:43 volumio go-librespot[1771]: crypto/tls.(*Conn).readFromUntil(0x2138b48, {0x882848, 0x20943f0}, 0x5) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x251cc8c sp=0x251cc64 pc=0x27c8dc Dec 23 19:19:43 volumio go-librespot[1771]: crypto/tls.(*Conn).readRecordOrCCS(0x2138b48, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x251cdf0 sp=0x251cc8c pc=0x279fdc Dec 23 19:19:43 volumio go-librespot[1771]: crypto/tls.(*Conn).readRecord(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 23 19:19:43 volumio go-librespot[1771]: crypto/tls.(*Conn).Read(0x2138b48, {0x256c000, 0x1000, 0x1000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x251ce20 sp=0x251cdf0 pc=0x27fe4c Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*persistConn).Read(0x2436218, {0x256c000, 0x1000, 0x1000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/transport.go:1977 +0x16c fp=0x251ce54 sp=0x251ce20 pc=0x3555cc Dec 23 19:19:43 volumio go-librespot[1771]: bufio.(*Reader).fill(0x2001920) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x251ce78 sp=0x251ce54 pc=0x2bddb4 Dec 23 19:19:43 volumio go-librespot[1771]: bufio.(*Reader).Peek(0x2001920, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/bufio/bufio.go:148 +0x68 fp=0x251ce84 sp=0x251ce78 pc=0x2bdf38 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*persistConn).readLoop(0x2436218) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/transport.go:2141 +0x194 fp=0x251cfe4 sp=0x251ce84 pc=0x35615c Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Transport).dialConn.gowrap2() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/transport.go:1799 +0x28 fp=0x251cfec sp=0x251cfe4 pc=0x354a7c Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x251cfec sp=0x251cfec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by net/http.(*Transport).dialConn in goroutine 3750606 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/transport.go:1799 +0x13f4 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 11 gp=0x2003568 m=nil [IO wait, 1380 minutes]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e40f0, 0xb4f7ec98, 0x2, 0x2, 0x5) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2043e5c sp=0x2043e48 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.netpollblock(0xb4f7ec88, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2043e74 sp=0x2043e5c pc=0x548cc Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.runtime_pollWait(0xb4f7ec88, 0x72) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x2043e88 sp=0x2043e74 pc=0x8f4a8 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).wait(0x217b328, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x2043e9c sp=0x2043e88 pc=0x10a318 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).waitRead(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*FD).RawRead(0x217b310, 0x21c1320) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x2043ec8 sp=0x2043e9c pc=0x10f788 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*rawConn).Read(0x22152f8, 0x21c1320) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x2043ee8 sp=0x2043ec8 pc=0x190d28 Dec 23 19:19:43 volumio go-librespot[1771]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x240cae0, 0x21c12f0, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x2043f18 sp=0x2043ee8 pc=0x548824 Dec 23 19:19:43 volumio go-librespot[1771]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Dec 23 19:19:43 volumio go-librespot[1771]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x21c12c8, {0x2300000, 0x10000, 0x10000}) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x2043f9c sp=0x2043f18 pc=0x550194 Dec 23 19:19:43 volumio go-librespot[1771]: github.com/grandcat/zeroconf.(*Server).recv6(0x24c7ec0, 0x21c12c0) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x2043fe0 sp=0x2043f9c pc=0x5a3290 Dec 23 19:19:43 volumio go-librespot[1771]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x2043fec sp=0x2043fe0 pc=0x5a2cf8 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2043fec sp=0x2043fec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 9 Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 26 gp=0x20859e8 m=nil [GC worker (idle)]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e3fbc, 0x22ceb10, 0x1a, 0xa, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2042f90 sp=0x2042f7c pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gcBgMarkWorker() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x2042fec sp=0x2042f90 pc=0x38590 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2042fec sp=0x2042fec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 27 gp=0x2085b08 m=nil [GC worker (idle)]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e3fbc, 0x22ceb28, 0x1a, 0xa, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2043790 sp=0x204377c pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gcBgMarkWorker() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x20437ec sp=0x2043790 pc=0x38590 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20437ec sp=0x20437ec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 36 gp=0x2234248 m=nil [GC worker (idle)]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e3fbc, 0x247a450, 0x1a, 0xa, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2044790 sp=0x204477c pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gcBgMarkWorker() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x20447ec sp=0x2044790 pc=0x38590 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20447ec sp=0x20447ec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 37 gp=0x2234368 m=nil [GC worker (idle)]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e3fbc, 0x247a468, 0x1a, 0xa, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24b8790 sp=0x24b877c pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gcBgMarkWorker() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x24b87ec sp=0x24b8790 pc=0x38590 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24b87ec sp=0x24b87ec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by runtime.gcBgMarkStartWorkers in goroutine 34 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 71 gp=0x2002fc8 m=nil [IO wait, 79 minutes]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e40f0, 0xb4f7edd8, 0x2, 0x2, 0x5) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24b6de8 sp=0x24b6dd4 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.netpollblock(0xb4f7edc8, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x24b6e00 sp=0x24b6de8 pc=0x548cc Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.runtime_pollWait(0xb4f7edc8, 0x72) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x24b6e14 sp=0x24b6e00 pc=0x8f4a8 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).wait(0x217b288, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x24b6e28 sp=0x24b6e14 pc=0x10a318 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).waitRead(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*FD).Accept(0x217b270) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x24b6e70 sp=0x24b6e28 pc=0x10eb84 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*netFD).accept(0x217b270) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x24b6ed8 sp=0x24b6e70 pc=0x17e4c4 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*TCPListener).accept(0x225ac00) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x24b6ef0 sp=0x24b6ed8 pc=0x196900 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*TCPListener).Accept(0x225ac00) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x24b6f0c sp=0x24b6ef0 pc=0x19596c Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*onceCloseListener).Accept(0x2097da0) Dec 23 19:19:43 volumio go-librespot[1771]: :1 +0x34 fp=0x24b6f24 sp=0x24b6f0c pc=0x362b54 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Server).Serve(0x22ba828, {0x886cb8, 0x225ac00}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x24b6fbc sp=0x24b6f24 pc=0x341210 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.Serve(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:2794 Dec 23 19:19:43 volumio go-librespot[1771]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Dec 23 19:19:43 volumio go-librespot[1771]: /src/zeroconf/zeroconf.go:280 +0x8c fp=0x24b6fec sp=0x24b6fbc pc=0x5a8184 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24b6fec sp=0x24b6fec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Dec 23 19:19:43 volumio go-librespot[1771]: /src/zeroconf/zeroconf.go:280 +0x160 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 70 gp=0x2003e68 m=nil [select]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2518750 sp=0x251873c pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.selectgo(0x2518888, 0x2518830, 0x0, 0x0, 0x4, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x25187f0 sp=0x2518750 pc=0x70e78 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Transport).getConn(0xca8050, 0x429dd60, {{}, 0x0, {0x7ae7b2, 0x5}, {0x249efe0, 0x1b}, 0x0}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/transport.go:1406 +0x4a0 fp=0x2518920 sp=0x25187f0 pc=0x351678 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Transport).roundTrip(0xca8050, 0x24125a8) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/transport.go:595 +0x778 fp=0x2518a3c sp=0x2518920 pc=0x34d650 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Transport).RoundTrip(0xca8050, 0x24125a8) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/roundtrip.go:17 +0x24 fp=0x2518a54 sp=0x2518a3c pc=0x331a6c Dec 23 19:19:43 volumio go-librespot[1771]: net/http.send(0x2412508, {0x882bb8, 0xca8050}, {0xc24ab917731aace0, 0x4b5ec9f002be, 0xce0068}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/client.go:259 +0x540 fp=0x2518b80 sp=0x2518a54 pc=0x2ec794 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Client).send(0x2096fe0, 0x2412508, {0xc24ab917731aace0, 0x4b5ec9f002be, 0xce0068}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/client.go:180 +0x98 fp=0x2518bc4 sp=0x2518b80 pc=0x2ec088 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Client).do(0x2096fe0, 0x2412508) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/client.go:724 +0x8bc fp=0x2518cd4 sp=0x2518bc4 pc=0x2ee488 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Client).Do(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/client.go:590 Dec 23 19:19:43 volumio go-librespot[1771]: github.com/devgianlu/go-librespot/session.retrieveClientToken(0x2096fe0, {0x22d0270, 0x28}) Dec 23 19:19:43 volumio go-librespot[1771]: /src/session/client_token.go:41 +0x668 fp=0x2518d6c sp=0x2518cd4 pc=0x544be4 Dec 23 19:19:43 volumio go-librespot[1771]: github.com/devgianlu/go-librespot/session.NewSessionFromOptions({0x8874f0, 0xd0ad00}, 0x2518f18) Dec 23 19:19:43 volumio go-librespot[1771]: /src/session/session.go:70 +0x228 fp=0x2518ed8 sp=0x2518d6c pc=0x545aa8 Dec 23 19:19:43 volumio go-librespot[1771]: main.(*App).newAppPlayer(0x20de780, {0x8874f0, 0xd0ad00}, {0x6daf48, 0x23287b0}) Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/main.go:121 +0x204 fp=0x2518f50 sp=0x2518ed8 pc=0x63cb28 Dec 23 19:19:43 volumio go-librespot[1771]: main.(*App).SpotifyToken.(*App).withCredentials.func1({0x8874f0, 0xd0ad00}) Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/main.go:195 +0x26c fp=0x2518f90 sp=0x2518f50 pc=0x63d248 Dec 23 19:19:43 volumio go-librespot[1771]: main.(*App).withAppPlayer.func2() Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/main.go:298 +0x14c fp=0x2518fec sp=0x2518f90 pc=0x63e410 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2518fec sp=0x2518fec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by main.(*App).withAppPlayer in goroutine 1 Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/main.go:281 +0x504 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 5195088 gp=0x2234128 m=nil [chan receive]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e3f90, 0x20994b0, 0xe, 0x7, 0x2) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x28dd784 sp=0x28dd770 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.chanrecv(0x2099480, 0x0, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x28dd7c0 sp=0x28dd784 pc=0x20078 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.chanrecv1(0x2099480, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x28dd7d4 sp=0x28dd7c0 pc=0x1fb98 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Resolver).lookupIPAddr.func2(0x2099480, 0x3ca60f8) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/lookup.go:338 +0x24 fp=0x28dd7e0 sp=0x28dd7d4 pc=0x19d92c Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Resolver).lookupIPAddr.gowrap2() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/lookup.go:355 +0x38 fp=0x28dd7ec sp=0x28dd7e0 pc=0x18b594 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x28dd7ec sp=0x28dd7ec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by net.(*Resolver).lookupIPAddr in goroutine 5193415 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/lookup.go:355 +0x6d8 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 75 gp=0x2235c28 m=nil [IO wait, 1380 minutes]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e40f0, 0xb4f7ea18, 0x2, 0x2, 0x5) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x251787c sp=0x2517868 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.netpollblock(0xb4f7ea08, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2517894 sp=0x251787c pc=0x548cc Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.runtime_pollWait(0xb4f7ea08, 0x72) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x25178a8 sp=0x2517894 pc=0x8f4a8 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).wait(0x236ae28, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x25178bc sp=0x25178a8 pc=0x10a318 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).waitRead(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*FD).Read(0x236ae10, {0x2411000, 0x1000, 0x1000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2517904 sp=0x25178bc pc=0x10b504 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*netFD).Read(0x236ae10, {0x2411000, 0x1000, 0x1000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2517930 sp=0x2517904 pc=0x17c5d0 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*conn).Read(0x23ee0e8, {0x2411000, 0x1000, 0x1000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x251795c sp=0x2517930 pc=0x18caec Dec 23 19:19:43 volumio go-librespot[1771]: net.(*TCPConn).Read(0x23ee0e8, {0x2411000, 0x1000, 0x1000}) Dec 23 19:19:43 volumio go-librespot[1771]: :1 +0x44 fp=0x251797c sp=0x251795c pc=0x19fd40 Dec 23 19:19:43 volumio go-librespot[1771]: io.(*multiReader).Read(0x22063a0, {0x2411000, 0x1000, 0x1000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x25179a8 sp=0x251797c pc=0x105528 Dec 23 19:19:43 volumio go-librespot[1771]: bufio.(*Reader).fill(0x2528030) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x25179cc sp=0x25179a8 pc=0x2bddb4 Dec 23 19:19:43 volumio go-librespot[1771]: bufio.(*Reader).ReadByte(0x2528030) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x25179d8 sp=0x25179cc pc=0x2be638 Dec 23 19:19:43 volumio go-librespot[1771]: nhooyr.io/websocket.readFrameHeader(0x2528030, {0x2556a38, 0x8, 0x8}) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2517a18 sp=0x25179d8 pc=0x47788c Dec 23 19:19:43 volumio go-librespot[1771]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x2556a08, {0x8874d0, 0xd0ad00}) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2517ad8 sp=0x2517a18 pc=0x47a4f0 Dec 23 19:19:43 volumio go-librespot[1771]: nhooyr.io/websocket.(*Conn).readLoop(0x2556a08, {0x8874d0, 0xd0ad00}) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2517b9c sp=0x2517ad8 pc=0x479fd8 Dec 23 19:19:43 volumio go-librespot[1771]: nhooyr.io/websocket.(*Conn).reader(0x2556a08, {0x8874d0, 0xd0ad00}) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2517c28 sp=0x2517b9c pc=0x47b1ec Dec 23 19:19:43 volumio go-librespot[1771]: nhooyr.io/websocket.(*Conn).Reader(...) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Dec 23 19:19:43 volumio go-librespot[1771]: nhooyr.io/websocket.(*Conn).Read(0x2556a08, {0x8874d0, 0xd0ad00}) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2517c50 sp=0x2517c28 pc=0x4798bc Dec 23 19:19:43 volumio go-librespot[1771]: main.(*ConcreteApiServer).serve.func17({0x886dec, 0x2413548}, 0x22ba5a8) Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/api_server.go:600 +0x3c0 fp=0x2517ccc sp=0x2517c50 pc=0x633988 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.HandlerFunc.ServeHTTP(0x2095978, {0x886dec, 0x2413548}, 0x22ba5a8) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2517cdc sp=0x2517ccc pc=0x33d7f4 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*ServeMux).ServeHTTP(0x20de800, {0x886dec, 0x2413548}, 0x22ba5a8) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x2517d18 sp=0x2517cdc pc=0x33f654 Dec 23 19:19:43 volumio go-librespot[1771]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x2413548}, 0x22ba5a8) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x2517d40 sp=0x2517d18 pc=0x633388 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.HandlerFunc.ServeHTTP(0x2091400, {0x886dec, 0x2413548}, 0x22ba5a8) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2517d50 sp=0x2517d40 pc=0x33d7f4 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.serverHandler.ServeHTTP({0x22bb908}, {0x886dec, 0x2413548}, 0x22ba5a8) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x2517d6c sp=0x2517d50 pc=0x340de4 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*conn).serve(0x2364480, {0x887510, 0x20da780}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x2517fdc sp=0x2517d6c pc=0x33c034 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Server).Serve.gowrap3() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x2517fec sp=0x2517fdc pc=0x341688 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2517fec sp=0x2517fec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by net/http.(*Server).Serve in goroutine 23 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:3285 +0x468 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 98 gp=0x216d448 m=nil [select]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24bbea8 sp=0x24bbe94 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.selectgo(0x24bbfa4, 0x24bbf6c, 0x0, 0x0, 0x5, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x24bbf48 sp=0x24bbea8 pc=0x70e78 Dec 23 19:19:43 volumio go-librespot[1771]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2556a08) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x24bbfe4 sp=0x24bbf48 pc=0x474738 Dec 23 19:19:43 volumio go-librespot[1771]: nhooyr.io/websocket.newConn.gowrap1() Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x24bbfec sp=0x24bbfe4 pc=0x47432c Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24bbfec sp=0x24bbfec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by nhooyr.io/websocket.newConn in goroutine 75 Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 5195117 gp=0x2156ea8 m=nil [IO wait]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e40f0, 0xb4f7e8d8, 0x2, 0x2, 0x5) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x24466b4 sp=0x24466a0 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.netpollblock(0xb4f7e8c8, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x24466cc sp=0x24466b4 pc=0x548cc Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.runtime_pollWait(0xb4f7e8c8, 0x72) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x24466e0 sp=0x24466cc pc=0x8f4a8 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).wait(0x2d4c1a8, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x24466f4 sp=0x24466e0 pc=0x10a318 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).waitRead(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*FD).Read(0x2d4c190, {0x3fe612d, 0x1, 0x1}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x244673c sp=0x24466f4 pc=0x10b504 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*netFD).Read(0x2d4c190, {0x3fe612d, 0x1, 0x1}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2446768 sp=0x244673c pc=0x17c5d0 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*conn).Read(0x3ca6898, {0x3fe612d, 0x1, 0x1}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x2446794 sp=0x2446768 pc=0x18caec Dec 23 19:19:43 volumio go-librespot[1771]: net.(*TCPConn).Read(0x3ca6898, {0x3fe612d, 0x1, 0x1}) Dec 23 19:19:43 volumio go-librespot[1771]: :1 +0x44 fp=0x24467b4 sp=0x2446794 pc=0x19fd40 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*connReader).backgroundRead(0x3fe6120) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:681 +0x40 fp=0x24467e4 sp=0x24467b4 pc=0x335cfc Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*connReader).startBackgroundRead.gowrap2() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:677 +0x28 fp=0x24467ec sp=0x24467e4 pc=0x335c34 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x24467ec sp=0x24467ec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by net/http.(*connReader).startBackgroundRead in goroutine 5195116 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:677 +0x160 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 5195073 gp=0x2376908 m=nil [runnable]: Dec 23 19:19:43 volumio go-librespot[1771]: context.(*cancelCtx).Done(0x3f178c0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/context/context.go:438 +0x160 fp=0x263dc28 sp=0x263dc28 pc=0xc2db4 Dec 23 19:19:43 volumio go-librespot[1771]: net.doBlockingWithCtx[...]({0x887530, 0x3f178c0}, 0x2328990) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/cgo_unix.go:63 +0xd8 fp=0x263dc98 sp=0x263dc28 pc=0x19e2e4 Dec 23 19:19:43 volumio go-librespot[1771]: net.cgoLookupIP({0x887530, 0x3f178c0}, {0x790fa8, 0x3}, {0x249efe0, 0x17}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/cgo_unix.go:216 +0x90 fp=0x263dcc0 sp=0x263dc98 pc=0x16fe28 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Resolver).lookupIP(0xcdfa30, {0x887530, 0x3f178c0}, {0x790fa8, 0x3}, {0x249efe0, 0x17}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/lookup_unix.go:66 +0x90 fp=0x263defc sp=0x263dcc0 pc=0x18c1fc Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Resolver).lookupIP-fm({0x887530, 0x3f178c0}, {0x790fa8, 0x3}, {0x249efe0, 0x17}) Dec 23 19:19:43 volumio go-librespot[1771]: :1 +0x58 fp=0x263df30 sp=0x263defc pc=0x1a3f78 Dec 23 19:19:43 volumio go-librespot[1771]: net.init.func1({0x887530, 0x3f178c0}, 0x39fcdb0, {0x790fa8, 0x3}, {0x249efe0, 0x17}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/hook.go:22 +0x4c fp=0x263df60 sp=0x263df30 pc=0x16d188 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Resolver).lookupIPAddr.func1() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/lookup.go:334 +0x58 fp=0x263df9c sp=0x263df60 pc=0x18b67c Dec 23 19:19:43 volumio go-librespot[1771]: internal/singleflight.(*Group).doCall(0xcdfa38, 0x3f178f0, {0x249f000, 0x1b}, 0x429dd80) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/singleflight/singleflight.go:93 +0x1c fp=0x263dfd4 sp=0x263df9c pc=0x16b538 Dec 23 19:19:43 volumio go-librespot[1771]: internal/singleflight.(*Group).DoChan.gowrap1() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x48 fp=0x263dfec sp=0x263dfd4 pc=0x16b4f0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x263dfec sp=0x263dfec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by internal/singleflight.(*Group).DoChan in goroutine 5195072 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x2fc Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 5195072 gp=0x23b8248 m=nil [select]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2056860 sp=0x205684c pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.selectgo(0x2056a18, 0x2056930, 0x0, 0x0, 0x2, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2056900 sp=0x2056860 pc=0x70e78 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Resolver).lookupIPAddr(0xcdfa30, {0x887570, 0x25327d0}, {0x790fa8, 0x3}, {0x249efe0, 0x17}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/lookup.go:342 +0x394 fp=0x2056a3c sp=0x2056900 pc=0x18abac Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Resolver).internetAddrList(0xcdfa30, {0x887570, 0x25327d0}, {0x790fa8, 0x3}, {0x249efe0, 0x1b}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/ipsock.go:288 +0x6bc fp=0x2056ad0 sp=0x2056a3c pc=0x188694 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Resolver).resolveAddrList(0xcdfa30, {0x887570, 0x25327d0}, {0x79138a, 0x4}, {0x790fa8, 0x3}, {0x249efe0, 0x1b}, {0x0, ...}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/dial.go:283 +0x510 fp=0x2056b58 sp=0x2056ad0 pc=0x1728d0 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Dialer).DialContext(0x21c8190, {0x887570, 0x25327d0}, {0x790fa8, 0x3}, {0x249efe0, 0x1b}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/dial.go:490 +0x43c fp=0x2056c40 sp=0x2056b58 pc=0x173828 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Dialer).DialContext-fm({0x887570, 0x25327d0}, {0x790fa8, 0x3}, {0x249efe0, 0x1b}) Dec 23 19:19:43 volumio go-librespot[1771]: :1 +0x58 fp=0x2056c70 sp=0x2056c40 pc=0x363d58 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Transport).dial(0xca8050, {0x887570, 0x25327d0}, {0x790fa8, 0x3}, {0x249efe0, 0x1b}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/transport.go:1187 +0x158 fp=0x2056cb0 sp=0x2056c70 pc=0x350a74 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Transport).dialConn(0xca8050, {0x887570, 0x25327d0}, {{}, 0x0, {0x7ae7b2, 0x5}, {0x249efe0, 0x1b}, 0x0}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/transport.go:1648 +0x6bc fp=0x2056f48 sp=0x2056cb0 pc=0x35338c Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Transport).dialConnFor(0xca8050, 0x20a0c00) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/transport.go:1485 +0x84 fp=0x2056fe0 sp=0x2056f48 pc=0x352090 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Transport).queueForDial.gowrap1() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/transport.go:1449 +0x30 fp=0x2056fec sp=0x2056fe0 pc=0x351fe0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2056fec sp=0x2056fec pc=0x93d6c Dec 23 19:19:43 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Dec 23 19:19:43 volumio volumio[1366]: info: Connection to go-librespot Websocket closed Dec 23 19:19:43 volumio volumio[1366]: error: Failed to send command to Spotify local API: /player/play: Error: socket hang up Dec 23 19:19:43 volumio go-librespot[1771]: created by net/http.(*Transport).queueForDial in goroutine 70 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/transport.go:1449 +0x98 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 5193417 gp=0x234bc28 m=9 mp=0x204a108 [syscall]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.cgocall(0x648a4c, 0x244a6c8) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/cgocall.go:157 +0x50 fp=0x244a6b0 sp=0x244a698 pc=0x1d820 Dec 23 19:19:43 volumio go-librespot[1771]: net._C2func_getaddrinfo(0x22714c0, 0x0, 0x207f260, 0x3ca6108) Dec 23 19:19:43 volumio go-librespot[1771]: _cgo_gotypes.go:101 +0x48 fp=0x244a6c4 sp=0x244a6b0 pc=0x19d378 Dec 23 19:19:43 volumio go-librespot[1771]: net._C_getaddrinfo.func1(0x22714c0, 0x0, 0x207f260, 0x3ca6108) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/cgo_unix_cgo.go:78 +0x7c fp=0x244a6e4 sp=0x244a6c4 pc=0x19d6c4 Dec 23 19:19:43 volumio go-librespot[1771]: net._C_getaddrinfo(0x22714c0, 0x0, 0x207f260, 0x3ca6108) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/cgo_unix_cgo.go:78 +0x34 fp=0x244a704 sp=0x244a6e4 pc=0x19d620 Dec 23 19:19:43 volumio go-librespot[1771]: net.cgoLookupHostIP({0x790fa8, 0x3}, {0x2271480, 0x18}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/cgo_unix.go:168 +0x248 fp=0x244a794 sp=0x244a704 pc=0x16f7f8 Dec 23 19:19:43 volumio go-librespot[1771]: net.cgoLookupIP.func1() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/cgo_unix.go:217 +0x34 fp=0x244a7bc sp=0x244a794 pc=0x16fea8 Dec 23 19:19:43 volumio go-librespot[1771]: net.doBlockingWithCtx[...].func1() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/cgo_unix.go:56 +0x30 fp=0x244a7ec sp=0x244a7bc pc=0x19d85c Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x244a7ec sp=0x244a7ec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by net.doBlockingWithCtx[...] in goroutine 5193416 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/cgo_unix.go:54 +0xb8 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 5195116 gp=0x21ff328 m=nil [chan receive]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e3f90, 0x2184df0, 0xe, 0x7, 0x2) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2055bb0 sp=0x2055b9c pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.chanrecv(0x2184dc0, 0x2055c7c, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x2055bec sp=0x2055bb0 pc=0x20078 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.chanrecv1(0x2184dc0, 0x2055c7c) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x2055c00 sp=0x2055bec pc=0x1fb98 Dec 23 19:19:43 volumio go-librespot[1771]: main.(*ConcreteApiServer).handleRequest(0x20a0420, {{0x79134a, 0x4}, {0x6eba88, 0x2026588}, 0x2184dc0}, {0x886dec, 0x22188c8}) Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/api_server.go:319 +0x74 fp=0x2055c8c sp=0x2055c00 pc=0x631f94 Dec 23 19:19:43 volumio go-librespot[1771]: main.(*ConcreteApiServer).serve.func4({0x886dec, 0x22188c8}, 0x2218828) Dec 23 19:19:43 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 19:19:43 volumio go-librespot[1771]: /src/cmd/daemon/api_server.go:412 +0x148 fp=0x2055ccc sp=0x2055c8c pc=0x634b60 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.HandlerFunc.ServeHTTP(0x2095838, {0x886dec, 0x22188c8}, 0x2218828) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2055cdc sp=0x2055ccc pc=0x33d7f4 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*ServeMux).ServeHTTP(0x20de800, {0x886dec, 0x22188c8}, 0x2218828) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x2055d18 sp=0x2055cdc pc=0x33f654 Dec 23 19:19:43 volumio go-librespot[1771]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x886dec, 0x22188c8}, 0x2218828) Dec 23 19:19:43 volumio go-librespot[1771]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x2055d40 sp=0x2055d18 pc=0x633388 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.HandlerFunc.ServeHTTP(0x2091400, {0x886dec, 0x22188c8}, 0x2218828) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2055d50 sp=0x2055d40 pc=0x33d7f4 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.serverHandler.ServeHTTP({0x22bb908}, {0x886dec, 0x22188c8}, 0x2218828) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x2055d6c sp=0x2055d50 pc=0x340de4 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*conn).serve(0x23655c0, {0x887510, 0x20da780}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x2055fdc sp=0x2055d6c pc=0x33c034 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Server).Serve.gowrap3() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x2055fec sp=0x2055fdc pc=0x341688 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2055fec sp=0x2055fec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by net/http.(*Server).Serve in goroutine 23 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/server.go:3285 +0x468 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 3750502 gp=0x23a2d88 m=nil [chan send, 225 minutes]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e3f90, 0x24a52f0, 0xf, 0x6, 0x2) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x205be50 sp=0x205be3c pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.chansend(0x24a52c0, 0x205bef0, 0x1, 0x533320) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/chan.go:259 +0x378 fp=0x205be88 sp=0x205be50 pc=0x1f208 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.chansend1(0x24a52c0, 0x205bef0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/chan.go:145 +0x28 fp=0x205bea0 sp=0x205be88 pc=0x1ee8c Dec 23 19:19:43 volumio go-librespot[1771]: github.com/devgianlu/go-librespot/dealer.(*Dealer).handleMessage(0x20b3298, 0x2554a00) Dec 23 19:19:43 volumio go-librespot[1771]: /src/dealer/recv.go:181 +0x4f0 fp=0x205bf2c sp=0x205bea0 pc=0x533320 Dec 23 19:19:43 volumio systemd[1]: go-librespot-daemon.service: Consumed 10min 16.512s CPU time. Dec 23 19:19:43 volumio go-librespot[1771]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x20b3298) Dec 23 19:19:43 volumio go-librespot[1771]: /src/dealer/dealer.go:210 +0x3fc fp=0x205bfe4 sp=0x205bf2c pc=0x532108 Dec 23 19:19:43 volumio go-librespot[1771]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect.gowrap1() Dec 23 19:19:43 volumio go-librespot[1771]: /src/dealer/dealer.go:296 +0x28 fp=0x205bfec sp=0x205bfe4 pc=0x5329bc Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x205bfec sp=0x205bfec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect in goroutine 3750488 Dec 23 19:19:43 volumio go-librespot[1771]: /src/dealer/dealer.go:296 +0xf4 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 5195067 gp=0x2401208 m=nil [IO wait]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e40f0, 0xb4f7e978, 0x2, 0x2, 0x5) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x251aad4 sp=0x251aac0 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.netpollblock(0xb4f7e968, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x251aaec sp=0x251aad4 pc=0x548cc Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.runtime_pollWait(0xb4f7e968, 0x72) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x251ab00 sp=0x251aaec pc=0x8f4a8 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).wait(0x3835008, 0x72, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x251ab14 sp=0x251ab00 pc=0x10a318 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*pollDesc).waitRead(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Dec 23 19:19:43 volumio go-librespot[1771]: internal/poll.(*FD).Read(0x3834ff0, {0x22e5000, 0x1000, 0x1000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x251ab5c sp=0x251ab14 pc=0x10b504 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*netFD).Read(0x3834ff0, {0x22e5000, 0x1000, 0x1000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x251ab88 sp=0x251ab5c pc=0x17c5d0 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*conn).Read(0x39fca28, {0x22e5000, 0x1000, 0x1000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x251abb4 sp=0x251ab88 pc=0x18caec Dec 23 19:19:43 volumio go-librespot[1771]: net.(*TCPConn).Read(0x39fca28, {0x22e5000, 0x1000, 0x1000}) Dec 23 19:19:43 volumio go-librespot[1771]: :1 +0x44 fp=0x251abd4 sp=0x251abb4 pc=0x19fd40 Dec 23 19:19:43 volumio go-librespot[1771]: crypto/tls.(*atLeastReader).Read(0x2091bc0, {0x22e5000, 0x1000, 0x1000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/crypto/tls/conn.go:806 +0x78 fp=0x251ac00 sp=0x251abd4 pc=0x27c680 Dec 23 19:19:43 volumio go-librespot[1771]: bytes.(*Buffer).ReadFrom(0x21ae3cc, {0x883674, 0x2091bc0}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/bytes/buffer.go:211 +0xa4 fp=0x251ac3c sp=0x251ac00 pc=0x139bd0 Dec 23 19:19:43 volumio go-librespot[1771]: crypto/tls.(*Conn).readFromUntil(0x21ae248, {0x882848, 0x39fca28}, 0x5) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/crypto/tls/conn.go:828 +0xd4 fp=0x251ac64 sp=0x251ac3c pc=0x27c8dc Dec 23 19:19:43 volumio go-librespot[1771]: crypto/tls.(*Conn).readRecordOrCCS(0x21ae248, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/crypto/tls/conn.go:626 +0x134 fp=0x251adc8 sp=0x251ac64 pc=0x279fdc Dec 23 19:19:43 volumio go-librespot[1771]: crypto/tls.(*Conn).readRecord(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/crypto/tls/conn.go:588 Dec 23 19:19:43 volumio go-librespot[1771]: crypto/tls.(*Conn).Read(0x21ae248, {0x2414000, 0x1000, 0x1000}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x15c fp=0x251adf8 sp=0x251adc8 pc=0x27fe4c Dec 23 19:19:43 volumio go-librespot[1771]: bufio.(*Reader).Read(0x3f17320, {0x2525424, 0x9, 0x9}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/bufio/bufio.go:241 +0x214 fp=0x251ae1c sp=0x251adf8 pc=0x2be440 Dec 23 19:19:43 volumio go-librespot[1771]: io.ReadAtLeast({0x882a3c, 0x3f17320}, {0x2525424, 0x9, 0x9}, 0x9) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x251ae48 sp=0x251ae1c pc=0x1042c0 Dec 23 19:19:43 volumio go-librespot[1771]: io.ReadFull(...) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/io/io.go:354 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.http2readFrameHeader({0x2525424, 0x9, 0x9}, {0x882a3c, 0x3f17320}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/h2_bundle.go:1638 +0x54 fp=0x251ae70 sp=0x251ae48 pc=0x2f733c Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*http2Framer).ReadFrame(0x2525400) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/h2_bundle.go:1902 +0x88 fp=0x251aeec sp=0x251ae70 pc=0x2f7ad4 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*http2clientConnReadLoop).run(0x251afdc) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/h2_bundle.go:9334 +0x150 fp=0x251afa4 sp=0x251aeec pc=0x31c2f0 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*http2ClientConn).readLoop(0x20a9608) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/h2_bundle.go:9229 +0x68 fp=0x251afe4 sp=0x251afa4 pc=0x31b7e4 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*http2Transport).newClientConn.gowrap1() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0x28 fp=0x251afec sp=0x251afe4 pc=0x314830 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x251afec sp=0x251afec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by net/http.(*http2Transport).newClientConn in goroutine 5195066 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/h2_bundle.go:7879 +0xcb0 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 5195122 gp=0x24b3328 m=nil [runnable]: Dec 23 19:19:43 volumio go-librespot[1771]: net.doBlockingWithCtx[...].func1() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/cgo_unix.go:54 fp=0x23acfec sp=0x23acfec pc=0x19d82c Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x23acfec sp=0x23acfec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by net.doBlockingWithCtx[...] in goroutine 5195073 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/cgo_unix.go:54 +0xb8 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 3750648 gp=0x24d6c68 m=nil [select]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x251deb0 sp=0x251de9c pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.selectgo(0x251dfac, 0x251df78, 0x0, 0x0, 0x2, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x251df50 sp=0x251deb0 pc=0x70e78 Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*persistConn).writeLoop(0x2436218) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/transport.go:2444 +0xd0 fp=0x251dfe4 sp=0x251df50 pc=0x357c1c Dec 23 19:19:43 volumio go-librespot[1771]: net/http.(*Transport).dialConn.gowrap3() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/transport.go:1800 +0x28 fp=0x251dfec sp=0x251dfe4 pc=0x354a28 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x251dfec sp=0x251dfec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by net/http.(*Transport).dialConn in goroutine 3750606 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/http/transport.go:1800 +0x1444 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 5195105 gp=0x25eaa28 m=nil [chan receive]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e3f90, 0x358f530, 0xe, 0x7, 0x2) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2042784 sp=0x2042770 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.chanrecv(0x358f500, 0x0, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x20427c0 sp=0x2042784 pc=0x20078 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.chanrecv1(0x358f500, 0x0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/chan.go:442 +0x20 fp=0x20427d4 sp=0x20427c0 pc=0x1fb98 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Resolver).lookupIPAddr.func2(0x358f500, 0x2484cc0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/lookup.go:338 +0x24 fp=0x20427e0 sp=0x20427d4 pc=0x19d92c Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Resolver).lookupIPAddr.gowrap2() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/lookup.go:355 +0x38 fp=0x20427ec sp=0x20427e0 pc=0x18b594 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x20427ec sp=0x20427ec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by net.(*Resolver).lookupIPAddr in goroutine 5195083 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/lookup.go:355 +0x6d8 Dec 23 19:19:43 volumio go-librespot[1771]: goroutine 5193416 gp=0x25ebd48 m=nil [select]: Dec 23 19:19:43 volumio go-librespot[1771]: runtime.gopark(0x7e4134, 0x0, 0x9, 0x3, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x22eab88 sp=0x22eab74 pc=0x5d0c0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.selectgo(0x22eac74, 0x22eac4c, 0x0, 0x0, 0x2, 0x1) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x22eac28 sp=0x22eab88 pc=0x70e78 Dec 23 19:19:43 volumio go-librespot[1771]: net.doBlockingWithCtx[...]({0x887530, 0x39800f0}, 0x20262d0) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/cgo_unix.go:60 +0x134 fp=0x22eac98 sp=0x22eac28 pc=0x19e340 Dec 23 19:19:43 volumio go-librespot[1771]: net.cgoLookupIP({0x887530, 0x39800f0}, {0x790fa8, 0x3}, {0x2271480, 0x18}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/cgo_unix.go:216 +0x90 fp=0x22eacc0 sp=0x22eac98 pc=0x16fe28 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Resolver).lookupIP(0xcdfa30, {0x887530, 0x39800f0}, {0x790fa8, 0x3}, {0x2271480, 0x18}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/lookup_unix.go:66 +0x90 fp=0x22eaefc sp=0x22eacc0 pc=0x18c1fc Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Resolver).lookupIP-fm({0x887530, 0x39800f0}, {0x790fa8, 0x3}, {0x2271480, 0x18}) Dec 23 19:19:43 volumio go-librespot[1771]: :1 +0x58 fp=0x22eaf30 sp=0x22eaefc pc=0x1a3f78 Dec 23 19:19:43 volumio go-librespot[1771]: net.init.func1({0x887530, 0x39800f0}, 0x3ca60f0, {0x790fa8, 0x3}, {0x2271480, 0x18}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/hook.go:22 +0x4c fp=0x22eaf60 sp=0x22eaf30 pc=0x16d188 Dec 23 19:19:43 volumio go-librespot[1771]: net.(*Resolver).lookupIPAddr.func1() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/net/lookup.go:334 +0x58 fp=0x22eaf9c sp=0x22eaf60 pc=0x18b67c Dec 23 19:19:43 volumio go-librespot[1771]: internal/singleflight.(*Group).doCall(0xcdfa38, 0x3980120, {0x22714a0, 0x1c}, 0x207f200) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/singleflight/singleflight.go:93 +0x1c fp=0x22eafd4 sp=0x22eaf9c pc=0x16b538 Dec 23 19:19:43 volumio go-librespot[1771]: internal/singleflight.(*Group).DoChan.gowrap1() Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x48 fp=0x22eafec sp=0x22eafd4 pc=0x16b4f0 Dec 23 19:19:43 volumio go-librespot[1771]: runtime.goexit({}) Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x22eafec sp=0x22eafec pc=0x93d6c Dec 23 19:19:43 volumio go-librespot[1771]: created by internal/singleflight.(*Group).DoChan in goroutine 5193415 Dec 23 19:19:43 volumio go-librespot[1771]: /usr/local/go/src/internal/singleflight/singleflight.go:86 +0x2fc Dec 23 19:19:43 volumio go-librespot[1766]: Aborted Dec 23 19:19:44 volumio volumio[1366]: SPOTIFY: PUSH STATE SPOTIFY Dec 23 19:19:44 volumio volumio[1366]: SPOTIFY: {"status":"stop","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 23 19:19:44 volumio volumio[1366]: info: CoreCommandRouter::servicePushState Dec 23 19:19:44 volumio volumio[1366]: info: CoreStateMachine::pushState Dec 23 19:19:44 volumio volumio[1366]: info: CoreCommandRouter::volumioPushState Dec 23 19:19:44 volumio volumio[1366]: info: CorePlayQueue::getTrack 2 Dec 23 19:19:44 volumio volumio[1366]: verbose: STATE SERVICE {"status":"stop","service":"spop","title":"","artist":"","album":"","albumart":"/albumart","uri":"","trackType":"spotify","seek":0,"duration":0,"samplerate":"44.1 KHz","bitdepth":"16 bit","bitrate":"320 kbps","codec":"ogg","channels":2,"random":null,"repeat":null,"repeatSingle":null} Dec 23 19:19:44 volumio volumio[1366]: verbose: CURRENT POSITION 2 Dec 23 19:19:44 volumio volumio[1366]: info: CoreStateMachine::syncState stateService stop Dec 23 19:19:44 volumio volumio[1366]: info: CoreStateMachine::syncState currentStatus stop Dec 23 19:19:44 volumio volumio[1366]: info: CoreStateMachine::pushState Dec 23 19:19:44 volumio volumio[1366]: info: CoreCommandRouter::volumioPushState Dec 23 19:19:44 volumio volumio[1366]: info: No code Dec 23 19:19:44 volumio volumio[1366]: info: CoreStateMachine::pushState Dec 23 19:19:44 volumio volumio[1366]: info: CoreCommandRouter::volumioPushState Dec 23 19:19:44 volumio volumio[1366]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Dec 23 19:19:44 volumio volumio[1366]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Dec 23 19:19:44 volumio volumio[1366]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Dec 23 19:19:46 volumio volumio[1366]: info: Initializing connection to go-librespot Websocket Dec 23 19:19:46 volumio volumio[1366]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Dec 23 19:19:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Dec 23 19:19:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Dec 23 19:19:47 volumio systemd[1]: go-librespot-daemon.service: Consumed 10min 16.512s CPU time. Dec 23 19:19:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Dec 23 19:19:47 volumio go-librespot[20481]: go-librespot daemon starting... Dec 23 19:19:47 volumio go-librespot[20482]: time="2025-12-23T19:19:47+09:00" level=info msg="running go-librespot 0.4.0" Dec 23 19:19:47 volumio go-librespot[20482]: time="2025-12-23T19:19:47+09:00" level=debug msg="app state loaded" Dec 23 19:19:47 volumio go-librespot[20482]: time="2025-12-23T19:19:47+09:00" level=info msg="api server listening on 127.0.0.1:9879" Dec 23 19:19:49 volumio volumio[1366]: info: Initializing connection to go-librespot Websocket Dec 23 19:19:49 volumio go-librespot[20482]: time="2025-12-23T19:19:49+09:00" level=debug msg="new websocket client" Dec 23 19:19:49 volumio volumio[1366]: info: Connection to go-librespot Websocket established Dec 23 19:19:52 volumio go-librespot[20482]: time="2025-12-23T19:19:52+09: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]" Dec 23 19:19:52 volumio go-librespot[20482]: time="2025-12-23T19:19:52+09: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]" Dec 23 19:19:52 volumio go-librespot[20482]: time="2025-12-23T19:19:52+09: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]" Dec 23 19:19:52 volumio go-librespot[20482]: time="2025-12-23T19:19:52+09:00" level=info msg="zeroconf server listening on port 36543" Dec 23 19:19:52 volumio volumio[1366]: info: Getting Spotify volume Dec 23 19:19:52 volumio volumio[1366]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7 Dec 23 19:19:52 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Dec 23 19:19:52 volumio volumio[1366]: SPOTIFY: RECEIVED VOLUMIO VOLUME 34 Dec 23 19:19:57 volumio go-librespot[20482]: time="2025-12-23T19:19:57+09:00" level=debug msg="obtained new client token: AACqhgO1k5YBsoXEeoKxu5xuYMsct8vKOzey6Uj4A0+fVE7Q8vQBsomkaA15eoUzIxxnEUv5DCsJ5npCb1v6pQkWQSfDDJ4Rp0i3nzJrY32IjHE1rrM8RC8TrMyr+HrYjF/3+TYZnTj9O5LmPElD9v+D3uUl+nklKskzXbjbTG4JoB+NiG8JQLRqdVBtBDhiUN8tr+ivkaVrAILDu6h/cRCXSo7MYFt6cp4rZx63WmDl86irZa9VyHtiFmY=" Dec 23 19:20:00 volumio sudo[20510]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig eth0 Dec 23 19:20:00 volumio sudo[20510]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 19:20:00 volumio sudo[20510]: pam_unix(sudo:session): session closed for user root Dec 23 19:20:00 volumio sudo[20512]: volumio : PWD=/ ; USER=root ; COMMAND=/sbin/ifconfig wlan0 Dec 23 19:20:00 volumio sudo[20512]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Dec 23 19:20:00 volumio sudo[20512]: pam_unix(sudo:session): session closed for user root Dec 23 19:20:00 volumio volumio[1366]: verbose: New Socket.io Connection to 192.168.219.119 from 192.168.219.110 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/143.0.0.0 Safari/537.36 Engine version: 3 Transport: polling Total Clients: 7 Dec 23 19:20:00 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled Dec 23 19:20:00 volumio volumio[1366]: info: CoreCommandRouter::volumioGetVisibleSources Dec 23 19:20:00 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources Dec 23 19:20:00 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Dec 23 19:20:00 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback Dec 23 19:20:00 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom Dec 23 19:20:00 volumio volumio[1366]: info: Received Get System Info Dec 23 19:20:00 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 19:20:00 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 19:20:00 volumio volumio[1366]: info: Discovery: Getting this device information Dec 23 19:20:00 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Dec 23 19:20:00 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 19:20:00 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Dec 23 19:20:00 volumio volumio[1366]: info: Listing playlists Dec 23 19:20:00 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: appearance , getUiSettings Dec 23 19:20:00 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getPrivacySettings Dec 23 19:20:00 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache Dec 23 19:20:01 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Dec 23 19:20:01 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: wizard , getOnboardingWizard Dec 23 19:20:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getMyVolumioStatus Dec 23 19:20:02 volumio volumio[1366]: info: Received Get System Info Dec 23 19:20:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo Dec 23 19:20:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice Dec 23 19:20:02 volumio volumio[1366]: info: Discovery: Getting this device information Dec 23 19:20:02 volumio volumio[1366]: info: CoreCommandRouter::volumioGetState Dec 23 19:20:02 volumio volumio[1366]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses Dec 23 19:20:02 volumio go-librespot[20482]: time="2025-12-23T19:20:02+09:00" level=debug msg="connected to ap-gae2.spotify.com:4070" Dec 23 19:20:02 volumio go-librespot[20482]: time="2025-12-23T19:20:02+09:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed performing keyexchange: failed reading APResponseMessage message: failed reading message length: read tcp 192.168.219.119:53564->104.199.241.202:4070: read: connection reset by peer" Dec 23 19:20:02 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Dec 23 19:20:02 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Dec 23 19:20:02 volumio volumio[1366]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 19:20:02 volumio volumio[1366]: Error: socket hang up Dec 23 19:20:02 volumio volumio[1366]: at connResetException (node:internal/errors:720:14) Dec 23 19:20:02 volumio volumio[1366]: at Socket.socketOnEnd (node:_http_client:519:23) Dec 23 19:20:02 volumio volumio[1366]: at Socket.emit (node:events:526:35) Dec 23 19:20:02 volumio volumio[1366]: at endReadableNT (node:internal/streams/readable:1376:12) Dec 23 19:20:02 volumio volumio[1366]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Dec 23 19:20:02 volumio volumio[1366]: code: 'ECONNRESET', Dec 23 19:20:02 volumio volumio[1366]: response: undefined Dec 23 19:20:02 volumio volumio[1366]: } Dec 23 19:20:02 volumio volumio[1366]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Dec 23 19:20:03 volumio sudo[20534]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-12-23 19:19' Dec 23 19:20:03 volumio sudo[20534]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Raspbian GNU/Linux 12 (bookworm)" NAME="Raspbian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=raspbian ID_LIKE=debian HOME_URL="http://www.raspbian.org/" SUPPORT_URL="http://www.raspbian.org/RaspbianForums" BUG_REPORT_URL="http://www.raspbian.org/RaspbianBugs" VOLUMIO_BUILD_VERSION="cfdc872d40b979cca36352a7333c940c38dc64b5" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="6cbc2303e10f00c3a01cb7f02c6d12448bd32c62" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Fri Dec 5 20:42:14 UTC 2025" VOLUMIO_VERSION="4.073" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="67fdc5f60f00b7a328ccd8c4ad441086"