Jul 06 15:45:17 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:17+07:00" level=error msg="did not receive last pong from dealer, 900s passed" Jul 06 15:45:17 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:17+07:00" level=error msg="did not receive last pong ack from accesspoint, 963s passed" Jul 06 15:45:21 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:21+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:21 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:21+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:21 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:21+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:21 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:21+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:21 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:21+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:21 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:21+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:21 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:21+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:21 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:21+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:21 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:21+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:21 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:21+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:21 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:21+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:26 volumio-pc-beta systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 06 15:45:26 volumio-pc-beta dbus-daemon[794]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.1700' (uid=0 pid=190605 comm="timedatectl show --property=NTPSynchronized --valu") Jul 06 15:45:26 volumio-pc-beta systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 06 15:45:26 volumio-pc-beta dbus-daemon[794]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 06 15:45:26 volumio-pc-beta systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 06 15:45:26 volumio-pc-beta setdatetime-helper.sh[190604]: Time is already synchronized. Jul 06 15:45:26 volumio-pc-beta systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 06 15:45:26 volumio-pc-beta systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. Jul 06 15:45:29 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:29+07:00" level=debug msg="renewing login5 access token" Jul 06 15:45:47 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:47+07:00" level=error msg="did not receive last pong from dealer, 930s passed" Jul 06 15:45:51 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:51+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:51 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:51+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:51 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:51+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:52 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:52+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:52 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:52+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:52 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:52+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:52 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:52+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:52 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:52+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:52 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:52+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:52 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:52+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:52 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:52+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:52 volumio-pc-beta go-librespot[36332]: time="2025-07-06T15:45:52+07:00" level=error msg="failed reconnecting accesspoint" error="failed to connect to AP ap-gae2.spotify.com:4070: dial tcp: lookup ap-gae2.spotify.com: device or resource busy" Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: fatal error: sync: Unlock of unlocked RWMutex Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 769545 gp=0xc000181c00 m=3 mp=0xc000065008 [running]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.fatal({0xafe6db?, 0x81901a?}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/panic.go:1042 +0x5c fp=0xc0000efde0 sp=0xc0000efdb0 pc=0x44061c Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: sync.fatal({0xafe6db?, 0x819060?}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/panic.go:1007 +0x18 fp=0xc0000efe00 sp=0xc0000efde0 pc=0x470af8 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: sync.(*RWMutex).Unlock(0xc000138de8) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/sync/rwmutex.go:208 +0x45 fp=0xc0000efe38 sp=0xc0000efe00 pc=0x4820c5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).recvLoop(0xc000138d20) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/ap/ap.go:329 +0x4cb fp=0xc0000effc8 sp=0xc0000efe38 pc=0x819bab Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect.gowrap1() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/ap/ap.go:390 +0x25 fp=0xc0000effe0 sp=0xc0000effc8 pc=0x81a385 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0000effe8 sp=0xc0000effe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).reconnect in goroutine 768968 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/ap/ap.go:390 +0x108 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 1 gp=0xc0000061c0 m=nil [select, 1030 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0xc000045c78?, 0x2?, 0x38?, 0xed?, 0xc000045c14?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000543aa8 sp=0xc000543a88 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.selectgo(0xc000543c78, 0xc000045c10, 0xc000045c60?, 0x0, 0x40?, 0x1) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc000543bc8 sp=0xc000543aa8 pc=0x454845 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve(0xc000490000, 0xc000543d80) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/zeroconf/zeroconf.go:270 +0x2a6 fp=0xc000543d10 sp=0xc000543bc8 pc=0x8e4a86 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: main.(*App).withAppPlayer(0xc0000d2a80, {0xbd06b8, 0x1091660}, 0xc00004b8a0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/main.go:316 +0x52b fp=0xc000543e10 sp=0xc000543d10 pc=0x97a0cb Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: main.(*App).withCredentials(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/main.go:190 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: main.(*App).SpotifyToken(0xc0000d2a80, {0xbd06b8, 0x1091660}, {0xc0000b8c80?, 0xaee9a5?}, {0xc0000a43c0?, 0x2697?}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/main.go:182 +0xcd fp=0xc000543e68 sp=0xc000543e10 pc=0x97960d Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: main.main() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/main.go:618 +0x6d8 fp=0xc000543f50 sp=0xc000543e68 pc=0x97c8d8 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.main() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:271 +0x29d fp=0xc000543fe0 sp=0xc000543f50 pc=0x44309d Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000543fe8 sp=0xc000543fe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 2 gp=0xc000006c40 m=nil [force gc (idle), 3 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0x44aefae532b2?, 0x0?, 0x0?, 0x0?, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005efa8 sp=0xc00005ef88 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goparkunlock(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:408 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.forcegchelper() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:326 +0xb3 fp=0xc00005efe0 sp=0xc00005efa8 pc=0x443353 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005efe8 sp=0xc00005efe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by runtime.init.6 in goroutine 1 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:314 +0x1a Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 3 gp=0xc000007180 m=nil [GC sweep wait]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0x1?, 0x0?, 0x0?, 0x0?, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005f780 sp=0xc00005f760 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goparkunlock(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:408 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.bgsweep(0xc00007e000) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgcsweep.go:318 +0xdf fp=0xc00005f7c8 sp=0xc00005f780 pc=0x42ea7f Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gcenable.gowrap1() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgc.go:203 +0x25 fp=0xc00005f7e0 sp=0xc00005f7c8 pc=0x423385 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005f7e8 sp=0xc00005f7e0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by runtime.gcenable in goroutine 1 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgc.go:203 +0x66 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 4 gp=0xc000007340 m=nil [GC scavenge wait]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0x10000?, 0x167d0b4?, 0x0?, 0x0?, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005ff78 sp=0xc00005ff58 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goparkunlock(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:408 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.(*scavengerState).park(0x10309e0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgcscavenge.go:425 +0x49 fp=0xc00005ffa8 sp=0xc00005ff78 pc=0x42c429 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.bgscavenge(0xc00007e000) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgcscavenge.go:658 +0x59 fp=0xc00005ffc8 sp=0xc00005ffa8 pc=0x42c9d9 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gcenable.gowrap2() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgc.go:204 +0x25 fp=0xc00005ffe0 sp=0xc00005ffc8 pc=0x423325 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005ffe8 sp=0xc00005ffe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by runtime.gcenable in goroutine 1 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgc.go:204 +0xa5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 5 gp=0xc000007c00 m=nil [finalizer wait, 262 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0x0?, 0xc0003b02d0?, 0x20?, 0x80?, 0x1000000010?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00066fe20 sp=0xc00066fe00 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.runfinq() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mfinal.go:194 +0x107 fp=0xc00066ffe0 sp=0xc00066fe20 pc=0x4223c7 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00066ffe8 sp=0xc00066ffe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by runtime.createfing in goroutine 1 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mfinal.go:164 +0x3d Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 8 gp=0xc0001f8c40 m=nil [IO wait, 867 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0xc000052f08?, 0x0?, 0x40?, 0x8c?, 0xc00006eab0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00006ea78 sp=0xc00006ea58 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.netpollblock(0xc00006eb10?, 0x40c5a6?, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc00006eab0 sp=0xc00006ea78 pc=0x43c237 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.runtime_pollWait(0x7fb2711c36d0, 0x72) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc00006ead0 sp=0xc00006eab0 pc=0x4701e5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*pollDesc).wait(0x7?, 0x266?, 0x0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc00006eaf8 sp=0xc00006ead0 pc=0x4e1047 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*pollDesc).waitRead(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*FD).Accept(0xc0000d2b00) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x2ac fp=0xc00006eba0 sp=0xc00006eaf8 pc=0x4e63ec Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net.(*netFD).accept(0xc0000d2b00) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/fd_unix.go:172 +0x29 fp=0xc00006ec58 sp=0xc00006eba0 pc=0x54ee49 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net.(*TCPListener).accept(0xc00004b860) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1e fp=0xc00006ec80 sp=0xc00006ec58 pc=0x564d9e Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net.(*TCPListener).Accept(0xc00004b860) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0xc00006ecb0 sp=0xc00006ec80 pc=0x563f90 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.(*onceCloseListener).Accept(0xc00015e120?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: :1 +0x24 fp=0xc00006ecc8 sp=0xc00006ecb0 pc=0x7121a4 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.(*Server).Serve(0xc000138000, {0xbcf5c0, 0xc00004b860}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:3255 +0x33e fp=0xc00006edf8 sp=0xc00006ecc8 pc=0x6f00fe Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.Serve(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:2794 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: main.(*ConcreteApiServer).serve(0xc0001fa510) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/api_server.go:610 +0x90c fp=0xc00006efc8 sp=0xc00006edf8 pc=0x97018c Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: main.NewApiServer.gowrap1() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/api_server.go:289 +0x25 fp=0xc00006efe0 sp=0xc00006efc8 pc=0x96f0a5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00006efe8 sp=0xc00006efe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by main.NewApiServer in goroutine 1 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/api_server.go:289 +0x2ff Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 34 gp=0xc0001f8e00 m=nil [IO wait]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0xc000075d88?, 0x41ed38?, 0xa0?, 0x5?, 0xc00013bcc0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000075d30 sp=0xc000075d10 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.netpollblock(0xbc9bc0?, 0xfea5a8?, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc000075d68 sp=0xc000075d30 pc=0x43c237 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.runtime_pollWait(0x7fb2711c33e8, 0x72) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc000075d88 sp=0xc000075d68 pc=0x4701e5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*pollDesc).wait(0xc00012c600?, 0x4166bb?, 0x0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000075db0 sp=0xc000075d88 pc=0x4e1047 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*pollDesc).waitRead(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*FD).RawRead(0xc00012c600, 0xc00013bcc0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x12a fp=0xc000075e10 sp=0xc000075db0 pc=0x4e706a Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net.(*rawConn).Read(0xc00046e020, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/rawconn.go:44 +0x36 fp=0xc000075e48 sp=0xc000075e10 pc=0x55fdb6 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0xc000474180, 0xc00044fe60, 0x0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x144 fp=0xc000075ea0 sp=0xc000075e48 pc=0x8874a4 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: golang.org/x/net/ipv4.(*payloadHandler).ReadFrom(0xc0004483d0, {0xc000500000, 0x10000, 0x10000}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv4/payload_cmsg.go:31 +0x4ae fp=0xc000075f48 sp=0xc000075ea0 pc=0x88af2e Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/grandcat/zeroconf.(*Server).recv4(0xc00044e0c0, 0xc0004483c0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:254 +0xd0 fp=0xc000075fc0 sp=0xc000075f48 pc=0x8dfb10 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap1() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x25 fp=0xc000075fe0 sp=0xc000075fc0 pc=0x8df7a5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000075fe8 sp=0xc000075fe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 15 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:188 +0x78 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 82 gp=0xc0001f8fc0 m=nil [chan receive, 867 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0xc00008ac00?, 0xc00008a7e0?, 0xc8?, 0xc6?, 0xc00016c6e8?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00016c698 sp=0xc00016c678 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.chanrecv(0xc000096300, 0xc00016c798, 0x1) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/chan.go:583 +0x3bf fp=0xc00016c710 sp=0xc00016c698 pc=0x40f41f Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.chanrecv2(0xc000468f30?, 0x40e8c0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/chan.go:447 +0x12 fp=0xc00016c738 sp=0xc00016c710 pc=0x40f052 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: main.(*App).withAppPlayer.func1() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/main.go:264 +0x6c fp=0xc00016c7e0 sp=0xc00016c738 pc=0x97ab4c Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00016c7e8 sp=0xc00016c7e0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by main.(*App).withAppPlayer in goroutine 1 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/main.go:261 +0x3eb Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 19 gp=0xc0001f9180 m=nil [GC worker (idle), 867 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0x1574a1563b66?, 0x3?, 0xec?, 0xab?, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000060750 sp=0xc000060730 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gcBgMarkWorker() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgc.go:1310 +0xe5 fp=0xc0000607e0 sp=0xc000060750 pc=0x425465 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0000607e8 sp=0xc0000607e0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgc.go:1234 +0x1c Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 14 gp=0xc000323500 m=nil [GC worker (idle), 3 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0x44aefb151519?, 0x3?, 0x8e?, 0x93?, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000061750 sp=0xc000061730 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gcBgMarkWorker() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgc.go:1310 +0xe5 fp=0xc0000617e0 sp=0xc000061750 pc=0x425465 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0000617e8 sp=0xc0000617e0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgc.go:1234 +0x1c Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 20 gp=0xc000102700 m=nil [GC worker (idle), 14 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0x440482ada8fc?, 0x3?, 0xcc?, 0xea?, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000060f50 sp=0xc000060f30 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gcBgMarkWorker() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgc.go:1310 +0xe5 fp=0xc000060fe0 sp=0xc000060f50 pc=0x425465 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000060fe8 sp=0xc000060fe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgc.go:1234 +0x1c Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 21 gp=0xc0001028c0 m=nil [GC worker (idle), 867 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0x1574a1564593?, 0x3?, 0x22?, 0x2b?, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00005a750 sp=0xc00005a730 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gcBgMarkWorker() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgc.go:1310 +0xe5 fp=0xc00005a7e0 sp=0xc00005a750 pc=0x425465 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00005a7e8 sp=0xc00005a7e0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by runtime.gcBgMarkStartWorkers in goroutine 18 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/mgc.go:1234 +0x1c Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 116 gp=0xc000102c40 m=nil [select, 790 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0xc000402f38?, 0x5?, 0xd0?, 0x2d?, 0xc000402ee6?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000402d88 sp=0xc000402d68 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.selectgo(0xc000402f38, 0xc000402edc, 0x412f3a?, 0x0, 0xc00013a4b0?, 0x1) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc000402ea8 sp=0xc000402d88 pc=0x454845 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.(*Conn).timeoutLoop(0xc000126ea0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x156 fp=0xc000402fc8 sp=0xc000402ea8 pc=0x803036 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.newConn.gowrap1() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x25 fp=0xc000402fe0 sp=0xc000402fc8 pc=0x802ba5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000402fe8 sp=0xc000402fe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by nhooyr.io/websocket.newConn in goroutine 91 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x4c5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 35 gp=0xc0001f8a80 m=nil [IO wait, 1030 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0xc000061d20?, 0x42b8fc?, 0x0?, 0xc0?, 0xc000412000?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000073ce0 sp=0xc000073cc0 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.netpollblock(0xbc9bc0?, 0xfea5a8?, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc000073d18 sp=0xc000073ce0 pc=0x43c237 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.runtime_pollWait(0x7fb2711c32f0, 0x72) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc000073d38 sp=0xc000073d18 pc=0x4701e5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*pollDesc).wait(0xc00012c680?, 0x416825?, 0x0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000073d60 sp=0xc000073d38 pc=0x4e1047 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*pollDesc).waitRead(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*FD).RawRead(0xc00012c680, 0xc000412000) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_unix.go:708 +0x12a fp=0xc000073dc0 sp=0xc000073d60 pc=0x4e706a Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net.(*rawConn).Read(0xc00046e030, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/rawconn.go:44 +0x36 fp=0xc000073df8 sp=0xc000073dc0 pc=0x55fdb6 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: golang.org/x/net/internal/socket.(*Conn).recvMsg(0xc0004741a0, 0xc00040a000, 0x0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/rawconn_msg.go:27 +0x144 fp=0xc000073e50 sp=0xc000073df8 pc=0x8874a4 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: golang.org/x/net/internal/socket.(*Conn).RecvMsg(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/golang.org/x/net@v0.26.0/internal/socket/socket.go:247 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: golang.org/x/net/ipv6.(*payloadHandler).ReadFrom(0xc000448420, {0xc000424000, 0x10000, 0x10000}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/golang.org/x/net@v0.26.0/ipv6/payload_cmsg.go:31 +0x38d fp=0xc000073f48 sp=0xc000073e50 pc=0x88e2cd Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/grandcat/zeroconf.(*Server).recv6(0xc00044e0c0, 0xc000448410) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:282 +0xd0 fp=0xc000073fc0 sp=0xc000073f48 pc=0x8dfcd0 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/grandcat/zeroconf.(*Server).mainloop.gowrap2() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0x25 fp=0xc000073fe0 sp=0xc000073fc0 pc=0x8df745 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000073fe8 sp=0xc000073fe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by github.com/grandcat/zeroconf.(*Server).mainloop in goroutine 15 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/github.com/grandcat/zeroconf@v1.0.0/server.go:191 +0xd9 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 83 gp=0xc000482380 m=nil [chan receive, 790 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0x48125d?, 0xc000100808?, 0x10?, 0xe?, 0xc00053feb0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000601e70 sp=0xc000601e50 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.chanrecv(0xc0000962a0, 0xc00053ffb8, 0x1) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/chan.go:583 +0x3bf fp=0xc000601ee8 sp=0xc000601e70 pc=0x40f41f Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.chanrecv2(0xfe2460?, 0x5?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/chan.go:447 +0x12 fp=0xc000601f10 sp=0xc000601ee8 pc=0x40f052 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: main.(*App).withAppPlayer.func2() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/main.go:280 +0x85 fp=0xc000601fe0 sp=0xc000601f10 pc=0x97a7c5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000601fe8 sp=0xc000601fe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by main.(*App).withAppPlayer in goroutine 1 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/main.go:277 +0x4ab Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 84 gp=0xc000482540 m=nil [IO wait, 11 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0xc000052f08?, 0x0?, 0x40?, 0x25?, 0xc000071c40?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000071c08 sp=0xc000071be8 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.netpollblock(0xc000071ca0?, 0x40c5a6?, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc000071c40 sp=0xc000071c08 pc=0x43c237 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.runtime_pollWait(0x7fb2711c34e0, 0x72) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc000071c60 sp=0xc000071c40 pc=0x4701e5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*pollDesc).wait(0x9?, 0x1?, 0x0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000071c88 sp=0xc000071c60 pc=0x4e1047 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*pollDesc).waitRead(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*FD).Accept(0xc00012c180) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_unix.go:611 +0x2ac fp=0xc000071d30 sp=0xc000071c88 pc=0x4e63ec Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net.(*netFD).accept(0xc00012c180) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/fd_unix.go:172 +0x29 fp=0xc000071de8 sp=0xc000071d30 pc=0x54ee49 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net.(*TCPListener).accept(0xc0004740a0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/tcpsock_posix.go:159 +0x1e fp=0xc000071e10 sp=0xc000071de8 pc=0x564d9e Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net.(*TCPListener).Accept(0xc0004740a0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/tcpsock.go:327 +0x30 fp=0xc000071e40 sp=0xc000071e10 pc=0x563f90 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.(*onceCloseListener).Accept(0xc00024b200?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: :1 +0x24 fp=0xc000071e58 sp=0xc000071e40 pc=0x7121a4 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.(*Server).Serve(0xc000138780, {0xbcf5c0, 0xc0004740a0}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:3255 +0x33e fp=0xc000071f88 sp=0xc000071e58 pc=0x6f00fe Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.Serve(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:2794 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve.func2() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/zeroconf/zeroconf.go:267 +0x85 fp=0xc000071fe0 sp=0xc000071f88 pc=0x8e4ba5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000071fe8 sp=0xc000071fe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by github.com/devgianlu/go-librespot/zeroconf.(*Zeroconf).Serve in goroutine 1 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/zeroconf/zeroconf.go:267 +0x165 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 48 gp=0xc000482e00 m=nil [IO wait, 1030 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0x7fb27003b038?, 0x0?, 0x0?, 0x0?, 0xb?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000545298 sp=0xc000545278 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.netpollblock(0x486bd8?, 0x40c5a6?, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc0005452d0 sp=0xc000545298 pc=0x43c237 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.runtime_pollWait(0x7fb2711c2d20, 0x72) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc0005452f0 sp=0xc0005452d0 pc=0x4701e5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*pollDesc).wait(0xc000224e00?, 0xc000451000?, 0x0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc000545318 sp=0xc0005452f0 pc=0x4e1047 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*pollDesc).waitRead(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*FD).Read(0xc000224e00, {0xc000451000, 0x1000, 0x1000}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc0005453b0 sp=0xc000545318 pc=0x4e233a Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net.(*netFD).Read(0xc000224e00, {0xc000451000?, 0xc000545470?, 0x443769?}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/fd_posix.go:55 +0x25 fp=0xc0005453f8 sp=0xc0005453b0 pc=0x54ce65 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net.(*conn).Read(0xc000062000, {0xc000451000?, 0x453ff0?, 0x4434ce?}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/net.go:179 +0x45 fp=0xc000545440 sp=0xc0005453f8 pc=0x55be45 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net.(*TCPConn).Read(0xc000545590?, {0xc000451000?, 0xc0005456a0?, 0x2?}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: :1 +0x25 fp=0xc000545470 sp=0xc000545440 pc=0x56d825 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: io.(*multiReader).Read(0xc0003b0138, {0xc000451000, 0x1000, 0x1000}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/io/multi.go:26 +0x93 fp=0xc0005454b8 sp=0xc000545470 pc=0x4dc9b3 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: bufio.(*Reader).fill(0xc00008a9c0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/bufio/bufio.go:110 +0x103 fp=0xc0005454f0 sp=0xc0005454b8 pc=0x672a23 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: bufio.(*Reader).ReadByte(0xc00008a9c0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/bufio/bufio.go:269 +0x27 fp=0xc000545510 sp=0xc0005454f0 pc=0x673147 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.readFrameHeader(0xc00008a9c0, {0xc0001266e0, 0x8, 0x8}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x8b fp=0xc0005455a0 sp=0xc000545510 pc=0x80632b Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.(*Conn).readFrameHeader(0xc000126680, {0xbd0680, 0x1091660}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xd9 fp=0xc0005456e0 sp=0xc0005455a0 pc=0x807d79 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.(*Conn).readLoop(0xc000126680, {0xbd0680, 0x1091660}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x65 fp=0xc000545840 sp=0xc0005456e0 pc=0x8076e5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.(*Conn).reader(0xc000126680, {0xbd0680, 0x1091660}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x125 fp=0xc000545940 sp=0xc000545840 pc=0x808ce5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.(*Conn).Reader(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.(*Conn).Read(0xc0001fa588?, {0xbd0680?, 0x1091660?}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x1e fp=0xc000545970 sp=0xc000545940 pc=0x80705e Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: main.(*ConcreteApiServer).serve.func17({0xbcf7d0, 0xc0002821c0}, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/api_server.go:580 +0x3ae fp=0xc000545a68 sp=0xc000545970 pc=0x97098e Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.HandlerFunc.ServeHTTP(0xc00010e000?, {0xbcf7d0?, 0xc0002821c0?}, 0xc000224e00?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:2166 +0x29 fp=0xc000545a90 sp=0xc000545a68 pc=0x6ec6a9 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.(*ServeMux).ServeHTTP(0xc00010e270?, {0xbcf7d0, 0xc0002821c0}, 0xc000144000) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:2683 +0x1ad fp=0xc000545ae0 sp=0xc000545a90 pc=0x6ee58d Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xbcf7d0, 0xc0002821c0}, 0xc000144000) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x184 fp=0xc000545b38 sp=0xc000545ae0 pc=0x9703e4 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.HandlerFunc.ServeHTTP(0x46edf9?, {0xbcf7d0?, 0xc0002821c0?}, 0xc00055ab68?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:2166 +0x29 fp=0xc000545b60 sp=0xc000545b38 pc=0x6ec6a9 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.serverHandler.ServeHTTP({0xc000250600?}, {0xbcf7d0?, 0xc0002821c0?}, 0x6?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:3137 +0x8e fp=0xc000545b90 sp=0xc000545b60 pc=0x6efcce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.(*conn).serve(0xc0001363f0, {0xbd06f0, 0xc000124480}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:2039 +0x5e8 fp=0xc000545fb8 sp=0xc000545b90 pc=0x6eb088 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.(*Server).Serve.gowrap3() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:3285 +0x28 fp=0xc000545fe0 sp=0xc000545fb8 pc=0x6f04e8 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000545fe8 sp=0xc000545fe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by net/http.(*Server).Serve in goroutine 8 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:3285 +0x4b4 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 114 gp=0xc000482fc0 m=nil [select, 790 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0xc00055af38?, 0x5?, 0xa0?, 0x5?, 0xc00055aee6?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00055ad88 sp=0xc00055ad68 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.selectgo(0xc00055af38, 0xc00055aedc, 0x0?, 0x0, 0x0?, 0x1) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc00055aea8 sp=0xc00055ad88 pc=0x454845 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.(*Conn).timeoutLoop(0xc000126680) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:153 +0x156 fp=0xc00055afc8 sp=0xc00055aea8 pc=0x803036 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.newConn.gowrap1() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x25 fp=0xc00055afe0 sp=0xc00055afc8 pc=0x802ba5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00055afe8 sp=0xc00055afe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by nhooyr.io/websocket.newConn in goroutine 48 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/conn_notjs.go:114 +0x4c5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 91 gp=0xc000483180 m=nil [IO wait, 1030 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0x7fb27003b038?, 0x0?, 0x0?, 0x0?, 0xb?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0000e9298 sp=0xc0000e9278 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.netpollblock(0x486bd8?, 0x40c5a6?, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/netpoll.go:573 +0xf7 fp=0xc0000e92d0 sp=0xc0000e9298 pc=0x43c237 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.runtime_pollWait(0x7fb2711c2c28, 0x72) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/netpoll.go:345 +0x85 fp=0xc0000e92f0 sp=0xc0000e92d0 pc=0x4701e5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*pollDesc).wait(0xc00054ad80?, 0xc0002aa000?, 0x0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:84 +0x27 fp=0xc0000e9318 sp=0xc0000e92f0 pc=0x4e1047 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*pollDesc).waitRead(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_poll_runtime.go:89 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: internal/poll.(*FD).Read(0xc00054ad80, {0xc0002aa000, 0x1000, 0x1000}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/internal/poll/fd_unix.go:164 +0x27a fp=0xc0000e93b0 sp=0xc0000e9318 pc=0x4e233a Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net.(*netFD).Read(0xc00054ad80, {0xc0002aa000?, 0x7fb2711c2c58?, 0xe7791f701?}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/fd_posix.go:55 +0x25 fp=0xc0000e93f8 sp=0xc0000e93b0 pc=0x54ce65 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net.(*conn).Read(0xc000122240, {0xc0002aa000?, 0x453ff0?, 0x4434ce?}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/net.go:179 +0x45 fp=0xc0000e9440 sp=0xc0000e93f8 pc=0x55be45 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net.(*TCPConn).Read(0xc0000e9590?, {0xc0002aa000?, 0xc0000e96a0?, 0x2?}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: :1 +0x25 fp=0xc0000e9470 sp=0xc0000e9440 pc=0x56d825 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: io.(*multiReader).Read(0xc0003b0150, {0xc0002aa000, 0x1000, 0x1000}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/io/multi.go:26 +0x93 fp=0xc0000e94b8 sp=0xc0000e9470 pc=0x4dc9b3 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: bufio.(*Reader).fill(0xc000110840) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/bufio/bufio.go:110 +0x103 fp=0xc0000e94f0 sp=0xc0000e94b8 pc=0x672a23 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: bufio.(*Reader).ReadByte(0xc000110840) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/bufio/bufio.go:269 +0x27 fp=0xc0000e9510 sp=0xc0000e94f0 pc=0x673147 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.readFrameHeader(0xc000110840, {0xc000126f00, 0x8, 0x8}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/frame.go:54 +0x8b fp=0xc0000e95a0 sp=0xc0000e9510 pc=0x80632b Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.(*Conn).readFrameHeader(0xc000126ea0, {0xbd0680, 0x1091660}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:188 +0xd9 fp=0xc0000e96e0 sp=0xc0000e95a0 pc=0x807d79 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.(*Conn).readLoop(0xc000126ea0, {0xbd0680, 0x1091660}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:146 +0x65 fp=0xc0000e9840 sp=0xc0000e96e0 pc=0x8076e5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.(*Conn).reader(0xc000126ea0, {0xbd0680, 0x1091660}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:315 +0x125 fp=0xc0000e9940 sp=0xc0000e9840 pc=0x808ce5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.(*Conn).Reader(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:30 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: nhooyr.io/websocket.(*Conn).Read(0xc0001fa588?, {0xbd0680?, 0x1091660?}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/nhooyr.io/websocket@v1.8.7/read.go:36 +0x1e fp=0xc0000e9970 sp=0xc0000e9940 pc=0x80705e Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: main.(*ConcreteApiServer).serve.func17({0xbcf7d0, 0xc000370700}, 0x0?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/api_server.go:580 +0x3ae fp=0xc0000e9a68 sp=0xc0000e9970 pc=0x97098e Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.HandlerFunc.ServeHTTP(0xc00010e000?, {0xbcf7d0?, 0xc000370700?}, 0xc00054ad80?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:2166 +0x29 fp=0xc0000e9a90 sp=0xc0000e9a68 pc=0x6ec6a9 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.(*ServeMux).ServeHTTP(0xc00010e270?, {0xbcf7d0, 0xc000370700}, 0xc000214c60) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:2683 +0x1ad fp=0xc0000e9ae0 sp=0xc0000e9a90 pc=0x6ee58d Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: main.(*ConcreteApiServer).serve.(*Cors).Handler.func19({0xbcf7d0, 0xc000370700}, 0xc000214c60) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/github.com/rs/cors@v1.11.1/cors.go:289 +0x184 fp=0xc0000e9b38 sp=0xc0000e9ae0 pc=0x9703e4 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.HandlerFunc.ServeHTTP(0x46edf9?, {0xbcf7d0?, 0xc000370700?}, 0xc00006fb68?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:2166 +0x29 fp=0xc0000e9b60 sp=0xc0000e9b38 pc=0x6ec6a9 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.serverHandler.ServeHTTP({0xc0004afe90?}, {0xbcf7d0?, 0xc000370700?}, 0x6?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:3137 +0x8e fp=0xc0000e9b90 sp=0xc0000e9b60 pc=0x6efcce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.(*conn).serve(0xc000552ea0, {0xbd06f0, 0xc000124480}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:2039 +0x5e8 fp=0xc0000e9fb8 sp=0xc0000e9b90 pc=0x6eb088 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: net/http.(*Server).Serve.gowrap3() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:3285 +0x28 fp=0xc0000e9fe0 sp=0xc0000e9fb8 pc=0x6f04e8 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0000e9fe8 sp=0xc0000e9fe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by net/http.(*Server).Serve in goroutine 8 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/net/http/server.go:3285 +0x4b4 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 768951 gp=0xc000292000 m=nil [select, 790 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0xc000692710?, 0x3?, 0x38?, 0xed?, 0xc0006926c2?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000692568 sp=0xc000692548 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.selectgo(0xc000692710, 0xc0006926bc, 0xc000095040?, 0x0, 0xc00069259e?, 0x1) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc000692688 sp=0xc000692568 pc=0x454845 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/devgianlu/go-librespot/player.(*Player).manageLoop(0xc0002096c0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/player/player.go:181 +0x205 fp=0xc0006927c8 sp=0xc000692688 pc=0x85a605 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/devgianlu/go-librespot/player.NewPlayer.gowrap1() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/player/player.go:163 +0x25 fp=0xc0006927e0 sp=0xc0006927c8 pc=0x85a1c5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0006927e8 sp=0xc0006927e0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by github.com/devgianlu/go-librespot/player.NewPlayer in goroutine 83 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/player/player.go:163 +0x19a Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 769586 gp=0xc00017aa80 m=nil [select]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0xc00053fde0?, 0x2?, 0x20?, 0x1b?, 0xc00053fd8c?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc00053fc30 sp=0xc00053fc10 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.selectgo(0xc00053fde0, 0xc00053fd88, 0x9d2520?, 0x0, 0xc00040b920?, 0x1) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc00053fd50 sp=0xc00053fc30 pc=0x454845 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/cenkalti/backoff/v4.doRetryNotify[...](0xc00053fe78?, {0xbce8b8, 0xc00040b920}, 0x0, {0x0, 0x0?}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:112 +0x24f fp=0xc00053fe40 sp=0xc00053fd50 pc=0x80e1ef Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/cenkalti/backoff/v4.RetryNotifyWithTimer(0xc0001271e0?, {0xbce8b8?, 0xc00040b920?}, 0x0?, {0x0?, 0x0?}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:61 +0x5c fp=0xc00053fe98 sp=0xc00053fe40 pc=0x80dc1c Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/cenkalti/backoff/v4.RetryNotify(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:49 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/cenkalti/backoff/v4.Retry(...) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/.gocache/mod/github.com/cenkalti/backoff/v4@v4.2.1/retry.go:38 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/devgianlu/go-librespot/dealer.(*Dealer).recvLoop(0xc00063c1c0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/dealer/dealer.go:237 +0x5cb fp=0xc00053ffc8 sp=0xc00053fe98 pc=0x8750eb Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect.gowrap1() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/dealer/dealer.go:295 +0x25 fp=0xc00053ffe0 sp=0xc00053ffc8 pc=0x875685 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc00053ffe8 sp=0xc00053ffe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).reconnect in goroutine 768970 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/dealer/dealer.go:295 +0xeb Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 768952 gp=0xc000f068c0 m=nil [select, 166 minutes]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0xc0006a7c20?, 0x8?, 0x0?, 0x8c?, 0xc0006a74cc?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0006a7148 sp=0xc0006a7128 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.selectgo(0xc0006a7c20, 0xc0006a74bc, 0x1091660?, 0x0, 0x1d?, 0x1) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc0006a7268 sp=0xc0006a7148 pc=0x454845 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: main.(*AppPlayer).Run(0xc0005f2100, {0xbd06b8, 0x1091660}, 0xc00053d0e0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/player.go:570 +0x405 fp=0xc0006a7fb0 sp=0xc0006a7268 pc=0x981825 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: main.(*App).withAppPlayer.func2.gowrap1() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/main.go:305 +0x2c fp=0xc0006a7fe0 sp=0xc0006a7fb0 pc=0x97aaac Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0006a7fe8 sp=0xc0006a7fe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by main.(*App).withAppPlayer.func2 in goroutine 83 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/cmd/daemon/main.go:305 +0x286 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 768971 gp=0xc000171c00 m=nil [select]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0xc0000ebf98?, 0x2?, 0xb0?, 0x2a?, 0xc0000ebf44?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc0000ebde8 sp=0xc0000ebdc8 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.selectgo(0xc0000ebf98, 0xc0000ebf40, 0x0?, 0x0, 0xc00035cec0?, 0x1) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc0000ebf08 sp=0xc0000ebde8 pc=0x454845 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/devgianlu/go-librespot/dealer.(*Dealer).pingTicker(0xc00063c1c0) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/dealer/dealer.go:138 +0x8f fp=0xc0000ebfc8 sp=0xc0000ebf08 pc=0x8747cf Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1.gowrap2() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/dealer/dealer.go:129 +0x25 fp=0xc0000ebfe0 sp=0xc0000ebfc8 pc=0x8746a5 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc0000ebfe8 sp=0xc0000ebfe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by github.com/devgianlu/go-librespot/dealer.(*Dealer).startReceiving.func1 in goroutine 768952 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/dealer/dealer.go:129 +0xf6 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: goroutine 768969 gp=0xc000151340 m=nil [select]: Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.gopark(0xc000fe9f98?, 0x2?, 0xe0?, 0x2c?, 0xc000fe9f6c?) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/proc.go:402 +0xce fp=0xc000fe9e18 sp=0xc000fe9df8 pc=0x4434ce Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.selectgo(0xc000fe9f98, 0xc000fe9f68, 0x1030840?, 0x0, 0xc0003aac60?, 0x1) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/select.go:327 +0x725 fp=0xc000fe9f38 sp=0xc000fe9e18 pc=0x454845 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).pongAckTicker(0xc000138d20) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/ap/ap.go:355 +0x7d fp=0xc000fe9fc8 sp=0xc000fe9f38 pc=0x819fbd Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1.gowrap2() Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/ap/ap.go:264 +0x25 fp=0xc000fe9fe0 sp=0xc000fe9fc8 pc=0x819645 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: runtime.goexit({}) Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /usr/local/go/src/runtime/asm_amd64.s:1695 +0x1 fp=0xc000fe9fe8 sp=0xc000fe9fe0 pc=0x475461 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: created by github.com/devgianlu/go-librespot/ap.(*Accesspoint).startReceiving.func1 in goroutine 768952 Jul 06 15:45:52 volumio-pc-beta go-librespot[36332]: /src/ap/ap.go:264 +0xff Jul 06 15:45:52 volumio-pc-beta go-librespot[36331]: Aborted Jul 06 15:45:52 volumio-pc-beta volumio[1113]: info: Connection to go-librespot Websocket closed Jul 06 15:45:52 volumio-pc-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=134/n/a Jul 06 15:45:52 volumio-pc-beta volumio[1113]: info: Connection to go-librespot Websocket closed Jul 06 15:45:52 volumio-pc-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:45:52 volumio-pc-beta systemd[1]: go-librespot-daemon.service: Consumed 49.625s CPU time. Jul 06 15:45:55 volumio-pc-beta volumio[1113]: info: Initializing connection to go-librespot Websocket Jul 06 15:45:55 volumio-pc-beta volumio[1113]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 15:45:55 volumio-pc-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 1. Jul 06 15:45:55 volumio-pc-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:55 volumio-pc-beta systemd[1]: go-librespot-daemon.service: Consumed 49.625s CPU time. Jul 06 15:45:55 volumio-pc-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:55 volumio-pc-beta go-librespot[190680]: go-librespot daemon starting... Jul 06 15:45:55 volumio-pc-beta go-librespot[190681]: time="2025-07-06T15:45:55+07:00" level=info msg="running go-librespot 0.2.0" Jul 06 15:45:55 volumio-pc-beta go-librespot[190681]: time="2025-07-06T15:45:55+07:00" level=debug msg="app state loaded" Jul 06 15:45:55 volumio-pc-beta go-librespot[190681]: time="2025-07-06T15:45:55+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 15:45:56 volumio-pc-beta go-librespot[190681]: 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-pc-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 15:45:56 volumio-pc-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:45:56 volumio-pc-beta systemd[1]: systemd-timedated.service: Deactivated successfully. Jul 06 15:45:58 volumio-pc-beta volumio[1113]: info: Initializing connection to go-librespot Websocket Jul 06 15:45:58 volumio-pc-beta volumio[1113]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 15:45:59 volumio-pc-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 2. Jul 06 15:45:59 volumio-pc-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:59 volumio-pc-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:45:59 volumio-pc-beta go-librespot[190707]: go-librespot daemon starting... Jul 06 15:45:59 volumio-pc-beta go-librespot[190708]: time="2025-07-06T15:45:59+07:00" level=info msg="running go-librespot 0.2.0" Jul 06 15:45:59 volumio-pc-beta go-librespot[190708]: time="2025-07-06T15:45:59+07:00" level=debug msg="app state loaded" Jul 06 15:45:59 volumio-pc-beta go-librespot[190708]: 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-pc-beta go-librespot[190708]: 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-pc-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 15:45:59 volumio-pc-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:46:01 volumio-pc-beta volumio[1113]: info: Initializing connection to go-librespot Websocket Jul 06 15:46:01 volumio-pc-beta volumio[1113]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 15:46:02 volumio-pc-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 3. Jul 06 15:46:02 volumio-pc-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:46:02 volumio-pc-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:46:02 volumio-pc-beta go-librespot[190718]: go-librespot daemon starting... Jul 06 15:46:02 volumio-pc-beta go-librespot[190719]: time="2025-07-06T15:46:02+07:00" level=info msg="running go-librespot 0.2.0" Jul 06 15:46:02 volumio-pc-beta go-librespot[190719]: time="2025-07-06T15:46:02+07:00" level=debug msg="app state loaded" Jul 06 15:46:02 volumio-pc-beta go-librespot[190719]: time="2025-07-06T15:46:02+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 15:46:02 volumio-pc-beta go-librespot[190719]: time="2025-07-06T15:46:02+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:02 volumio-pc-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 15:46:02 volumio-pc-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:46:04 volumio-pc-beta volumio[1113]: info: Initializing connection to go-librespot Websocket Jul 06 15:46:04 volumio-pc-beta volumio[1113]: info: Error connecting to go-librespot Websocket: Error: connect ECONNREFUSED 127.0.0.1:9879 Jul 06 15:46:05 volumio-pc-beta systemd[1]: go-librespot-daemon.service: Scheduled restart job, restart counter is at 4. Jul 06 15:46:05 volumio-pc-beta systemd[1]: Stopped go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:46:05 volumio-pc-beta systemd[1]: Started go-librespot-daemon.service - go-librespot Daemon. Jul 06 15:46:05 volumio-pc-beta go-librespot[190728]: go-librespot daemon starting... Jul 06 15:46:05 volumio-pc-beta go-librespot[190729]: time="2025-07-06T15:46:05+07:00" level=info msg="running go-librespot 0.2.0" Jul 06 15:46:05 volumio-pc-beta go-librespot[190729]: time="2025-07-06T15:46:05+07:00" level=debug msg="app state loaded" Jul 06 15:46:05 volumio-pc-beta go-librespot[190729]: time="2025-07-06T15:46:05+07:00" level=info msg="api server listening on 127.0.0.1:9879" Jul 06 15:46:07 volumio-pc-beta volumio[1113]: info: Initializing connection to go-librespot Websocket Jul 06 15:46:07 volumio-pc-beta go-librespot[190729]: time="2025-07-06T15:46:07+07:00" level=debug msg="new websocket client" Jul 06 15:46:07 volumio-pc-beta volumio[1113]: info: Connection to go-librespot Websocket established Jul 06 15:46:10 volumio-pc-beta volumio[1113]: info: Getting Spotify volume Jul 06 15:46:10 volumio-pc-beta volumio[1113]: verbose: New Socket.io Connection to localhost:3000 from 127.0.0.1 UA: node-XMLHttpRequest Engine version: 3 Transport: polling Total Clients: 10 Jul 06 15:46:10 volumio-pc-beta volumio[1113]: info: CoreCommandRouter::volumioGetState Jul 06 15:46:10 volumio-pc-beta volumio[1113]: info: CorePlayQueue::getTrack 11 Jul 06 15:46:33 volumio-pc-beta volumio[1113]: error: [now-playing] API endpoint unsplash/getRandomPhoto returned error: TypeError: fetch failed Jul 06 15:46:33 volumio-pc-beta volumio[1113]: at Object.fetch (node:internal/deps/undici/undici:11576:11) Jul 06 15:46:33 volumio-pc-beta volumio[1113]: at process.processTicksAndRejections (node:internal/process/task_queues:95:5) Jul 06 15:46:33 volumio-pc-beta volumio[1113]: at async doFetch (/data/plugins/user_interface/now_playing/dist/lib/api/UnsplashAPI.js:13:26) Jul 06 15:46:33 volumio-pc-beta volumio[1113]: at async UnsplashAPI.getRandomPhoto (/data/plugins/user_interface/now_playing/dist/lib/api/UnsplashAPI.js:31:28) Jul 06 15:46:33 volumio-pc-beta volumio[1113]: at async Object.api (/data/plugins/user_interface/now_playing/dist/app/Handler.js:116:28) Jul 06 15:46:33 volumio-pc-beta go-librespot[190729]: time="2025-07-06T15:46:33+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:33 volumio-pc-beta systemd[1]: go-librespot-daemon.service: Main process exited, code=exited, status=1/FAILURE Jul 06 15:46:33 volumio-pc-beta systemd[1]: go-librespot-daemon.service: Failed with result 'exit-code'. Jul 06 15:46:33 volumio-pc-beta volumio[1113]: |||||||||||||||||||||||| WARNING: FATAL ERROR ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 15:46:33 volumio-pc-beta volumio[1113]: Error: socket hang up Jul 06 15:46:33 volumio-pc-beta volumio[1113]: at connResetException (node:internal/errors:720:14) Jul 06 15:46:33 volumio-pc-beta volumio[1113]: at Socket.socketOnEnd (node:_http_client:519:23) Jul 06 15:46:33 volumio-pc-beta volumio[1113]: at Socket.emit (node:events:526:35) Jul 06 15:46:33 volumio-pc-beta volumio[1113]: at endReadableNT (node:internal/streams/readable:1376:12) Jul 06 15:46:33 volumio-pc-beta volumio[1113]: at process.processTicksAndRejections (node:internal/process/task_queues:82:21) { Jul 06 15:46:33 volumio-pc-beta volumio[1113]: code: 'ECONNRESET', Jul 06 15:46:33 volumio-pc-beta volumio[1113]: response: undefined Jul 06 15:46:33 volumio-pc-beta volumio[1113]: } Jul 06 15:46:33 volumio-pc-beta volumio[1113]: ||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||||| Jul 06 15:46:33 volumio-pc-beta systemd[1]: Starting setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service... Jul 06 15:46:33 volumio-pc-beta dbus-daemon[794]: [system] Activating via systemd: service name='org.freedesktop.timedate1' unit='dbus-org.freedesktop.timedate1.service' requested by ':1.1702' (uid=0 pid=190818 comm="timedatectl show --property=NTPSynchronized --valu") Jul 06 15:46:33 volumio-pc-beta systemd[1]: Starting systemd-timedated.service - Time & Date Service... Jul 06 15:46:33 volumio-pc-beta sudo[190824]: volumio : PWD=/ ; USER=root ; COMMAND=/bin/journalctl '--since=2025-07-06 15:45' Jul 06 15:46:33 volumio-pc-beta sudo[190824]: pam_unix(sudo:session): session opened for user root(uid=0) by (uid=1000) Jul 06 15:46:33 volumio-pc-beta dbus-daemon[794]: [system] Successfully activated service 'org.freedesktop.timedate1' Jul 06 15:46:33 volumio-pc-beta systemd[1]: Started systemd-timedated.service - Time & Date Service. Jul 06 15:46:33 volumio-pc-beta setdatetime-helper.sh[190806]: Time is already synchronized. Jul 06 15:46:33 volumio-pc-beta systemd[1]: setdatetime-helper.timer.service: Deactivated successfully. Jul 06 15:46:33 volumio-pc-beta systemd[1]: Finished setdatetime-helper.timer.service - Volumio Time Sync Watchdog Service. PRETTY_NAME="Debian GNU/Linux 12 (bookworm)" NAME="Debian GNU/Linux" VERSION_ID="12" VERSION="12 (bookworm)" VERSION_CODENAME=bookworm ID=debian HOME_URL="https://www.debian.org/" SUPPORT_URL="https://www.debian.org/support" BUG_REPORT_URL="https://bugs.debian.org/" VOLUMIO_BUILD_VERSION="4f980d5cf693b4272997a2fa2da8bb8036ddf1ab" VOLUMIO_FE_VERSION="e7cae168f9927391640a091813b8a9656b6909b6" VOLUMIO_FE3_VERSION="df82a8f23c90a3617c15c55572c8aaea90d8b32a" VOLUMIO_BE_VERSION="811ff8312d42f9da0260be1d353081a805678820" VOLUMIO_ARCH="x64" VOLUMIO_VARIANT="volumio" VOLUMIO_TEST="FALSE" VOLUMIO_BUILD_DATE="Sat Jul 5 02:28:04 UTC 2025" VOLUMIO_VERSION="4.015" VOLUMIO_HARDWARE="x86_amd64" VOLUMIO_DEVICENAME="x86_64" VOLUMIO_HASH="fd2f0c5aa414951a829ccaf1ecae9bb1"