-- Logs begin at Tue 2025-03-18 14:21:02 -08, end at Tue 2025-03-18 15:18:00 -08. --
Mar 18 15:17:05 volumio go-librespot[1770]: time="2025-03-18T15:17:05-08:00" level=error msg="did not receive last pong from dealer, 180s passed"
Mar 18 15:17:11 volumio ntpd[1290]: Soliciting pool server 2406:2000:fc:437::1001
Mar 18 15:17:13 volumio go-librespot[1770]: time="2025-03-18T15:17:13-08:00" level=warning msg="failed to connect to AP ap-gae2.spotify.com:443 (error: dial tcp: lookup ap-gae2.spotify.com: device or resource busy), retrying with a different AP"
Mar 18 15:17:14 volumio kernel: CIFS: VFS: \\192.168.8.117 has not responded in 180 seconds. Reconnecting...
Mar 18 15:17:18 volumio ntpd[1290]: error resolving pool 0.debian.pool.ntp.org: System error (-11)
Mar 18 15:17:32 volumio dhcpcd[917]: wlan0: offered 192.168.8.121 from 192.168.8.18
Mar 18 15:17:32 volumio dhcpcd[917]: wlan0: probing address 192.168.8.121/24
Mar 18 15:17:33 volumio go-librespot[1770]: time="2025-03-18T15:17:33-08:00" level=error msg="did not receive last pong ack from accesspoint, 308s passed"
Mar 18 15:17:33 volumio go-librespot[1770]: panic: runtime error: invalid memory address or nil pointer dereference
Mar 18 15:17:33 volumio go-librespot[1770]: [signal SIGSEGV: segmentation violation code=0x1 addr=0x18 pc=0x824a89]
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 35 gp=0xc000230e00 m=4 mp=0xc000065808 [running]:
Mar 18 15:17:33 volumio go-librespot[1770]: panic({0x98d8a0?, 0xeac160?})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:779 +0x158 fp=0xc000366ee0 sp=0xc000366e30 pc=0x441a58
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.panicmem(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/panic.go:261
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.sigpanic()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/signal_unix.go:881 +0x378 fp=0xc000366f40 sp=0xc000366ee0 pc=0x45a338
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/ap.(*Accesspoint).pongAckTicker(0xc00020aa90)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:328 +0x189 fp=0xc000366fc8 sp=0xc000366f40 pc=0x824a89
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/ap.NewAccesspoint.gowrap1()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:67 +0x25 fp=0xc000366fe0 sp=0xc000366fc8 pc=0x822c25
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000366fe8 sp=0xc000366fe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by go-librespot/ap.NewAccesspoint in goroutine 1
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:67 +0x159
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 1 gp=0xc0000061c0 m=nil [select, 56 minutes]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0xc000045c58?, 0x2?, 0x18?, 0xd?, 0xc000045bf4?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc0001f5a88 sp=0xc0001f5a68 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.selectgo(0xc0001f5c58, 0xc000045bf0, 0xc000045c40?, 0x0, 0x30?, 0x1)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0x725 fp=0xc0001f5ba8 sp=0xc0001f5a88 pc=0x456825
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/zeroconf.(*Zeroconf).Serve(0xc0002103f0, 0xc0001f5d50)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:268 +0x2a6 fp=0xc0001f5cf0 sp=0xc0001f5ba8 pc=0x8b9846
Mar 18 15:17:33 volumio go-librespot[1770]: main.(*App).withAppPlayer(0xc0000a82d0, 0xc00021e040)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:297 +0x493 fp=0xc0001f5dd0 sp=0xc0001f5cf0 pc=0x90a053
Mar 18 15:17:33 volumio go-librespot[1770]: main.(*App).withCredentials(0xc0000a82d0, {0x9b76a0, 0xc000200000})
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:171 +0x38c fp=0xc0001f5e90 sp=0xc0001f5dd0 pc=0x90982c
Mar 18 15:17:33 volumio go-librespot[1770]: main.(*App).SpotifyToken(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:131
Mar 18 15:17:33 volumio go-librespot[1770]: main.main()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:466 +0x605 fp=0xc0001f5f50 sp=0xc0001f5e90 pc=0x90b545
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.main()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:271 +0x29d fp=0xc0001f5fe0 sp=0xc0001f5f50 pc=0x44507d
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0001f5fe8 sp=0xc0001f5fe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 18 gp=0xc000092380 m=nil [force gc (idle), 3 minutes]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0x3044c154997?, 0x0?, 0x0?, 0x0?, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc00005a7a8 sp=0xc00005a788 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goparkunlock(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.forcegchelper()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:326 +0xb3 fp=0xc00005a7e0 sp=0xc00005a7a8 pc=0x445333
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005a7e8 sp=0xc00005a7e0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by runtime.init.6 in goroutine 1
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:314 +0x1a
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 19 gp=0xc000092540 m=nil [GC sweep wait]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0x1?, 0x0?, 0x0?, 0x0?, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc00005af80 sp=0xc00005af60 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goparkunlock(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.bgsweep(0xc0000a0000)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcsweep.go:318 +0xdf fp=0xc00005afc8 sp=0xc00005af80 pc=0x430a5f
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gcenable.gowrap1()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:203 +0x25 fp=0xc00005afe0 sp=0xc00005afc8 pc=0x425365
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005afe8 sp=0xc00005afe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by runtime.gcenable in goroutine 1
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:203 +0x66
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 20 gp=0xc000092700 m=nil [GC scavenge wait]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0x12fbf1?, 0xc2b43?, 0x0?, 0x0?, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc00005b778 sp=0xc00005b758 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goparkunlock(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:408
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.(*scavengerState).park(0xf03a20)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcscavenge.go:425 +0x49 fp=0xc00005b7a8 sp=0xc00005b778 pc=0x42e409
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.bgscavenge(0xc0000a0000)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgcscavenge.go:658 +0x59 fp=0xc00005b7c8 sp=0xc00005b7a8 pc=0x42e9b9
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gcenable.gowrap2()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:204 +0x25 fp=0xc00005b7e0 sp=0xc00005b7c8 pc=0x425305
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005b7e8 sp=0xc00005b7e0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by runtime.gcenable in goroutine 1
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:204 +0xa5
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 21 gp=0xc000092c40 m=nil [finalizer wait]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0x0?, 0xc00038e420?, 0x0?, 0x40?, 0x1000000010?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc00005e620 sp=0xc00005e600 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.runfinq()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mfinal.go:194 +0x107 fp=0xc00005e7e0 sp=0xc00005e620 pc=0x4243a7
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005e7e8 sp=0xc00005e7e0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by runtime.createfing in goroutine 1
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mfinal.go:164 +0x3d
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 22 gp=0xc000169340 m=nil [IO wait, 56 minutes]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0xc000050a08?, 0x0?, 0x40?, 0x93?, 0xc00006ec18?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc00006ebe0 sp=0xc00006ebc0 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.netpollblock(0xc00006ec78?, 0x40e586?, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0xf7 fp=0xc00006ec18 sp=0xc00006ebe0 pc=0x43e217
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.runtime_pollWait(0x7f5c5d832e40, 0x72)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x85 fp=0xc00006ec38 sp=0xc00006ec18 pc=0x472185
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*pollDesc).wait(0x3?, 0x1?, 0x0)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc00006ec60 sp=0xc00006ec38 pc=0x4e15c7
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*pollDesc).waitRead(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*FD).Accept(0xc0000f4880)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:611 +0x2ac fp=0xc00006ed08 sp=0xc00006ec60 pc=0x4e696c
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*netFD).accept(0xc0000f4880)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_unix.go:172 +0x29 fp=0xc00006edc0 sp=0xc00006ed08 pc=0x65cfa9
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*TCPListener).accept(0xc00009e5c0)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock_posix.go:159 +0x1e fp=0xc00006ede8 sp=0xc00006edc0 pc=0x672efe
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*TCPListener).Accept(0xc00009e5c0)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock.go:327 +0x30 fp=0xc00006ee18 sp=0xc00006ede8 pc=0x6720f0
Mar 18 15:17:33 volumio go-librespot[1770]: net/http.(*onceCloseListener).Accept(0xc0001cd170?)
Mar 18 15:17:33 volumio go-librespot[1770]: :1 +0x24 fp=0xc00006ee30 sp=0xc00006ee18 pc=0x7f6fe4
Mar 18 15:17:33 volumio go-librespot[1770]: net/http.(*Server).Serve(0xc0001ae000, {0xb1a640, 0xc00009e5c0})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3255 +0x33e fp=0xc00006ef60 sp=0xc00006ee30 pc=0x7d4f3e
Mar 18 15:17:33 volumio go-librespot[1770]: net/http.Serve(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2794
Mar 18 15:17:33 volumio go-librespot[1770]: main.(*ApiServer).serve(0xc0000a6480)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:470 +0x50e fp=0xc00006efc8 sp=0xc00006ef60 pc=0x90260e
Mar 18 15:17:33 volumio go-librespot[1770]: main.NewApiServer.gowrap1()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:240 +0x25 fp=0xc00006efe0 sp=0xc00006efc8 pc=0x901d05
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00006efe8 sp=0xc00006efe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by main.NewApiServer in goroutine 1
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:240 +0x1db
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 133 gp=0xc000230000 m=nil [select]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0xc0001f9dd0?, 0x2?, 0x0?, 0x53?, 0xc0001f9d7c?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc0001f9c20 sp=0xc0001f9c00 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.selectgo(0xc0001f9dd0, 0xc0001f9d78, 0x9547a0?, 0x0, 0xc00029e060?, 0x1)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0x725 fp=0xc0001f9d40 sp=0xc0001f9c20 pc=0x456825
Mar 18 15:17:33 volumio go-librespot[1770]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0xc0001f9e68?, {0xb19b98, 0xc00029e060}, 0x0, {0x0, 0x0?})
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:112 +0x24f fp=0xc0001f9e30 sp=0xc0001f9d40 pc=0x81708f
Mar 18 15:17:33 volumio go-librespot[1770]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0xc00016a820?, {0xb19b98?, 0xc00029e060?}, 0x991b00?, {0x0?, 0x0?})
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:61 +0x5c fp=0xc0001f9e88 sp=0xc0001f9e30 pc=0x816b7c
Mar 18 15:17:33 volumio go-librespot[1770]: github.com/cenkalti/backoff/v4.RetryNotify(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:49
Mar 18 15:17:33 volumio go-librespot[1770]: github.com/cenkalti/backoff/v4.Retry(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:38
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/dealer.(*Dealer).recvLoop(0xc00020c0c0)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:193 +0x471 fp=0xc0001f9fc8 sp=0xc0001f9e88 pc=0x84e431
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/dealer.(*Dealer).reconnect.gowrap1()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:243 +0x25 fp=0xc0001f9fe0 sp=0xc0001f9fc8 pc=0x84e905
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0001f9fe8 sp=0xc0001f9fe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by go-librespot/dealer.(*Dealer).reconnect in goroutine 80
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:243 +0x65
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 26 gp=0xc0001696c0 m=nil [GC worker (idle), 56 minutes]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0xc000236778?, 0x4102f7?, 0x0?, 0x0?, 0xc0002367a8?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc000236750 sp=0xc000236730 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gcBgMarkWorker()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xe5 fp=0xc0002367e0 sp=0xc000236750 pc=0x427445
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0002367e8 sp=0xc0002367e0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by runtime.gcBgMarkStartWorkers in goroutine 25
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x1c
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 27 gp=0xc00033afc0 m=nil [GC worker (idle), 3 minutes]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0x3044c270ec9?, 0x3?, 0x1a?, 0xca?, 0x7e63ee?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc00005bf50 sp=0xc00005bf30 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gcBgMarkWorker()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xe5 fp=0xc00005bfe0 sp=0xc00005bf50 pc=0x427445
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005bfe8 sp=0xc00005bfe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by runtime.gcBgMarkStartWorkers in goroutine 25
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x1c
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 28 gp=0xc00033b180 m=nil [GC worker (idle)]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0x3044c504a08?, 0x3?, 0x9a?, 0xa?, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc00005c750 sp=0xc00005c730 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gcBgMarkWorker()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xe5 fp=0xc00005c7e0 sp=0xc00005c750 pc=0x427445
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005c7e8 sp=0xc00005c7e0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by runtime.gcBgMarkStartWorkers in goroutine 25
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x1c
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 50 gp=0xc0003b7880 m=nil [GC worker (idle), 56 minutes]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0x9d88a9dd5?, 0x0?, 0x0?, 0x0?, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc000232750 sp=0xc000232730 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gcBgMarkWorker()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1310 +0xe5 fp=0xc0002327e0 sp=0xc000232750 pc=0x427445
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0002327e8 sp=0xc0002327e0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by runtime.gcBgMarkStartWorkers in goroutine 25
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/mgc.go:1234 +0x1c
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 4 gp=0xc000169180 m=nil [IO wait]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0xc000369d88?, 0x420d18?, 0xc0?, 0x83?, 0xc000036d70?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc000369d30 sp=0xc000369d10 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.netpollblock(0xb15ce0?, 0xec2d98?, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0xf7 fp=0xc000369d68 sp=0xc000369d30 pc=0x43e217
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.runtime_pollWait(0x7f5c5d832b58, 0x72)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x85 fp=0xc000369d88 sp=0xc000369d68 pc=0x472185
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*pollDesc).wait(0xc000286580?, 0x41869b?, 0x0)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000369db0 sp=0xc000369d88 pc=0x4e15c7
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*pollDesc).waitRead(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*FD).RawRead(0xc000286580, 0xc000036d70)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:708 +0x12a fp=0xc000369e10 sp=0xc000369db0 pc=0x4e75ea
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*rawConn).Read(0xc0003be1c0, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/rawconn.go:44 +0x36 fp=0xc000369e48 sp=0xc000369e10 pc=0x66df16
Mar 18 15:17:33 volumio go-librespot[1770]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0xc000201880, 0xc00057d020, 0x0)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x144 fp=0xc000369ea0 sp=0xc000369e48 pc=0x85c404
Mar 18 15:17:33 volumio go-librespot[1770]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247
Mar 18 15:17:33 volumio go-librespot[1770]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0xc00057af10, {0xc0002a8000, 0x10000, 0x10000})
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv4/payload_cmsg.go:32 +0x4ae fp=0xc000369f48 sp=0xc000369ea0 pc=0x85fe8e
Mar 18 15:17:33 volumio go-librespot[1770]: github.com/grandcat/zeroconf.(*Server).recv4(0xc00057dc80, 0xc00057af00)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xd0 fp=0xc000369fc0 sp=0xc000369f48 pc=0x8b4a70
Mar 18 15:17:33 volumio go-librespot[1770]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x25 fp=0xc000369fe0 sp=0xc000369fc0 pc=0x8b4705
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000369fe8 sp=0xc000369fe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 32
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x78
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 5 gp=0xc000169880 m=nil [IO wait, 56 minutes]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0xc00005dd20?, 0x42d8dc?, 0x0?, 0x0?, 0xc0002a4000?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc000368ce0 sp=0xc000368cc0 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.netpollblock(0xb15ce0?, 0xec2d98?, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0xf7 fp=0xc000368d18 sp=0xc000368ce0 pc=0x43e217
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.runtime_pollWait(0x7f5c5d832a60, 0x72)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x85 fp=0xc000368d38 sp=0xc000368d18 pc=0x472185
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*pollDesc).wait(0xc000286600?, 0x418805?, 0x0)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000368d60 sp=0xc000368d38 pc=0x4e15c7
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*pollDesc).waitRead(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*FD).RawRead(0xc000286600, 0xc0002a4000)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:708 +0x12a fp=0xc000368dc0 sp=0xc000368d60 pc=0x4e75ea
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*rawConn).Read(0xc0003be1d0, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/rawconn.go:44 +0x36 fp=0xc000368df8 sp=0xc000368dc0 pc=0x66df16
Mar 18 15:17:33 volumio go-librespot[1770]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0xc0002018a0, 0xc00029e000, 0x0)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/rawconn_msg.go:28 +0x144 fp=0xc000368e50 sp=0xc000368df8 pc=0x85c404
Mar 18 15:17:33 volumio go-librespot[1770]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/internal/socket/socket.go:247
Mar 18 15:17:33 volumio go-librespot[1770]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0xc00057af60, {0xc00028e000, 0x10000, 0x10000})
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/golang.org/x/net@v0.11.0/ipv6/payload_cmsg.go:32 +0x38d fp=0xc000368f48 sp=0xc000368e50 pc=0x86322d
Mar 18 15:17:33 volumio go-librespot[1770]: github.com/grandcat/zeroconf.(*Server).recv6(0xc00057dc80, 0xc00057af50)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xd0 fp=0xc000368fc0 sp=0xc000368f48 pc=0x8b4c30
Mar 18 15:17:33 volumio go-librespot[1770]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x25 fp=0xc000368fe0 sp=0xc000368fc0 pc=0x8b46a5
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000368fe8 sp=0xc000368fe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 32
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xd9
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 105 gp=0xc000231180 m=nil [select, 56 minutes]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0xc000061f38?, 0x5?, 0xd0?, 0x1d?, 0xc000061ee6?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc000061d88 sp=0xc000061d68 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.selectgo(0xc000061f38, 0xc000061edc, 0x414ffd?, 0x0, 0x416bf0?, 0x1)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0x725 fp=0xc000061ea8 sp=0xc000061d88 pc=0x456825
Mar 18 15:17:33 volumio go-librespot[1770]: nhooyr.io/websocket.(*Conn).timeoutLoop(0xc000448820)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x156 fp=0xc000061fc8 sp=0xc000061ea8 pc=0x80bf96
Mar 18 15:17:33 volumio go-librespot[1770]: nhooyr.io/websocket.newConn.gowrap1()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x25 fp=0xc000061fe0 sp=0xc000061fc8 pc=0x80bb05
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000061fe8 sp=0xc000061fe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by nhooyr.io/websocket.newConn in goroutine 41
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x4c5
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 84 gp=0xc00033a1c0 m=nil [select]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0xc0001f7f98?, 0x2?, 0xb0?, 0x12?, 0xc0001f7f54?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc0001f7df8 sp=0xc0001f7dd8 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.selectgo(0xc0001f7f98, 0xc0001f7f50, 0x0?, 0x0, 0xc0001f7f88?, 0x1)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0x725 fp=0xc0001f7f18 sp=0xc0001f7df8 pc=0x456825
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/dealer.(*Dealer).pingTicker(0xc00020c0c0)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:107 +0x8f fp=0xc0001f7fc8 sp=0xc0001f7f18 pc=0x84dd0f
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/dealer.NewDealer.gowrap1()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:57 +0x25 fp=0xc0001f7fe0 sp=0xc0001f7fc8 pc=0x84d745
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0001f7fe8 sp=0xc0001f7fe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by go-librespot/dealer.NewDealer in goroutine 1
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/dealer/dealer.go:57 +0x158
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 65 gp=0xc00033a380 m=nil [select]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0xc000367b78?, 0x2?, 0x18?, 0xd?, 0xc000367b34?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc0003679e0 sp=0xc0003679c0 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.selectgo(0xc000367b78, 0xc000367b30, 0xc000367b98?, 0x0, 0x7f5ca5abe108?, 0x1)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0x725 fp=0xc000367b00 sp=0xc0003679e0 pc=0x456825
Mar 18 15:17:33 volumio go-librespot[1770]: net.doBlockingWithCtx[...]({0xb1b0c0, 0xc00057a0f0}, 0xc000248180)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:60 +0x158 fp=0xc000367bd0 sp=0xc000367b00 pc=0x67a3f8
Mar 18 15:17:33 volumio go-librespot[1770]: net.cgoLookupIP({0xb1b0c0, 0xc00057a0f0}, {0xa44bf4, 0x3}, {0xc0003b8948, 0x13})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:216 +0xa5 fp=0xc000367c00 sp=0xc000367bd0 pc=0x64ea05
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*Resolver).lookupIP(0xf02d20, {0xb1b0c0, 0xc00057a0f0}, {0xa44bf4, 0x3}, {0xc0003b8948, 0x13})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/lookup_unix.go:66 +0xe5 fp=0xc000367e58 sp=0xc000367c00 pc=0x6697a5
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*Resolver).lookupIP-fm({0xb1b0c0?, 0xc00057a0f0?}, {0xa44bf4?, 0x0?}, {0xc0003b8948?, 0x0?})
Mar 18 15:17:33 volumio go-librespot[1770]: :1 +0x49 fp=0xc000367ea0 sp=0xc000367e58 pc=0x67f7e9
Mar 18 15:17:33 volumio go-librespot[1770]: net.init.func1({0xb1b0c0?, 0xc00057a0f0?}, 0x0?, {0xa44bf4?, 0x0?}, {0xc0003b8948?, 0x0?})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/hook.go:22 +0x37 fp=0xc000367ee0 sp=0xc000367ea0 pc=0x64bff7
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*Resolver).lookupIPAddr.func1()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/lookup.go:334 +0x37 fp=0xc000367f38 sp=0xc000367ee0 pc=0x668db7
Mar 18 15:17:33 volumio go-librespot[1770]: internal/singleflight.(*Group).doCall(0xf02d30, 0xc00057a140, {0xc00003a060, 0x17}, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/singleflight/singleflight.go:93 +0x35 fp=0xc000367fa8 sp=0xc000367f38 pc=0x64af55
Mar 18 15:17:33 volumio go-librespot[1770]: internal/singleflight.(*Group).DoChan.gowrap1()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/singleflight/singleflight.go:86 +0x30 fp=0xc000367fe0 sp=0xc000367fa8 pc=0x64aef0
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000367fe8 sp=0xc000367fe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by internal/singleflight.(*Group).DoChan in goroutine 147
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/singleflight/singleflight.go:86 +0x2d2
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 85 gp=0xc000230380 m=nil [select, 56 minutes]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0xc00005f788?, 0x3?, 0x18?, 0xd?, 0xc00005f6ca?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc00005f568 sp=0xc00005f548 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.selectgo(0xc00005f788, 0xc00005f6c4, 0x0?, 0x0, 0x0?, 0x1)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0x725 fp=0xc00005f688 sp=0xc00005f568 pc=0x456825
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/player.(*Player).manageLoop(0xc00057c5a0)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/player/player.go:109 +0x1f4 fp=0xc00005f7c8 sp=0xc00005f688 pc=0x83f3b4
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/player.NewPlayer.gowrap1()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/player/player.go:91 +0x25 fp=0xc00005f7e0 sp=0xc00005f7c8 pc=0x83f0a5
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005f7e8 sp=0xc00005f7e0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by go-librespot/player.NewPlayer in goroutine 1
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/player/player.go:91 +0x219
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 86 gp=0xc000230540 m=nil [select, 10 minutes]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0xc000307c68?, 0x6?, 0x80?, 0x8d?, 0xc0003074cc?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc000307148 sp=0xc000307128 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.selectgo(0xc000307c68, 0xc0003074c0, 0xc00024e000?, 0x0, 0x12dd?, 0x1)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0x725 fp=0xc000307268 sp=0xc000307148 pc=0x456825
Mar 18 15:17:33 volumio go-librespot[1770]: main.(*AppPlayer).Run(0xc000286680, 0xc0003bbf80)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/player.go:430 +0x28f fp=0xc000307fc0 sp=0xc000307268 pc=0x90eaef
Mar 18 15:17:33 volumio go-librespot[1770]: main.(*App).withAppPlayer.gowrap1()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:235 +0x25 fp=0xc000307fe0 sp=0xc000307fc0 pc=0x90a9a5
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000307fe8 sp=0xc000307fe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by main.(*App).withAppPlayer in goroutine 1
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:235 +0x307
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 87 gp=0xc000230700 m=nil [chan receive, 56 minutes]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0xc0000a6e40?, 0xc0001e4360?, 0x0?, 0x0?, 0xc0000606e8?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc000060698 sp=0xc000060678 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.chanrecv(0xc0000aa420, 0xc000060798, 0x1)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x3bf fp=0xc000060710 sp=0xc000060698 pc=0x4113ff
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.chanrecv2(0x0?, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:447 +0x12 fp=0xc000060738 sp=0xc000060710 pc=0x411032
Mar 18 15:17:33 volumio go-librespot[1770]: main.(*App).withAppPlayer.func1()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:245 +0x66 fp=0xc0000607e0 sp=0xc000060738 pc=0x90a8e6
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0000607e8 sp=0xc0000607e0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by main.(*App).withAppPlayer in goroutine 1
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:242 +0x3a5
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 88 gp=0xc0002308c0 m=nil [chan receive, 56 minutes]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0x0?, 0x0?, 0x0?, 0x0?, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc000060e78 sp=0xc000060e58 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.chanrecv(0xc0000aa360, 0xc000060fb8, 0x1)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:583 +0x3bf fp=0xc000060ef0 sp=0xc000060e78 pc=0x4113ff
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.chanrecv2(0x0?, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/chan.go:447 +0x12 fp=0xc000060f18 sp=0xc000060ef0 pc=0x411032
Mar 18 15:17:33 volumio go-librespot[1770]: main.(*App).withAppPlayer.func2()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:261 +0x71 fp=0xc000060fe0 sp=0xc000060f18 pc=0x90a591
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000060fe8 sp=0xc000060fe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by main.(*App).withAppPlayer in goroutine 1
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/main.go:258 +0x445
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 89 gp=0xc000230a80 m=nil [IO wait, 56 minutes]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0x41869b?, 0xc000206168?, 0x0?, 0x0?, 0xc0000614a8?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc000075c08 sp=0xc000075be8 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.netpollblock(0x7f5c5d835828?, 0x40e586?, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0xf7 fp=0xc000075c40 sp=0xc000075c08 pc=0x43e217
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.runtime_pollWait(0x7f5c5d832c50, 0x72)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x85 fp=0xc000075c60 sp=0xc000075c40 pc=0x472185
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*pollDesc).wait(0x8?, 0x10?, 0x0)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000075c88 sp=0xc000075c60 pc=0x4e15c7
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*pollDesc).waitRead(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*FD).Accept(0xc000286200)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:611 +0x2ac fp=0xc000075d30 sp=0xc000075c88 pc=0x4e696c
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*netFD).accept(0xc000286200)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_unix.go:172 +0x29 fp=0xc000075de8 sp=0xc000075d30 pc=0x65cfa9
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*TCPListener).accept(0xc0002017e0)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock_posix.go:159 +0x1e fp=0xc000075e10 sp=0xc000075de8 pc=0x672efe
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*TCPListener).Accept(0xc0002017e0)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/tcpsock.go:327 +0x30 fp=0xc000075e40 sp=0xc000075e10 pc=0x6720f0
Mar 18 15:17:33 volumio go-librespot[1770]: net/http.(*onceCloseListener).Accept(0xb1b050?)
Mar 18 15:17:33 volumio go-librespot[1770]: :1 +0x24 fp=0xc000075e58 sp=0xc000075e40 pc=0x7f6fe4
Mar 18 15:17:33 volumio go-librespot[1770]: net/http.(*Server).Serve(0xc00024c000, {0xb1a640, 0xc0002017e0})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3255 +0x33e fp=0xc000075f88 sp=0xc000075e58 pc=0x7d4f3e
Mar 18 15:17:33 volumio go-librespot[1770]: net/http.Serve(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2794
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/zeroconf.(*Zeroconf).Serve.func2()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:265 +0x85 fp=0xc000075fe0 sp=0xc000075f88 pc=0x8b9965
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000075fe8 sp=0xc000075fe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/zeroconf/zeroconf.go:265 +0x165
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 194 gp=0xc0003b6380 m=3 mp=0xc000065008 [syscall]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.cgocall(0x911220, 0xc000238da8)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/cgocall.go:157 +0x4b fp=0xc000238d80 sp=0xc000238d48 pc=0x40edeb
Mar 18 15:17:33 volumio go-librespot[1770]: net._C2func_getaddrinfo(0xc00003a120, 0x0, 0xc0002481e0, 0xc000206070)
Mar 18 15:17:33 volumio go-librespot[1770]: _cgo_gotypes.go:105 +0x55 fp=0xc000238da8 sp=0xc000238d80 pc=0x6793f5
Mar 18 15:17:33 volumio go-librespot[1770]: net._C_getaddrinfo.func1(0xc00003a120, 0x0, 0xc0002481e0, 0xc000206070)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix_cgo.go:78 +0x7a fp=0xc000238df0 sp=0xc000238da8 pc=0x6797ba
Mar 18 15:17:33 volumio go-librespot[1770]: net._C_getaddrinfo(0xc0003b8948?, 0x13?, 0xc0001e8e00?, 0x30?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix_cgo.go:78 +0x13 fp=0xc000238e20 sp=0xc000238df0 pc=0x6796f3
Mar 18 15:17:33 volumio go-librespot[1770]: net.cgoLookupHostIP({0xa44bf4, 0x3}, {0xc0003b8948, 0x13})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:168 +0x228 fp=0xc000238f60 sp=0xc000238e20 pc=0x64e368
Mar 18 15:17:33 volumio go-librespot[1770]: net.cgoLookupIP.func1()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:217 +0x25 fp=0xc000238f90 sp=0xc000238f60 pc=0x64ea85
Mar 18 15:17:33 volumio go-librespot[1770]: net.doBlockingWithCtx[...].func1()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:56 +0x32 fp=0xc000238fe0 sp=0xc000238f90 pc=0x6799d2
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000238fe8 sp=0xc000238fe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by net.doBlockingWithCtx[...] in goroutine 65
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:54 +0xd8
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 41 gp=0xc00033aa80 m=nil [IO wait, 56 minutes]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0x7f5c5c81e2b8?, 0x0?, 0x0?, 0x0?, 0xb?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc0001052e8 sp=0xc0001052c8 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.netpollblock(0x488a38?, 0x40e586?, 0x0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:573 +0xf7 fp=0xc000105320 sp=0xc0001052e8 pc=0x43e217
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.runtime_pollWait(0x7f5c5d832490, 0x72)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/netpoll.go:345 +0x85 fp=0xc000105340 sp=0xc000105320 pc=0x472185
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*pollDesc).wait(0xc0001bd100?, 0xc000023000?, 0x0)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000105368 sp=0xc000105340 pc=0x4e15c7
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*pollDesc).waitRead(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_poll_runtime.go:89
Mar 18 15:17:33 volumio go-librespot[1770]: internal/poll.(*FD).Read(0xc0001bd100, {0xc000023000, 0x1000, 0x1000})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc000105400 sp=0xc000105368 pc=0x4e28ba
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*netFD).Read(0xc0001bd100, {0xc000023000?, 0x7f5c5d8324c0?, 0xc000105634?})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/fd_posix.go:55 +0x25 fp=0xc000105448 sp=0xc000105400 pc=0x65afc5
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*conn).Read(0xc0003be498, {0xc000023000?, 0xc000231180?, 0x410a60?})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/net.go:179 +0x45 fp=0xc000105490 sp=0xc000105448 pc=0x669fa5
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*TCPConn).Read(0xc0001055e0?, {0xc000023000?, 0xc0001056f0?, 0xc000208120?})
Mar 18 15:17:33 volumio go-librespot[1770]: :1 +0x25 fp=0xc0001054c0 sp=0xc000105490 pc=0x67b985
Mar 18 15:17:33 volumio go-librespot[1770]: io.(*multiReader).Read(0xc0002027c8, {0xc000023000, 0x1000, 0x1000})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/io/multi.go:26 +0x93 fp=0xc000105508 sp=0xc0001054c0 pc=0x4dcf33
Mar 18 15:17:33 volumio go-librespot[1770]: bufio.(*Reader).fill(0xc0001e4480)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:110 +0x103 fp=0xc000105540 sp=0xc000105508 pc=0x531d03
Mar 18 15:17:33 volumio go-librespot[1770]: bufio.(*Reader).ReadByte(0xc0001e4480)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/bufio/bufio.go:269 +0x27 fp=0xc000105560 sp=0xc000105540 pc=0x532427
Mar 18 15:17:33 volumio go-librespot[1770]: nhooyr.io/websocket.readFrameHeader(0xc0001e4480, {0xc000448880, 0x8, 0x8})
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x8b fp=0xc0001055f0 sp=0xc000105560 pc=0x80f28b
Mar 18 15:17:33 volumio go-librespot[1770]: nhooyr.io/websocket.(*Conn).readFrameHeader(0xc000448820, {0xb1b050, 0xf646c0})
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xd9 fp=0xc000105730 sp=0xc0001055f0 pc=0x810cd9
Mar 18 15:17:33 volumio go-librespot[1770]: nhooyr.io/websocket.(*Conn).readLoop(0xc000448820, {0xb1b050, 0xf646c0})
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x65 fp=0xc000105890 sp=0xc000105730 pc=0x810645
Mar 18 15:17:33 volumio go-librespot[1770]: nhooyr.io/websocket.(*Conn).reader(0xc000448820, {0xb1b050, 0xf646c0})
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x125 fp=0xc000105990 sp=0xc000105890 pc=0x811c45
Mar 18 15:17:33 volumio go-librespot[1770]: nhooyr.io/websocket.(*Conn).Reader(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:30
Mar 18 15:17:33 volumio go-librespot[1770]: nhooyr.io/websocket.(*Conn).Read(0xebb780?, {0xb1b050?, 0xf646c0?})
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x1e fp=0xc0001059c0 sp=0xc000105990 pc=0x80ffbe
Mar 18 15:17:33 volumio go-librespot[1770]: main.(*ApiServer).serve.func14({0xb1a850, 0xc000568c40}, 0xc00036ab08?)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:450 +0x1c5 fp=0xc000105a70 sp=0xc0001059c0 pc=0x902945
Mar 18 15:17:33 volumio go-librespot[1770]: net/http.HandlerFunc.ServeHTTP(0xc0000b9860?, {0xb1a850?, 0xc000568c40?}, 0xc00036aaa0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x29 fp=0xc000105a98 sp=0xc000105a70 pc=0x7d14e9
Mar 18 15:17:33 volumio go-librespot[1770]: net/http.(*ServeMux).ServeHTTP(0xc0000a2320?, {0xb1a850, 0xc000568c40}, 0xc000189200)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2683 +0x1ad fp=0xc000105ae8 sp=0xc000105a98 pc=0x7d33cd
Mar 18 15:17:33 volumio go-librespot[1770]: main.(*ApiServer).serve.(*ApiServer).allowOriginMiddleware.func15({0xb1a850?, 0xc000568c40?}, 0xc000225600?)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/cmd/daemon/api_server.go:273 +0x9e fp=0xc000105b38 sp=0xc000105ae8 pc=0x90273e
Mar 18 15:17:33 volumio go-librespot[1770]: net/http.HandlerFunc.ServeHTTP(0x470d99?, {0xb1a850?, 0xc000568c40?}, 0xc00036ab68?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2166 +0x29 fp=0xc000105b60 sp=0xc000105b38 pc=0x7d14e9
Mar 18 15:17:33 volumio go-librespot[1770]: net/http.serverHandler.ServeHTTP({0xc0001fc0c0?}, {0xb1a850?, 0xc000568c40?}, 0x6?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3137 +0x8e fp=0xc000105b90 sp=0xc000105b60 pc=0x7d4b0e
Mar 18 15:17:33 volumio go-librespot[1770]: net/http.(*conn).serve(0xc0001cd0e0, {0xb1b088, 0xc000190e10})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:2039 +0x5e8 fp=0xc000105fb8 sp=0xc000105b90 pc=0x7cfec8
Mar 18 15:17:33 volumio go-librespot[1770]: net/http.(*Server).Serve.gowrap3()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x28 fp=0xc000105fe0 sp=0xc000105fb8 pc=0x7d5328
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000105fe8 sp=0xc000105fe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by net/http.(*Server).Serve in goroutine 22
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/http/server.go:3285 +0x4b4
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 147 gp=0xc000231a40 m=nil [select]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.gopark(0xc0001f3730?, 0x2?, 0x8?, 0x50?, 0xc0001f35e0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/proc.go:402 +0xce fp=0xc0001f3480 sp=0xc0001f3460 pc=0x4454ae
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.selectgo(0xc0001f3730, 0xc0001f35dc, 0x17?, 0x0, 0x40?, 0x1)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/select.go:327 +0x725 fp=0xc0001f35a0 sp=0xc0001f3480 pc=0x456825
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*Resolver).lookupIPAddr(0xf02d20, {0xb1b130, 0xc0001922a0}, {0xa44bf4, 0x3}, {0xc0003b8948, 0x13})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/lookup.go:342 +0x3d1 fp=0xc0001f3788 sp=0xc0001f35a0 pc=0x668371
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*Resolver).internetAddrList(0xf02d20?, {0xb1b130?, 0xc0001922a0?}, {0xa44bf4, 0x3}, {0xc0003b8948?, 0x10?})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/ipsock.go:288 +0x4d9 fp=0xc0001f38a8 sp=0xc0001f3788 pc=0x666139
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*Resolver).resolveAddrList(0xf02d20, {0xb1b130, 0xc0001922a0}, {0xa44fd5, 0x4}, {0xa44bf4?, 0x30?}, {0xc0003b8948, 0x16}, {0x0, ...})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/dial.go:283 +0x3e5 fp=0xc0001f39a8 sp=0xc0001f38a8 pc=0x650be5
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*Dialer).DialContext(0xc0001f3b00, {0xb1b050, 0xf646c0}, {0xa44bf4, 0x3}, {0xc0003b8948, 0x16})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/dial.go:490 +0x405 fp=0xc0001f3ac8 sp=0xc0001f39a8 pc=0x651ac5
Mar 18 15:17:33 volumio go-librespot[1770]: net.(*Dialer).Dial(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/dial.go:434
Mar 18 15:17:33 volumio go-librespot[1770]: net.DialTimeout({0xa44bf4?, 0x3?}, {0xc0003b8948?, 0x44?}, 0xc0001f3be0?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/dial.go:416 +0x7c fp=0xc0001f3b80 sp=0xc0001f3ac8 pc=0x65165c
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/ap.(*Accesspoint).init(0xc00020aa90)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:89 +0x1e5 fp=0xc0001f3c60 sp=0xc0001f3b80 pc=0x822e45
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/ap.(*Accesspoint).reconnect(0xc00020aa90)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:342 +0x30 fp=0xc0001f3cb8 sp=0xc0001f3c60 pc=0x824b10
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/ap.(*Accesspoint).reconnect-fm()
Mar 18 15:17:33 volumio go-librespot[1770]: :1 +0x25 fp=0xc0001f3cd0 sp=0xc0001f3cb8 pc=0x828125
Mar 18 15:17:33 volumio go-librespot[1770]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer.Operation.withEmptyData.func1()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:18 +0x13 fp=0xc0001f3ce0 sp=0xc0001f3cd0 pc=0x816bf3
Mar 18 15:17:33 volumio go-librespot[1770]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0xc0001f3e08?, {0xb19b98, 0xc0001e4900}, 0x0, {0x0, 0x0?})
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:88 +0x132 fp=0xc0001f3dd0 sp=0xc0001f3ce0 pc=0x816f72
Mar 18 15:17:33 volumio go-librespot[1770]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0xc0001922a0?, {0xb19b98?, 0xc0001e4900?}, 0x991b00?, {0x0?, 0x0?})
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:61 +0x5c fp=0xc0001f3e28 sp=0xc0001f3dd0 pc=0x816b7c
Mar 18 15:17:33 volumio go-librespot[1770]: github.com/cenkalti/backoff/v4.RetryNotify(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:49
Mar 18 15:17:33 volumio go-librespot[1770]: github.com/cenkalti/backoff/v4.Retry(...)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/go/pkg/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:38
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/ap.(*Accesspoint).recvLoop(0xc00020aa90)
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:289 +0x395 fp=0xc0001f3fc8 sp=0xc0001f3e28 pc=0x824535
Mar 18 15:17:33 volumio go-librespot[1770]: go-librespot/ap.(*Accesspoint).reconnect.gowrap1()
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:353 +0x25 fp=0xc0001f3fe0 sp=0xc0001f3fc8 pc=0x824ce5
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0001f3fe8 sp=0xc0001f3fe0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by go-librespot/ap.(*Accesspoint).reconnect in goroutine 95
Mar 18 15:17:33 volumio go-librespot[1770]: /home/runner/work/go-librespot/go-librespot/ap/ap.go:353 +0x10a
Mar 18 15:17:33 volumio go-librespot[1770]: goroutine 197 gp=0xc00033b6c0 m=9 mp=0xc000220808 [syscall]:
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.cgocall(0x911220, 0xc0002555a8)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/cgocall.go:157 +0x4b fp=0xc000255580 sp=0xc000255548 pc=0x40edeb
Mar 18 15:17:33 volumio go-librespot[1770]: net._C2func_getaddrinfo(0xc00003a228, 0x0, 0xc000248690, 0xc0002060b0)
Mar 18 15:17:33 volumio go-librespot[1770]: _cgo_gotypes.go:105 +0x55 fp=0xc0002555a8 sp=0xc000255580 pc=0x6793f5
Mar 18 15:17:33 volumio go-librespot[1770]: net._C_getaddrinfo.func1(0xc00003a228, 0x0, 0xc000248690, 0xc0002060b0)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix_cgo.go:78 +0x7a fp=0xc0002555f0 sp=0xc0002555a8 pc=0x6797ba
Mar 18 15:17:33 volumio go-librespot[1770]: net._C_getaddrinfo(0xc0003760c0?, 0x17?, 0x410f30?, 0xc000255668?)
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix_cgo.go:78 +0x13 fp=0xc000255620 sp=0xc0002555f0 pc=0x6796f3
Mar 18 15:17:33 volumio go-librespot[1770]: net.cgoLookupHostIP({0xa44bf4, 0x3}, {0xc0003760c0, 0x17})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:168 +0x228 fp=0xc000255760 sp=0xc000255620 pc=0x64e368
Mar 18 15:17:33 volumio go-librespot[1770]: net.cgoLookupIP.func1()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:217 +0x25 fp=0xc000255790 sp=0xc000255760 pc=0x64ea85
Mar 18 15:17:33 volumio go-librespot[1770]: net.doBlockingWithCtx[...].func1()
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:56 +0x32 fp=0xc0002557e0 sp=0xc000255790 pc=0x6799d2
Mar 18 15:17:33 volumio go-librespot[1770]: runtime.goexit({})
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0002557e8 sp=0xc0002557e0 pc=0x477401
Mar 18 15:17:33 volumio go-librespot[1770]: created by net.doBlockingWithCtx[...] in goroutine 196
Mar 18 15:17:33 volumio go-librespot[1770]: /opt/hostedtoolcache/go/1.22.2/x64/src/net/cgo_unix.go:54 +0xd8
Mar 18 15:17:33 volumio go-librespot[1770]: Aborted
Mar 18 15:17:33 volumio systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a
Mar 18 15:17:33 volumio systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'.
Mar 18 15:17:33 volumio volumio[1301]: info: Connection to go-librespot Websocket closed
Mar 18 15:17:35 volumio volumio[1301]: info:
Mar 18 15:17:35 volumio volumio[1301]: ---------------------------- MPD announces system playlist update
Mar 18 15:17:35 volumio volumio[1301]: info: Ignoring MPD Status Update
Mar 18 15:17:35 volumio volumio[1301]: info:
Mar 18 15:17:35 volumio volumio[1301]: ---------------------------- MPD announces state update: player
Mar 18 15:17:35 volumio volumio[1301]: info: ControllerMpd::getState
Mar 18 15:17:35 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status
Mar 18 15:17:35 volumio volumio[1301]: info:
Mar 18 15:17:35 volumio volumio[1301]: ---------------------------- MPD announces system playlist update
Mar 18 15:17:35 volumio volumio[1301]: info: Ignoring MPD Status Update
Mar 18 15:17:35 volumio volumio[1301]: info:
Mar 18 15:17:35 volumio volumio[1301]: ---------------------------- MPD announces state update: player
Mar 18 15:17:35 volumio volumio[1301]: info: ControllerMpd::getState
Mar 18 15:17:35 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status
Mar 18 15:17:35 volumio volumio[1301]: info:
Mar 18 15:17:35 volumio volumio[1301]: ---------------------------- MPD announces system playlist update
Mar 18 15:17:35 volumio volumio[1301]: info: Ignoring MPD Status Update
Mar 18 15:17:35 volumio volumio[1301]: info:
Mar 18 15:17:35 volumio volumio[1301]: ---------------------------- MPD announces state update: player
Mar 18 15:17:35 volumio volumio[1301]: info: ControllerMpd::getState
Mar 18 15:17:35 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status
Mar 18 15:17:35 volumio volumio[1301]: info: ------------------------------ 3ms
Mar 18 15:17:35 volumio volumio[1301]: info: sendMpdCommand status took 2 milliseconds
Mar 18 15:17:35 volumio volumio[1301]: info: ------------------------------ 2ms
Mar 18 15:17:35 volumio volumio[1301]: info: sendMpdCommand status took 3 milliseconds
Mar 18 15:17:35 volumio volumio[1301]: info: ------------------------------ 2ms
Mar 18 15:17:35 volumio volumio[1301]: verbose: ControllerMpd::parseState
Mar 18 15:17:35 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 18 15:17:35 volumio volumio[1301]: verbose: ControllerMpd::parseState
Mar 18 15:17:35 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 18 15:17:35 volumio volumio[1301]: info: sendMpdCommand status took 3 milliseconds
Mar 18 15:17:35 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 1 milliseconds
Mar 18 15:17:35 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 1 milliseconds
Mar 18 15:17:35 volumio volumio[1301]: verbose: ControllerMpd::parseState
Mar 18 15:17:35 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 18 15:17:35 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo
Mar 18 15:17:35 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo
Mar 18 15:17:35 volumio volumio[1301]: info: ControllerMpd::pushState
Mar 18 15:17:35 volumio volumio[1301]: info: CoreCommandRouter::servicePushState
Mar 18 15:17:35 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:35 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":247,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"15 - Unknown Artist.dff","artist":null,"album":null,"uri":"NAS/yinyue/DFF DSF/è‹æ°¸åº·-好精选 DFF/15 - Unknown Artist.dff","trackType":"dff"}
Mar 18 15:17:35 volumio volumio[1301]: verbose: CURRENT POSITION 14
Mar 18 15:17:35 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play
Mar 18 15:17:35 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play
Mar 18 15:17:35 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload
Mar 18 15:17:35 volumio volumio[1301]: info: CoreStateMachine::pushState
Mar 18 15:17:35 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:35 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 15:17:35 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Mar 18 15:17:35 volumio volumio[1301]: info: CoreStateMachine::pushState
Mar 18 15:17:35 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:35 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Mar 18 15:17:35 volumio volumio[1301]: info: ControllerMpd::pushState
Mar 18 15:17:35 volumio volumio[1301]: info: CoreCommandRouter::servicePushState
Mar 18 15:17:35 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:35 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":247,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"15 - Unknown Artist.dff","artist":null,"album":null,"uri":"NAS/yinyue/DFF DSF/è‹æ°¸åº·-好精选 DFF/15 - Unknown Artist.dff","trackType":"dff"}
Mar 18 15:17:35 volumio volumio[1301]: verbose: CURRENT POSITION 14
Mar 18 15:17:35 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play
Mar 18 15:17:35 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play
Mar 18 15:17:35 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload
Mar 18 15:17:35 volumio volumio[1301]: info: CoreStateMachine::pushState
Mar 18 15:17:35 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:35 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Mar 18 15:17:35 volumio volumio[1301]: info: CoreStateMachine::pushState
Mar 18 15:17:35 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:35 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Mar 18 15:17:35 volumio volumio[1301]: info: ------------------------------ 29ms
Mar 18 15:17:35 volumio volumio[1301]: info: ------------------------------ 28ms
Mar 18 15:17:35 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 25 milliseconds
Mar 18 15:17:35 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo
Mar 18 15:17:35 volumio volumio[1301]: info: ControllerMpd::pushState
Mar 18 15:17:35 volumio volumio[1301]: info: CoreCommandRouter::servicePushState
Mar 18 15:17:35 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:35 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":0,"duration":247,"samplerate":null,"bitdepth":null,"channels":null,"random":false,"updatedb":false,"repeat":false,"bitrate":null,"isStreaming":false,"title":"15 - Unknown Artist.dff","artist":null,"album":null,"uri":"NAS/yinyue/DFF DSF/è‹æ°¸åº·-好精选 DFF/15 - Unknown Artist.dff","trackType":"dff"}
Mar 18 15:17:35 volumio volumio[1301]: verbose: CURRENT POSITION 14
Mar 18 15:17:35 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play
Mar 18 15:17:35 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play
Mar 18 15:17:35 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload
Mar 18 15:17:35 volumio volumio[1301]: info: CoreStateMachine::pushState
Mar 18 15:17:35 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:35 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Mar 18 15:17:35 volumio volumio[1301]: info: CoreStateMachine::pushState
Mar 18 15:17:35 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:35 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Mar 18 15:17:35 volumio volumio[1301]: info: ------------------------------ 40ms
Mar 18 15:17:36 volumio volumio[1301]: info: Initializing connection to go-librespot Websocket
Mar 18 15:17:36 volumio volumio[1301]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879
Mar 18 15:17:36 volumio systemd[1]: go-librespot-daemon.service: Service RestartSec=3s expired, scheduling restart.
Mar 18 15:17:36 volumio systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1.
Mar 18 15:17:36 volumio systemd[1]: Stopped go-librespot Daemon.
Mar 18 15:17:36 volumio systemd[1]: Started go-librespot Daemon.
Mar 18 15:17:36 volumio go-librespot[17073]: Librespot-go daemon starting...
Mar 18 15:17:36 volumio go-librespot[17073]: time="2025-03-18T15:17:36-08:00" level=info msg="generated new device id: e7768e7feb9550692f148690742089184bd00522"
Mar 18 15:17:36 volumio go-librespot[17073]: time="2025-03-18T15:17:36-08:00" level=debug msg="stored credentials found for 31u4qduifkwexuitbvvikqwjwz5q"
Mar 18 15:17:37 volumio dhcpcd[917]: wlan0: leased 192.168.8.121 for 7200 seconds
Mar 18 15:17:37 volumio avahi-daemon[834]: Registering new address record for 192.168.8.121 on wlan0.IPv4.
Mar 18 15:17:37 volumio dhcpcd[917]: wlan0: adding route to 192.168.8.0/24
Mar 18 15:17:37 volumio dhcpcd[917]: wlan0: changing default route via 192.168.8.16
Mar 18 15:17:38 volumio ntpd[1290]: ntpd exiting on signal 15 (Terminated)
Mar 18 15:17:38 volumio ntpd[1290]: 183.177.72.201 local addr 169.254.157.195 ->
Mar 18 15:17:38 volumio systemd[1]: Stopping Network Time Service...
Mar 18 15:17:38 volumio ntpd[1290]: 114.34.171.136 local addr 169.254.157.195 ->
Mar 18 15:17:38 volumio ntpd[1290]: 220.134.230.37 local addr 169.254.157.195 ->
Mar 18 15:17:38 volumio ntpd[1290]: 125.229.106.76 local addr 169.254.157.195 ->
Mar 18 15:17:38 volumio ntpd[1290]: 103.147.22.149 local addr 169.254.157.195 ->
Mar 18 15:17:38 volumio ntpd[1290]: 123.204.232.128 local addr 169.254.157.195 ->
Mar 18 15:17:38 volumio systemd[1]: ntp.service: Succeeded.
Mar 18 15:17:38 volumio systemd[1]: Stopped Network Time Service.
Mar 18 15:17:38 volumio systemd[1]: Starting Network Time Service...
Mar 18 15:17:38 volumio ntpd[17116]: ntpd 4.2.8p12@1.3728-o (1): Starting
Mar 18 15:17:38 volumio ntpd[17116]: Command line: /usr/sbin/ntpd -p /var/run/ntpd.pid -g -u 104:103
Mar 18 15:17:38 volumio systemd[1]: Started Network Time Service.
Mar 18 15:17:38 volumio ntpd[17122]: proto: precision = 0.178 usec (-22)
Mar 18 15:17:38 volumio avahi-daemon[834]: Withdrawing address record for 169.254.157.195 on wlan0.
Mar 18 15:17:38 volumio avahi-daemon[834]: Leaving mDNS multicast group on interface wlan0.IPv4 with address 169.254.157.195.
Mar 18 15:17:38 volumio dhcpcd[917]: wlan0: deleting route to 169.254.0.0/16
Mar 18 15:17:38 volumio avahi-daemon[834]: Joining mDNS multicast group on interface wlan0.IPv4 with address 192.168.8.121.
Mar 18 15:17:38 volumio ntpd[17122]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): good hash signature
Mar 18 15:17:38 volumio ntpd[17122]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): loaded, expire=2022-12-28T00:00:00Z last=2017-01-01T00:00:00Z ofs=37
Mar 18 15:17:38 volumio ntpd[17122]: leapsecond file ('/usr/share/zoneinfo/leap-seconds.list'): expired less than 812 days ago
Mar 18 15:17:38 volumio ntpd[17122]: Listen and drop on 0 v6wildcard [::]:123
Mar 18 15:17:38 volumio ntpd[17122]: Listen and drop on 1 v4wildcard 0.0.0.0:123
Mar 18 15:17:38 volumio ntpd[17122]: Listen normally on 2 lo 127.0.0.1:123
Mar 18 15:17:38 volumio ntpd[17122]: Listen normally on 3 wlan0 192.168.8.121:123
Mar 18 15:17:38 volumio ntpd[17122]: Listening on routing socket on fd #20 for interface updates
Mar 18 15:17:38 volumio ntpd[17122]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized
Mar 18 15:17:38 volumio ntpd[17122]: kernel reports TIME_ERROR: 0x2041: Clock Unsynchronized
Mar 18 15:17:39 volumio volumio[1301]: info: Initializing connection to go-librespot Websocket
Mar 18 15:17:39 volumio go-librespot[17073]: time="2025-03-18T15:17:39-08:00" level=debug msg="new websocket client"
Mar 18 15:17:39 volumio volumio[1301]: info: Connection to go-librespot Websocket established
Mar 18 15:17:39 volumio ntpd[17122]: Soliciting pool server 118.163.81.63
Mar 18 15:17:39 volumio volumio[1301]: info:
Mar 18 15:17:39 volumio volumio[1301]: ---------------------------- MPD announces state update: player
Mar 18 15:17:39 volumio volumio[1301]: info: ControllerMpd::getState
Mar 18 15:17:39 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status
Mar 18 15:17:39 volumio volumio[1301]: info:
Mar 18 15:17:39 volumio volumio[1301]: ---------------------------- MPD announces state update: player
Mar 18 15:17:39 volumio volumio[1301]: info: ControllerMpd::getState
Mar 18 15:17:39 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status
Mar 18 15:17:39 volumio volumio[1301]: info:
Mar 18 15:17:39 volumio volumio[1301]: ---------------------------- MPD announces state update: player
Mar 18 15:17:39 volumio volumio[1301]: info: ControllerMpd::getState
Mar 18 15:17:39 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand status
Mar 18 15:17:39 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds
Mar 18 15:17:39 volumio volumio[1301]: info: sendMpdCommand status took 1 milliseconds
Mar 18 15:17:39 volumio volumio[1301]: verbose: ControllerMpd::parseState
Mar 18 15:17:39 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 18 15:17:39 volumio volumio[1301]: verbose: ControllerMpd::parseState
Mar 18 15:17:39 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 18 15:17:39 volumio volumio[1301]: info: sendMpdCommand status took 2 milliseconds
Mar 18 15:17:39 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 1 milliseconds
Mar 18 15:17:39 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 1 milliseconds
Mar 18 15:17:39 volumio volumio[1301]: verbose: ControllerMpd::parseState
Mar 18 15:17:39 volumio volumio[1301]: verbose: ControllerMpd::sendMpdCommand playlistinfo
Mar 18 15:17:39 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo
Mar 18 15:17:39 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo
Mar 18 15:17:39 volumio volumio[1301]: info: ControllerMpd::pushState
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::servicePushState
Mar 18 15:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:39 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5,"duration":247,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"15 - Unknown Artist.dff","artist":null,"album":null,"uri":"NAS/yinyue/DFF DSF/è‹æ°¸åº·-好精选 DFF/15 - Unknown Artist.dff","trackType":"dff"}
Mar 18 15:17:39 volumio volumio[1301]: verbose: CURRENT POSITION 14
Mar 18 15:17:39 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play
Mar 18 15:17:39 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play
Mar 18 15:17:39 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload
Mar 18 15:17:39 volumio volumio[1301]: info: CoreStateMachine::pushState
Mar 18 15:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , saveDeviceInfo
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Mar 18 15:17:39 volumio volumio[1301]: info: CoreStateMachine::pushState
Mar 18 15:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Mar 18 15:17:39 volumio volumio[1301]: info: ControllerMpd::pushState
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::servicePushState
Mar 18 15:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:39 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5,"duration":247,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"15 - Unknown Artist.dff","artist":null,"album":null,"uri":"NAS/yinyue/DFF DSF/è‹æ°¸åº·-好精选 DFF/15 - Unknown Artist.dff","trackType":"dff"}
Mar 18 15:17:39 volumio volumio[1301]: verbose: CURRENT POSITION 14
Mar 18 15:17:39 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play
Mar 18 15:17:39 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play
Mar 18 15:17:39 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload
Mar 18 15:17:39 volumio volumio[1301]: info: CoreStateMachine::pushState
Mar 18 15:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Mar 18 15:17:39 volumio volumio[1301]: info: CoreStateMachine::pushState
Mar 18 15:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Mar 18 15:17:39 volumio volumio[1301]: info: ------------------------------ 30ms
Mar 18 15:17:39 volumio volumio[1301]: info: ------------------------------ 29ms
Mar 18 15:17:39 volumio volumio[1301]: info: sendMpdCommand playlistinfo took 27 milliseconds
Mar 18 15:17:39 volumio volumio[1301]: verbose: ControllerMpd::parseTrackInfo
Mar 18 15:17:39 volumio volumio[1301]: info: ControllerMpd::pushState
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::servicePushState
Mar 18 15:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:39 volumio volumio[1301]: verbose: STATE SERVICE {"status":"play","position":0,"seek":5,"duration":247,"samplerate":"2.82 MHz","bitdepth":"1 bit","channels":2,"random":false,"updatedb":false,"repeat":false,"bitrate":"5644 Kbps","isStreaming":false,"title":"15 - Unknown Artist.dff","artist":null,"album":null,"uri":"NAS/yinyue/DFF DSF/è‹æ°¸åº·-好精选 DFF/15 - Unknown Artist.dff","trackType":"dff"}
Mar 18 15:17:39 volumio volumio[1301]: verbose: CURRENT POSITION 14
Mar 18 15:17:39 volumio volumio[1301]: info: CoreStateMachine::syncState stateService play
Mar 18 15:17:39 volumio volumio[1301]: info: CoreStateMachine::syncState currentStatus play
Mar 18 15:17:39 volumio volumio[1301]: info: Received an update from plugin. extracting info from payload
Mar 18 15:17:39 volumio volumio[1301]: info: CoreStateMachine::pushState
Mar 18 15:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Mar 18 15:17:39 volumio volumio[1301]: info: CoreStateMachine::pushState
Mar 18 15:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::volumioPushState
Mar 18 15:17:39 volumio volumio[1301]: info: ------------------------------ 39ms
Mar 18 15:17:39 volumio volumio[1301]: verbose: New Socket.io Connection to 192.168.8.121 from 192.168.8.115 UA: Mozilla/5.0 (Windows NT 10.0; Win64; x64) AppleWebKit/537.36 (KHTML, like Gecko) Chrome/134.0.0.0 Safari/537.36 Edg/134.0.0.0 Engine version: 3 Transport: polling Total Clients: 6
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: my_volumio , getAutoUpdateCheckEnabled
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::volumioGetVisibleSources
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: my_music , getDisabledSources
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState
Mar 18 15:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState
Mar 18 15:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: metavolumio , getInfinityPlayback
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: multiroom , getMultiroom
Mar 18 15:17:39 volumio volumio[1301]: info: Received Get System Info
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: system , getSystemInfo
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: volumiodiscovery , getThisDevice
Mar 18 15:17:39 volumio volumio[1301]: info: Discovery: Getting this device information
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState
Mar 18 15:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: network , getCachedIPAddresses
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState
Mar 18 15:17:39 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:39 volumio volumio[1301]: info: Listing playlists
Mar 18 15:17:39 volumio volumio[1301]: info: CoreCommandRouter::volumioGetQueue
Mar 18 15:17:39 volumio volumio[1301]: info: CoreStateMachine::getQueue
Mar 18 15:17:39 volumio volumio[1301]: info: CorePlayQueue::getQueue
Mar 18 15:17:40 volumio ntpd[17122]: Soliciting pool server 17.253.116.253
Mar 18 15:17:40 volumio ntpd[17122]: Soliciting pool server 122.117.253.246
Mar 18 15:17:40 volumio volumio[1301]: info: CoreCommandRouter::executeOnPlugin: updater_comm , getUpdateMessageCache
Mar 18 15:17:41 volumio ntpd[17122]: Soliciting pool server 123.204.232.128
Mar 18 15:17:41 volumio ntpd[17122]: Soliciting pool server 218.35.163.245
Mar 18 15:17:41 volumio go-librespot[17073]: time="2025-03-18T15:17:41-08:00" level=debug msg="fetched new accesspoints: [ap-gae2.spotify.com:4070 ap-gae2.spotify.com:443 ap-gae2.spotify.com:80 ap-guc3.spotify.com:4070 ap-gue1.spotify.com:443 ap-gew4.spotify.com:80]"
Mar 18 15:17:41 volumio go-librespot[17073]: time="2025-03-18T15:17:41-08:00" level=debug msg="fetched new dealers: [gae2-dealer.spotify.com:443 guc3-dealer.spotify.com:443 gue1-dealer.spotify.com:443 gew4-dealer.spotify.com:443]"
Mar 18 15:17:41 volumio go-librespot[17073]: time="2025-03-18T15:17:41-08:00" level=debug msg="fetched new spclients: [gae2-spclient.spotify.com:443 guc3-spclient.spotify.com:443 gue1-spclient.spotify.com:443 gew4-spclient.spotify.com:443]"
Mar 18 15:17:41 volumio go-librespot[17073]: time="2025-03-18T15:17:41-08:00" level=debug msg="zeroconf server listening on port 45503"
Mar 18 15:17:42 volumio ntpd[17122]: Soliciting pool server 118.163.74.161
Mar 18 15:17:42 volumio ntpd[17122]: Soliciting pool server 118.163.81.61
Mar 18 15:17:42 volumio volumio[1301]: info: Getting Spotify volume
Mar 18 15:17:42 volumio volumio[1301]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 7
Mar 18 15:17:42 volumio volumio[1301]: info: CoreCommandRouter::volumioGetState
Mar 18 15:17:42 volumio volumio[1301]: info: CorePlayQueue::getTrack 14
Mar 18 15:17:42 volumio ntpd[17122]: Soliciting pool server 183.177.72.202
Mar 18 15:17:42 volumio go-librespot[17073]: time="2025-03-18T15:17:42-08:00" level=debug msg="obtained new client token: AADHNGs2pi9DLlxGpgxbyuZlfWCB3O8x4tWD7sZAE3V3+lV9Cpyrn+p7v2DtNQkHLy0F5IUVKrNJIgIPLxMaee6iv12KZYbTzXAJ/AvJl6E3ZFdkapnIz6r5ThS0sXaARUwod3Gn0jcy7l4y5n72Z4sIIrFH6XKHPCUw6nXZ8PTidvWXJ2b2dSgu1aG0mK9RGkVkMQNIRJ+K5qQ72ILBgvp+NlG8+qKbKUqyphcgAhTgSKXo/pcy6//B9/S7aA=="
Mar 18 15:17:42 volumio go-librespot[17073]: time="2025-03-18T15:17:42-08:00" level=info msg="connected to ap-gae2.spotify.com:4070"
Mar 18 15:17:43 volumio go-librespot[17073]: time="2025-03-18T15:17:43-08:00" level=debug msg="completed keyexchange"
Mar 18 15:17:43 volumio ntpd[17122]: Soliciting pool server 103.147.22.149
Mar 18 15:17:43 volumio go-librespot[17073]: time="2025-03-18T15:17:43-08:00" level=debug msg="completed challenge"
Mar 18 15:17:43 volumio go-librespot[17073]: time="2025-03-18T15:17:43-08:00" level=debug msg="authenticated as 31u4qduifkwexuitbvvikqwjwz5q"
Mar 18 15:17:44 volumio ntpd[17122]: Soliciting pool server 125.229.162.223
Mar 18 15:17:44 volumio go-librespot[17073]: time="2025-03-18T15:17:44-08:00" level=debug msg="authenticated as 31u4qduifkwexuitbvvikqwjwz5q"
Mar 18 15:17:45 volumio go-librespot[17073]: time="2025-03-18T15:17:45-08:00" level=debug msg="dealer connection opened"
Mar 18 15:17:45 volumio go-librespot[17073]: time="2025-03-18T15:17:45-08:00" level=debug msg="initializing zeroconf session, username: 31u4qduifkwexuitbvvikqwjwz5q"
Mar 18 15:17:45 volumio go-librespot[17073]: time="2025-03-18T15:17:45-08:00" level=debug msg="skipping packet PacketTypeSecretBlock, len: 336"
Mar 18 15:17:45 volumio go-librespot[17073]: time="2025-03-18T15:17:45-08:00" level=debug msg="skipping packet PacketTypeLicenseVersion, len: 2"
Mar 18 15:17:45 volumio go-librespot[17073]: time="2025-03-18T15:17:45-08:00" level=debug msg="skipping packet PacketTypeUnknown1f, len: 17"
Mar 18 15:17:45 volumio go-librespot[17073]: time="2025-03-18T15:17:45-08:00" level=debug msg="skipping packet PacketTypeLegacyWelcome, len: 0"
Mar 18 15:17:45 volumio go-librespot[17073]: time="2025-03-18T15:17:45-08:00" level=debug msg="skipping packet PacketTypeMercuryEvent, len: 481"
Mar 18 15:17:45 volumio go-librespot[17073]: time="2025-03-18T15:17:45-08:00" level=debug msg="autoplay enabled: false"
Mar 18 15:17:45 volumio go-librespot[17073]: time="2025-03-18T15:17:45-08:00" level=debug msg="received connection id: ZDgzODI5NGMtYWQxZi00ODQ4LTlkZGYtNmJhZTc1YjhhMzllK2RlYWxlcit0Y3A6Ly8wYWM5NDk0MC5pcC5nYWUyLnNwb3RpZnkubmV0OjU3MDArMUQyMjNFNkNFRUM0NzBFOUJFNDE4M0E2OEFFRjA0RjFFNEZDNEQ0OTUyRDA1MDcxN0MyNjVFQzQ0OEY0RDNCQw=="
Mar 18 15:17:45 volumio volumio[1301]: info: Spotify volume: 100
Mar 18 15:17:45 volumio go-librespot[17073]: time="2025-03-18T15:17:45-08:00" level=debug msg="put connect state because NEW_DEVICE"
Mar 18 15:18:00 volumio volumio[1301]: |||||||||||||||||||||||| WARNING: FATAL ERROR |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 18 15:18:00 volumio volumio[1301]: [t [Error]: A network error (such as timeout, interrupted connection or unreachable host) has occurred.] {
Mar 18 15:18:00 volumio volumio[1301]: code: 'auth/network-request-failed',
Mar 18 15:18:00 volumio volumio[1301]: a: null
Mar 18 15:18:00 volumio volumio[1301]: }
Mar 18 15:18:00 volumio volumio[1301]: |||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||
Mar 18 15:18:00 volumio sudo[17250]: volumio : TTY=unknown ; PWD=/ ; USER=root ; COMMAND=/bin/journalctl --since=2025-03-18 15:17
Mar 18 15:18:00 volumio sudo[17250]: pam_unix(sudo:session): session opened for user root by (uid=0)
PRETTY_NAME="Debian GNU/Linux 10 (buster)"
NAME="Debian GNU/Linux"
VERSION_ID="10"
VERSION="10 (buster)"
VERSION_CODENAME=buster
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="147953990fca4aa103a8f90538ef56fb0454638c"
VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6"
VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a"
VOLUMIO_BE_VERSION="a01a833dd28483f88cb390596f5090b7187992af"
VOLUMIO_ARCH="x64"
VOLUMIO_VARIANT="volumio"
VOLUMIO_TEST="FALSE"
VOLUMIO_BUILD_DATE="Thu 27 Feb 2025 12:15:38 PM CET"
VOLUMIO_VERSION="3.795"
VOLUMIO_HARDWARE="x86_amd64"
VOLUMIO_DEVICENAME="x86_64"
VOLUMIO_HASH="9e0e048b157c8fb0f19240808f3da58b"