Apr 14 12:49:08 volumio-z83 go-librespot[2151]: time="2026-04-14T12:49:08+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Apr 14 12:49:08 volumio-z83 go-librespot[2151]: time="2026-04-14T12:49:08+02:00" level=debug msg="completed keyexchange" Apr 14 12:49:08 volumio-z83 go-librespot[2151]: time="2026-04-14T12:49:08+02:00" level=debug msg="completed challenge" Apr 14 12:49:20 volumio-z83 go-librespot[2151]: time="2026-04-14T12:49:20+02:00" level=trace msg="sent dealer ping" Apr 14 12:49:20 volumio-z83 go-librespot[2151]: time="2026-04-14T12:49:20+02:00" level=trace msg="received dealer pong" Apr 14 12:49:50 volumio-z83 go-librespot[2151]: time="2026-04-14T12:49:50+02:00" level=trace msg="sent dealer ping" Apr 14 12:49:50 volumio-z83 go-librespot[2151]: time="2026-04-14T12:49:50+02:00" level=error msg="did not receive last pong ack from accesspoint, 960s passed" Apr 14 12:49:50 volumio-z83 go-librespot[2151]: time="2026-04-14T12:49:50+02:00" level=trace msg="received dealer pong" Apr 14 12:49:56 volumio-z83 go-librespot[2151]: time="2026-04-14T12:49:56+02:00" level=warning msg="failed to connect to AP ap-gue1.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.255.62:443: connect: connection refused" Apr 14 12:49:56 volumio-z83 go-librespot[2151]: time="2026-04-14T12:49:56+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Apr 14 12:49:56 volumio-z83 go-librespot[2151]: time="2026-04-14T12:49:56+02:00" level=debug msg="completed keyexchange" Apr 14 12:49:56 volumio-z83 go-librespot[2151]: time="2026-04-14T12:49:56+02:00" level=debug msg="completed challenge" Apr 14 12:49:57 volumio-z83 go-librespot[2151]: time="2026-04-14T12:49:57+02:00" level=error msg="failed reconnecting accesspoint" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 14 12:49:57 volumio-z83 go-librespot[2151]: fatal error: sync: Unlock of unlocked RWMutex Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 247 gp=0xc000150380 m=5 mp=0xc000090008 [running]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.fatal({0xd0f73f, 0x20}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/panic.go:1116 +0x5c fp=0xc000499dc0 sp=0xc000499d90 pc=0x45199c Apr 14 12:49:57 volumio-z83 go-librespot[2151]: sync.fatal({0xd0f73f?, 0xc0000d2de8?}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/panic.go:1026 +0x18 fp=0xc000499de0 sp=0xc000499dc0 pc=0x488bf8 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: sync.(*RWMutex).Unlock(0xc0000d2de8) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/sync/rwmutex.go:212 +0x45 fp=0xc000499e18 sp=0xc000499de0 pc=0x49bd85 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0xc0000d2d20) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/ap/ap.go:342 +0x4b7 fp=0xc000499fc8 sp=0xc000499e18 pc=0x8a67f7 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1.gowrap1() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/ap/ap.go:273 +0x25 fp=0xc000499fe0 sp=0xc000499fc8 pc=0x8a6305 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000499fe8 sp=0xc000499fe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1 in goroutine 260 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/ap/ap.go:273 +0x78 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 1 gp=0xc000002380 m=nil [select, 1353 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0xc000045c58?, 0x2?, 0x60?, 0xb?, 0xc000045bf4?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000233a68 sp=0xc000233a48 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.selectgo(0xc000233c58, 0xc000045bf0, 0xc000126050?, 0x0, 0x0?, 0x1) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc000233ba8 sp=0xc000233a68 pc=0x467bd7 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0xc000367cc0, 0xc000233d80) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/zeroconf/zeroconf.go:300 +0x2aa fp=0xc000233cf8 sp=0xc000233ba8 pc=0x9b2aca Apr 14 12:49:57 volumio-z83 go-librespot[2151]: main.(*App).withAppPlayer(0xc0001cc800, {0xe22968, 0x14ad0c0}, 0xc0000a0400) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/main.go:340 +0x5d0 fp=0xc000233e10 sp=0xc000233cf8 pc=0xa3fd70 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: main.(*App).withCredentials(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/main.go:209 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: main.(*App).SpotifyToken(0xc0001cc800, {0xe22968, 0x14ad0c0}, {0xc0001906c0?, 0xcfc256?}, {0xc000254000?, 0x2697?}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/main.go:201 +0xc5 fp=0xc000233e68 sp=0xc000233e10 pc=0xa3f1e5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: main.main() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/main.go:620 +0x745 fp=0xc000233f50 sp=0xc000233e68 pc=0xa42245 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.main() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:285 +0x29d fp=0xc000233fe0 sp=0xc000233f50 pc=0x45509d Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000233fe8 sp=0xc000233fe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 18 gp=0xc000092380 m=nil [force gc (idle), 2 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x49e68ce971e5?, 0x1489ba0?, 0x11?, 0xa?, 0x1?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0000587a8 sp=0xc000058788 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goparkunlock(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:466 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.forcegchelper() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:373 +0xb3 fp=0xc0000587e0 sp=0xc0000587a8 pc=0x4553d3 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0000587e8 sp=0xc0000587e0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by runtime.init.7 in goroutine 1 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:361 +0x1a Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 19 gp=0xc000092540 m=nil [GC sweep wait]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x148aa01?, 0x148aa00?, 0xc?, 0x9?, 0x1?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000058f80 sp=0xc000058f60 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goparkunlock(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:466 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.bgsweep(0xc0000a2000) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgcsweep.go:323 +0xdf fp=0xc000058fc8 sp=0xc000058f80 pc=0x43f29f Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gcenable.gowrap1() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:212 +0x25 fp=0xc000058fe0 sp=0xc000058fc8 pc=0x4332a5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000058fe8 sp=0xc000058fe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by runtime.gcenable in goroutine 1 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:212 +0x66 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 20 gp=0xc000092700 m=nil [GC scavenge wait]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x10000?, 0x13e57be?, 0x0?, 0x0?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000059778 sp=0xc000059758 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goparkunlock(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:466 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.(*scavengerState).park(0x148bba0) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x49 fp=0xc0000597a8 sp=0xc000059778 pc=0x43cd29 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.bgscavenge(0xc0000a2000) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x59 fp=0xc0000597c8 sp=0xc0000597a8 pc=0x43d2d9 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gcenable.gowrap2() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:213 +0x25 fp=0xc0000597e0 sp=0xc0000597c8 pc=0x433245 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0000597e8 sp=0xc0000597e0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by runtime.gcenable in goroutine 1 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:213 +0xa5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 34 gp=0xc000102380 m=nil [GOMAXPROCS updater (idle), 1353 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000116788 sp=0xc000116768 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goparkunlock(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:466 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.updateMaxProcsGoroutine() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:6720 +0xe7 fp=0xc0001167e0 sp=0xc000116788 pc=0x4632c7 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0001167e8 sp=0xc0001167e0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:6708 +0x37 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 35 gp=0xc0001028c0 m=nil [finalizer wait, 256 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x0?, 0xc00025a410?, 0x0?, 0x80?, 0x1000000010?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005c620 sp=0xc00005c600 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.runFinalizers() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mfinal.go:210 +0x107 fp=0xc00005c7e0 sp=0xc00005c620 pc=0x4321a7 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005c7e8 sp=0xc00005c7e0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by runtime.createfing in goroutine 1 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mfinal.go:172 +0x3d Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 36 gp=0xc000102c40 m=nil [cleanup wait, 11 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x7f65cc11fa00?, 0xc000116f90?, 0xb5?, 0x7a?, 0x7f65cc11fa00?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000116f68 sp=0xc000116f48 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goparkunlock(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:466 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.(*cleanupQueue).dequeue(0x148bfe0) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mcleanup.go:439 +0xc5 fp=0xc000116fa0 sp=0xc000116f68 pc=0x42f3c5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.runCleanups() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mcleanup.go:635 +0x45 fp=0xc000116fe0 sp=0xc000116fa0 pc=0x42fa85 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000116fe8 sp=0xc000116fe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mcleanup.go:589 +0xa5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 54 gp=0xc0002108c0 m=nil [GC worker (idle)]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x49e68d677d64?, 0x1?, 0x58?, 0xf0?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00026bf38 sp=0xc00026bf18 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gcBgMarkWorker(0xc000260e70) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc00026bfc8 sp=0xc00026bf38 pc=0x43598b Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gcBgMarkStartWorkers.gowrap1() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc00026bfe0 sp=0xc00026bfc8 pc=0x435865 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00026bfe8 sp=0xc00026bfe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by runtime.gcBgMarkStartWorkers in goroutine 52 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 55 gp=0xc000210a80 m=nil [GC worker (idle), 256 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x14ae960?, 0x3?, 0xf5?, 0x5e?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0005b2f38 sp=0xc0005b2f18 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gcBgMarkWorker(0xc000260e70) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc0005b2fc8 sp=0xc0005b2f38 pc=0x43598b Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gcBgMarkStartWorkers.gowrap1() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc0005b2fe0 sp=0xc0005b2fc8 pc=0x435865 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0005b2fe8 sp=0xc0005b2fe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by runtime.gcBgMarkStartWorkers in goroutine 52 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 21 gp=0xc000092a80 m=nil [IO wait, 1353 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00006ca58 sp=0xc00006ca38 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.netpollblock(0xc00006caa8?, 0x41f886?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc00006ca90 sp=0xc00006ca58 pc=0x44d977 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.runtime_pollWait(0x7f65cc2e7e00, 0x72) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc00006cab0 sp=0xc00006ca90 pc=0x4880e5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*pollDesc).wait(0xc00031c000?, 0x900000036?, 0x0) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc00006cad8 sp=0xc00006cab0 pc=0x50b687 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*pollDesc).waitRead(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*FD).Accept(0xc00031c000) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x28c fp=0xc00006cb80 sp=0xc00006cad8 pc=0x510aac Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net.(*netFD).accept(0xc00031c000) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/fd_unix.go:161 +0x29 fp=0xc00006cc38 sp=0xc00006cb80 pc=0x57d2a9 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net.(*TCPListener).accept(0xc0000a6740) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1b fp=0xc00006cc88 sp=0xc00006cc38 pc=0x592a1b Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net.(*TCPListener).Accept(0xc0000a6740) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0xc00006ccb8 sp=0xc00006cc88 pc=0x591bf0 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net/http.(*onceCloseListener).Accept(0xc00032a120?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: :1 +0x24 fp=0xc00006ccd0 sp=0xc00006ccb8 pc=0x73b104 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net/http.(*Server).Serve(0xc0001cc900, {0xe218d0, 0xc0000a6740}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/http/server.go:3463 +0x30c fp=0xc00006ce00 sp=0xc00006ccd0 pc=0x71306c Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net/http.Serve(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/http/server.go:2971 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: main.(*ConcreteApiServer).serve(0xc000244360) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/api_server.go:666 +0x90c fp=0xc00006cfc8 sp=0xc00006ce00 pc=0xa3504c Apr 14 12:49:57 volumio-z83 go-librespot[2151]: main.NewApiServer.gowrap1() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/api_server.go:332 +0x25 fp=0xc00006cfe0 sp=0xc00006cfc8 pc=0xa33d85 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00006cfe8 sp=0xc00006cfe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by main.NewApiServer in goroutine 1 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/api_server.go:332 +0x2ff Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 40 gp=0xc000092e00 m=nil [IO wait, 1353 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00049da80 sp=0xc00049da60 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.netpollblock(0x0?, 0x41f886?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc00049dab8 sp=0xc00049da80 pc=0x44d977 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.runtime_pollWait(0x7f65cc2e7800, 0x72) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc00049dad8 sp=0xc00049dab8 pc=0x4880e5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*pollDesc).wait(0xc00031c280?, 0xc000182b60?, 0x0) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc00049db00 sp=0xc00049dad8 pc=0x50b687 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*pollDesc).waitRead(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*FD).ReadMsg(0xc00031c280, {0xc000182b60, 0x10, 0x10}, {0xc000481328, 0x1000, 0x1000}, 0x40000000) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_unix.go:295 +0x385 fp=0xc00049dbe8 sp=0xc00049db00 pc=0x50db45 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net.(*netFD).readMsg(0xc00031c280, {0xc000182b60?, 0xc0000a0600?, 0xc00049dcb8?}, {0xc000481328?, 0x20?, 0xba1820?}, 0xc?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/fd_posix.go:91 +0x31 fp=0xc00049dc70 sp=0xc00049dbe8 pc=0x57b811 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net.(*UnixConn).readMsg(0xc000126040, {0xc000182b60?, 0x2?, 0xc00049dd48?}, {0xc000481328?, 0xc00049de00?, 0x863e7c?}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/unixsock_posix.go:115 +0x3d fp=0xc00049dd00 sp=0xc00049dc70 pc=0x598cbd Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net.(*UnixConn).ReadMsgUnix(0xc000126040, {0xc000182b60?, 0xc00018e060?, 0xc00049ddc0?}, {0xc000481328?, 0x0?, 0xc0000a0600?}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/unixsock.go:143 +0x36 fp=0xc00049dd78 sp=0xc00049dd00 pc=0x5972b6 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/godbus/dbus/v5.(*oobReader).Read(0xc000481308, {0xc000182b60?, 0x2d?, 0x42a214?}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:41 +0x3c fp=0xc00049ddf0 sp=0xc00049dd78 pc=0x87313c Apr 14 12:49:57 volumio-z83 go-librespot[2151]: io.ReadAtLeast({0xe1ada0, 0xc000481308}, {0xc000182b60, 0x10, 0x10}, 0x10) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/io/io.go:335 +0x8e fp=0xc00049de38 sp=0xc00049ddf0 pc=0x4c91ae Apr 14 12:49:57 volumio-z83 go-librespot[2151]: io.ReadFull(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/io/io.go:354 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/godbus/dbus/v5.(*unixTransport).ReadMessage(0xc0000cafa8) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:124 +0x225 fp=0xc00049df58 sp=0xc00049de38 pc=0x873825 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/godbus/dbus/v5.(*Conn).inWorker(0xc0000d2780) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:390 +0x37 fp=0xc00049dfc8 sp=0xc00049df58 pc=0x85b857 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/godbus/dbus/v5.(*Conn).Auth.gowrap1() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x25 fp=0xc00049dfe0 sp=0xc00049dfc8 pc=0x8591c5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00049dfe8 sp=0xc00049dfe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by github.com/godbus/dbus/v5.(*Conn).Auth in goroutine 1 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x7ec Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 56 gp=0xc000211880 m=nil [GC worker (idle), 258 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x3bceb516172f?, 0xc00024d200?, 0x1c?, 0xa?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005ef38 sp=0xc00005ef18 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gcBgMarkWorker(0xc000260e70) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc00005efc8 sp=0xc00005ef38 pc=0x43598b Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gcBgMarkStartWorkers.gowrap1() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc00005efe0 sp=0xc00005efc8 pc=0x435865 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005efe8 sp=0xc00005efe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by runtime.gcBgMarkStartWorkers in goroutine 52 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 57 gp=0xc000211a40 m=nil [GC worker (idle), 15 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x4931042a004a?, 0x1?, 0x12?, 0x7f?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000119738 sp=0xc000119718 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gcBgMarkWorker(0xc000260e70) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:1463 +0xeb fp=0xc0001197c8 sp=0xc000119738 pc=0x43598b Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gcBgMarkStartWorkers.gowrap1() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:1373 +0x25 fp=0xc0001197e0 sp=0xc0001197c8 pc=0x435865 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0001197e8 sp=0xc0001197e0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by runtime.gcBgMarkStartWorkers in goroutine 52 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/mgc.go:1373 +0x105 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 27 gp=0xc000102fc0 m=nil [chan receive, 1353 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x0?, 0xb9cf60?, 0x0?, 0x0?, 0xc000119fa8?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000119f18 sp=0xc000119ef8 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.chanrecv(0xc000261650, 0x0, 0x1) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/chan.go:667 +0x473 fp=0xc000119f90 sp=0xc000119f18 pc=0x422773 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.chanrecv1(0xc00020d450?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/chan.go:509 +0x12 fp=0xc000119fb8 sp=0xc000119f90 pc=0x4222d2 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/godbus/dbus/v5.newConn.func1() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:303 +0x2c fp=0xc000119fe0 sp=0xc000119fb8 pc=0x85b32c Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000119fe8 sp=0xc000119fe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by github.com/godbus/dbus/v5.newConn in goroutine 1 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:302 +0x4da Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 99 gp=0xc000103180 m=nil [chan receive, 1353 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0xc000362360?, 0xc000110360?, 0x0?, 0x80?, 0xc00005a6e8?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00005a698 sp=0xc00005a678 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.chanrecv(0xc000260070, 0xc00005a798, 0x1) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/chan.go:667 +0x473 fp=0xc00005a710 sp=0xc00005a698 pc=0x422773 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.chanrecv2(0xc00005a780?, 0xc00005a780?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/chan.go:514 +0x12 fp=0xc00005a738 sp=0xc00005a710 pc=0x4222f2 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: main.(*App).withAppPlayer.func1() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/main.go:284 +0x72 fp=0xc00005a7e0 sp=0xc00005a738 pc=0xa408d2 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00005a7e8 sp=0xc00005a7e0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by main.(*App).withAppPlayer in goroutine 1 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/main.go:281 +0x49f Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 248 gp=0xc000103500 m=nil [select]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0xc0003c3f98?, 0x2?, 0x0?, 0x0?, 0xc0003c3f6c?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0003c3df8 sp=0xc0003c3dd8 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.selectgo(0xc0003c3f98, 0xc0003c3f68, 0x148b900?, 0x0, 0x150c2099ab8ccef1?, 0x1) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc0003c3f38 sp=0xc0003c3df8 pc=0x467bd7 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0xc0000d2d20) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/ap/ap.go:368 +0x7d fp=0xc0003c3fc8 sp=0xc0003c3f38 pc=0x8a6c1d Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1.gowrap2() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/ap/ap.go:277 +0x25 fp=0xc0003c3fe0 sp=0xc0003c3fc8 pc=0x8a62a5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0003c3fe8 sp=0xc0003c3fe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1 in goroutine 260 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/ap/ap.go:277 +0xff Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 259 gp=0xc000103dc0 m=nil [select, 258 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0xc000072f20?, 0x3?, 0xa7?, 0x23?, 0xc000072eda?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000072d60 sp=0xc000072d40 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.selectgo(0xc000072f20, 0xc000072ed4, 0x422128?, 0x0, 0x5?, 0x1) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc000072ea0 sp=0xc000072d60 pc=0x467bd7 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0xc00072a880) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/player/player.go:215 +0x205 fp=0xc000072fc8 sp=0xc000072ea0 pc=0x912865 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/player/player.go:197 +0x25 fp=0xc000072fe0 sp=0xc000072fc8 pc=0x912425 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000072fe8 sp=0xc000072fe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 100 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/player/player.go:197 +0x1e5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 260 gp=0xc000211180 m=nil [select, 78 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0xc0003b7ed0?, 0xa?, 0x0?, 0x0?, 0xc0003b76c0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc0003b7318 sp=0xc0003b72f8 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.selectgo(0xc0003b7ed0, 0xc0003b76ac, 0x14ad0c0?, 0x0, 0x1d?, 0x1) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc0003b7458 sp=0xc0003b7318 pc=0x467bd7 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: main.(*AppPlayer).Run(0xc00061cc00, {0xe22968, 0x14ad0c0}, 0xc0004c3ea0, 0xc0004c3f10) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/player.go:661 +0x427 fp=0xc0003b7fa8 sp=0xc0003b7458 pc=0xa47f47 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: main.(*App).withAppPlayer.func2.gowrap1() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/main.go:328 +0x30 fp=0xc0003b7fe0 sp=0xc0003b7fa8 pc=0xa40830 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc0003b7fe8 sp=0xc0003b7fe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by main.(*App).withAppPlayer.func2 in goroutine 100 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/main.go:328 +0x2a8 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 100 gp=0xc000093180 m=nil [chan receive, 258 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0xc000235ee0?, 0x91f65c?, 0xe0?, 0x15?, 0x5?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000494e98 sp=0xc000494e78 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.chanrecv(0xc000260000, 0xc000235fb8, 0x1) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/chan.go:667 +0x473 fp=0xc000494f10 sp=0xc000494e98 pc=0x422773 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.chanrecv2(0xc0000b96e0?, 0xd0c398?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/chan.go:514 +0x12 fp=0xc000494f38 sp=0xc000494f10 pc=0x4222f2 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: main.(*App).withAppPlayer.func2() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/main.go:300 +0x87 fp=0xc000494fe0 sp=0xc000494f38 pc=0xa40507 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000494fe8 sp=0xc000494fe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by main.(*App).withAppPlayer in goroutine 1 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/main.go:297 +0x55f Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 101 gp=0xc000093340 m=nil [IO wait, 163 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000071be0 sp=0xc000071bc0 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.netpollblock(0xc000071c30?, 0x41f886?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc000071c18 sp=0xc000071be0 pc=0x44d977 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.runtime_pollWait(0x7f65cc2e7a00, 0x72) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc000071c38 sp=0xc000071c18 pc=0x4880e5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*pollDesc).wait(0xc00031c200?, 0x900000036?, 0x0) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000071c60 sp=0xc000071c38 pc=0x50b687 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*pollDesc).waitRead(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*FD).Accept(0xc00031c200) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x28c fp=0xc000071d08 sp=0xc000071c60 pc=0x510aac Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net.(*netFD).accept(0xc00031c200) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/fd_unix.go:161 +0x29 fp=0xc000071dc0 sp=0xc000071d08 pc=0x57d2a9 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net.(*TCPListener).accept(0xc00013c840) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1b fp=0xc000071e10 sp=0xc000071dc0 pc=0x592a1b Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net.(*TCPListener).Accept(0xc00013c840) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0xc000071e40 sp=0xc000071e10 pc=0x591bf0 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net/http.(*onceCloseListener).Accept(0xc00017c510?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: :1 +0x24 fp=0xc000071e58 sp=0xc000071e40 pc=0x73b104 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net/http.(*Server).Serve(0xc0002c8400, {0xe218d0, 0xc00013c840}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/http/server.go:3463 +0x30c fp=0xc000071f88 sp=0xc000071e58 pc=0x71306c Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net/http.Serve(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/http/server.go:2971 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/zeroconf/zeroconf.go:297 +0x85 fp=0xc000071fe0 sp=0xc000071f88 pc=0x9b2be5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000071fe8 sp=0xc000071fe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/zeroconf/zeroconf.go:297 +0x169 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 250 gp=0xc0000036c0 m=nil [select]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0xc00026df98?, 0x2?, 0x0?, 0x0?, 0xc00026df44?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00026ddc8 sp=0xc00026dda8 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.selectgo(0xc00026df98, 0xc00026df40, 0xc0004b1030?, 0x0, 0xc00047ee70?, 0x1) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/select.go:351 +0x8b7 fp=0xc00026df08 sp=0xc00026ddc8 pc=0x467bd7 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0xc000350380) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/dealer/dealer.go:138 +0x8f fp=0xc00026dfc8 sp=0xc00026df08 pc=0x92efaf Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1.gowrap2() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/dealer/dealer.go:129 +0x25 fp=0xc00026dfe0 sp=0xc00026dfc8 pc=0x92ee85 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00026dfe8 sp=0xc00026dfe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1 in goroutine 260 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/dealer/dealer.go:129 +0xf6 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 78 gp=0xc000003880 m=nil [IO wait, 1353 minutes]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0xc0005bc1f0?, 0xc0002373a8?, 0xa8?, 0x73?, 0xb?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc000237338 sp=0xc000237318 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.netpollblock(0x4ac6d8?, 0x41f886?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc000237370 sp=0xc000237338 pc=0x44d977 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.runtime_pollWait(0x7f65cc19c000, 0x72) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc000237390 sp=0xc000237370 pc=0x4880e5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*pollDesc).wait(0xc0000fcd00?, 0xc000562000?, 0x0) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc0002373b8 sp=0xc000237390 pc=0x50b687 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*pollDesc).waitRead(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*FD).Read(0xc0000fcd00, {0xc000562000, 0x1000, 0x1000}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x279 fp=0xc000237450 sp=0xc0002373b8 pc=0x50c979 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net.(*netFD).Read(0xc0000fcd00, {0xc000562000?, 0x7f6613775108?, 0x10?}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/fd_posix.go:68 +0x25 fp=0xc000237498 sp=0xc000237450 pc=0x57b405 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net.(*conn).Read(0xc000186018, {0xc000562000?, 0x10?, 0xc000237520?}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/net.go:196 +0x45 fp=0xc0002374e0 sp=0xc000237498 pc=0x589925 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: io.(*multiReader).Read(0xc000012258, {0xc000562000, 0x1000, 0x1000}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/io/multi.go:26 +0x93 fp=0xc000237528 sp=0xc0002374e0 pc=0x4ca073 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: bufio.(*Reader).fill(0xc000477f20) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/bufio/bufio.go:113 +0x103 fp=0xc000237560 sp=0xc000237528 pc=0x68e823 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: bufio.(*Reader).ReadByte(0xc000477f20) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/bufio/bufio.go:273 +0x27 fp=0xc000237580 sp=0xc000237560 pc=0x68ef47 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/coder/websocket.readFrameHeader(0xc000477f20, {0xc000550420, 0x8, 0x8}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x85 fp=0xc000237610 sp=0xc000237580 pc=0x745ae5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/coder/websocket.(*Conn).readFrameHeader(0xc0005503c0, {0xe22930, 0x14ad0c0}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xe5 fp=0xc0002376e8 sp=0xc000237610 pc=0x747925 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/coder/websocket.(*Conn).readLoop(0xc0005503c0, {0xe22930, 0x14ad0c0}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x65 fp=0xc000237848 sp=0xc0002376e8 pc=0x746f45 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/coder/websocket.(*Conn).reader(0xc0005503c0, {0xe22930, 0x14ad0c0}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0x10f fp=0xc000237938 sp=0xc000237848 pc=0x7486af Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/coder/websocket.(*Conn).Reader(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/coder/websocket.(*Conn).Read(0xc0002443d8?, {0xe22930?, 0x14ad0c0?}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x1e fp=0xc000237968 sp=0xc000237938 pc=0x7466fe Apr 14 12:49:57 volumio-z83 go-librespot[2151]: main.(*ConcreteApiServer).serve.func17({0xe21a50, 0xc0003dc0f0}, 0xc000131710?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/cmd/daemon/api_server.go:636 +0x39f fp=0xc000237a68 sp=0xc000237968 pc=0xa357ff Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net/http.HandlerFunc.ServeHTTP(0xc000198480?, {0xe21a50?, 0xc0003dc0f0?}, 0x2000000000000?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/http/server.go:2322 +0x29 fp=0xc000237a90 sp=0xc000237a68 pc=0x70f769 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net/http.(*ServeMux).ServeHTTP(0xc00022b930?, {0xe21a50, 0xc0003dc0f0}, 0xc0002cb540) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/http/server.go:2861 +0x1c7 fp=0xc000237ae0 sp=0xc000237a90 pc=0x711647 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xe21a50, 0xc0003dc0f0}, 0xc0002cb540) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x164 fp=0xc000237b38 sp=0xc000237ae0 pc=0xa35284 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net/http.HandlerFunc.ServeHTTP(0x485cb9?, {0xe21a50?, 0xc0003dc0f0?}, 0xc000493b68?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/http/server.go:2322 +0x29 fp=0xc000237b60 sp=0xc000237b38 pc=0x70f769 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net/http.serverHandler.ServeHTTP({0xc0004e09c0?}, {0xe21a50?, 0xc0003dc0f0?}, 0x1?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/http/server.go:3340 +0x8e fp=0xc000237b90 sp=0xc000237b60 pc=0x72ebee Apr 14 12:49:57 volumio-z83 volumio[1344]: info: Connection to go-librespot Websocket closed Apr 14 12:49:57 volumio-z83 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net/http.(*conn).serve(0xc00017c240, {0xe229a0, 0xc000262b40}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/http/server.go:2109 +0x665 fp=0xc000237fb8 sp=0xc000237b90 pc=0x70d865 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net/http.(*Server).Serve.gowrap3() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/http/server.go:3493 +0x28 fp=0xc000237fe0 sp=0xc000237fb8 pc=0x713468 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc000237fe8 sp=0xc000237fe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by net/http.(*Server).Serve in goroutine 21 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/http/server.go:3493 +0x485 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: goroutine 249 gp=0xc000211dc0 m=nil [IO wait]: Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.gopark(0x16?, 0x80?, 0x14?, 0x0?, 0xb?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/proc.go:460 +0xce fp=0xc00049b4c8 sp=0xc00049b4a8 pc=0x488f0e Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.netpollblock(0x4ac6d8?, 0x41f886?, 0x0?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/netpoll.go:575 +0xf7 fp=0xc00049b500 sp=0xc00049b4c8 pc=0x44d977 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.runtime_pollWait(0x7f65cc19bc00, 0x72) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/netpoll.go:351 +0x85 fp=0xc00049b520 sp=0xc00049b500 pc=0x4880e5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*pollDesc).wait(0xc00061cf00?, 0xc0007dc000?, 0x0) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc00049b548 sp=0xc00049b520 pc=0x50b687 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*pollDesc).waitRead(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: internal/poll.(*FD).Read(0xc00061cf00, {0xc0007dc000, 0x1500, 0x1500}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x279 fp=0xc00049b5e0 sp=0xc00049b548 pc=0x50c979 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net.(*netFD).Read(0xc00061cf00, {0xc0007dc000?, 0x433636?, 0x14ae960?}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/fd_posix.go:68 +0x25 fp=0xc00049b628 sp=0xc00049b5e0 pc=0x57b405 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net.(*conn).Read(0xc000126088, {0xc0007dc000?, 0x7f65cc2c25c0?, 0x7f6613775108?}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/net.go:196 +0x45 fp=0xc00049b670 sp=0xc00049b628 pc=0x589925 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: crypto/tls.(*atLeastReader).Read(0xc0004d8078, {0xc0007dc000?, 0x7f65cc32ba00?, 0x7f6613775108?}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/crypto/tls/conn.go:816 +0x3b fp=0xc00049b6b8 sp=0xc00049b670 pc=0x63fedb Apr 14 12:49:57 volumio-z83 go-librespot[2151]: bytes.(*Buffer).ReadFrom(0xc0006962a8, {0xe1b3a0, 0xc0004d8078}) Apr 14 12:49:57 volumio-z83 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/bytes/buffer.go:217 +0x98 fp=0xc00049b710 sp=0xc00049b6b8 pc=0x5357b8 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: crypto/tls.(*Conn).readFromUntil(0xc000696008, {0xe19300, 0xc000126088}, 0x450db4?) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/crypto/tls/conn.go:838 +0xde fp=0xc00049b748 sp=0xc00049b710 pc=0x6400be Apr 14 12:49:57 volumio-z83 go-librespot[2151]: crypto/tls.(*Conn).readRecordOrCCS(0xc000696008, 0x0) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/crypto/tls/conn.go:627 +0x3db fp=0xc00049b9c0 sp=0xc00049b748 pc=0x63d13b Apr 14 12:49:57 volumio-z83 go-librespot[2151]: crypto/tls.(*Conn).readRecord(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/crypto/tls/conn.go:589 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: crypto/tls.(*Conn).Read(0xc000696008, {0xc0004ba000, 0x1000, 0x7f65cc328ee0?}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x145 fp=0xc00049ba28 sp=0xc00049b9c0 pc=0x6439e5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: net/http.(*readWriteCloserBody).Read(0xc000090008?, {0xc0004ba000?, 0xc00049ba98?, 0x486a25?}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/net/http/transport.go:2578 +0x88 fp=0xc00049ba58 sp=0xc00049ba28 pc=0x729e48 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: bufio.(*Reader).fill(0xc000110300) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/bufio/bufio.go:113 +0x103 fp=0xc00049ba90 sp=0xc00049ba58 pc=0x68e823 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: bufio.(*Reader).ReadByte(0xc000110300) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/bufio/bufio.go:273 +0x27 fp=0xc00049bab0 sp=0xc00049ba90 pc=0x68ef47 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/coder/websocket.readFrameHeader(0xc000110300, {0xc0003aa600, 0x8, 0x8}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x85 fp=0xc00049bb40 sp=0xc00049bab0 pc=0x745ae5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/coder/websocket.(*Conn).readFrameHeader(0xc0003aa5a0, {0xe22930, 0x14ad0c0}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xe5 fp=0xc00049bc18 sp=0xc00049bb40 pc=0x747925 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/coder/websocket.(*Conn).readLoop(0xc0003aa5a0, {0xe22930, 0x14ad0c0}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x65 fp=0xc00049bd78 sp=0xc00049bc18 pc=0x746f45 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/coder/websocket.(*Conn).reader(0xc0003aa5a0, {0xe22930, 0x14ad0c0}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0x10f fp=0xc00049be68 sp=0xc00049bd78 pc=0x7486af Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/coder/websocket.(*Conn).Reader(...) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Apr 14 12:49:57 volumio-z83 systemd[1]: go-librespot-daemon.service: Consumed 12.982s CPU time. Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/coder/websocket.(*Conn).Read(0xc00024c000?, {0xe22930?, 0x14ad0c0?}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x1e fp=0xc00049be98 sp=0xc00049be68 pc=0x7466fe Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0xc000350380) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/dealer/dealer.go:188 +0x67 fp=0xc00049bfc8 sp=0xc00049be98 pc=0x92f367 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1.gowrap1() Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/dealer/dealer.go:125 +0x25 fp=0xc00049bfe0 sp=0xc00049bfc8 pc=0x92eee5 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: runtime.goexit({}) Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /usr/local/go/src/runtime/asm_amd64.s:1693 +0x1 fp=0xc00049bfe8 sp=0xc00049bfe0 pc=0x490a41 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1 in goroutine 260 Apr 14 12:49:57 volumio-z83 go-librespot[2151]: /src/dealer/dealer.go:125 +0x78 Apr 14 12:49:57 volumio-z83 go-librespot[2147]: Aborted Apr 14 12:50:00 volumio-z83 volumio[1344]: info: Initializing connection to go-librespot Websocket Apr 14 12:50:00 volumio-z83 volumio[1344]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 14 12:50:00 volumio-z83 systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Apr 14 12:50:00 volumio-z83 systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 14 12:50:00 volumio-z83 systemd[1]: go-librespot-daemon.service: Consumed 12.982s CPU time. Apr 14 12:50:00 volumio-z83 systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 14 12:50:00 volumio-z83 go-librespot[212452]: go-librespot daemon starting... Apr 14 12:50:00 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:00+02:00" level=info msg="running go-librespot 0.7.1" Apr 14 12:50:00 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:00+02:00" level=debug msg="app state loaded" Apr 14 12:50:00 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:00+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 14 12:50:01 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:01+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-gue1.spotify.com:4070 ap-gew1.spotify.com:443 ap-gae2.spotify.com:80]" Apr 14 12:50:01 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:01+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443 gae2-dealer.spotify.com:443]" Apr 14 12:50:01 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:01+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443 gae2-spclient.spotify.com:443]" Apr 14 12:50:01 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:01+02:00" level=info msg="zeroconf server listening on port 38903" Apr 14 12:50:01 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:01+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Apr 14 12:50:02 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:02+02:00" level=debug msg="obtained new client token: AABqJUtOmt3yVu0Cg+BCayoVgNMU9ce6yX+Cl0w6LFtTn+3hLKazBhx6+f8i1QzAG8ytCQFjrU7JTHZzt2/QRcV+MvIYFcPYcqkZNN3CLkfeuC9Y2aSsayaDdyVbWsN0Xajop15o8Nnwc+0ET6Y5iaHn4MZhQLa9o1cm4AsUJTGwGen2wro3RiGfZzVH5QYTL9VE9G9wBKHZswmDFoP3i/Lo5PPNXPptmTaj65jtGOWQjPP8opXQDQ==" Apr 14 12:50:03 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:03+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:4070, retrying with a different AP" error="dial tcp 34.158.1.133:4070: connect: connection refused" Apr 14 12:50:03 volumio-z83 volumio[1344]: info: Initializing connection to go-librespot Websocket Apr 14 12:50:03 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:03+02:00" level=debug msg="new websocket client" Apr 14 12:50:03 volumio-z83 volumio[1344]: info: Connection to go-librespot Websocket established Apr 14 12:50:03 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:03+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:443, retrying with a different AP" error="dial tcp 34.158.1.133:443: connect: connection refused" Apr 14 12:50:04 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:04+02:00" level=warning msg="failed to connect to AP ap-gew4.spotify.com:80, retrying with a different AP" error="dial tcp 34.158.1.133:80: connect: connection refused" Apr 14 12:50:04 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:04+02:00" level=debug msg="connected to ap-gue1.spotify.com:4070" Apr 14 12:50:05 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:05+02:00" level=debug msg="completed keyexchange" Apr 14 12:50:05 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:05+02:00" level=debug msg="completed challenge" Apr 14 12:50:05 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:05+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 14 12:50:06 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:06+02:00" level=debug msg="connected to ap-gew1.spotify.com:443" Apr 14 12:50:06 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:06+02:00" level=debug msg="completed keyexchange" Apr 14 12:50:06 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:06+02:00" level=debug msg="completed challenge" Apr 14 12:50:06 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:06+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 14 12:50:06 volumio-z83 volumio[1344]: info: Getting Spotify volume Apr 14 12:50:06 volumio-z83 volumio[1344]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Apr 14 12:50:06 volumio-z83 volumio[1344]: info: CoreCommandRouter::volumioGetState Apr 14 12:50:06 volumio-z83 volumio[1344]: info: CorePlayQueue::getTrack 0 Apr 14 12:50:07 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:07+02:00" level=debug msg="connected to ap-gae2.spotify.com:80" Apr 14 12:50:07 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:07+02:00" level=debug msg="completed keyexchange" Apr 14 12:50:07 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:07+02:00" level=debug msg="completed challenge" Apr 14 12:50:08 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:08+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 14 12:50:09 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:09+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Apr 14 12:50:09 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:09+02:00" level=debug msg="completed keyexchange" Apr 14 12:50:09 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:09+02:00" level=debug msg="completed challenge" Apr 14 12:50:10 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:10+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 14 12:50:10 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:10+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Apr 14 12:50:11 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:11+02:00" level=debug msg="completed keyexchange" Apr 14 12:50:11 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:11+02:00" level=debug msg="completed challenge" Apr 14 12:50:11 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:11+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 14 12:50:12 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:12+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Apr 14 12:50:12 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:12+02:00" level=debug msg="completed keyexchange" Apr 14 12:50:12 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:12+02:00" level=debug msg="completed challenge" Apr 14 12:50:12 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:12+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 14 12:50:12 volumio-z83 go-librespot[212453]: time="2026-04-14T12:50:12+02:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: BadCredentials " Apr 14 12:50:12 volumio-z83 systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 14 12:50:12 volumio-z83 systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 14 12:50:12 volumio-z83 volumio[1344]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 12:50:12 volumio-z83 volumio[1344]: Error: socket hang up Apr 14 12:50:12 volumio-z83 volumio[1344]: at connResetException (node:internal/errors:720:14) Apr 14 12:50:12 volumio-z83 volumio[1344]: at Socket.socketOnEnd (node:_http_client:519:23) Apr 14 12:50:12 volumio-z83 volumio[1344]: at Socket.emit (node:events:526:35) Apr 14 12:50:12 volumio-z83 volumio[1344]: at endReadableNT (node:internal/streams/readable:1376:12) Apr 14 12:50:12 volumio-z83 volumio[1344]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Apr 14 12:50:12 volumio-z83 volumio[1344]: code: 'ECONNRESET', Apr 14 12:50:12 volumio-z83 volumio[1344]: response: undefined Apr 14 12:50:12 volumio-z83 volumio[1344]: } Apr 14 12:50:12 volumio-z83 volumio[1344]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 14 12:50:13 volumio-z83 sudo[212505]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-14 12:49' Apr 14 12:50:13 volumio-z83 sudo[212505]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:45:45 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6bf7cd61fe53483b72878254df87f1c0"