Jul 06 15:45:00 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:00+07:00" level=error msg="did not receive last pong from dealer, 900s passed" Jul 06 15:45:19 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:19+07:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:45:19 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:19+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Jul 06 15:45:19 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:19+07:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:45:19 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:19+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Jul 06 15:45:19 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:19+07:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:45:19 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:19+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Jul 06 15:45:19 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:19+07:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:45:19 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:19+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Jul 06 15:45:19 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:19+07:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:45:19 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:19+07:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:4070, retrying with a different AP" error="dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Jul 06 15:45:20 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:20+07:00" level=warning msg="failed fetching new endpoint for accesspoint" error="failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:45:29 volumio-pi4-beta systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 06 15:45:30 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:30+07:00" level=error msg="did not receive last pong from dealer, 930s passed" Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:37+07:00" level=debug msg="renewing login5 access token" Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: time="2025-07-06T15:45:37+07:00" level=error msg="failed reconnecting dealer" error="failed obtaining dealer access token: failed renewing login5 access token: failed requesting login5 endpoint: failed requesting login5: Post \"https://login5.spotify.com/v3/login\": dial tcp: lookup login5.spotify.com: device or resource busy" Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: fatal error: sync: Unlock of unlocked RWMutex Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 897 gp=0x288e908 m=5 mp=0x2849608 [running]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.fatal({0x71be82, 0x20}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/panic.go:1042 +0x4c fp=0x28d4f08 sp=0x28d4ef4 pc=0x596e8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: sync.fatal({0x71be82, 0x20}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/panic.go:1007 +0x24 fp=0x28d4f14 sp=0x28d4f08 pc=0x8fdec Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: sync.(*RWMutex).Unlock(0x2a945d4) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/sync/rwmutex.go:208 +0x50 fp=0x28d4f2c sp=0x28d4f14 pc=0xa0da0 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0x2a94588) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/dealer/dealer.go:244 +0x758 fp=0x28d4fe4 sp=0x28d4f2c pc=0x4e9214 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect.gowrap1() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/dealer/dealer.go:295 +0x28 fp=0x28d4fec sp=0x28d4fe4 pc=0x4e9768 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x28d4fec sp=0x28d4fec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect in goroutine 347 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/dealer/dealer.go:295 +0xf4 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 1 gp=0x2802128 m=nil [select, 2479 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2b7dcfc sp=0x2b7dce8 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.selectgo(0x2b7ddfc, 0x2a9ddc8, 0x0, 0x0, 0x2, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2b7dd9c sp=0x2b7dcfc pc=0x70d80 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0x2a50240, 0x2b7de88) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/zeroconf/zeroconf.go:270 +0x228 fp=0x2b7de40 sp=0x2b7dd9c pc=0x55dd34 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: main.(*App).withAppPlayer(0x2a129c0, {0x7d7634, 0xbf6000}, 0x2a06320) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/main.go:316 +0x514 fp=0x2b7dec8 sp=0x2b7de40 pc=0x5f2dcc Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: main.(*App).withCredentials(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/main.go:190 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: main.(*App).SpotifyToken(0x2a129c0, {0x7d7634, 0xbf6000}, {0x2a8a580, 0x19}, {0x2ab0000, 0x12f}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/main.go:182 +0xd8 fp=0x2b7def8 sp=0x2b7dec8 pc=0x5f22c0 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: main.main() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/main.go:618 +0x61c fp=0x2b7dfa8 sp=0x2b7def8 pc=0x5f5a58 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.main() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:271 +0x2fc fp=0x2b7dfec sp=0x2b7dfa8 pc=0x5ca38 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2b7dfec sp=0x2b7dfec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 2 gp=0x2802c68 m=nil [force gc (idle), 4 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736864, 0xbca928, 0x11, 0xa, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2844fd4 sp=0x2844fc0 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goparkunlock(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:408 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.forcegchelper() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:326 +0xe4 fp=0x2844fec sp=0x2844fd4 pc=0x5ce04 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2844fec sp=0x2844fec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by runtime.init.5 in goroutine 1 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:314 +0x1c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 3 gp=0x2802d88 m=nil [GC sweep wait]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736864, 0xbcaee0, 0xc, 0x9, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x28457c4 sp=0x28457b0 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goparkunlock(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:408 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.bgsweep(0x2874000) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgcsweep.go:318 +0x11c fp=0x28457e4 sp=0x28457c4 pc=0x45750 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gcenable.gowrap1() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgc.go:203 +0x28 fp=0x28457ec sp=0x28457e4 pc=0x35870 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x28457ec sp=0x28457ec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by runtime.gcenable in goroutine 1 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgc.go:203 +0x74 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 4 gp=0x2802ea8 m=nil [GC scavenge wait]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736864, 0xbcb508, 0xd, 0xa, 0x2) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2845fb4 sp=0x2845fa0 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goparkunlock(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:408 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.(*scavengerState).park(0xbcb508) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x68 fp=0x2845fc8 sp=0x2845fb4 pc=0x42a70 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.bgscavenge(0x2874000) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x60 fp=0x2845fe4 sp=0x2845fc8 pc=0x431a8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gcenable.gowrap2() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgc.go:204 +0x28 fp=0x2845fec sp=0x2845fe4 pc=0x3581c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2845fec sp=0x2845fec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by runtime.gcenable in goroutine 1 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgc.go:204 +0xbc Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 18 gp=0x2906248 m=nil [finalizer wait, 261 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736710, 0xbf609c, 0x10, 0xa, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x284078c sp=0x2840778 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.runfinq() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mfinal.go:194 +0x110 fp=0x28407ec sp=0x284078c pc=0x3465c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x28407ec sp=0x28407ec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by runtime.createfing in goroutine 1 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mfinal.go:164 +0x5c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 36 gp=0x2a265a8 m=nil [IO wait, 2479 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736854, 0xb600df18, 0x2, 0x2, 0x5) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x28d3d28 sp=0x28d3d14 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.netpollblock(0xb600df08, 0x72, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x28d3d40 sp=0x28d3d28 pc=0x547d4 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.runtime_pollWait(0xb600df08, 0x72) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x28d3d54 sp=0x28d3d40 pc=0x8f388 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*pollDesc).wait(0x2a642e8, 0x72, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x28d3d68 sp=0x28d3d54 pc=0x10a2d8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*pollDesc).waitRead(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*FD).Accept(0x2a642d0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x28d3db0 sp=0x28d3d68 pc=0x10eb44 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net.(*netFD).accept(0x2a642d0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x28d3e18 sp=0x28d3db0 pc=0x17e32c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net.(*TCPListener).accept(0x2a4e420) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x28d3e30 sp=0x28d3e18 pc=0x19642c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net.(*TCPListener).Accept(0x2a4e420) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x28d3e4c sp=0x28d3e30 pc=0x195498 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net/http.(*onceCloseListener).Accept(0x2919380) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: :1 +0x34 fp=0x28d3e64 sp=0x28d3e4c pc=0x362680 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net/http.(*Server).Serve(0x292de08, {0x7d6e18, 0x2a4e420}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x28d3efc sp=0x28d3e64 pc=0x340d3c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net/http.Serve(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/http/server.go:2794 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: main.(*ConcreteApiServer).serve(0x2a50720) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/api_server.go:610 +0x974 fp=0x28d3fe4 sp=0x28d3efc pc=0x5e8bc0 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: main.NewApiServer.gowrap1() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/api_server.go:289 +0x28 fp=0x28d3fec sp=0x28d3fe4 pc=0x5e7a90 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x28d3fec sp=0x28d3fec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by main.NewApiServer in goroutine 1 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/api_server.go:289 +0x310 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 67 gp=0x2a266c8 m=nil [select, 2479 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2b1e674 sp=0x2b1e660 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.selectgo(0x2b1e774, 0x2b1e73c, 0x0, 0x0, 0x3, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2b1e714 sp=0x2b1e674 pc=0x70d80 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0x2ba4440) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/player/player.go:181 +0x1fc fp=0x2b1e7e4 sp=0x2b1e714 pc=0x4cbbd8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/player/player.go:163 +0x28 fp=0x2b1e7ec sp=0x2b1e7e4 pc=0x4cb7b8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2b1e7ec sp=0x2b1e7ec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 1 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/player/player.go:163 +0x1c8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 37 gp=0x2803448 m=nil [GC worker (idle), 516 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736720, 0x297f218, 0x1a, 0xa, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2844790 sp=0x284477c pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gcBgMarkWorker() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x28447ec sp=0x2844790 pc=0x38498 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x28447ec sp=0x28447ec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by runtime.gcBgMarkStartWorkers in goroutine 19 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 20 gp=0x2803568 m=nil [GC worker (idle)]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736720, 0x297f230, 0x1a, 0xa, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2846790 sp=0x284677c pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gcBgMarkWorker() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x28467ec sp=0x2846790 pc=0x38498 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x28467ec sp=0x28467ec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by runtime.gcBgMarkStartWorkers in goroutine 19 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 21 gp=0x2907568 m=nil [GC worker (idle), 16 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736720, 0x297f248, 0x1a, 0xa, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2840f90 sp=0x2840f7c pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gcBgMarkWorker() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x2840fec sp=0x2840f90 pc=0x38498 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2840fec sp=0x2840fec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by runtime.gcBgMarkStartWorkers in goroutine 19 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 22 gp=0x2907688 m=nil [GC worker (idle), 108 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736720, 0x297f260, 0x1a, 0xa, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2841790 sp=0x284177c pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gcBgMarkWorker() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgc.go:1310 +0xec fp=0x28417ec sp=0x2841790 pc=0x38498 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x28417ec sp=0x28417ec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by runtime.gcBgMarkStartWorkers in goroutine 19 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/mgc.go:1234 +0x20 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 11 gp=0x288e488 m=nil [IO wait]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736854, 0xb600dd38, 0x2, 0x2, 0x5) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x28d7e84 sp=0x28d7e70 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.netpollblock(0xb600dd28, 0x72, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x28d7e9c sp=0x28d7e84 pc=0x547d4 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.runtime_pollWait(0xb600dd28, 0x72) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x28d7eb0 sp=0x28d7e9c pc=0x8f388 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*pollDesc).wait(0x2816108, 0x72, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x28d7ec4 sp=0x28d7eb0 pc=0x10a2d8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*pollDesc).waitRead(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*FD).RawRead(0x28160f0, 0x2cfa6c0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x28d7ef0 sp=0x28d7ec4 pc=0x10f748 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net.(*rawConn).Read(0x2912338, 0x2cfa6c0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x28d7f10 sp=0x28d7ef0 pc=0x190854 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2c0e1f0, 0x2cfa690, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x28d7f40 sp=0x28d7f10 pc=0x4fe72c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0x2afc038, {0x2b30000, 0x10000, 0x10000}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x44c fp=0x28d7f9c sp=0x28d7f40 pc=0x502714 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/grandcat/zeroconf.(*Server).recv4(0x2a12640, 0x2afc030) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xec fp=0x28d7fe0 sp=0x28d7f9c pc=0x558fa4 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x30 fp=0x28d7fec sp=0x28d7fe0 pc=0x558c5c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x28d7fec sp=0x28d7fec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 50 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x7c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 12 gp=0x288e5a8 m=nil [IO wait, 2479 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736854, 0xb600dc98, 0x2, 0x2, 0x5) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x29c365c sp=0x29c3648 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.netpollblock(0xb600dc88, 0x72, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x29c3674 sp=0x29c365c pc=0x547d4 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.runtime_pollWait(0xb600dc88, 0x72) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x29c3688 sp=0x29c3674 pc=0x8f388 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*pollDesc).wait(0x2816158, 0x72, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x29c369c sp=0x29c3688 pc=0x10a2d8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*pollDesc).waitRead(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*FD).RawRead(0x2816140, 0x2a2a030) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x164 fp=0x29c36c8 sp=0x29c369c pc=0x10f748 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net.(*rawConn).Read(0x2912358, 0x2a2a030) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/rawconn.go:44 +0x38 fp=0x29c36e8 sp=0x29c36c8 pc=0x190854 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0x2c0e200, 0x2a2a000, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x154 fp=0x29c3718 sp=0x29c36e8 pc=0x4fe72c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0x2afc068, {0x2b20000, 0x10000, 0x10000}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x2ec fp=0x29c379c sp=0x29c3718 pc=0x50609c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/grandcat/zeroconf.(*Server).recv6(0x2a12640, 0x2afc060) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xec fp=0x29c37e0 sp=0x29c379c pc=0x559198 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x30 fp=0x29c37ec sp=0x29c37e0 pc=0x558c00 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x29c37ec sp=0x29c37ec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 50 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xe8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 48 gp=0x29070e8 m=nil [IO wait, 2479 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736854, 0xb600d8d8, 0x2, 0x2, 0x5) Jul 06 15:45:37 volumio-pi4-beta volumio[1037]: info: Connection to go-librespot Websocket closed Jul 06 15:45:37 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x285587c sp=0x2855868 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.netpollblock(0xb600d8c8, 0x72, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x2855894 sp=0x285587c pc=0x547d4 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.runtime_pollWait(0xb600d8c8, 0x72) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x28558a8 sp=0x2855894 pc=0x8f388 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*pollDesc).wait(0x2b8cba8, 0x72, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x28558bc sp=0x28558a8 pc=0x10a2d8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*pollDesc).waitRead(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*FD).Read(0x2b8cb90, {0x2b9a000, 0x1000, 0x1000}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x238 fp=0x2855904 sp=0x28558bc pc=0x10b4c4 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net.(*netFD).Read(0x2b8cb90, {0x2b9a000, 0x1000, 0x1000}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/fd_posix.go:55 +0x38 fp=0x2855930 sp=0x2855904 pc=0x17c438 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net.(*conn).Read(0x2cf62d0, {0x2b9a000, 0x1000, 0x1000}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/net.go:179 +0x48 fp=0x285595c sp=0x2855930 pc=0x18c618 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net.(*TCPConn).Read(0x2cf62d0, {0x2b9a000, 0x1000, 0x1000}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: :1 +0x44 fp=0x285597c sp=0x285595c pc=0x19f86c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: io.(*multiReader).Read(0x2cea340, {0x2b9a000, 0x1000, 0x1000}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/io/multi.go:26 +0xb4 fp=0x28559a8 sp=0x285597c pc=0x1054e8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: bufio.(*Reader).fill(0x2b8e8d0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/bufio/bufio.go:110 +0x10c fp=0x28559cc sp=0x28559a8 pc=0x2bd8e0 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: bufio.(*Reader).ReadByte(0x2b8e8d0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/bufio/bufio.go:269 +0x28 fp=0x28559d8 sp=0x28559cc pc=0x2be164 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: nhooyr.io/websocket.readFrameHeader(0x2b8e8d0, {0x2b04538, 0x8, 0x8}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x74 fp=0x2855a18 sp=0x28559d8 pc=0x470950 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: nhooyr.io/websocket.(*Conn).readFrameHeader(0x2b04508, {0x7d7614, 0xbf6000}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xb4 fp=0x2855ad8 sp=0x2855a18 pc=0x4735b4 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: nhooyr.io/websocket.(*Conn).readLoop(0x2b04508, {0x7d7614, 0xbf6000}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x3c fp=0x2855b9c sp=0x2855ad8 pc=0x47309c Jul 06 15:45:37 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: nhooyr.io/websocket.(*Conn).reader(0x2b04508, {0x7d7614, 0xbf6000}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x108 fp=0x2855c28 sp=0x2855b9c pc=0x4742b0 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: nhooyr.io/websocket.(*Conn).Reader(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: nhooyr.io/websocket.(*Conn).Read(0x2b04508, {0x7d7614, 0xbf6000}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x30 fp=0x2855c50 sp=0x2855c28 pc=0x472980 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: main.(*ConcreteApiServer).serve.func17({0x7d6f4c, 0x2c803c8}, 0x2c80328) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/api_server.go:580 +0x3c0 fp=0x2855ccc sp=0x2855c50 pc=0x5e9490 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net/http.HandlerFunc.ServeHTTP(0x2913568, {0x7d6f4c, 0x2c803c8}, 0x2c80328) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2855cdc sp=0x2855ccc pc=0x33d320 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net/http.(*ServeMux).ServeHTTP(0x291c780, {0x7d6f4c, 0x2c803c8}, 0x2c80328) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/http/server.go:2683 +0x208 fp=0x2855d18 sp=0x2855cdc pc=0x33f180 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0x7d6f4c, 0x2c803c8}, 0x2c80328) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x1f8 fp=0x2855d40 sp=0x2855d18 pc=0x5e8e90 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net/http.HandlerFunc.ServeHTTP(0x291f180, {0x7d6f4c, 0x2c803c8}, 0x2c80328) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/http/server.go:2166 +0x34 fp=0x2855d50 sp=0x2855d40 pc=0x33d320 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net/http.serverHandler.ServeHTTP({0x292de08}, {0x7d6f4c, 0x2c803c8}, 0x2c80328) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/http/server.go:3137 +0xe0 fp=0x2855d6c sp=0x2855d50 pc=0x340910 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net/http.(*conn).serve(0x2944600, {0x7d7654, 0x29164f8}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/http/server.go:2039 +0x61c fp=0x2855fdc sp=0x2855d6c pc=0x33bb60 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net/http.(*Server).Serve.gowrap3() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/http/server.go:3285 +0x38 fp=0x2855fec sp=0x2855fdc pc=0x3411b4 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2855fec sp=0x2855fec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by net/http.(*Server).Serve in goroutine 36 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/http/server.go:3285 +0x468 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 68 gp=0x29078c8 m=nil [select, 165 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 06 15:45:37 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Consumed 35.229s CPU time. Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2d05854 sp=0x2d05840 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.selectgo(0x2d05e10, 0x2d05a34, 0x0, 0x0, 0x8, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2d058f4 sp=0x2d05854 pc=0x70d80 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: main.(*AppPlayer).Run(0x2816190, {0x7d7634, 0xbf6000}, 0x2900bc0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/player.go:570 +0x370 fp=0x2d05fd8 sp=0x2d058f4 pc=0x5faf20 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: main.(*App).withAppPlayer.gowrap1() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/main.go:254 +0x40 fp=0x2d05fec sp=0x2d05fd8 pc=0x5f3a44 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2d05fec sp=0x2d05fec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by main.(*App).withAppPlayer in goroutine 1 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/main.go:254 +0x398 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 69 gp=0x2907b08 m=nil [chan receive, 2479 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x7366f4, 0x2a081b0, 0xe, 0x7, 0x2) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2b1f748 sp=0x2b1f734 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.chanrecv(0x2a08180, 0x2b1f7d0, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x2b1f784 sp=0x2b1f748 pc=0x1ff80 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.chanrecv2(0x2a08180, 0x2b1f7d0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x2b1f798 sp=0x2b1f784 pc=0x1fac4 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: main.(*App).withAppPlayer.func1() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/main.go:264 +0x68 fp=0x2b1f7ec sp=0x2b1f798 pc=0x5f3964 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2b1f7ec sp=0x2b1f7ec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by main.(*App).withAppPlayer in goroutine 1 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/main.go:261 +0x424 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 70 gp=0x2907c28 m=nil [chan receive, 2479 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x7366f4, 0x2a08170, 0xe, 0x7, 0x2) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2b1ff38 sp=0x2b1ff24 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.chanrecv(0x2a08140, 0x2b1ffe0, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/chan.go:583 +0x4b0 fp=0x2b1ff74 sp=0x2b1ff38 pc=0x1ff80 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.chanrecv2(0x2a08140, 0x2b1ffe0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/chan.go:447 +0x20 fp=0x2b1ff88 sp=0x2b1ff74 pc=0x1fac4 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: main.(*App).withAppPlayer.func2() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/main.go:280 +0x6c fp=0x2b1ffec sp=0x2b1ff88 pc=0x5f352c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2b1ffec sp=0x2b1ffec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by main.(*App).withAppPlayer in goroutine 1 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/cmd/daemon/main.go:277 +0x4c4 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 71 gp=0x2907d48 m=nil [IO wait, 165 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736854, 0xb600ddd8, 0x2, 0x2, 0x5) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x29bf5e8 sp=0x29bf5d4 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.netpollblock(0xb600ddc8, 0x72, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/netpoll.go:573 +0x100 fp=0x29bf600 sp=0x29bf5e8 pc=0x547d4 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.runtime_pollWait(0xb600ddc8, 0x72) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/netpoll.go:345 +0x54 fp=0x29bf614 sp=0x29bf600 pc=0x8f388 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*pollDesc).wait(0x28160b8, 0x72, 0x0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x30 fp=0x29bf628 sp=0x29bf614 pc=0x10a2d8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*pollDesc).waitRead(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: internal/poll.(*FD).Accept(0x28160a0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x294 fp=0x29bf670 sp=0x29bf628 pc=0x10eb44 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net.(*netFD).accept(0x28160a0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/fd_unix.go:172 +0x20 fp=0x29bf6d8 sp=0x29bf670 pc=0x17e32c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net.(*TCPListener).accept(0x28e00f0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x20 fp=0x29bf6f0 sp=0x29bf6d8 pc=0x19642c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net.(*TCPListener).Accept(0x28e00f0) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0x29bf70c sp=0x29bf6f0 pc=0x195498 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net/http.(*onceCloseListener).Accept(0x2919d20) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: :1 +0x34 fp=0x29bf724 sp=0x29bf70c pc=0x362680 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net/http.(*Server).Serve(0x292c968, {0x7d6e18, 0x28e00f0}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/http/server.go:3255 +0x314 fp=0x29bf7bc sp=0x29bf724 pc=0x340d3c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: net/http.Serve(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/net/http/server.go:2794 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/zeroconf/zeroconf.go:267 +0x8c fp=0x29bf7ec sp=0x29bf7bc pc=0x55de30 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x29bf7ec sp=0x29bf7ec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/zeroconf/zeroconf.go:267 +0x160 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 98 gp=0x2a26908 m=nil [select, 2479 minutes]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x2b186a8 sp=0x2b18694 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.selectgo(0x2b187a4, 0x2b1876c, 0x0, 0x0, 0x5, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x2b18748 sp=0x2b186a8 pc=0x70d80 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: nhooyr.io/websocket.(*Conn).timeoutLoop(0x2b04508) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x10c fp=0x2b187e4 sp=0x2b18748 pc=0x46d7fc Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: nhooyr.io/websocket.newConn.gowrap1() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x28 fp=0x2b187ec sp=0x2b187e4 pc=0x46d3f0 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x2b187ec sp=0x2b187ec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by nhooyr.io/websocket.newConn in goroutine 48 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x544 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 75 gp=0x2bae008 m=nil [select]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x28d1eb8 sp=0x28d1ea4 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.selectgo(0x28d1fd4, 0x28d1f7c, 0x0, 0x0, 0x2, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x28d1f58 sp=0x28d1eb8 pc=0x70d80 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0x2a94218) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/ap/ap.go:355 +0x8c fp=0x28d1fe4 sp=0x28d1f58 pc=0x4859a8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1.gowrap2() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/ap/ap.go:264 +0x28 fp=0x28d1fec sp=0x28d1fe4 pc=0x4850a0 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x28d1fec sp=0x28d1fec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).Receive.(*Accesspoint).startReceiving.func1 in goroutine 68 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/ap/ap.go:264 +0x15c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 77 gp=0x2bae248 m=nil [select]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x28d0e98 sp=0x28d0e84 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.selectgo(0x28d0fd4, 0x28d0f60, 0x0, 0x0, 0x2, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x28d0f38 sp=0x28d0e98 pc=0x70d80 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0x2a94588) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/dealer/dealer.go:138 +0x8c fp=0x28d0fe4 sp=0x28d0f38 pc=0x4e868c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1.gowrap2() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/dealer/dealer.go:129 +0x28 fp=0x28d0fec sp=0x28d0fe4 pc=0x4ea594 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x28d0fec sp=0x28d0fec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).ReceiveMessage.(*Dealer).startReceiving.func1 in goroutine 68 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/dealer/dealer.go:129 +0x15c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: goroutine 962 gp=0x2c01c28 m=nil [select]: Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.gopark(0x736898, 0x0, 0x9, 0x3, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/proc.go:402 +0x104 fp=0x285bdb8 sp=0x285bda4 pc=0x5cfc8 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.selectgo(0x285beac, 0x285be80, 0x0, 0x0, 0x2, 0x1) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/select.go:327 +0xaf0 fp=0x285be58 sp=0x285bdb8 pc=0x70d80 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0x285befc, {0x7d65a0, 0x29454a0}, 0x0, {0x0, 0x0}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:112 +0x260 fp=0x285bed4 sp=0x285be58 pc=0x479890 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0x285bf6c, {0x7d65a0, 0x29454a0}, 0x0, {0x0, 0x0}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:61 +0x6c fp=0x285bf04 sp=0x285bed4 pc=0x479200 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/cenkalti/backoff/v4.RetryNotify(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:49 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/cenkalti/backoff/v4.Retry(...) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:38 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0x2a94218) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/ap/ap.go:322 +0x430 fp=0x285bfe4 sp=0x285bf04 pc=0x485550 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/ap/ap.go:390 +0x28 fp=0x285bfec sp=0x285bfe4 pc=0x485e68 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: runtime.goexit({}) Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /usr/local/go/src/runtime/asm_arm.s:859 +0x4 fp=0x285bfec sp=0x285bfec pc=0x93c4c Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 345 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1394]: /src/ap/ap.go:390 +0x120 Jul 06 15:45:37 volumio-pi4-beta go-librespot[1391]: Aborted Jul 06 15:45:40 volumio-pi4-beta volumio[1037]: info: Initializing connection to go-librespot Websocket Jul 06 15:45:40 volumio-pi4-beta volumio[1037]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 15:45:40 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jul 06 15:45:40 volumio-pi4-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:40 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Consumed 35.229s CPU time. Jul 06 15:45:40 volumio-pi4-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:40 volumio-pi4-beta go-librespot[4916]: go-librespot daemon starting... Jul 06 15:45:40 volumio-pi4-beta go-librespot[4917]: time="2025-07-06T15:45:40+07:00" level=info msg="running go-librespot 0.2.0" Jul 06 15:45:40 volumio-pi4-beta go-librespot[4917]: time="2025-07-06T15:45:40+07:00" level=debug msg="app state loaded" Jul 06 15:45:40 volumio-pi4-beta go-librespot[4917]: time="2025-07-06T15:45:40+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 15:45:40 volumio-pi4-beta go-librespot[4917]: time="2025-07-06T15:45:40+07:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:45:40 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 15:45:40 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:45:43 volumio-pi4-beta volumio[1037]: info: Initializing connection to go-librespot Websocket Jul 06 15:45:43 volumio-pi4-beta volumio[1037]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 15:45:43 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jul 06 15:45:43 volumio-pi4-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:43 volumio-pi4-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:43 volumio-pi4-beta go-librespot[4925]: go-librespot daemon starting... Jul 06 15:45:43 volumio-pi4-beta go-librespot[4926]: time="2025-07-06T15:45:43+07:00" level=info msg="running go-librespot 0.2.0" Jul 06 15:45:43 volumio-pi4-beta go-librespot[4926]: time="2025-07-06T15:45:43+07:00" level=debug msg="app state loaded" Jul 06 15:45:43 volumio-pi4-beta go-librespot[4926]: time="2025-07-06T15:45:43+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 15:45:43 volumio-pi4-beta go-librespot[4926]: time="2025-07-06T15:45:43+07:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:45:43 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 15:45:43 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:45:46 volumio-pi4-beta volumio[1037]: info: Initializing connection to go-librespot Websocket Jul 06 15:45:46 volumio-pi4-beta volumio[1037]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 15:45:46 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jul 06 15:45:46 volumio-pi4-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:46 volumio-pi4-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:46 volumio-pi4-beta go-librespot[4946]: go-librespot daemon starting... Jul 06 15:45:46 volumio-pi4-beta go-librespot[4947]: time="2025-07-06T15:45:46+07:00" level=info msg="running go-librespot 0.2.0" Jul 06 15:45:46 volumio-pi4-beta go-librespot[4947]: time="2025-07-06T15:45:46+07:00" level=debug msg="app state loaded" Jul 06 15:45:46 volumio-pi4-beta go-librespot[4947]: time="2025-07-06T15:45:46+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 15:45:47 volumio-pi4-beta go-librespot[4947]: time="2025-07-06T15:45:47+07:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:45:47 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 15:45:47 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:45:49 volumio-pi4-beta volumio[1037]: info: Initializing connection to go-librespot Websocket Jul 06 15:45:49 volumio-pi4-beta volumio[1037]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 15:45:50 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jul 06 15:45:50 volumio-pi4-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:50 volumio-pi4-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:50 volumio-pi4-beta go-librespot[4954]: go-librespot daemon starting... Jul 06 15:45:50 volumio-pi4-beta go-librespot[4955]: time="2025-07-06T15:45:50+07:00" level=info msg="running go-librespot 0.2.0" Jul 06 15:45:50 volumio-pi4-beta go-librespot[4955]: time="2025-07-06T15:45:50+07:00" level=debug msg="app state loaded" Jul 06 15:45:50 volumio-pi4-beta go-librespot[4955]: time="2025-07-06T15:45:50+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 15:45:50 volumio-pi4-beta go-librespot[4955]: time="2025-07-06T15:45:50+07:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:45:50 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 15:45:50 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:45:52 volumio-pi4-beta volumio[1037]: info: Initializing connection to go-librespot Websocket Jul 06 15:45:52 volumio-pi4-beta volumio[1037]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 15:45:53 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 5. Jul 06 15:45:53 volumio-pi4-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:53 volumio-pi4-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:53 volumio-pi4-beta go-librespot[4964]: go-librespot daemon starting... Jul 06 15:45:53 volumio-pi4-beta go-librespot[4965]: time="2025-07-06T15:45:53+07:00" level=info msg="running go-librespot 0.2.0" Jul 06 15:45:53 volumio-pi4-beta go-librespot[4965]: time="2025-07-06T15:45:53+07:00" level=debug msg="app state loaded" Jul 06 15:45:53 volumio-pi4-beta go-librespot[4965]: time="2025-07-06T15:45:53+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 15:45:53 volumio-pi4-beta go-librespot[4965]: time="2025-07-06T15:45:53+07:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:45:53 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 15:45:53 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:45:55 volumio-pi4-beta volumio[1037]: info: Initializing connection to go-librespot Websocket Jul 06 15:45:55 volumio-pi4-beta volumio[1037]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 15:45:56 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 6. Jul 06 15:45:56 volumio-pi4-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:56 volumio-pi4-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:56 volumio-pi4-beta go-librespot[4985]: go-librespot daemon starting... Jul 06 15:45:56 volumio-pi4-beta go-librespot[4986]: time="2025-07-06T15:45:56+07:00" level=info msg="running go-librespot 0.2.0" Jul 06 15:45:56 volumio-pi4-beta go-librespot[4986]: time="2025-07-06T15:45:56+07:00" level=debug msg="app state loaded" Jul 06 15:45:56 volumio-pi4-beta go-librespot[4986]: time="2025-07-06T15:45:56+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 15:45:56 volumio-pi4-beta go-librespot[4986]: time="2025-07-06T15:45:56+07:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:45:56 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 15:45:56 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:45:58 volumio-pi4-beta volumio[1037]: info: Initializing connection to go-librespot Websocket Jul 06 15:45:58 volumio-pi4-beta volumio[1037]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 15:45:59 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 7. Jul 06 15:45:59 volumio-pi4-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:59 volumio-pi4-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:59 volumio-pi4-beta go-librespot[4993]: go-librespot daemon starting... Jul 06 15:45:59 volumio-pi4-beta systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 06 15:45:59 volumio-pi4-beta go-librespot[4994]: time="2025-07-06T15:45:59+07:00" level=info msg="running go-librespot 0.2.0" Jul 06 15:45:59 volumio-pi4-beta go-librespot[4994]: time="2025-07-06T15:45:59+07:00" level=debug msg="app state loaded" Jul 06 15:45:59 volumio-pi4-beta go-librespot[4994]: time="2025-07-06T15:45:59+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 15:45:59 volumio-pi4-beta dbus-daemon[632]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.3347' (uid=0 pid=5002 comm="timedatectl show --property=NTPSynchronized --valu") Jul 06 15:45:59 volumio-pi4-beta systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 06 15:45:59 volumio-pi4-beta go-librespot[4994]: time="2025-07-06T15:45:59+07:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:45:59 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 15:45:59 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:46:00 volumio-pi4-beta dbus-daemon[632]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 06 15:46:00 volumio-pi4-beta systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 06 15:46:00 volumio-pi4-beta setdatetime-helper.sh[4995]: Time is already synchronized. Jul 06 15:46:00 volumio-pi4-beta systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 06 15:46:00 volumio-pi4-beta systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 06 15:46:01 volumio-pi4-beta volumio[1037]: info: Initializing connection to go-librespot Websocket Jul 06 15:46:01 volumio-pi4-beta volumio[1037]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 15:46:03 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 8. Jul 06 15:46:03 volumio-pi4-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:46:03 volumio-pi4-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:46:03 volumio-pi4-beta go-librespot[5005]: go-librespot daemon starting... Jul 06 15:46:03 volumio-pi4-beta go-librespot[5006]: time="2025-07-06T15:46:03+07:00" level=info msg="running go-librespot 0.2.0" Jul 06 15:46:03 volumio-pi4-beta go-librespot[5006]: time="2025-07-06T15:46:03+07:00" level=debug msg="app state loaded" Jul 06 15:46:03 volumio-pi4-beta go-librespot[5006]: time="2025-07-06T15:46:03+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 15:46:03 volumio-pi4-beta go-librespot[5006]: time="2025-07-06T15:46:03+07:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:46:03 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 15:46:03 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:46:04 volumio-pi4-beta volumio[1037]: info: Initializing connection to go-librespot Websocket Jul 06 15:46:04 volumio-pi4-beta volumio[1037]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 15:46:06 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 9. Jul 06 15:46:06 volumio-pi4-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:46:06 volumio-pi4-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:46:06 volumio-pi4-beta go-librespot[5026]: go-librespot daemon starting... Jul 06 15:46:06 volumio-pi4-beta go-librespot[5027]: time="2025-07-06T15:46:06+07:00" level=info msg="running go-librespot 0.2.0" Jul 06 15:46:06 volumio-pi4-beta go-librespot[5027]: time="2025-07-06T15:46:06+07:00" level=debug msg="app state loaded" Jul 06 15:46:06 volumio-pi4-beta go-librespot[5027]: time="2025-07-06T15:46:06+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 15:46:07 volumio-pi4-beta volumio[1037]: info: Initializing connection to go-librespot Websocket Jul 06 15:46:07 volumio-pi4-beta go-librespot[5027]: time="2025-07-06T15:46:07+07:00" level=debug msg="new websocket client" Jul 06 15:46:07 volumio-pi4-beta volumio[1037]: info: Connection to go-librespot Websocket established Jul 06 15:46:10 volumio-pi4-beta volumio[1037]: info: Getting Spotify volume Jul 06 15:46:10 volumio-pi4-beta volumio[1037]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 11 Jul 06 15:46:10 volumio-pi4-beta volumio[1037]: info: CoreCommandRouter::volumioGetState Jul 06 15:46:10 volumio-pi4-beta volumio[1037]: info: CorePlayQueue::getTrack 0 Jul 06 15:46:10 volumio-pi4-beta volumio[1037]: SPOTIFY: RECEIVED VOLUMIO VOLUME 100 Jul 06 15:46:30 volumio-pi4-beta systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 06 15:46:34 volumio-pi4-beta go-librespot[5027]: time="2025-07-06T15:46:34+07:00" level=fatal msg="failed running with username and spotify token" error="failed getting endpoints from resolver: failed fetching apresolve URL: Get \"https://apresolve.spotify.com/?type=accesspoint&type=dealer&type=spclient\": dial tcp: lookup apresolve.spotify.com: device or resource busy" Jul 06 15:46:34 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 15:46:34 volumio-pi4-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:46:34 volumio-pi4-beta volumio[1037]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 15:46:34 volumio-pi4-beta volumio[1037]: Error: socket hang up Jul 06 15:46:34 volumio-pi4-beta volumio[1037]: at connResetException (node:internal/errors:720:14) Jul 06 15:46:34 volumio-pi4-beta volumio[1037]: at Socket.socketOnEnd (node:_http_client:519:23) Jul 06 15:46:34 volumio-pi4-beta volumio[1037]: at Socket.emit (node:events:526:35) Jul 06 15:46:34 volumio-pi4-beta volumio[1037]: at endReadableNT (node:internal/streams/readable:1376:12) Jul 06 15:46:34 volumio-pi4-beta volumio[1037]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jul 06 15:46:34 volumio-pi4-beta volumio[1037]: code: 'ECONNRESET', Jul 06 15:46:34 volumio-pi4-beta volumio[1037]: response: undefined Jul 06 15:46:34 volumio-pi4-beta volumio[1037]: } Jul 06 15:46:34 volumio-pi4-beta volumio[1037]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 15:46:34 volumio-pi4-beta sudo[5079]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-06 15:45' Jul 06 15:46:34 volumio-pi4-beta sudo[5079]: 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="f9454895cc8e2f1971c705a815a7226e021e37d7" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="1fc282af1865fae9b40239cedf2cc17c112eef0d" VOLUMIO_ARCH="arm" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jun 28 16:48:46 UTC 2025" VOLUMIO_VERSION="4.014" VOLUMIO_HARDWARE="pi" VOLUMIO_DEVICENAME="Raspberry Pi" VOLUMIO_HASH="1b557e4869cd2eea14f4113140ecea1c"