Feb 25 09:13:07 volumio go-librespot[1668]: time="2026-02-25T09:13:07+07:00" level=trace msg="sent dealer ping" Feb 25 09:13:08 volumio go-librespot[1668]: time="2026-02-25T09:13:08+07:00" level=trace msg="received dealer pong" Feb 25 09:13:08 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:13:08 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:13:08 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:13:08 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:13:18 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:13:18 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:13:18 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:13:18 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:13:18 volumio volumio[997]: info: Listing playlists Feb 25 09:13:18 volumio volumio[997]: info: Listing playlists Feb 25 09:13:28 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:13:28 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:13:28 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:13:28 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:13:37 volumio go-librespot[1668]: time="2026-02-25T09:13:37+07:00" level=trace msg="sent dealer ping" Feb 25 09:13:37 volumio go-librespot[1668]: time="2026-02-25T09:13:37+07:00" level=error msg="did not receive last pong ack from accesspoint, 924s passed" Feb 25 09:13:38 volumio go-librespot[1668]: time="2026-02-25T09:13:38+07:00" level=trace msg="received dealer pong" Feb 25 09:13:38 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:13:38 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:13:38 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:13:38 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:13:38 volumio volumio[997]: info: Listing playlists Feb 25 09:13:38 volumio volumio[997]: info: Listing playlists Feb 25 09:13:47 volumio nmbd[979]: [2026/02/25 09:13:47.673673, 0] ../../source3/nmbd/nmbd_namequery.c:109(query_name_response) Feb 25 09:13:47 volumio nmbd[979]: query_name_response: Multiple (2) responses received for a query on subnet 10.10.9.65 for name WORKGROUP<1d>. Feb 25 09:13:47 volumio nmbd[979]: This response was from IP 10.10.9.5, reporting an IP address of 10.10.9.5. Feb 25 09:13:48 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:13:48 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:13:48 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:13:48 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:13:54 volumio go-librespot[1668]: time="2026-02-25T09:13:54+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:80, retrying with a different AP" error="dial tcp 104.199.241.202:80: connect: connection refused" Feb 25 09:13:54 volumio go-librespot[1668]: time="2026-02-25T09:13:54+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 09:13:55 volumio go-librespot[1668]: time="2026-02-25T09:13:55+07:00" level=debug msg="completed keyexchange" Feb 25 09:13:55 volumio go-librespot[1668]: time="2026-02-25T09:13:55+07:00" level=debug msg="completed challenge" Feb 25 09:13:58 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:13:58 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:13:58 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:13:58 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:13:58 volumio volumio[997]: info: Listing playlists Feb 25 09:13:58 volumio volumio[997]: info: Listing playlists Feb 25 09:14:07 volumio go-librespot[1668]: time="2026-02-25T09:14:07+07:00" level=trace msg="sent dealer ping" Feb 25 09:14:08 volumio go-librespot[1668]: time="2026-02-25T09:14:08+07:00" level=trace msg="received dealer pong" Feb 25 09:14:08 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:14:08 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:14:08 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:14:08 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:14:18 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:14:18 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:14:18 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:14:18 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:14:18 volumio volumio[997]: info: Listing playlists Feb 25 09:14:18 volumio volumio[997]: info: Listing playlists Feb 25 09:14:28 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:14:28 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:14:28 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:14:28 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:14:31 volumio go-librespot[1668]: time="2026-02-25T09:14:31+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 09:14:31 volumio go-librespot[1668]: time="2026-02-25T09:14:31+07:00" level=debug msg="completed keyexchange" Feb 25 09:14:31 volumio go-librespot[1668]: time="2026-02-25T09:14:31+07:00" level=debug msg="completed challenge" Feb 25 09:14:31 volumio go-librespot[1668]: time="2026-02-25T09:14:31+07:00" level=error msg="failed reconnecting accesspoint" error="failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 09:14:31 volumio go-librespot[1668]: fatal error: sync: Unlock of unlocked RWMutex Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 574 gp=0xc00023ac40 m=5 mp=0xc0000d5008 [running]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.fatal({0xb74234?, 0x81d49a?}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/panic.go:1042 +0x5c fp=0xc0000e1de0 sp=0xc0000e1db0 pc=0x44063c Feb 25 09:14:31 volumio go-librespot[1668]: sync.fatal({0xb74234?, 0x81d4e0?}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/panic.go:1007 +0x18 fp=0xc0000e1e00 sp=0xc0000e1de0 pc=0x470b38 Feb 25 09:14:31 volumio go-librespot[1668]: sync.(*RWMutex).Unlock(0xc0001e4848) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/sync/rwmutex.go:208 +0x45 fp=0xc0000e1e38 sp=0xc0000e1e00 pc=0x482105 Feb 25 09:14:31 volumio go-librespot[1668]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0xc0001e4780) Feb 25 09:14:31 volumio go-librespot[1668]: /src/ap/ap.go:329 +0x4cb fp=0xc0000e1fc8 sp=0xc0000e1e38 pc=0x81e02b Feb 25 09:14:31 volumio go-librespot[1668]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Feb 25 09:14:31 volumio go-librespot[1668]: /src/ap/ap.go:390 +0x25 fp=0xc0000e1fe0 sp=0xc0000e1fc8 pc=0x81e805 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0000e1fe8 sp=0xc0000e1fe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 564 Feb 25 09:14:31 volumio go-librespot[1668]: /src/ap/ap.go:390 +0x108 Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 1 gp=0xc0000061c0 m=nil [select, 5578 minutes]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0xc000045c70?, 0x2?, 0x58?, 0xed?, 0xc000045c0c?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0000e5aa0 sp=0xc0000e5a80 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.selectgo(0xc0000e5c70, 0xc000045c08, 0x0?, 0x0, 0x40?, 0x1) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc0000e5bc0 sp=0xc0000e5aa0 pc=0x454865 Feb 25 09:14:31 volumio go-librespot[1668]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0xc00032cfa0, 0xc0000e5d80) Feb 25 09:14:31 volumio go-librespot[1668]: /src/zeroconf/zeroconf.go:283 +0x2a6 fp=0xc0000e5d08 sp=0xc0000e5bc0 pc=0x913c46 Feb 25 09:14:31 volumio go-librespot[1668]: main.(*App).withAppPlayer(0xc0001e4000, {0xc6e698, 0x1194a60}, 0xc00007da40) Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/main.go:321 +0x55c fp=0xc0000e5e10 sp=0xc0000e5d08 pc=0x9a9a5c Feb 25 09:14:31 volumio go-librespot[1668]: main.(*App).withCredentials(...) Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/main.go:193 Feb 25 09:14:31 volumio go-librespot[1668]: main.(*App).SpotifyToken(0xc0001e4000, {0xc6e698, 0x1194a60}, {0xc0000aed80?, 0x1c?}, {0xc00009a3c0?, 0x13c?}) Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/main.go:185 +0xcd fp=0xc0000e5e68 sp=0xc0000e5e10 pc=0x9a8f2d Feb 25 09:14:31 volumio go-librespot[1668]: main.main() Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/main.go:557 +0x698 fp=0xc0000e5f50 sp=0xc0000e5e68 pc=0x9aba78 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.main() Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:271 +0x29d fp=0xc0000e5fe0 sp=0xc0000e5f50 pc=0x4430bd Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0000e5fe8 sp=0xc0000e5fe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 2 gp=0xc000006fc0 m=nil [force gc (idle), 3 minutes]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0x13067f1039248?, 0x0?, 0x0?, 0x0?, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005efa8 sp=0xc00005ef88 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goparkunlock(...) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:408 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.forcegchelper() Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:326 +0xb3 fp=0xc00005efe0 sp=0xc00005efa8 pc=0x443373 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005efe8 sp=0xc00005efe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by runtime.init.6 in goroutine 1 Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:314 +0x1a Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 3 gp=0xc000007180 m=nil [GC sweep wait]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0x1?, 0x0?, 0x0?, 0x0?, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005f780 sp=0xc00005f760 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goparkunlock(...) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:408 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.bgsweep(0xc00007e000) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/mgcsweep.go:318 +0xdf fp=0xc00005f7c8 sp=0xc00005f780 pc=0x42ea9f Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gcenable.gowrap1() Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/mgc.go:203 +0x25 fp=0xc00005f7e0 sp=0xc00005f7c8 pc=0x4233a5 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005f7e8 sp=0xc00005f7e0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by runtime.gcenable in goroutine 1 Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/mgc.go:203 +0x66 Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 4 gp=0xc000007340 m=nil [GC scavenge wait]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0x1133de0?, 0x93c1b?, 0x0?, 0x0?, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005ff78 sp=0xc00005ff58 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goparkunlock(...) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:408 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.(*scavengerState).park(0x1133de0) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x49 fp=0xc00005ffa8 sp=0xc00005ff78 pc=0x42c449 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.bgscavenge(0xc00007e000) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x59 fp=0xc00005ffc8 sp=0xc00005ffa8 pc=0x42c9f9 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gcenable.gowrap2() Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/mgc.go:204 +0x25 fp=0xc00005ffe0 sp=0xc00005ffc8 pc=0x423345 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005ffe8 sp=0xc00005ffe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by runtime.gcenable in goroutine 1 Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/mgc.go:204 +0xa5 Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 5 gp=0xc000007880 m=nil [finalizer wait]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0x0?, 0xbcaa60?, 0x0?, 0x80?, 0x2000000020?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0003a7e20 sp=0xc0003a7e00 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.runfinq() Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/mfinal.go:194 +0x107 fp=0xc0003a7fe0 sp=0xc0003a7e20 pc=0x4223e7 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0003a7fe8 sp=0xc0003a7fe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by runtime.createfing in goroutine 1 Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/mfinal.go:164 +0x3d Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 8 gp=0xc00017d6c0 m=nil [IO wait, 5429 minutes]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0xc00004c008?, 0x0?, 0xc0?, 0xd6?, 0xc000071ab0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000071a78 sp=0xc000071a58 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.netpollblock(0xc000071b10?, 0x40c5c6?, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc000071ab0 sp=0xc000071a78 pc=0x43c257 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.runtime_pollWait(0x7fc7584c1e80, 0x72) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc000071ad0 sp=0xc000071ab0 pc=0x470225 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*pollDesc).wait(0x7?, 0x1?, 0x0) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000071af8 sp=0xc000071ad0 pc=0x4e0fa7 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*pollDesc).waitRead(...) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*FD).Accept(0xc0000acd00) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x2ac fp=0xc000071ba0 sp=0xc000071af8 pc=0x4e634c Feb 25 09:14:31 volumio go-librespot[1668]: net.(*netFD).accept(0xc0000acd00) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/fd_unix.go:172 +0x29 fp=0xc000071c58 sp=0xc000071ba0 pc=0x54eec9 Feb 25 09:14:31 volumio go-librespot[1668]: net.(*TCPListener).accept(0xc00007da00) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1e fp=0xc000071c80 sp=0xc000071c58 pc=0x5650de Feb 25 09:14:31 volumio go-librespot[1668]: net.(*TCPListener).Accept(0xc00007da00) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0xc000071cb0 sp=0xc000071c80 pc=0x5642d0 Feb 25 09:14:31 volumio go-librespot[1668]: net/http.(*onceCloseListener).Accept(0xc0002ff0e0?) Feb 25 09:14:31 volumio go-librespot[1668]: :1 +0x24 fp=0xc000071cc8 sp=0xc000071cb0 pc=0x7124e4 Feb 25 09:14:31 volumio go-librespot[1668]: net/http.(*Server).Serve(0xc0001e40f0, {0xc6d560, 0xc00007da00}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/http/server.go:3255 +0x33e fp=0xc000071df8 sp=0xc000071cc8 pc=0x6f043e Feb 25 09:14:31 volumio go-librespot[1668]: net/http.Serve(...) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/http/server.go:2794 Feb 25 09:14:31 volumio go-librespot[1668]: main.(*ConcreteApiServer).serve(0xc0001c6510) Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/api_server.go:630 +0x90c fp=0xc000071fc8 sp=0xc000071df8 pc=0x99f54c Feb 25 09:14:31 volumio go-librespot[1668]: main.NewApiServer.gowrap1() Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/api_server.go:296 +0x25 fp=0xc000071fe0 sp=0xc000071fc8 pc=0x99e265 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000071fe8 sp=0xc000071fe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by main.NewApiServer in goroutine 1 Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/api_server.go:296 +0x2ff Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 51 gp=0xc00017d880 m=nil [select, 5429 minutes]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0xc00005bf10?, 0x3?, 0x58?, 0xed?, 0xc00005bec2?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005bd68 sp=0xc00005bd48 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.selectgo(0xc00005bf10, 0xc00005bebc, 0x40e83a?, 0x0, 0xc00008b500?, 0x1) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc00005be88 sp=0xc00005bd68 pc=0x454865 Feb 25 09:14:31 volumio go-librespot[1668]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0xc000203500) Feb 25 09:14:31 volumio go-librespot[1668]: /src/player/player.go:201 +0x205 fp=0xc00005bfc8 sp=0xc00005be88 pc=0x887765 Feb 25 09:14:31 volumio go-librespot[1668]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Feb 25 09:14:31 volumio go-librespot[1668]: /src/player/player.go:183 +0x25 fp=0xc00005bfe0 sp=0xc00005bfc8 pc=0x887325 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005bfe8 sp=0xc00005bfe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Feb 25 09:14:31 volumio go-librespot[1668]: /src/player/player.go:183 +0x1da Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 16 gp=0xc0003d0380 m=nil [GC worker (idle), 3 minutes]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0x13067f1198a83?, 0x0?, 0xd0?, 0xf?, 0x7018ee?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000060f50 sp=0xc000060f30 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gcBgMarkWorker() Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/mgc.go:1310 +0xe5 fp=0xc000060fe0 sp=0xc000060f50 pc=0x425485 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000060fe8 sp=0xc000060fe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by runtime.gcBgMarkStartWorkers in goroutine 14 Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/mgc.go:1234 +0x1c Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 38 gp=0xc00023a1c0 m=nil [IO wait, 5578 minutes]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0x7fc758368b18?, 0x0?, 0x0?, 0x0?, 0xb?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0000df298 sp=0xc0000df278 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.netpollblock(0x486c18?, 0x40c5c6?, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc0000df2d0 sp=0xc0000df298 pc=0x43c257 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.runtime_pollWait(0x7fc7584c17b8, 0x72) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc0000df2f0 sp=0xc0000df2d0 pc=0x470225 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*pollDesc).wait(0xc0003e3600?, 0xc000348000?, 0x0) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc0000df318 sp=0xc0000df2f0 pc=0x4e0fa7 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*pollDesc).waitRead(...) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*FD).Read(0xc0003e3600, {0xc000348000, 0x1000, 0x1000}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc0000df3b0 sp=0xc0000df318 pc=0x4e229a Feb 25 09:14:31 volumio go-librespot[1668]: net.(*netFD).Read(0xc0003e3600, {0xc000348000?, 0x46ada5?, 0xe7791f701?}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/fd_posix.go:55 +0x25 fp=0xc0000df3f8 sp=0xc0000df3b0 pc=0x54cee5 Feb 25 09:14:31 volumio go-librespot[1668]: net.(*conn).Read(0xc00004a0e0, {0xc000348000?, 0x454010?, 0x4434ee?}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/net.go:179 +0x45 fp=0xc0000df440 sp=0xc0000df3f8 pc=0x55c185 Feb 25 09:14:31 volumio go-librespot[1668]: net.(*TCPConn).Read(0xc0000df590?, {0xc000348000?, 0xc0000df6a0?, 0x2?}) Feb 25 09:14:31 volumio go-librespot[1668]: :1 +0x25 fp=0xc0000df470 sp=0xc0000df440 pc=0x56db65 Feb 25 09:14:31 volumio go-librespot[1668]: io.(*multiReader).Read(0xc000328618, {0xc000348000, 0x1000, 0x1000}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/io/multi.go:26 +0x93 fp=0xc0000df4b8 sp=0xc0000df470 pc=0x4dc913 Feb 25 09:14:31 volumio go-librespot[1668]: bufio.(*Reader).fill(0xc0003c0960) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/bufio/bufio.go:110 +0x103 fp=0xc0000df4f0 sp=0xc0000df4b8 pc=0x672d63 Feb 25 09:14:31 volumio go-librespot[1668]: bufio.(*Reader).ReadByte(0xc0003c0960) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/bufio/bufio.go:269 +0x27 fp=0xc0000df510 sp=0xc0000df4f0 pc=0x673487 Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.readFrameHeader(0xc0003c0960, {0xc000165720, 0x8, 0x8}) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x8b fp=0xc0000df5a0 sp=0xc0000df510 pc=0x80a52b Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.(*Conn).readFrameHeader(0xc0001656c0, {0xc6e660, 0x1194a60}) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xd9 fp=0xc0000df6e0 sp=0xc0000df5a0 pc=0x80bf79 Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.(*Conn).readLoop(0xc0001656c0, {0xc6e660, 0x1194a60}) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x65 fp=0xc0000df840 sp=0xc0000df6e0 pc=0x80b8e5 Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.(*Conn).reader(0xc0001656c0, {0xc6e660, 0x1194a60}) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x125 fp=0xc0000df940 sp=0xc0000df840 pc=0x80cee5 Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.(*Conn).Reader(...) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.(*Conn).Read(0xc0001c6588?, {0xc6e660?, 0x1194a60?}) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x1e fp=0xc0000df970 sp=0xc0000df940 pc=0x80b25e Feb 25 09:14:31 volumio go-librespot[1668]: main.(*ConcreteApiServer).serve.func17({0xc6d770, 0xc000342000}, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/api_server.go:600 +0x3ae fp=0xc0000dfa68 sp=0xc0000df970 pc=0x99fd4e Feb 25 09:14:31 volumio go-librespot[1668]: net/http.HandlerFunc.ServeHTTP(0xc0001e88f0?, {0xc6d770?, 0xc000342000?}, 0xc0003e3600?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/http/server.go:2166 +0x29 fp=0xc0000dfa90 sp=0xc0000dfa68 pc=0x6ec9e9 Feb 25 09:14:31 volumio go-librespot[1668]: net/http.(*ServeMux).ServeHTTP(0xc0001e8c30?, {0xc6d770, 0xc000342000}, 0xc0001da480) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/http/server.go:2683 +0x1ad fp=0xc0000dfae0 sp=0xc0000dfa90 pc=0x6ee8cd Feb 25 09:14:31 volumio go-librespot[1668]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xc6d770, 0xc000342000}, 0xc0001da480) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x184 fp=0xc0000dfb38 sp=0xc0000dfae0 pc=0x99f7a4 Feb 25 09:14:31 volumio go-librespot[1668]: net/http.HandlerFunc.ServeHTTP(0x46ee19?, {0xc6d770?, 0xc000342000?}, 0xc00006cb68?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/http/server.go:2166 +0x29 fp=0xc0000dfb60 sp=0xc0000dfb38 pc=0x6ec9e9 Feb 25 09:14:31 volumio go-librespot[1668]: net/http.serverHandler.ServeHTTP({0xc0001cc7b0?}, {0xc6d770?, 0xc000342000?}, 0x6?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/http/server.go:3137 +0x8e fp=0xc0000dfb90 sp=0xc0000dfb60 pc=0x6f000e Feb 25 09:14:31 volumio go-librespot[1668]: net/http.(*conn).serve(0xc0003efcb0, {0xc6e6d0, 0xc0001fc4e0}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/http/server.go:2039 +0x5e8 fp=0xc0000dffb8 sp=0xc0000dfb90 pc=0x6eb3c8 Feb 25 09:14:31 volumio go-librespot[1668]: net/http.(*Server).Serve.gowrap3() Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/http/server.go:3285 +0x28 fp=0xc0000dffe0 sp=0xc0000dffb8 pc=0x6f0828 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0000dffe8 sp=0xc0000dffe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by net/http.(*Server).Serve in goroutine 8 Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/http/server.go:3285 +0x4b4 Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 40 gp=0xc00023a380 m=nil [select, 5429 minutes]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0xc00005b738?, 0x5?, 0x90?, 0xba?, 0xc00005b6e6?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005b588 sp=0xc00005b568 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.selectgo(0xc00005b738, 0xc00005b6dc, 0x41303d?, 0x0, 0x414c30?, 0x1) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc00005b6a8 sp=0xc00005b588 pc=0x454865 Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.(*Conn).timeoutLoop(0xc0001656c0) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x156 fp=0xc00005b7c8 sp=0xc00005b6a8 pc=0x807236 Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.newConn.gowrap1() Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x25 fp=0xc00005b7e0 sp=0xc00005b7c8 pc=0x806da5 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005b7e8 sp=0xc00005b7e0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by nhooyr.io/websocket.newConn in goroutine 38 Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x4c5 Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 26 gp=0xc00023a700 m=nil [IO wait]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0xc0003a8d88?, 0x41ed58?, 0x0?, 0x88?, 0xc00024ed20?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0003a8d30 sp=0xc0003a8d10 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.netpollblock(0xc665c0?, 0x10fcf28?, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc0003a8d68 sp=0xc0003a8d30 pc=0x43c257 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.runtime_pollWait(0x7fc7584c1b98, 0x72) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc0003a8d88 sp=0xc0003a8d68 pc=0x470225 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*pollDesc).wait(0xc0000ad280?, 0x7fc7584f1bb8?, 0x0) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc0003a8db0 sp=0xc0003a8d88 pc=0x4e0fa7 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*pollDesc).waitRead(...) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*FD).RawRead(0xc0000ad280, 0xc00024ed20) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x12a fp=0xc0003a8e10 sp=0xc0003a8db0 pc=0x4e6fca Feb 25 09:14:31 volumio go-librespot[1668]: net.(*rawConn).Read(0xc00004a368, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/rawconn.go:44 +0x36 fp=0xc0003a8e48 sp=0xc0003a8e10 pc=0x5600f6 Feb 25 09:14:31 volumio go-librespot[1668]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0xc0002411e0, 0xc000389140, 0x0) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x144 fp=0xc0003a8ea0 sp=0xc0003a8e48 pc=0x8b6324 Feb 25 09:14:31 volumio go-librespot[1668]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Feb 25 09:14:31 volumio go-librespot[1668]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0xc000301410, {0xc000480000, 0x10000, 0x10000}) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x4ae fp=0xc0003a8f48 sp=0xc0003a8ea0 pc=0x8b9dae Feb 25 09:14:31 volumio go-librespot[1668]: github.com/grandcat/zeroconf.(*Server).recv4(0xc00030fda0, 0xc000301400) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xd0 fp=0xc0003a8fc0 sp=0xc0003a8f48 pc=0x90e990 Feb 25 09:14:31 volumio go-librespot[1668]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x25 fp=0xc0003a8fe0 sp=0xc0003a8fc0 pc=0x90e625 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0003a8fe8 sp=0xc0003a8fe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 21 Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x78 Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 27 gp=0xc00023a8c0 m=nil [IO wait, 5578 minutes]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0xc00005bd38?, 0x41ed58?, 0x50?, 0x19?, 0xc000301bd0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000073ce0 sp=0xc000073cc0 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.netpollblock(0xc665c0?, 0x10fcf28?, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc000073d18 sp=0xc000073ce0 pc=0x43c257 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.runtime_pollWait(0x7fc7584c1aa0, 0x72) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc000073d38 sp=0xc000073d18 pc=0x470225 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*pollDesc).wait(0xc0000ad300?, 0x4166db?, 0x0) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000073d60 sp=0xc000073d38 pc=0x4e0fa7 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*pollDesc).waitRead(...) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*FD).RawRead(0xc0000ad300, 0xc000301bd0) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x12a fp=0xc000073dc0 sp=0xc000073d60 pc=0x4e6fca Feb 25 09:14:31 volumio go-librespot[1668]: net.(*rawConn).Read(0xc00004a378, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/rawconn.go:44 +0x36 fp=0xc000073df8 sp=0xc000073dc0 pc=0x5600f6 Feb 25 09:14:31 volumio go-librespot[1668]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0xc000241200, 0xc000388b40, 0x0) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x144 fp=0xc000073e50 sp=0xc000073df8 pc=0x8b6324 Feb 25 09:14:31 volumio go-librespot[1668]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Feb 25 09:14:31 volumio go-librespot[1668]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0xc000301460, {0xc0003ac000, 0x10000, 0x10000}) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x38d fp=0xc000073f48 sp=0xc000073e50 pc=0x8bd14d Feb 25 09:14:31 volumio go-librespot[1668]: github.com/grandcat/zeroconf.(*Server).recv6(0xc00030fda0, 0xc000301450) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xd0 fp=0xc000073fc0 sp=0xc000073f48 pc=0x90eb50 Feb 25 09:14:31 volumio go-librespot[1668]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x25 fp=0xc000073fe0 sp=0xc000073fc0 pc=0x90e5c5 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000073fe8 sp=0xc000073fe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 21 Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xd9 Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 52 gp=0xc00023aa80 m=nil [select, 425 minutes]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0xc00021dc20?, 0x8?, 0x8?, 0x0?, 0xc00021d4cc?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00021d148 sp=0xc00021d128 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.selectgo(0xc00021dc20, 0xc00021d4bc, 0xc000289300?, 0x0, 0x1113?, 0x1) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc00021d268 sp=0xc00021d148 pc=0x454865 Feb 25 09:14:31 volumio go-librespot[1668]: main.(*AppPlayer).Run(0xc0000ad380, {0xc6e698, 0x1194a60}, 0xc00035ea20) Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/player.go:590 +0x405 fp=0xc00021dfb0 sp=0xc00021d268 pc=0x9b0d45 Feb 25 09:14:31 volumio go-librespot[1668]: main.(*App).withAppPlayer.gowrap1() Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/main.go:258 +0x2c fp=0xc00021dfe0 sp=0xc00021dfb0 pc=0x9aa62c Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00021dfe8 sp=0xc00021dfe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by main.(*App).withAppPlayer in goroutine 1 Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/main.go:258 +0x379 Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 53 gp=0xc00023ae00 m=nil [chan receive, 5429 minutes]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0xc0003883c0?, 0xc000388180?, 0x0?, 0x0?, 0xc00005cee8?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005ce98 sp=0xc00005ce78 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.chanrecv(0xc00008e300, 0xc00005cf98, 0x1) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/chan.go:583 +0x3bf fp=0xc00005cf10 sp=0xc00005ce98 pc=0x40f43f Feb 25 09:14:31 volumio go-librespot[1668]: runtime.chanrecv2(0x0?, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/chan.go:447 +0x12 fp=0xc00005cf38 sp=0xc00005cf10 pc=0x40f072 Feb 25 09:14:31 volumio go-librespot[1668]: main.(*App).withAppPlayer.func1() Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/main.go:268 +0x72 fp=0xc00005cfe0 sp=0xc00005cf38 pc=0x9aa552 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005cfe8 sp=0xc00005cfe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by main.(*App).withAppPlayer in goroutine 1 Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/main.go:265 +0x419 Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 54 gp=0xc00023afc0 m=nil [chan receive, 5578 minutes]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005d680 sp=0xc00005d660 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.chanrecv(0xc00008e2a0, 0xc00005d7b8, 0x1) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/chan.go:583 +0x3bf fp=0xc00005d6f8 sp=0xc00005d680 pc=0x40f43f Feb 25 09:14:31 volumio go-librespot[1668]: runtime.chanrecv2(0x0?, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/chan.go:447 +0x12 fp=0xc00005d720 sp=0xc00005d6f8 pc=0x40f072 Feb 25 09:14:31 volumio go-librespot[1668]: main.(*App).withAppPlayer.func2() Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/main.go:284 +0x87 fp=0xc00005d7e0 sp=0xc00005d720 pc=0x9aa1a7 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005d7e8 sp=0xc00005d7e0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by main.(*App).withAppPlayer in goroutine 1 Feb 25 09:14:31 volumio go-librespot[1668]: /src/cmd/daemon/main.go:281 +0x4d9 Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 55 gp=0xc00023b180 m=nil [IO wait, 85 minutes]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0xc00004c008?, 0xc00006fc88?, 0x11?, 0xf8?, 0x2000?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00006fc08 sp=0xc00006fbe8 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.netpollblock(0xc00006fca0?, 0x40c5c6?, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc00006fc40 sp=0xc00006fc08 pc=0x43c257 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.runtime_pollWait(0x7fc7584c1c90, 0x72) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc00006fc60 sp=0xc00006fc40 pc=0x470225 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*pollDesc).wait(0x9?, 0x45?, 0x0) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc00006fc88 sp=0xc00006fc60 pc=0x4e0fa7 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*pollDesc).waitRead(...) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*FD).Accept(0xc0000aca80) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x2ac fp=0xc00006fd30 sp=0xc00006fc88 pc=0x4e634c Feb 25 09:14:31 volumio go-librespot[1668]: net.(*netFD).accept(0xc0000aca80) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/fd_unix.go:172 +0x29 fp=0xc00006fde8 sp=0xc00006fd30 pc=0x54eec9 Feb 25 09:14:31 volumio go-librespot[1668]: net.(*TCPListener).accept(0xc000241160) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1e fp=0xc00006fe10 sp=0xc00006fde8 pc=0x5650de Feb 25 09:14:31 volumio go-librespot[1668]: net.(*TCPListener).Accept(0xc000241160) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0xc00006fe40 sp=0xc00006fe10 pc=0x5642d0 Feb 25 09:14:31 volumio go-librespot[1668]: net/http.(*onceCloseListener).Accept(0xc000292000?) Feb 25 09:14:31 volumio go-librespot[1668]: :1 +0x24 fp=0xc00006fe58 sp=0xc00006fe40 pc=0x7124e4 Feb 25 09:14:31 volumio go-librespot[1668]: net/http.(*Server).Serve(0xc0001e5a40, {0xc6d560, 0xc000241160}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/http/server.go:3255 +0x33e fp=0xc00006ff88 sp=0xc00006fe58 pc=0x6f043e Feb 25 09:14:31 volumio go-librespot[1668]: net/http.Serve(...) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/http/server.go:2794 Feb 25 09:14:31 volumio go-librespot[1668]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Feb 25 09:14:31 volumio go-librespot[1668]: /src/zeroconf/zeroconf.go:280 +0x85 fp=0xc00006ffe0 sp=0xc00006ff88 pc=0x913d65 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00006ffe8 sp=0xc00006ffe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Feb 25 09:14:31 volumio go-librespot[1668]: /src/zeroconf/zeroconf.go:280 +0x165 Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 67 gp=0xc00023b340 m=nil [select]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0xc00006ef98?, 0x2?, 0x0?, 0x0?, 0xc00006ef6c?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00006ee18 sp=0xc00006edf8 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.selectgo(0xc00006ef98, 0xc00006ef68, 0x1133c40?, 0x0, 0x0?, 0x1) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc00006ef38 sp=0xc00006ee18 pc=0x454865 Feb 25 09:14:31 volumio go-librespot[1668]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0xc0001e4780) Feb 25 09:14:31 volumio go-librespot[1668]: /src/ap/ap.go:355 +0x7d fp=0xc00006efc8 sp=0xc00006ef38 pc=0x81e43d Feb 25 09:14:31 volumio go-librespot[1668]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1.gowrap2() Feb 25 09:14:31 volumio go-librespot[1668]: /src/ap/ap.go:264 +0x25 fp=0xc00006efe0 sp=0xc00006efc8 pc=0x81dac5 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00006efe8 sp=0xc00006efe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1 in goroutine 52 Feb 25 09:14:31 volumio go-librespot[1668]: /src/ap/ap.go:264 +0xff Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 501 gp=0xc00023b500 m=nil [IO wait]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0x13179c0326151276?, 0xd12ab6ad60c33eec?, 0x76?, 0x12?, 0xb?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0000e3410 sp=0xc0000e33f0 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.netpollblock(0x486c18?, 0x40c5c6?, 0x0?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc0000e3448 sp=0xc0000e3410 pc=0x43c257 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.runtime_pollWait(0x7fc7584c14d0, 0x72) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc0000e3468 sp=0xc0000e3448 pc=0x470225 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*pollDesc).wait(0xc00043f380?, 0xc0001e0000?, 0x0) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc0000e3490 sp=0xc0000e3468 pc=0x4e0fa7 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*pollDesc).waitRead(...) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Feb 25 09:14:31 volumio go-librespot[1668]: internal/poll.(*FD).Read(0xc00043f380, {0xc0001e0000, 0x1000, 0x1000}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc0000e3528 sp=0xc0000e3490 pc=0x4e229a Feb 25 09:14:31 volumio go-librespot[1668]: net.(*netFD).Read(0xc00043f380, {0xc0001e0000?, 0x7fc7584f1bb8?, 0xc000334210?}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/fd_posix.go:55 +0x25 fp=0xc0000e3570 sp=0xc0000e3528 pc=0x54cee5 Feb 25 09:14:31 volumio go-librespot[1668]: net.(*conn).Read(0xc00004a550, {0xc0001e0000?, 0xc0000e3650?, 0x4166db?}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/net.go:179 +0x45 fp=0xc0000e35b8 sp=0xc0000e3570 pc=0x55c185 Feb 25 09:14:31 volumio go-librespot[1668]: net.(*TCPConn).Read(0xb40560?, {0xc0001e0000?, 0xc0000e3650?, 0x4f72da?}) Feb 25 09:14:31 volumio go-librespot[1668]: :1 +0x25 fp=0xc0000e35e8 sp=0xc0000e35b8 pc=0x56db65 Feb 25 09:14:31 volumio go-librespot[1668]: crypto/tls.(*atLeastReader).Read(0xc000334210, {0xc0001e0000?, 0x0?, 0xc000334210?}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/crypto/tls/conn.go:806 +0x3b fp=0xc0000e3630 sp=0xc0000e35e8 pc=0x63285b Feb 25 09:14:31 volumio go-librespot[1668]: bytes.(*Buffer).ReadFrom(0xc0000f69b0, {0xc67d60, 0xc000334210}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/bytes/buffer.go:211 +0x98 fp=0xc0000e3688 sp=0xc0000e3630 pc=0x50e978 Feb 25 09:14:31 volumio go-librespot[1668]: crypto/tls.(*Conn).readFromUntil(0xc0000f6708, {0xc666c0, 0xc00004a550}, 0xc0000e3698?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/crypto/tls/conn.go:828 +0xde fp=0xc0000e36c0 sp=0xc0000e3688 pc=0x632a3e Feb 25 09:14:31 volumio go-librespot[1668]: crypto/tls.(*Conn).readRecordOrCCS(0xc0000f6708, 0x0) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/crypto/tls/conn.go:626 +0x3cf fp=0xc0000e3940 sp=0xc0000e36c0 pc=0x62fb4f Feb 25 09:14:31 volumio go-librespot[1668]: crypto/tls.(*Conn).readRecord(...) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/crypto/tls/conn.go:588 Feb 25 09:14:31 volumio go-librespot[1668]: crypto/tls.(*Conn).Read(0xc0000f6708, {0xc000255000, 0x1000, 0x2?}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/crypto/tls/conn.go:1370 +0x156 fp=0xc0000e39b0 sp=0xc0000e3940 pc=0x6363f6 Feb 25 09:14:31 volumio go-librespot[1668]: net/http.(*readWriteCloserBody).Read(0x0?, {0xc000255000?, 0x0?, 0xffffffffffffffff?}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/net/http/transport.go:2429 +0x88 fp=0xc0000e39e0 sp=0xc0000e39b0 pc=0x706da8 Feb 25 09:14:31 volumio go-librespot[1668]: bufio.(*Reader).fill(0xc0002527e0) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/bufio/bufio.go:110 +0x103 fp=0xc0000e3a18 sp=0xc0000e39e0 pc=0x672d63 Feb 25 09:14:31 volumio go-librespot[1668]: bufio.(*Reader).ReadByte(0xc0002527e0) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/bufio/bufio.go:269 +0x27 fp=0xc0000e3a38 sp=0xc0000e3a18 pc=0x673487 Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.readFrameHeader(0xc0002527e0, {0xc0001ed8c0, 0x8, 0x8}) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x8b fp=0xc0000e3ac8 sp=0xc0000e3a38 pc=0x80a52b Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.(*Conn).readFrameHeader(0xc0001ed860, {0xc6e660, 0x1194a60}) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xd9 fp=0xc0000e3c08 sp=0xc0000e3ac8 pc=0x80bf79 Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.(*Conn).readLoop(0xc0001ed860, {0xc6e660, 0x1194a60}) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x65 fp=0xc0000e3d68 sp=0xc0000e3c08 pc=0x80b8e5 Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.(*Conn).reader(0xc0001ed860, {0xc6e660, 0x1194a60}) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x125 fp=0xc0000e3e68 sp=0xc0000e3d68 pc=0x80cee5 Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.(*Conn).Reader(...) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.(*Conn).Read(0xc0002f2400?, {0xc6e660?, 0x1194a60?}) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x1e fp=0xc0000e3e98 sp=0xc0000e3e68 pc=0x80b25e Feb 25 09:14:31 volumio go-librespot[1668]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0xc000342c40) Feb 25 09:14:31 volumio go-librespot[1668]: /src/dealer/dealer.go:188 +0x67 fp=0xc0000e3fc8 sp=0xc0000e3e98 pc=0x8a32c7 Feb 25 09:14:31 volumio go-librespot[1668]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect.gowrap1() Feb 25 09:14:31 volumio go-librespot[1668]: /src/dealer/dealer.go:296 +0x25 fp=0xc0000e3fe0 sp=0xc0000e3fc8 pc=0x8a3e05 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0000e3fe8 sp=0xc0000e3fe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect in goroutine 459 Feb 25 09:14:31 volumio go-librespot[1668]: /src/dealer/dealer.go:296 +0xeb Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 500 gp=0xc00023ba40 m=nil [select]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0xc000287f38?, 0x5?, 0x30?, 0x7e?, 0xc000287ee6?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000287d88 sp=0xc000287d68 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.selectgo(0xc000287f38, 0xc000287edc, 0xc000384d80?, 0x0, 0xc000089740?, 0x1) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc000287ea8 sp=0xc000287d88 pc=0x454865 Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.(*Conn).timeoutLoop(0xc0001ed860) Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x156 fp=0xc000287fc8 sp=0xc000287ea8 pc=0x807236 Feb 25 09:14:31 volumio go-librespot[1668]: nhooyr.io/websocket.newConn.gowrap1() Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x25 fp=0xc000287fe0 sp=0xc000287fc8 pc=0x806da5 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000287fe8 sp=0xc000287fe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by nhooyr.io/websocket.newConn in goroutine 459 Feb 25 09:14:31 volumio go-librespot[1668]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x4c5 Feb 25 09:14:31 volumio go-librespot[1668]: goroutine 69 gp=0xc0003d01c0 m=nil [select]: Feb 25 09:14:31 volumio go-librespot[1668]: runtime.gopark(0xc000213f98?, 0x2?, 0x0?, 0x0?, 0xc000213f44?) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000213de8 sp=0xc000213dc8 pc=0x4434ee Feb 25 09:14:31 volumio go-librespot[1668]: runtime.selectgo(0xc000213f98, 0xc000213f40, 0xc0000c4a10?, 0x0, 0xc0003ec300?, 0x1) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc000213f08 sp=0xc000213de8 pc=0x454865 Feb 25 09:14:31 volumio go-librespot[1668]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0xc000342c40) Feb 25 09:14:31 volumio go-librespot[1668]: /src/dealer/dealer.go:138 +0x8f fp=0xc000213fc8 sp=0xc000213f08 pc=0x8a2f0f Feb 25 09:14:31 volumio go-librespot[1668]: github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1.gowrap2() Feb 25 09:14:31 volumio go-librespot[1668]: /src/dealer/dealer.go:129 +0x25 fp=0xc000213fe0 sp=0xc000213fc8 pc=0x8a2de5 Feb 25 09:14:31 volumio go-librespot[1668]: runtime.goexit({}) Feb 25 09:14:31 volumio go-librespot[1668]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000213fe8 sp=0xc000213fe0 pc=0x4754a1 Feb 25 09:14:31 volumio go-librespot[1668]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1 in goroutine 52 Feb 25 09:14:31 volumio go-librespot[1668]: /src/dealer/dealer.go:129 +0xf6 Feb 25 09:14:31 volumio volumio[997]: info: Connection to go-librespot Websocket closed Feb 25 09:14:31 volumio go-librespot[1667]: Aborted Feb 25 09:14:31 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Feb 25 09:14:31 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 09:14:31 volumio systemd[1]: go-librespot-daemon.service: Consumed 21.784s CPU time. Feb 25 09:14:34 volumio volumio[997]: info: Initializing connection to go-librespot Websocket Feb 25 09:14:34 volumio volumio[997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 09:14:35 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Feb 25 09:14:35 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 09:14:35 volumio systemd[1]: go-librespot-daemon.service: Consumed 21.784s CPU time. Feb 25 09:14:35 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 09:14:35 volumio go-librespot[862774]: go-librespot daemon starting... Feb 25 09:14:35 volumio go-librespot[862775]: time="2026-02-25T09:14:35+07:00" level=info msg="running go-librespot 0.4.0" Feb 25 09:14:35 volumio go-librespot[862775]: time="2026-02-25T09:14:35+07:00" level=debug msg="app state loaded" Feb 25 09:14:35 volumio go-librespot[862775]: time="2026-02-25T09:14:35+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 09:14:35 volumio go-librespot[862775]: time="2026-02-25T09:14:35+07: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]" Feb 25 09:14:35 volumio go-librespot[862775]: time="2026-02-25T09:14:35+07: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]" Feb 25 09:14:35 volumio go-librespot[862775]: time="2026-02-25T09:14:35+07: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]" Feb 25 09:14:35 volumio go-librespot[862775]: time="2026-02-25T09:14:35+07:00" level=info msg="zeroconf server listening on port 33675" Feb 25 09:14:35 volumio go-librespot[862775]: time="2026-02-25T09:14:35+07:00" level=debug msg="obtained new client token: AACOCWSzXQbfdKa/uwD0KM1GPN8/BjYk6mX9t1O2CZ+v+PQIUEm92Sd41LmapLGCyfWYYiFBbgTSh1jaWvTRAZrFwqva8D2MVrcoeFLNpeh4JlFvUCAZMGLSsg68SsoEoNP+nmW7tAsmZAdXHdNslnuAUH1VsWOnQ4eRSesWuUmx4A/t4kI/Fa6dqBDiYcEJAdBPGBoQMncQyC5x4uv8BgXK4+VV/2Kz/SfnxxcE+iJxrYCl+AgGVphWsls=" Feb 25 09:14:35 volumio go-librespot[862775]: time="2026-02-25T09:14:35+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 09:14:35 volumio go-librespot[862775]: time="2026-02-25T09:14:35+07:00" level=debug msg="completed keyexchange" Feb 25 09:14:35 volumio go-librespot[862775]: time="2026-02-25T09:14:35+07:00" level=debug msg="completed challenge" Feb 25 09:14:36 volumio go-librespot[862775]: time="2026-02-25T09:14:36+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 09:14:36 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 09:14:36 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 09:14:37 volumio volumio[997]: info: Initializing connection to go-librespot Websocket Feb 25 09:14:37 volumio volumio[997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 09:14:38 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:14:38 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:14:38 volumio volumio[997]: info: CoreCommandRouter::volumioGetState Feb 25 09:14:38 volumio volumio[997]: info: CorePlayQueue::getTrack 170 Feb 25 09:14:38 volumio volumio[997]: info: Listing playlists Feb 25 09:14:38 volumio volumio[997]: info: Listing playlists Feb 25 09:14:39 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Feb 25 09:14:39 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 09:14:39 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 09:14:39 volumio go-librespot[862785]: go-librespot daemon starting... Feb 25 09:14:39 volumio go-librespot[862786]: time="2026-02-25T09:14:39+07:00" level=info msg="running go-librespot 0.4.0" Feb 25 09:14:39 volumio go-librespot[862786]: time="2026-02-25T09:14:39+07:00" level=debug msg="app state loaded" Feb 25 09:14:39 volumio go-librespot[862786]: time="2026-02-25T09:14:39+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 09:14:39 volumio go-librespot[862786]: time="2026-02-25T09:14:39+07: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]" Feb 25 09:14:39 volumio go-librespot[862786]: time="2026-02-25T09:14:39+07: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]" Feb 25 09:14:39 volumio go-librespot[862786]: time="2026-02-25T09:14:39+07: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]" Feb 25 09:14:39 volumio go-librespot[862786]: time="2026-02-25T09:14:39+07:00" level=info msg="zeroconf server listening on port 38107" Feb 25 09:14:39 volumio go-librespot[862786]: time="2026-02-25T09:14:39+07:00" level=debug msg="obtained new client token: AADIcu5ne95rbHcPfP+7xXQR8jMH1gwRJAutkknqZqkmGmCxV7BQLRT4FzYkSNHjpl9txfe9O4pHJE1QRq7dUUbBxLkxkef0dmiQ1uSv/G0wrPe6wLbrvDHtQn84p0sqaerRdz4ieO9zW2B+o6l4WW4G1aLzcpIw7LLeASHm0oSPFkYlOretu0O/9WMFPaRc9lyKoemYcxcKZRIHgmuzwAChyc7e4NI9for/X0wxS7gJRcalsl+sMSWY2Ko=" Feb 25 09:14:39 volumio go-librespot[862786]: time="2026-02-25T09:14:39+07: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" Feb 25 09:14:39 volumio go-librespot[862786]: time="2026-02-25T09:14:39+07:00" level=debug msg="connected to ap-gew4.spotify.com:443" Feb 25 09:14:40 volumio go-librespot[862786]: time="2026-02-25T09:14:40+07:00" level=debug msg="completed keyexchange" Feb 25 09:14:40 volumio go-librespot[862786]: time="2026-02-25T09:14:40+07:00" level=debug msg="completed challenge" Feb 25 09:14:40 volumio go-librespot[862786]: time="2026-02-25T09:14:40+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 09:14:40 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 09:14:40 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 09:14:40 volumio volumio[997]: info: Initializing connection to go-librespot Websocket Feb 25 09:14:40 volumio volumio[997]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 09:14:43 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Feb 25 09:14:43 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 09:14:43 volumio go-librespot[862809]: go-librespot daemon starting... Feb 25 09:14:43 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 09:14:43 volumio go-librespot[862810]: time="2026-02-25T09:14:43+07:00" level=info msg="running go-librespot 0.4.0" Feb 25 09:14:43 volumio go-librespot[862810]: time="2026-02-25T09:14:43+07:00" level=debug msg="app state loaded" Feb 25 09:14:43 volumio go-librespot[862810]: time="2026-02-25T09:14:43+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 09:14:43 volumio go-librespot[862810]: time="2026-02-25T09:14:43+07: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]" Feb 25 09:14:43 volumio go-librespot[862810]: time="2026-02-25T09:14:43+07: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]" Feb 25 09:14:43 volumio go-librespot[862810]: time="2026-02-25T09:14:43+07: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]" Feb 25 09:14:43 volumio go-librespot[862810]: time="2026-02-25T09:14:43+07:00" level=info msg="zeroconf server listening on port 43195" Feb 25 09:14:43 volumio go-librespot[862810]: time="2026-02-25T09:14:43+07:00" level=debug msg="obtained new client token: AACf0jvmewI9eDbO9UlazlSgwLqv9R3g5LUCpQtr3Dnx5GP4vCHAFikBzWjBBai+RGIfbW4XLtNtvuReCpNOBxbNfMJeSMe0tsNS9eMwCCall8aPPXOhCkmOUfDO2jR4kyD2BAFLd38r7tzoR09e5Of4vdq3HuWqBMQiJBIAR1gcfYAM05cxCfWBxXjsjzoV+eC8ba75JXzdKO7fOzB8QRg7dUiIBQn+3Bg9Y/9LgdZmnp4HXbrM0IeiXp8=" Feb 25 09:14:43 volumio go-librespot[862810]: time="2026-02-25T09:14:43+07:00" level=debug msg="connected to ap-gew4.spotify.com:4070" Feb 25 09:14:43 volumio volumio[997]: info: Initializing connection to go-librespot Websocket Feb 25 09:14:43 volumio go-librespot[862810]: time="2026-02-25T09:14:43+07:00" level=debug msg="new websocket client" Feb 25 09:14:43 volumio go-librespot[862810]: time="2026-02-25T09:14:43+07:00" level=debug msg="completed keyexchange" Feb 25 09:14:43 volumio go-librespot[862810]: time="2026-02-25T09:14:43+07:00" level=debug msg="completed challenge" Feb 25 09:14:43 volumio volumio[997]: info: Connection to go-librespot Websocket established Feb 25 09:14:44 volumio go-librespot[862810]: time="2026-02-25T09:14:44+07:00" level=fatal msg="failed running with username and spotify token" error="failed authenticating accesspoint with stored credentials: failed authenticating: accesspoint login failed: TravelRestriction " Feb 25 09:14:44 volumio volumio[997]: info: Connection to go-librespot Websocket closed Feb 25 09:14:44 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Feb 25 09:14:44 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Feb 25 09:14:46 volumio volumio[997]: info: Getting Spotify volume Feb 25 09:14:47 volumio volumio[997]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 09:14:47 volumio volumio[997]: Error: connect ECONNREFUSED 127.0.0.1:9879 Feb 25 09:14:47 volumio volumio[997]: at TCPConnectWrap.afterConnect [as oncomplete] (node:net:1595:16) { Feb 25 09:14:47 volumio volumio[997]: errno: -111, Feb 25 09:14:47 volumio volumio[997]: code: 'ECONNREFUSED', Feb 25 09:14:47 volumio volumio[997]: syscall: 'connect', Feb 25 09:14:47 volumio volumio[997]: address: '127.0.0.1', Feb 25 09:14:47 volumio volumio[997]: port: 9879, Feb 25 09:14:47 volumio volumio[997]: response: undefined Feb 25 09:14:47 volumio volumio[997]: } Feb 25 09:14:47 volumio volumio[997]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Feb 25 09:14:47 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Feb 25 09:14:47 volumio systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Feb 25 09:14:47 volumio systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Feb 25 09:14:47 volumio go-librespot[862834]: go-librespot daemon starting... Feb 25 09:14:47 volumio go-librespot[862835]: time="2026-02-25T09:14:47+07:00" level=info msg="running go-librespot 0.4.0" Feb 25 09:14:47 volumio go-librespot[862835]: time="2026-02-25T09:14:47+07:00" level=debug msg="app state loaded" Feb 25 09:14:47 volumio go-librespot[862835]: time="2026-02-25T09:14:47+07:00" level=info msg="api server listening on 127.0.0.1:9879" Feb 25 09:14:47 volumio sudo[862833]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2026-02-25 09:13' Feb 25 09:14:47 volumio sudo[862833]: 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="a78d359cf1dc63ac74e9d151015afd30d31a709a" VOLUMIO_FE_VERSION="35f8f4439f0076a62fefa72fd80b70701b3d6cbd" VOLUMIO_FE3_VERSION="bcca17b6b6b26edfb999e6fd7da1b222a88a61d2" VOLUMIO_BE_VERSION="c1ffe93b2136013fc1b5903a4ed7e3f34e0a7bec" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Thu Feb 5 14:31:57 UTC 2026" VOLUMIO_VERSION="4.096" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="6759e875c98b942866984e844891d6c1"