Apr 15 13:32:02 volumio go-librespot[1645]: time="2026-04-15T13:32:02+02:00" level=debug msg="renewing login5 access token" Apr 15 13:32:06 volumio go-librespot[1645]: time="2026-04-15T13:32:06+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Apr 15 13:32:06 volumio go-librespot[1645]: time="2026-04-15T13:32:06+02:00" level=debug msg="completed keyexchange" Apr 15 13:32:06 volumio go-librespot[1645]: time="2026-04-15T13:32:06+02:00" level=debug msg="completed challenge" Apr 15 13:32:06 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState Apr 15 13:32:06 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Apr 15 13:32:06 volumio volumio[1177]: info: Listing playlists Apr 15 13:32:06 volumio volumio[1177]: info: Listing playlists Apr 15 13:32:16 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState Apr 15 13:32:16 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Apr 15 13:32:19 volumio wpa_supplicant[1138]: wlan0: WPA: Group rekeying completed with 98:9b:cb:97:ec:23 [GTK=CCMP] Apr 15 13:32:26 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState Apr 15 13:32:26 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Apr 15 13:32:26 volumio volumio[1177]: info: Listing playlists Apr 15 13:32:26 volumio volumio[1177]: info: Listing playlists Apr 15 13:32:36 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState Apr 15 13:32:36 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Apr 15 13:32:39 volumio go-librespot[1645]: time="2026-04-15T13:32:39+02:00" level=debug msg="renewing login5 access token" Apr 15 13:32:39 volumio go-librespot[1645]: time="2026-04-15T13:32:39+02:00" level=error msg="failed reconnecting dealer" error="failed obtaining dealer access token: failed renewing login5 access token: failed authenticating with login5: INVALID_CREDENTIALS" Apr 15 13:32:39 volumio go-librespot[1645]: time="2026-04-15T13:32:39+02:00" level=trace msg="sent dealer ping" Apr 15 13:32:39 volumio go-librespot[1645]: time="2026-04-15T13:32:39+02:00" level=debug msg="dealer recv loop stopped" Apr 15 13:32:43 volumio go-librespot[1645]: time="2026-04-15T13:32:43+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Apr 15 13:32:43 volumio go-librespot[1645]: time="2026-04-15T13:32:43+02:00" level=debug msg="completed keyexchange" Apr 15 13:32:43 volumio go-librespot[1645]: time="2026-04-15T13:32:43+02:00" level=debug msg="completed challenge" Apr 15 13:32:46 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState Apr 15 13:32:46 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Apr 15 13:32:46 volumio volumio[1177]: info: Listing playlists Apr 15 13:32:46 volumio volumio[1177]: info: Listing playlists Apr 15 13:32:56 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState Apr 15 13:32:56 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Apr 15 13:33:06 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState Apr 15 13:33:06 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Apr 15 13:33:06 volumio volumio[1177]: info: Listing playlists Apr 15 13:33:06 volumio volumio[1177]: info: Listing playlists Apr 15 13:33:16 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState Apr 15 13:33:16 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Apr 15 13:33:23 volumio go-librespot[1645]: time="2026-04-15T13:33:23+02:00" level=error msg="did not receive last pong ack from accesspoint, 955s passed" Apr 15 13:33:26 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState Apr 15 13:33:26 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Apr 15 13:33:26 volumio volumio[1177]: info: Listing playlists Apr 15 13:33:26 volumio volumio[1177]: info: Listing playlists Apr 15 13:33:29 volumio go-librespot[1645]: time="2026-04-15T13:33:29+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Apr 15 13:33:29 volumio go-librespot[1645]: time="2026-04-15T13:33:29+02:00" level=debug msg="completed keyexchange" Apr 15 13:33:29 volumio go-librespot[1645]: time="2026-04-15T13:33:29+02:00" level=debug msg="completed challenge" Apr 15 13:33:29 volumio go-librespot[1645]: time="2026-04-15T13:33:29+02:00" level=error msg="failed reconnecting accesspoint" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 15 13:33:29 volumio go-librespot[1645]: fatal error: sync: Unlock of unlocked RWMutex Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 147 gp=0x32150e8 m=4 mp=0x304d808 [running]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.fatal({0x92f816, 0x20}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/panic.go:1116 +0x38 fp=0x32e3eb0 sp=0x32e3e9c pc=0x6c060 Apr 15 13:33:29 volumio go-librespot[1645]: sync.fatal({0x92f816, 0x20}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/panic.go:1026 +0x24 fp=0x32e3ebc sp=0x32e3eb0 pc=0xa9504 Apr 15 13:33:29 volumio go-librespot[1645]: sync.(*RWMutex).Unlock(0x334a088) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/sync/rwmutex.go:212 +0x50 fp=0x32e3ed4 sp=0x32e3ebc pc=0xbc100 Apr 15 13:33:29 volumio go-librespot[1645]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x334a008) Apr 15 13:33:29 volumio go-librespot[1645]: /src/ap/ap.go:342 +0x4c0 fp=0x32e3fe4 sp=0x32e3ed4 pc=0x4f7904 Apr 15 13:33:29 volumio go-librespot[1645]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Apr 15 13:33:29 volumio go-librespot[1645]: /src/ap/ap.go:403 +0x28 fp=0x32e3fec sp=0x32e3fe4 pc=0x4f81cc Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x32e3fec sp=0x32e3fec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 37 Apr 15 13:33:29 volumio go-librespot[1645]: /src/ap/ap.go:403 +0x120 Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 1 gp=0x3002128 m=nil [select, 1342 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x305dce0 sp=0x305dccc pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.selectgo(0x305ddf0, 0x32e1dbc, 0x0, 0x0, 0x2, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x305dd90 sp=0x305dce0 pc=0x85000 Apr 15 13:33:29 volumio go-librespot[1645]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x310a120, 0x305de90) Apr 15 13:33:29 volumio go-librespot[1645]: /src/zeroconf/zeroconf.go:300 +0x228 fp=0x305de38 sp=0x305dd90 pc=0x62f43c Apr 15 13:33:29 volumio go-librespot[1645]: main.(*App).withAppPlayer(0x30f6580, {0xa3da1c, 0x1005090}, 0x30a5510) Apr 15 13:33:29 volumio go-librespot[1645]: /src/cmd/daemon/main.go:340 +0x5dc fp=0x305ded0 sp=0x305de38 pc=0x6bcc60 Apr 15 13:33:29 volumio go-librespot[1645]: main.(*App).withCredentials(...) Apr 15 13:33:29 volumio go-librespot[1645]: /src/cmd/daemon/main.go:209 Apr 15 13:33:29 volumio go-librespot[1645]: main.(*App).SpotifyToken(0x30f6580, {0xa3da1c, 0x1005090}, {0x30984a0, 0xd}, {0x309e280, 0x12a}) Apr 15 13:33:29 volumio go-librespot[1645]: /src/cmd/daemon/main.go:201 +0xc0 fp=0x305df00 sp=0x305ded0 pc=0x6bc02c Apr 15 13:33:29 volumio go-librespot[1645]: main.main() Apr 15 13:33:29 volumio go-librespot[1645]: /src/cmd/daemon/main.go:620 +0x660 fp=0x305dfa8 sp=0x305df00 pc=0x6bf56c Apr 15 13:33:29 volumio go-librespot[1645]: runtime.main() Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:285 +0x2f0 fp=0x305dfec sp=0x305dfa8 pc=0x6f390 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x305dfec sp=0x305dfec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 2 gp=0x30027e8 m=nil [force gc (idle), 4 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x97746c, 0xff8fa8, 0x11, 0xa, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3048fd4 sp=0x3048fc0 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goparkunlock(...) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:466 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.forcegchelper() Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:373 +0xe4 fp=0x3048fec sp=0x3048fd4 pc=0x6f7f4 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3048fec sp=0x3048fec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by runtime.init.6 in goroutine 1 Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:361 +0x1c Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 3 gp=0x3002b48 m=nil [GC sweep wait]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x97746c, 0xff9850, 0xc, 0x9, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x30497c4 sp=0x30497b0 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goparkunlock(...) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:466 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.bgsweep(0x302e050) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgcsweep.go:323 +0x11c fp=0x30497e4 sp=0x30497c4 pc=0x5768c Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gcenable.gowrap1() Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:212 +0x28 fp=0x30497ec sp=0x30497e4 pc=0x46c3c Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x30497ec sp=0x30497ec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by runtime.gcenable in goroutine 1 Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:212 +0x74 Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 4 gp=0x3002c68 m=nil [GC scavenge wait]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x97746c, 0xffa170, 0xd, 0xa, 0x2) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3049fb4 sp=0x3049fa0 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goparkunlock(...) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:466 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.(*scavengerState).park(0xffa170) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x3049fc8 sp=0x3049fb4 pc=0x54a28 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.bgscavenge(0x302e050) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x3049fe4 sp=0x3049fc8 pc=0x5516c Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gcenable.gowrap2() Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:213 +0x28 fp=0x3049fec sp=0x3049fe4 pc=0x46be8 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3049fec sp=0x3049fec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by runtime.gcenable in goroutine 1 Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:213 +0xbc Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 5 gp=0x3002fc8 m=nil [GOMAXPROCS updater (idle), 1342 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x97746c, 0xff95e8, 0x12, 0xa, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x304a7a4 sp=0x304a790 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goparkunlock(...) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:466 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.updateMaxProcsGoroutine() Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:6720 +0x110 fp=0x304a7ec sp=0x304a7a4 pc=0x7f174 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x304a7ec sp=0x304a7ec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by runtime.defaultGOMAXPROCSUpdateEnable in goroutine 1 Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:6708 +0x40 Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 18 gp=0x308a248 m=nil [finalizer wait, 1340 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x977314, 0x1005180, 0x10, 0xa, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3044788 sp=0x3044774 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.runFinalizers() Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mfinal.go:210 +0x110 fp=0x30447ec sp=0x3044788 pc=0x45888 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x30447ec sp=0x30447ec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by runtime.createfing in goroutine 1 Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mfinal.go:172 +0x5c Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 34 gp=0x3214d88 m=nil [cleanup wait, 1340 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x97746c, 0xffa348, 0x2e, 0xa, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x33047a4 sp=0x3304790 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goparkunlock(...) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:466 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.(*cleanupQueue).dequeue(0xffa2e8) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mcleanup.go:439 +0x12c fp=0x33047c4 sp=0x33047a4 pc=0x41cb0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.runCleanups() Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mcleanup.go:635 +0x7c fp=0x33047ec sp=0x33047c4 pc=0x4284c Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x33047ec sp=0x33047ec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by runtime.(*cleanupQueue).createGs in goroutine 1 Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mcleanup.go:589 +0x124 Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 21 gp=0x32ccfc8 m=nil [IO wait, 1342 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x97745c, 0x74f83010, 0x2, 0x2, 0x5) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3044cf0 sp=0x3044cdc pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.netpollblock(0x74f83000, 0x72, 0x0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x3044d08 sp=0x3044cf0 pc=0x675a0 Apr 15 13:33:29 volumio go-librespot[1645]: internal/poll.runtime_pollWait(0x74f83000, 0x72) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x3044d1c sp=0x3044d08 pc=0xa8864 Apr 15 13:33:29 volumio go-librespot[1645]: internal/poll.(*pollDesc).wait(0x32951e8, 0x72, 0x0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x3044d30 sp=0x3044d1c pc=0x136044 Apr 15 13:33:29 volumio go-librespot[1645]: internal/poll.(*pollDesc).waitRead(...) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 15 13:33:29 volumio go-librespot[1645]: internal/poll.(*FD).Accept(0x32951d0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x3044d78 sp=0x3044d30 pc=0x13a800 Apr 15 13:33:29 volumio go-librespot[1645]: net.(*netFD).accept(0x32951d0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x3044de0 sp=0x3044d78 pc=0x1abc28 Apr 15 13:33:29 volumio go-librespot[1645]: net.(*TCPListener).accept(0x30a24b0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x3044e34 sp=0x3044de0 pc=0x1c3554 Apr 15 13:33:29 volumio go-librespot[1645]: net.(*TCPListener).Accept(0x30a24b0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x3044e50 sp=0x3044e34 pc=0x1c259c Apr 15 13:33:29 volumio go-librespot[1645]: net/http.(*onceCloseListener).Accept(0x306ecc0) Apr 15 13:33:29 volumio go-librespot[1645]: :1 +0x34 fp=0x3044e68 sp=0x3044e50 pc=0x367f9c Apr 15 13:33:29 volumio go-librespot[1645]: net/http.(*Server).Serve(0x318e2c8, {0xa3d218, 0x30a24b0}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x3044f00 sp=0x3044e68 pc=0x341154 Apr 15 13:33:29 volumio go-librespot[1645]: net/http.Serve(...) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/net/http/server.go:2971 Apr 15 13:33:29 volumio go-librespot[1645]: main.(*ConcreteApiServer).serve(0x309c3c0) Apr 15 13:33:29 volumio go-librespot[1645]: /src/cmd/daemon/api_server.go:666 +0x974 fp=0x3044fe4 sp=0x3044f00 pc=0x6b17bc Apr 15 13:33:29 volumio go-librespot[1645]: main.NewApiServer.gowrap1() Apr 15 13:33:29 volumio go-librespot[1645]: /src/cmd/daemon/api_server.go:332 +0x28 fp=0x3044fec sp=0x3044fe4 pc=0x6b04f4 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3044fec sp=0x3044fec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by main.NewApiServer in goroutine 1 Apr 15 13:33:29 volumio go-librespot[1645]: /src/cmd/daemon/api_server.go:332 +0x310 Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 14 gp=0x32cd0e8 m=nil [IO wait, 1342 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x97745c, 0x74f82a10, 0x2, 0x2, 0x5) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3359cbc sp=0x3359ca8 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.netpollblock(0x74f82a00, 0x72, 0x0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x3359cd4 sp=0x3359cbc pc=0x675a0 Apr 15 13:33:29 volumio go-librespot[1645]: internal/poll.runtime_pollWait(0x74f82a00, 0x72) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x3359ce8 sp=0x3359cd4 pc=0xa8864 Apr 15 13:33:29 volumio go-librespot[1645]: internal/poll.(*pollDesc).wait(0x33c00b8, 0x72, 0x0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x3359cfc sp=0x3359ce8 pc=0x136044 Apr 15 13:33:29 volumio go-librespot[1645]: internal/poll.(*pollDesc).waitRead(...) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 15 13:33:29 volumio go-librespot[1645]: internal/poll.(*FD).ReadMsg(0x33c00a0, {0x3342d60, 0x10, 0x10}, {0x34d7318, 0x1000, 0x1000}, 0x40000000) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_unix.go:295 +0x2c0 fp=0x3359d6c sp=0x3359cfc pc=0x1381bc Apr 15 13:33:29 volumio go-librespot[1645]: net.(*netFD).readMsg(0x33c00a0, {0x3342d60, 0x10, 0x10}, {0x34d7318, 0x1000, 0x1000}, 0x40000000) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/net/fd_posix.go:91 +0x58 fp=0x3359dc8 sp=0x3359d6c pc=0x1aa2c8 Apr 15 13:33:29 volumio go-librespot[1645]: net.(*UnixConn).readMsg(0x32062b0, {0x3342d60, 0x10, 0x10}, {0x34d7318, 0x1000, 0x1000}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/net/unixsock_posix.go:115 +0x58 fp=0x3359e28 sp=0x3359dc8 pc=0x1c9d80 Apr 15 13:33:29 volumio go-librespot[1645]: net.(*UnixConn).ReadMsgUnix(0x32062b0, {0x3342d60, 0x10, 0x10}, {0x34d7318, 0x1000, 0x1000}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/net/unixsock.go:143 +0x58 fp=0x3359e78 sp=0x3359e28 pc=0x1c820c Apr 15 13:33:29 volumio go-librespot[1645]: github.com/godbus/dbus/v5.(*oobReader).Read(0x34d7308, {0x3342d60, 0x10, 0x10}) Apr 15 13:33:29 volumio go-librespot[1645]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:41 +0x50 fp=0x3359ec8 sp=0x3359e78 pc=0x4c2af0 Apr 15 13:33:29 volumio go-librespot[1645]: io.ReadAtLeast({0xa39d58, 0x34d7308}, {0x3342d60, 0x10, 0x10}, 0x10) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x3359ef4 sp=0x3359ec8 pc=0xee954 Apr 15 13:33:29 volumio go-librespot[1645]: io.ReadFull(...) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/io/io.go:354 Apr 15 13:33:29 volumio go-librespot[1645]: github.com/godbus/dbus/v5.(*unixTransport).ReadMessage(0x33880d0) Apr 15 13:33:29 volumio go-librespot[1645]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/transport_unix.go:124 +0x224 fp=0x3359fa0 sp=0x3359ef4 pc=0x4c32c4 Apr 15 13:33:29 volumio go-librespot[1645]: github.com/godbus/dbus/v5.(*Conn).inWorker(0x30f6380) Apr 15 13:33:29 volumio go-librespot[1645]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:390 +0x44 fp=0x3359fe4 sp=0x3359fa0 pc=0x4aaef4 Apr 15 13:33:29 volumio go-librespot[1645]: github.com/godbus/dbus/v5.(*Conn).Auth.gowrap1() Apr 15 13:33:29 volumio go-librespot[1645]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x28 fp=0x3359fec sp=0x3359fe4 pc=0x4a8318 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3359fec sp=0x3359fec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by github.com/godbus/dbus/v5.(*Conn).Auth in goroutine 1 Apr 15 13:33:29 volumio go-librespot[1645]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/auth.go:118 +0x77c Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 85 gp=0x32cd328 m=nil [chan receive, 1342 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x9772f4, 0x30d61f8, 0xe, 0x7, 0x2) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3304f3c sp=0x3304f28 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.chanrecv(0x30d61c0, 0x3304fd0, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x3304f84 sp=0x3304f3c pc=0x3414c Apr 15 13:33:29 volumio go-librespot[1645]: runtime.chanrecv2(0x30d61c0, 0x3304fd0) Apr 15 13:33:29 volumio volumio[1177]: info: Connection to go-librespot Websocket closed Apr 15 13:33:29 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x3304f98 sp=0x3304f84 pc=0x33bf0 Apr 15 13:33:29 volumio go-librespot[1645]: main.(*App).withAppPlayer.func1() Apr 15 13:33:29 volumio go-librespot[1645]: /src/cmd/daemon/main.go:284 +0x68 fp=0x3304fec sp=0x3304f98 pc=0x6bd918 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3304fec sp=0x3304fec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by main.(*App).withAppPlayer in goroutine 1 Apr 15 13:33:29 volumio go-librespot[1645]: /src/cmd/daemon/main.go:281 +0x4f8 Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 84 gp=0x32cd448 m=nil [runnable]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.selectgo(0x353df70, 0x353db20, 0x0, 0x0, 0xa, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/select.go:122 +0x14ac fp=0x353d9e0 sp=0x353d9e0 pc=0x85860 Apr 15 13:33:29 volumio go-librespot[1645]: main.(*AppPlayer).Run(0x34161e0, {0xa3da1c, 0x1005090}, 0x307a300, 0x307a340) Apr 15 13:33:29 volumio go-librespot[1645]: /src/cmd/daemon/player.go:661 +0x368 fp=0x353dfd4 sp=0x353d9e0 pc=0x6c5950 Apr 15 13:33:29 volumio go-librespot[1645]: main.(*App).withAppPlayer.gowrap1() Apr 15 13:33:29 volumio go-librespot[1645]: /src/cmd/daemon/main.go:274 +0x48 fp=0x353dfec sp=0x353dfd4 pc=0x6bd9f0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x353dfec sp=0x353dfec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by main.(*App).withAppPlayer in goroutine 1 Apr 15 13:33:29 volumio go-librespot[1645]: /src/cmd/daemon/main.go:274 +0x46c Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 7 gp=0x3310128 m=nil [GC worker (idle)]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x977320, 0x333a200, 0x1c, 0xa, 0x0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3117f88 sp=0x3117f74 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gcBgMarkWorker(0x3344200) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x3117fe4 sp=0x3117f88 pc=0x49f34 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gcBgMarkStartWorkers.gowrap1() Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x3117fec sp=0x3117fe4 pc=0x49e14 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3117fec sp=0x3117fec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by runtime.gcBgMarkStartWorkers in goroutine 26 Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 8 gp=0x3310248 m=nil [GC worker (idle), 10 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x977320, 0x333a400, 0x1c, 0xa, 0x0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3306788 sp=0x3306774 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gcBgMarkWorker(0x3344200) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x33067e4 sp=0x3306788 pc=0x49f34 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gcBgMarkStartWorkers.gowrap1() Apr 15 13:33:29 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x33067ec sp=0x33067e4 pc=0x49e14 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x33067ec sp=0x33067ec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by runtime.gcBgMarkStartWorkers in goroutine 26 Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 9 gp=0x3310368 m=nil [GC worker (idle), 26 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x977320, 0x333a600, 0x1c, 0xa, 0x0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x304bf88 sp=0x304bf74 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gcBgMarkWorker(0x3344200) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x304bfe4 sp=0x304bf88 pc=0x49f34 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gcBgMarkStartWorkers.gowrap1() Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x304bfec sp=0x304bfe4 pc=0x49e14 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x304bfec sp=0x304bfec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by runtime.gcBgMarkStartWorkers in goroutine 26 Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 10 gp=0x3310488 m=nil [GC worker (idle), 132 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x977320, 0x333a800, 0x1c, 0xa, 0x0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3046f88 sp=0x3046f74 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gcBgMarkWorker(0x3344200) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:1463 +0xf4 fp=0x3046fe4 sp=0x3046f88 pc=0x49f34 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gcBgMarkStartWorkers.gowrap1() Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:1373 +0x28 fp=0x3046fec sp=0x3046fe4 pc=0x49e14 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3046fec sp=0x3046fec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by runtime.gcBgMarkStartWorkers in goroutine 26 Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/mgc.go:1373 +0x14c Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 35 gp=0x32cc008 m=nil [chan receive, 1342 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x9772f4, 0x3472538, 0xe, 0x7, 0x2) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3045778 sp=0x3045764 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.chanrecv(0x3472500, 0x0, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x30457c0 sp=0x3045778 pc=0x3414c Apr 15 13:33:29 volumio go-librespot[1645]: runtime.chanrecv1(0x3472500, 0x0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/chan.go:509 +0x20 fp=0x30457d4 sp=0x30457c0 pc=0x33bcc Apr 15 13:33:29 volumio go-librespot[1645]: github.com/godbus/dbus/v5.newConn.func1() Apr 15 13:33:29 volumio systemd[1]: go-librespot-daemon.service: Consumed 1min 7.799s CPU time. Apr 15 13:33:29 volumio go-librespot[1645]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:303 +0x40 fp=0x30457ec sp=0x30457d4 pc=0x4aa8ec Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x30457ec sp=0x30457ec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by github.com/godbus/dbus/v5.newConn in goroutine 1 Apr 15 13:33:29 volumio go-librespot[1645]: /src/.gocache/mod/github.com/godbus/dbus/v5@v5.2.0/conn.go:302 +0x52c Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 83 gp=0x32cc128 m=nil [select, 1342 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3533e74 sp=0x3533e60 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.selectgo(0x3533f80, 0x3533f4c, 0x0, 0x0, 0x3, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x3533f24 sp=0x3533e74 pc=0x85000 Apr 15 13:33:29 volumio go-librespot[1645]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x3294c30) Apr 15 13:33:29 volumio go-librespot[1645]: /src/player/player.go:215 +0x1f4 fp=0x3533fe4 sp=0x3533f24 pc=0x582954 Apr 15 13:33:29 volumio go-librespot[1645]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Apr 15 13:33:29 volumio go-librespot[1645]: /src/player/player.go:197 +0x28 fp=0x3533fec sp=0x3533fe4 pc=0x58253c Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3533fec sp=0x3533fec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Apr 15 13:33:29 volumio go-librespot[1645]: /src/player/player.go:197 +0x220 Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 63 gp=0x3214fc8 m=nil [select]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x9774a0, 0x0, 0x9, 0x3, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x335cea4 sp=0x335ce90 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.selectgo(0x335cfd4, 0x335cf78, 0x0, 0x0, 0x2, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/select.go:351 +0xc4c fp=0x335cf54 sp=0x335cea4 pc=0x85000 Apr 15 13:33:29 volumio go-librespot[1645]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x334a008) Apr 15 13:33:29 volumio go-librespot[1645]: /src/ap/ap.go:368 +0x8c fp=0x335cfe4 sp=0x335cf54 pc=0x4f7cf8 Apr 15 13:33:29 volumio go-librespot[1645]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Apr 15 13:33:29 volumio go-librespot[1645]: /src/ap/ap.go:277 +0x28 fp=0x335cfec sp=0x335cfe4 pc=0x4f73c4 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x335cfec sp=0x335cfec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 84 Apr 15 13:33:29 volumio go-librespot[1645]: /src/ap/ap.go:277 +0x15c Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 86 gp=0x32cc488 m=nil [chan receive, 1342 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x9772f4, 0x30d61b8, 0xe, 0x7, 0x2) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x3534f40 sp=0x3534f2c pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.chanrecv(0x30d6180, 0x3534fe0, 0x1) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/chan.go:667 +0x550 fp=0x3534f88 sp=0x3534f40 pc=0x3414c Apr 15 13:33:29 volumio go-librespot[1645]: runtime.chanrecv2(0x30d6180, 0x3534fe0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/chan.go:514 +0x20 fp=0x3534f9c sp=0x3534f88 pc=0x33bf0 Apr 15 13:33:29 volumio go-librespot[1645]: main.(*App).withAppPlayer.func2() Apr 15 13:33:29 volumio go-librespot[1645]: /src/cmd/daemon/main.go:300 +0x6c fp=0x3534fec sp=0x3534f9c pc=0x6bd47c Apr 15 13:33:29 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x3534fec sp=0x3534fec pc=0xb0874 Apr 15 13:33:29 volumio go-librespot[1645]: created by main.(*App).withAppPlayer in goroutine 1 Apr 15 13:33:29 volumio go-librespot[1645]: /src/cmd/daemon/main.go:297 +0x598 Apr 15 13:33:29 volumio go-librespot[1645]: goroutine 87 gp=0x32cc5a8 m=nil [IO wait, 86 minutes]: Apr 15 13:33:29 volumio go-librespot[1645]: runtime.gopark(0x97745c, 0x74f82c10, 0x2, 0x2, 0x5) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x35355ac sp=0x3535598 pc=0xa97c0 Apr 15 13:33:29 volumio go-librespot[1645]: runtime.netpollblock(0x74f82c00, 0x72, 0x0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x35355c4 sp=0x35355ac pc=0x675a0 Apr 15 13:33:29 volumio go-librespot[1645]: internal/poll.runtime_pollWait(0x74f82c00, 0x72) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x35355d8 sp=0x35355c4 pc=0xa8864 Apr 15 13:33:29 volumio go-librespot[1645]: internal/poll.(*pollDesc).wait(0x33c0068, 0x72, 0x0) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x35355ec sp=0x35355d8 pc=0x136044 Apr 15 13:33:29 volumio go-librespot[1645]: internal/poll.(*pollDesc).waitRead(...) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 15 13:33:29 volumio go-librespot[1645]: internal/poll.(*FD).Accept(0x33c0050) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_unix.go:613 +0x284 fp=0x3535634 sp=0x35355ec pc=0x13a800 Apr 15 13:33:29 volumio go-librespot[1645]: net.(*netFD).accept(0x33c0050) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/net/fd_unix.go:161 +0x20 fp=0x353569c sp=0x3535634 pc=0x1abc28 Apr 15 13:33:29 volumio go-librespot[1645]: net.(*TCPListener).accept(0x338a060) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x35356f0 sp=0x353569c pc=0x1c3554 Apr 15 13:33:29 volumio go-librespot[1645]: net.(*TCPListener).Accept(0x338a060) Apr 15 13:33:29 volumio go-librespot[1645]: /usr/local/go/src/net/tcpsock.go:380 +0x30 fp=0x353570c sp=0x35356f0 pc=0x1c259c Apr 15 13:33:29 volumio go-librespot[1645]: net/http.(*onceCloseListener).Accept(0x3104700) Apr 15 13:33:29 volumio go-librespot[1645]: :1 +0x34 fp=0x3535724 sp=0x353570c pc=0x367f9c Apr 15 13:33:30 volumio go-librespot[1645]: net/http.(*Server).Serve(0x339a2c8, {0xa3d218, 0x338a060}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/server.go:3463 +0x2d8 fp=0x35357bc sp=0x3535724 pc=0x341154 Apr 15 13:33:30 volumio go-librespot[1645]: net/http.Serve(...) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/server.go:2971 Apr 15 13:33:30 volumio go-librespot[1645]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Apr 15 13:33:30 volumio go-librespot[1645]: /src/zeroconf/zeroconf.go:297 +0x8c fp=0x35357ec sp=0x35357bc pc=0x62f538 Apr 15 13:33:30 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x35357ec sp=0x35357ec pc=0xb0874 Apr 15 13:33:30 volumio go-librespot[1645]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Apr 15 13:33:30 volumio go-librespot[1645]: /src/zeroconf/zeroconf.go:297 +0x160 Apr 15 13:33:30 volumio go-librespot[1645]: goroutine 103 gp=0x3311568 m=nil [IO wait, 1342 minutes]: Apr 15 13:33:30 volumio go-librespot[1645]: runtime.gopark(0x97745c, 0x74fd4210, 0x2, 0x2, 0x5) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x32df908 sp=0x32df8f4 pc=0xa97c0 Apr 15 13:33:30 volumio go-librespot[1645]: runtime.netpollblock(0x74fd4200, 0x72, 0x0) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x32df920 sp=0x32df908 pc=0x675a0 Apr 15 13:33:30 volumio go-librespot[1645]: internal/poll.runtime_pollWait(0x74fd4200, 0x72) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x32df934 sp=0x32df920 pc=0xa8864 Apr 15 13:33:30 volumio go-librespot[1645]: internal/poll.(*pollDesc).wait(0x31a2428, 0x72, 0x0) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x32df948 sp=0x32df934 pc=0x136044 Apr 15 13:33:30 volumio go-librespot[1645]: internal/poll.(*pollDesc).waitRead(...) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 15 13:33:30 volumio go-librespot[1645]: internal/poll.(*FD).Read(0x31a2410, {0x31a8000, 0x1000, 0x1000}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x32df990 sp=0x32df948 pc=0x1371c4 Apr 15 13:33:30 volumio go-librespot[1645]: net.(*netFD).Read(0x31a2410, {0x31a8000, 0x1000, 0x1000}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x32df9bc sp=0x32df990 pc=0x1a9e78 Apr 15 13:33:30 volumio go-librespot[1645]: net.(*conn).Read(0x30335a0, {0x31a8000, 0x1000, 0x1000}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x32df9e8 sp=0x32df9bc pc=0x1b967c Apr 15 13:33:30 volumio go-librespot[1645]: io.(*multiReader).Read(0x344d530, {0x31a8000, 0x1000, 0x1000}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x32dfa14 sp=0x32df9e8 pc=0xefb88 Apr 15 13:33:30 volumio go-librespot[1645]: bufio.(*Reader).fill(0x3179170) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/bufio/bufio.go:113 +0x10c fp=0x32dfa38 sp=0x32dfa14 pc=0x2b6c1c Apr 15 13:33:30 volumio go-librespot[1645]: bufio.(*Reader).ReadByte(0x3179170) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/bufio/bufio.go:273 +0x28 fp=0x32dfa44 sp=0x32dfa38 pc=0x2b7498 Apr 15 13:33:30 volumio go-librespot[1645]: github.com/coder/websocket.readFrameHeader(0x3179170, {0x3280ab8, 0x8, 0x8}) Apr 15 13:33:30 volumio go-librespot[1645]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/frame.go:55 +0x68 fp=0x32dfa84 sp=0x32dfa44 pc=0x372a08 Apr 15 13:33:30 volumio go-librespot[1645]: github.com/coder/websocket.(*Conn).readFrameHeader(0x3280a88, {0xa3da00, 0x1005090}) Apr 15 13:33:30 volumio go-librespot[1645]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:266 +0xd0 fp=0x32dfb08 sp=0x32dfa84 pc=0x375bd4 Apr 15 13:33:30 volumio go-librespot[1645]: github.com/coder/websocket.(*Conn).readLoop(0x3280a88, {0xa3da00, 0x1005090}) Apr 15 13:33:30 volumio go-librespot[1645]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:185 +0x3c fp=0x32dfbcc sp=0x32dfb08 pc=0x375390 Apr 15 13:33:30 volumio go-librespot[1645]: github.com/coder/websocket.(*Conn).reader(0x3280a88, {0xa3da00, 0x1005090}) Apr 15 13:33:30 volumio go-librespot[1645]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:381 +0xec fp=0x32dfc50 sp=0x32dfbcc pc=0x376798 Apr 15 13:33:30 volumio go-librespot[1645]: github.com/coder/websocket.(*Conn).Reader(...) Apr 15 13:33:30 volumio go-librespot[1645]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:36 Apr 15 13:33:30 volumio go-librespot[1645]: github.com/coder/websocket.(*Conn).Read(0x3280a88, {0xa3da00, 0x1005090}) Apr 15 13:33:30 volumio go-librespot[1645]: /src/.gocache/mod/github.com/coder/websocket@v1.8.14/read.go:42 +0x30 fp=0x32dfc78 sp=0x32dfc50 pc=0x374944 Apr 15 13:33:30 volumio go-librespot[1645]: main.(*ConcreteApiServer).serve.func17({0xa3d2d8, 0x334b558}, 0x334b3f8) Apr 15 13:33:30 volumio go-librespot[1645]: /src/cmd/daemon/api_server.go:636 +0x3b4 fp=0x32dfcfc sp=0x32dfc78 pc=0x6b2040 Apr 15 13:33:30 volumio go-librespot[1645]: net/http.HandlerFunc.ServeHTTP(0x3032380, {0xa3d2d8, 0x334b558}, 0x334b3f8) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x32dfd0c sp=0x32dfcfc pc=0x33d7d8 Apr 15 13:33:30 volumio go-librespot[1645]: net/http.(*ServeMux).ServeHTTP(0x319a100, {0xa3d2d8, 0x334b558}, 0x334b3f8) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/server.go:2861 +0x228 fp=0x32dfd4c sp=0x32dfd0c pc=0x33f6c8 Apr 15 13:33:30 volumio go-librespot[1645]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xa3d2d8, 0x334b558}, 0x334b3f8) Apr 15 13:33:30 volumio go-librespot[1645]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x124 fp=0x32dfd74 sp=0x32dfd4c pc=0x6b19b8 Apr 15 13:33:30 volumio go-librespot[1645]: net/http.HandlerFunc.ServeHTTP(0x3010390, {0xa3d2d8, 0x334b558}, 0x334b3f8) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/server.go:2322 +0x34 fp=0x32dfd84 sp=0x32dfd74 pc=0x33d7d8 Apr 15 13:33:30 volumio go-librespot[1645]: net/http.serverHandler.ServeHTTP({0x318e2c8}, {0xa3d2d8, 0x334b558}, 0x334b3f8) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/server.go:3340 +0xe0 fp=0x32dfda0 sp=0x32dfd84 pc=0x35cb44 Apr 15 13:33:30 volumio go-librespot[1645]: net/http.(*conn).serve(0x31a6000, {0xa3da38, 0x30286a8}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/server.go:2109 +0x794 fp=0x32dffdc sp=0x32dfda0 pc=0x33b894 Apr 15 13:33:30 volumio go-librespot[1645]: net/http.(*Server).Serve.gowrap3() Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/server.go:3493 +0x38 fp=0x32dffec sp=0x32dffdc pc=0x3415cc Apr 15 13:33:30 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x32dffec sp=0x32dffec pc=0xb0874 Apr 15 13:33:30 volumio go-librespot[1645]: created by net/http.(*Server).Serve in goroutine 21 Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/server.go:3493 +0x42c Apr 15 13:33:30 volumio go-librespot[1645]: goroutine 196 gp=0x34b5c28 m=nil [IO wait]: Apr 15 13:33:30 volumio go-librespot[1645]: runtime.gopark(0x97745c, 0x74f82210, 0x2, 0x2, 0x5) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/runtime/proc.go:460 +0x104 fp=0x335daf4 sp=0x335dae0 pc=0xa97c0 Apr 15 13:33:30 volumio go-librespot[1645]: runtime.netpollblock(0x74f82200, 0x72, 0x0) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/runtime/netpoll.go:575 +0x100 fp=0x335db0c sp=0x335daf4 pc=0x675a0 Apr 15 13:33:30 volumio go-librespot[1645]: internal/poll.runtime_pollWait(0x74f82200, 0x72) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/runtime/netpoll.go:351 +0x54 fp=0x335db20 sp=0x335db0c pc=0xa8864 Apr 15 13:33:30 volumio go-librespot[1645]: internal/poll.(*pollDesc).wait(0x31a22e8, 0x72, 0x0) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x335db34 sp=0x335db20 pc=0x136044 Apr 15 13:33:30 volumio go-librespot[1645]: internal/poll.(*pollDesc).waitRead(...) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Apr 15 13:33:30 volumio go-librespot[1645]: internal/poll.(*FD).Read(0x31a22d0, {0x314d000, 0x1000, 0x1000}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/internal/poll/fd_unix.go:165 +0x22c fp=0x335db7c sp=0x335db34 pc=0x1371c4 Apr 15 13:33:30 volumio go-librespot[1645]: net.(*netFD).Read(0x31a22d0, {0x314d000, 0x1000, 0x1000}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/fd_posix.go:68 +0x38 fp=0x335dba8 sp=0x335db7c pc=0x1a9e78 Apr 15 13:33:30 volumio go-librespot[1645]: net.(*conn).Read(0x3446028, {0x314d000, 0x1000, 0x1000}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/net.go:196 +0x48 fp=0x335dbd4 sp=0x335dba8 pc=0x1b967c Apr 15 13:33:30 volumio go-librespot[1645]: crypto/tls.(*atLeastReader).Read(0x30104a0, {0x314d000, 0x1000, 0x1000}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/crypto/tls/conn.go:816 +0x78 fp=0x335dc00 sp=0x335dbd4 pc=0x2674c0 Apr 15 13:33:30 volumio go-librespot[1645]: bytes.(*Buffer).ReadFrom(0x326860c, {0xa3a068, 0x30104a0}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/bytes/buffer.go:217 +0xa4 fp=0x335dc3c sp=0x335dc00 pc=0x162058 Apr 15 13:33:30 volumio go-librespot[1645]: crypto/tls.(*Conn).readFromUntil(0x3268488, {0xa38fa8, 0x3446028}, 0x5) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/crypto/tls/conn.go:838 +0xd4 fp=0x335dc64 sp=0x335dc3c pc=0x26771c Apr 15 13:33:30 volumio go-librespot[1645]: crypto/tls.(*Conn).readRecordOrCCS(0x3268488, 0x0) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/crypto/tls/conn.go:627 +0x134 fp=0x335ddc4 sp=0x335dc64 pc=0x264e40 Apr 15 13:33:30 volumio go-librespot[1645]: crypto/tls.(*Conn).readRecord(...) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/crypto/tls/conn.go:589 Apr 15 13:33:30 volumio go-librespot[1645]: crypto/tls.(*Conn).Read(0x3268488, {0x3182000, 0x1000, 0x1000}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/crypto/tls/conn.go:1392 +0x14c fp=0x335ddf4 sp=0x335ddc4 pc=0x26ac04 Apr 15 13:33:30 volumio go-librespot[1645]: bufio.(*Reader).Read(0x31789f0, {0x3408124, 0x9, 0x9}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/bufio/bufio.go:245 +0x214 fp=0x335de18 sp=0x335ddf4 pc=0x2b72a0 Apr 15 13:33:30 volumio go-librespot[1645]: io.ReadAtLeast({0xa391f8, 0x31789f0}, {0x3408124, 0x9, 0x9}, 0x9) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/io/io.go:335 +0x90 fp=0x335de44 sp=0x335de18 pc=0xee954 Apr 15 13:33:30 volumio go-librespot[1645]: io.ReadFull(...) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/io/io.go:354 Apr 15 13:33:30 volumio go-librespot[1645]: net/http.http2readFrameHeader({0x3408124, 0x9, 0x9}, {0xa391f8, 0x31789f0}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/h2_bundle.go:1811 +0x54 fp=0x335de6c sp=0x335de44 pc=0x2f86ac Apr 15 13:33:30 volumio go-librespot[1645]: net/http.(*http2Framer).ReadFrame(0x3408100) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/h2_bundle.go:2078 +0x8c fp=0x335def4 sp=0x335de6c pc=0x2f8e04 Apr 15 13:33:30 volumio go-librespot[1645]: net/http.(*http2clientConnReadLoop).run(0x335dfdc) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/h2_bundle.go:9539 +0x108 fp=0x335dfa4 sp=0x335def4 pc=0x31d33c Apr 15 13:33:30 volumio go-librespot[1645]: net/http.(*http2ClientConn).readLoop(0x3215208) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/h2_bundle.go:9408 +0x80 fp=0x335dfe4 sp=0x335dfa4 pc=0x31c604 Apr 15 13:33:30 volumio go-librespot[1645]: net/http.(*http2Transport).newClientConn.gowrap1() Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0x28 fp=0x335dfec sp=0x335dfe4 pc=0x316960 Apr 15 13:33:30 volumio go-librespot[1645]: runtime.goexit({}) Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/runtime/asm_arm.s:877 +0x4 fp=0x335dfec sp=0x335dfec pc=0xb0874 Apr 15 13:33:30 volumio go-librespot[1645]: created by net/http.(*http2Transport).newClientConn in goroutine 195 Apr 15 13:33:30 volumio go-librespot[1645]: /usr/local/go/src/net/http/h2_bundle.go:8192 +0xee4 Apr 15 13:33:30 volumio go-librespot[1644]: Aborted Apr 15 13:33:32 volumio volumio[1177]: info: Initializing connection to go-librespot Websocket Apr 15 13:33:32 volumio volumio[1177]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 15 13:33:33 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Apr 15 13:33:33 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Apr 15 13:33:33 volumio systemd[1]: go-librespot-daemon.service: Consumed 1min 7.799s CPU time. Apr 15 13:33:33 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Apr 15 13:33:33 volumio go-librespot[29038]: go-librespot daemon starting... Apr 15 13:33:33 volumio go-librespot[29039]: time="2026-04-15T13:33:33+02:00" level=info msg="running go-librespot 0.7.1" Apr 15 13:33:33 volumio go-librespot[29039]: time="2026-04-15T13:33:33+02:00" level=debug msg="app state loaded" Apr 15 13:33:33 volumio go-librespot[29039]: time="2026-04-15T13:33:33+02:00" level=info msg="api server listening on 127.0.0.1:9879" Apr 15 13:33:33 volumio go-librespot[29039]: time="2026-04-15T13:33:33+02:00" level=debug msg="fetched new accesspoints: [ap-gew4.spotify.com:4070 ap-gew4.spotify.com:443 ap-gew4.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew1.spotify.com:80]" Apr 15 13:33:33 volumio go-librespot[29039]: time="2026-04-15T13:33:33+02:00" level=debug msg="fetched new dealers: [gew4-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew1-dealer.spotify.com:443]" Apr 15 13:33:33 volumio go-librespot[29039]: time="2026-04-15T13:33:33+02:00" level=debug msg="fetched new spclients: [gew4-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew1-spclient.spotify.com:443]" Apr 15 13:33:33 volumio go-librespot[29039]: time="2026-04-15T13:33:33+02:00" level=info msg="zeroconf server listening on port 38125" Apr 15 13:33:33 volumio go-librespot[29039]: time="2026-04-15T13:33:33+02:00" level=info msg="using avahi-daemon avahi 0.8 for mDNS service registration" Apr 15 13:33:33 volumio go-librespot[29039]: time="2026-04-15T13:33:33+02:00" level=debug msg="obtained new client token: AADVhWgC65ejzB6XQdpwHT3zWxL33tXWdhJfIw8ReDDFQ1HhFwacrWJ/G3vS05jw08jfadhFDEAtagug5BRQHKUD5g1NtNBQyNwkctm5fPe0uh1yr8hcTT0FolOzKkwf9C7J6qsAaP18Wnvty8PlWqXSyV2MxHgvAlkiuKazLCZSu/ZtEDZ3aPkqFk2PsZ4OU+Sol2TkYhGisdjAVi9MttbmUXiwakfWO9vniEL38zgkNMWxdP777z63gw==" Apr 15 13:33:33 volumio go-librespot[29039]: time="2026-04-15T13:33:33+02:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Apr 15 13:33:33 volumio go-librespot[29039]: time="2026-04-15T13:33:33+02:00" level=debug msg="completed keyexchange" Apr 15 13:33:33 volumio go-librespot[29039]: time="2026-04-15T13:33:33+02:00" level=debug msg="completed challenge" Apr 15 13:33:34 volumio go-librespot[29039]: time="2026-04-15T13:33:34+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 15 13:33:34 volumio go-librespot[29039]: time="2026-04-15T13:33:34+02:00" level=debug msg="connected to ap-gew4.spotify.com:443" Apr 15 13:33:34 volumio go-librespot[29039]: time="2026-04-15T13:33:34+02:00" level=debug msg="completed keyexchange" Apr 15 13:33:34 volumio go-librespot[29039]: time="2026-04-15T13:33:34+02:00" level=debug msg="completed challenge" Apr 15 13:33:34 volumio go-librespot[29039]: time="2026-04-15T13:33:34+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 15 13:33:35 volumio go-librespot[29039]: time="2026-04-15T13:33:35+02:00" level=debug msg="connected to ap-gew4.spotify.com:80" Apr 15 13:33:35 volumio go-librespot[29039]: time="2026-04-15T13:33:35+02:00" level=debug msg="completed keyexchange" Apr 15 13:33:35 volumio go-librespot[29039]: time="2026-04-15T13:33:35+02:00" level=debug msg="completed challenge" Apr 15 13:33:35 volumio go-librespot[29039]: time="2026-04-15T13:33:35+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 15 13:33:35 volumio volumio[1177]: info: Initializing connection to go-librespot Websocket Apr 15 13:33:35 volumio go-librespot[29039]: time="2026-04-15T13:33:35+02:00" level=debug msg="new websocket client" Apr 15 13:33:35 volumio volumio[1177]: info: Connection to go-librespot Websocket established Apr 15 13:33:36 volumio go-librespot[29039]: time="2026-04-15T13:33:36+02:00" level=debug msg="connected to ap-guc3.spotify.com:4070" Apr 15 13:33:36 volumio go-librespot[29039]: time="2026-04-15T13:33:36+02:00" level=debug msg="completed keyexchange" Apr 15 13:33:36 volumio go-librespot[29039]: time="2026-04-15T13:33:36+02:00" level=debug msg="completed challenge" Apr 15 13:33:36 volumio go-librespot[29039]: time="2026-04-15T13:33:36+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 15 13:33:36 volumio volumio[1177]: info: CoreCommandRouter::volumioGetState Apr 15 13:33:36 volumio volumio[1177]: info: CorePlayQueue::getTrack 0 Apr 15 13:33:37 volumio go-librespot[29039]: time="2026-04-15T13:33:37+02:00" level=debug msg="connected to ap-gue1.spotify.com:443" Apr 15 13:33:37 volumio go-librespot[29039]: time="2026-04-15T13:33:37+02:00" level=debug msg="completed keyexchange" Apr 15 13:33:37 volumio go-librespot[29039]: time="2026-04-15T13:33:37+02:00" level=debug msg="completed challenge" Apr 15 13:33:37 volumio go-librespot[29039]: time="2026-04-15T13:33:37+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 15 13:33:37 volumio go-librespot[29039]: time="2026-04-15T13:33:37+02:00" level=debug msg="connected to ap-gew1.spotify.com:80" Apr 15 13:33:37 volumio go-librespot[29039]: time="2026-04-15T13:33:37+02:00" level=debug msg="completed keyexchange" Apr 15 13:33:37 volumio go-librespot[29039]: time="2026-04-15T13:33:37+02:00" level=debug msg="completed challenge" Apr 15 13:33:37 volumio go-librespot[29039]: time="2026-04-15T13:33:37+02:00" level=warning msg="failed connecting to accesspoint, retrying" error="failed authenticating: accesspoint login failed: BadCredentials " Apr 15 13:33:37 volumio go-librespot[29039]: time="2026-04-15T13:33:37+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 15 13:33:37 volumio volumio[1177]: info: Connection to go-librespot Websocket closed Apr 15 13:33:37 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Apr 15 13:33:37 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Apr 15 13:33:39 volumio volumio[1177]: info: Getting Spotify volume Apr 15 13:33:39 volumio volumio[1177]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 13:33:39 volumio volumio[1177]: Error: connect ECONNREFUSED 127.0.0.1:9879 Apr 15 13:33:39 volumio volumio[1177]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Apr 15 13:33:39 volumio volumio[1177]: errno: -111, Apr 15 13:33:39 volumio volumio[1177]: code: 'ECONNREFUSED', Apr 15 13:33:39 volumio volumio[1177]: syscall: 'connect', Apr 15 13:33:39 volumio volumio[1177]: address: '127.0.0.1', Apr 15 13:33:39 volumio volumio[1177]: port: 9879, Apr 15 13:33:39 volumio volumio[1177]: response: undefined Apr 15 13:33:39 volumio volumio[1177]: } Apr 15 13:33:39 volumio volumio[1177]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Apr 15 13:33:39 volumio sudo[29076]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-04-15 13:32' Apr 15 13:33:39 volumio sudo[29076]: 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="18952480e8d8c63f22208e9007a0f47a9563eae6" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="0e58f1861fa88e02087981b8d91f7511f0e7011b" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Tue Mar 24 17:20:52 UTC 2026" VOLUMIO_VERSION="4.119" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="d0c2fd9dbc5e70e58c32413c12353563"